{"level":"warn","ts":"2026-04-22T18:27:37.19769Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T18:27:37.197795Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.85: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.19.213.85:2380","--initial-cluster=instance=https://199.19.213.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.85: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-04-22T18:27:37.197865Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T18:27:37.197879Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-04-22T18:27:37.197921Z","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-04-22T18:27:37.198558Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"]} {"level":"info","ts":"2026-04-22T18:27:37.198649Z","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.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.85: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-04-22T18:27:37.201899Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.02847ms"} {"level":"info","ts":"2026-04-22T18:27:37.20641Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a0c678c4e560a44","cluster-id":"dbaaf49260b7a32b"} {"level":"info","ts":"2026-04-22T18:27:37.206489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T18:27:37.206516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 0"} {"level":"info","ts":"2026-04-22T18:27:37.206526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a0c678c4e560a44 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T18:27:37.206539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 1"} {"level":"info","ts":"2026-04-22T18:27:37.206658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"warn","ts":"2026-04-22T18:27:37.215977Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T18:27:37.217446Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T18:27:37.218733Z","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-04-22T18:27:37.221781Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a0c678c4e560a44","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T18:27:37.222117Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a0c678c4e560a44","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T18:27:37.222351Z","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-04-22T18:27:37.222493Z","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-04-22T18:27:37.222529Z","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-04-22T18:27:37.223335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"info","ts":"2026-04-22T18:27:37.225485Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","added-peer-id":"6a0c678c4e560a44","added-peer-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-04-22T18:27:37.230543Z","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-04-22T18:27:37.23071Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-04-22T18:27:37.230759Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-04-22T18:27:37.231027Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a0c678c4e560a44","initial-advertise-peer-urls":["https://199.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T18:27:37.231077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T18:27:37.807718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T18:27:37.807841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T18:27:37.807872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgPreVoteResp from 6a0c678c4e560a44 at term 1"} {"level":"info","ts":"2026-04-22T18:27:37.807896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became candidate at term 2"} {"level":"info","ts":"2026-04-22T18:27:37.807909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgVoteResp from 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-04-22T18:27:37.80793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became leader at term 2"} {"level":"info","ts":"2026-04-22T18:27:37.807947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a0c678c4e560a44 elected leader 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-04-22T18:27:37.809304Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:27:37.809502Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a0c678c4e560a44","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.85:2379]}","request-path":"/0/members/6a0c678c4e560a44/attributes","cluster-id":"dbaaf49260b7a32b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T18:27:37.809494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T18:27:37.809832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T18:27:37.8102Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T18:27:37.810296Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T18:27:37.810473Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:27:37.810642Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:27:37.810699Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:27:37.812986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T18:27:37.813129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.85:2379"} {"level":"warn","ts":"2026-04-22T18:28:06.47788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.299705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-22T18:28:06.478016Z","caller":"traceutil/trace.go:171","msg":"trace[894342278] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:504; }","duration":"230.486068ms","start":"2026-04-22T18:28:06.247508Z","end":"2026-04-22T18:28:06.477994Z","steps":["trace[894342278] 'range keys from in-memory index tree' (duration: 230.085542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:28:06.477918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.854736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-22T18:28:06.478124Z","caller":"traceutil/trace.go:171","msg":"trace[146602866] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:504; }","duration":"230.141611ms","start":"2026-04-22T18:28:06.247959Z","end":"2026-04-22T18:28:06.478101Z","steps":["trace[146602866] 'range keys from in-memory index tree' (duration: 229.667442ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:06.481623Z","caller":"traceutil/trace.go:171","msg":"trace[1102417300] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"156.784744ms","start":"2026-04-22T18:28:06.324805Z","end":"2026-04-22T18:28:06.48159Z","steps":["trace[1102417300] 'read index received' (duration: 68.174095ms)","trace[1102417300] 'applied index is now lower than readState.Index' (duration: 88.609018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:28:06.482124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.298894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-22T18:28:06.482235Z","caller":"traceutil/trace.go:171","msg":"trace[1547286691] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:504; }","duration":"157.426747ms","start":"2026-04-22T18:28:06.324781Z","end":"2026-04-22T18:28:06.482208Z","steps":["trace[1547286691] 'agreement among raft nodes before linearized reading' (duration: 156.930627ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:08.011814Z","caller":"traceutil/trace.go:171","msg":"trace[1120453826] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"110.799637ms","start":"2026-04-22T18:28:07.900947Z","end":"2026-04-22T18:28:08.011747Z","steps":["trace[1120453826] 'process raft request' (duration: 93.948724ms)","trace[1120453826] 'compare' (duration: 16.579778ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:28:08.011917Z","caller":"traceutil/trace.go:171","msg":"trace[1258804222] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"110.805096ms","start":"2026-04-22T18:28:07.901075Z","end":"2026-04-22T18:28:08.01188Z","steps":["trace[1258804222] 'process raft request' (duration: 110.633203ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:08.012216Z","caller":"traceutil/trace.go:171","msg":"trace[117939421] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"105.300369ms","start":"2026-04-22T18:28:07.906864Z","end":"2026-04-22T18:28:08.012164Z","steps":["trace[117939421] 'process raft request' (duration: 104.935681ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:08.012292Z","caller":"traceutil/trace.go:171","msg":"trace[612278703] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"100.510524ms","start":"2026-04-22T18:28:07.911768Z","end":"2026-04-22T18:28:08.012279Z","steps":["trace[612278703] 'process raft request' (duration: 100.214178ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:14.416034Z","caller":"traceutil/trace.go:171","msg":"trace[681609902] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"170.682249ms","start":"2026-04-22T18:28:14.245309Z","end":"2026-04-22T18:28:14.415991Z","steps":["trace[681609902] 'process raft request' (duration: 142.407951ms)","trace[681609902] 'compare' (duration: 28.111835ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:28:14.41802Z","caller":"traceutil/trace.go:171","msg":"trace[800428879] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"125.929605ms","start":"2026-04-22T18:28:14.292064Z","end":"2026-04-22T18:28:14.417993Z","steps":["trace[800428879] 'process raft request' (duration: 125.761792ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:14.922437Z","caller":"traceutil/trace.go:171","msg":"trace[1494649735] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"101.123135ms","start":"2026-04-22T18:28:14.821287Z","end":"2026-04-22T18:28:14.922411Z","steps":["trace[1494649735] 'process raft request' (duration: 100.536304ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:56.458637Z","caller":"traceutil/trace.go:171","msg":"trace[413134914] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"102.394991ms","start":"2026-04-22T18:28:56.356208Z","end":"2026-04-22T18:28:56.458603Z","steps":["trace[413134914] 'read index received' (duration: 63.55916ms)","trace[413134914] 'applied index is now lower than readState.Index' (duration: 38.834241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:28:56.459453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.225018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T18:28:56.459568Z","caller":"traceutil/trace.go:171","msg":"trace[1057101706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"103.34813ms","start":"2026-04-22T18:28:56.356187Z","end":"2026-04-22T18:28:56.459536Z","steps":["trace[1057101706] 'agreement among raft nodes before linearized reading' (duration: 102.518373ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:45.726846Z","caller":"traceutil/trace.go:171","msg":"trace[1609375174] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"213.32691ms","start":"2026-04-22T18:29:45.51349Z","end":"2026-04-22T18:29:45.726816Z","steps":["trace[1609375174] 'read index received' (duration: 169.377359ms)","trace[1609375174] 'applied index is now lower than readState.Index' (duration: 43.947721ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:29:45.726936Z","caller":"traceutil/trace.go:171","msg":"trace[1616767309] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"259.328322ms","start":"2026-04-22T18:29:45.467553Z","end":"2026-04-22T18:29:45.726881Z","steps":["trace[1616767309] 'process raft request' (duration: 215.419262ms)","trace[1616767309] 'compare' (duration: 43.697566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:29:45.727116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.614165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a8c14ba2dc7d84\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-22T18:29:45.727166Z","caller":"traceutil/trace.go:171","msg":"trace[1150171442] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a8c14ba2dc7d84; range_end:; response_count:1; response_revision:1840; }","duration":"213.730267ms","start":"2026-04-22T18:29:45.51342Z","end":"2026-04-22T18:29:45.72715Z","steps":["trace[1150171442] 'agreement among raft nodes before linearized reading' (duration: 213.561804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:29:46.195576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.400726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:29:46.195702Z","caller":"traceutil/trace.go:171","msg":"trace[1040161241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1844; }","duration":"113.606449ms","start":"2026-04-22T18:29:46.082065Z","end":"2026-04-22T18:29:46.195672Z","steps":["trace[1040161241] 'range keys from in-memory index tree' (duration: 113.237162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:29:46.195735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.339778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-22T18:29:46.195878Z","caller":"traceutil/trace.go:171","msg":"trace[408875624] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1844; }","duration":"180.555273ms","start":"2026-04-22T18:29:46.01529Z","end":"2026-04-22T18:29:46.195845Z","steps":["trace[408875624] 'range keys from in-memory index tree' (duration: 180.019172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:29:46.195901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.244191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T18:29:46.196231Z","caller":"traceutil/trace.go:171","msg":"trace[427175093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1844; }","duration":"102.72395ms","start":"2026-04-22T18:29:46.093472Z","end":"2026-04-22T18:29:46.196196Z","steps":["trace[427175093] 'range keys from in-memory index tree' (duration: 102.040998ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:30.575692Z","caller":"traceutil/trace.go:171","msg":"trace[2098865136] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"119.342236ms","start":"2026-04-22T18:30:30.456323Z","end":"2026-04-22T18:30:30.575665Z","steps":["trace[2098865136] 'read index received' (duration: 119.284765ms)","trace[2098865136] 'applied index is now lower than readState.Index' (duration: 55.721µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:30:30.575841Z","caller":"traceutil/trace.go:171","msg":"trace[1645189049] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"123.285852ms","start":"2026-04-22T18:30:30.452541Z","end":"2026-04-22T18:30:30.575827Z","steps":["trace[1645189049] 'process raft request' (duration: 122.967846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:30.576097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.752973ms","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-04-22T18:30:30.576142Z","caller":"traceutil/trace.go:171","msg":"trace[177930175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2139; }","duration":"119.845295ms","start":"2026-04-22T18:30:30.456286Z","end":"2026-04-22T18:30:30.576132Z","steps":["trace[177930175] 'agreement among raft nodes before linearized reading' (duration: 119.679422ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:30.654899Z","caller":"traceutil/trace.go:171","msg":"trace[1551605984] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"191.751834ms","start":"2026-04-22T18:30:30.463097Z","end":"2026-04-22T18:30:30.654849Z","steps":["trace[1551605984] 'process raft request' (duration: 173.070205ms)","trace[1551605984] 'compare' (duration: 18.456854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:30:30.655232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.550525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T18:30:30.655323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.734866ms","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-04-22T18:30:30.655329Z","caller":"traceutil/trace.go:171","msg":"trace[666119670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2141; }","duration":"113.734048ms","start":"2026-04-22T18:30:30.541572Z","end":"2026-04-22T18:30:30.655307Z","steps":["trace[666119670] 'agreement among raft nodes before linearized reading' (duration: 113.29312ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:30.655371Z","caller":"traceutil/trace.go:171","msg":"trace[1172204029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2141; }","duration":"153.832307ms","start":"2026-04-22T18:30:30.501526Z","end":"2026-04-22T18:30:30.655358Z","steps":["trace[1172204029] 'agreement among raft nodes before linearized reading' (duration: 153.346408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:36.485895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.30913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-22T18:30:36.486025Z","caller":"traceutil/trace.go:171","msg":"trace[1613331298] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"125.508404ms","start":"2026-04-22T18:30:36.360487Z","end":"2026-04-22T18:30:36.485995Z","steps":["trace[1613331298] 'range keys from in-memory index tree' (duration: 124.957174ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:36.822808Z","caller":"traceutil/trace.go:171","msg":"trace[373546626] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"140.831748ms","start":"2026-04-22T18:30:36.681941Z","end":"2026-04-22T18:30:36.822773Z","steps":["trace[373546626] 'read index received' (duration: 119.716994ms)","trace[373546626] 'applied index is now lower than readState.Index' (duration: 21.112374ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:30:36.822986Z","caller":"traceutil/trace.go:171","msg":"trace[1171970216] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"145.811583ms","start":"2026-04-22T18:30:36.67713Z","end":"2026-04-22T18:30:36.822941Z","steps":["trace[1171970216] 'process raft request' (duration: 124.587337ms)","trace[1171970216] 'compare' (duration: 20.750108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:30:36.823077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.550273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T18:30:36.823127Z","caller":"traceutil/trace.go:171","msg":"trace[1832522465] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2173; }","duration":"104.705226ms","start":"2026-04-22T18:30:36.71841Z","end":"2026-04-22T18:30:36.823115Z","steps":["trace[1832522465] 'agreement among raft nodes before linearized reading' (duration: 104.563473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:36.823107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.154975ms","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-04-22T18:30:36.823218Z","caller":"traceutil/trace.go:171","msg":"trace[1026221566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"141.286367ms","start":"2026-04-22T18:30:36.681886Z","end":"2026-04-22T18:30:36.823173Z","steps":["trace[1026221566] 'agreement among raft nodes before linearized reading' (duration: 141.060692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:31:25.655087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.948594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:31:25.655191Z","caller":"traceutil/trace.go:171","msg":"trace[1300536791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2613; }","duration":"114.113807ms","start":"2026-04-22T18:31:25.541055Z","end":"2026-04-22T18:31:25.655169Z","steps":["trace[1300536791] 'range keys from in-memory index tree' (duration: 113.862522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:31:59.243359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.895596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:31:59.243479Z","caller":"traceutil/trace.go:171","msg":"trace[733426938] linearizableReadLoop","detail":"{readStateIndex:2915; appliedIndex:2914; }","duration":"156.947081ms","start":"2026-04-22T18:31:59.086515Z","end":"2026-04-22T18:31:59.243462Z","steps":["trace[733426938] 'read index received' (duration: 24.399123ms)","trace[733426938] 'applied index is now lower than readState.Index' (duration: 132.546518ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:31:59.243546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.036143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:31:59.243581Z","caller":"traceutil/trace.go:171","msg":"trace[1084189440] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2812; }","duration":"157.118374ms","start":"2026-04-22T18:31:59.086451Z","end":"2026-04-22T18:31:59.243569Z","steps":["trace[1084189440] 'agreement among raft nodes before linearized reading' (duration: 157.056533ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:31:59.243661Z","caller":"traceutil/trace.go:171","msg":"trace[248502639] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"182.796582ms","start":"2026-04-22T18:31:59.060767Z","end":"2026-04-22T18:31:59.243564Z","steps":["trace[248502639] 'process raft request' (duration: 50.140992ms)","trace[248502639] 'compare' (duration: 131.785644ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:31:59.291047Z","caller":"traceutil/trace.go:171","msg":"trace[733528606] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"147.541042ms","start":"2026-04-22T18:31:59.143459Z","end":"2026-04-22T18:31:59.291Z","steps":["trace[733528606] 'process raft request' (duration: 147.269467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:36:17.495342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.735913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:36:17.495567Z","caller":"traceutil/trace.go:171","msg":"trace[87541403] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5364; }","duration":"129.326293ms","start":"2026-04-22T18:36:17.36622Z","end":"2026-04-22T18:36:17.495546Z","steps":["trace[87541403] 'read index received' (duration: 3.275864ms)","trace[87541403] 'applied index is now lower than readState.Index' (duration: 126.046549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:36:17.495727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.500567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T18:36:17.495763Z","caller":"traceutil/trace.go:171","msg":"trace[599542481] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5172; }","duration":"129.587178ms","start":"2026-04-22T18:36:17.366165Z","end":"2026-04-22T18:36:17.495752Z","steps":["trace[599542481] 'agreement among raft nodes before linearized reading' (duration: 129.440105ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:17.49597Z","caller":"traceutil/trace.go:171","msg":"trace[1991073127] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"226.687828ms","start":"2026-04-22T18:36:17.269271Z","end":"2026-04-22T18:36:17.495959Z","steps":["trace[1991073127] 'process raft request' (duration: 100.226781ms)","trace[1991073127] 'compare' (duration: 125.53458ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:36:17.496136Z","caller":"traceutil/trace.go:171","msg":"trace[1817441889] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"225.745041ms","start":"2026-04-22T18:36:17.270339Z","end":"2026-04-22T18:36:17.496084Z","steps":["trace[1817441889] 'process raft request' (duration: 225.131319ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:17.687315Z","caller":"traceutil/trace.go:171","msg":"trace[1020296414] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"114.486003ms","start":"2026-04-22T18:36:17.572779Z","end":"2026-04-22T18:36:17.687265Z","steps":["trace[1020296414] 'process raft request' (duration: 114.070245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:36:17.687551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.236176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62867"} {"level":"info","ts":"2026-04-22T18:36:17.687632Z","caller":"traceutil/trace.go:171","msg":"trace[1183970071] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5178; }","duration":"118.384448ms","start":"2026-04-22T18:36:17.569225Z","end":"2026-04-22T18:36:17.68761Z","steps":["trace[1183970071] 'agreement among raft nodes before linearized reading' (duration: 117.97842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:36:17.687635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.296266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-22T18:36:17.687753Z","caller":"traceutil/trace.go:171","msg":"trace[1505592584] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5178; }","duration":"118.611722ms","start":"2026-04-22T18:36:17.569114Z","end":"2026-04-22T18:36:17.687725Z","steps":["trace[1505592584] 'agreement among raft nodes before linearized reading' (duration: 118.185424ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:23.317696Z","caller":"traceutil/trace.go:171","msg":"trace[665143970] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"133.247801ms","start":"2026-04-22T18:36:23.184414Z","end":"2026-04-22T18:36:23.317662Z","steps":["trace[665143970] 'process raft request' (duration: 97.05778ms)","trace[665143970] 'compare' (duration: 35.983117ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:36:23.318183Z","caller":"traceutil/trace.go:171","msg":"trace[2041652270] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"136.944444ms","start":"2026-04-22T18:36:23.181194Z","end":"2026-04-22T18:36:23.318138Z","steps":["trace[2041652270] 'process raft request' (duration: 136.419413ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:23.318266Z","caller":"traceutil/trace.go:171","msg":"trace[1070005351] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"133.449904ms","start":"2026-04-22T18:36:23.184772Z","end":"2026-04-22T18:36:23.318222Z","steps":["trace[1070005351] 'process raft request' (duration: 133.2541ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:23.318182Z","caller":"traceutil/trace.go:171","msg":"trace[1041159206] linearizableReadLoop","detail":"{readStateIndex:5482; appliedIndex:5478; }","duration":"130.825094ms","start":"2026-04-22T18:36:23.187334Z","end":"2026-04-22T18:36:23.318159Z","steps":["trace[1041159206] 'read index received' (duration: 30.184434ms)","trace[1041159206] 'applied index is now lower than readState.Index' (duration: 100.6395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:36:23.318532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.103319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:36:23.318712Z","caller":"traceutil/trace.go:171","msg":"trace[1548977508] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5286; }","duration":"131.390234ms","start":"2026-04-22T18:36:23.187294Z","end":"2026-04-22T18:36:23.318684Z","steps":["trace[1548977508] 'agreement among raft nodes before linearized reading' (duration: 130.955976ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:34.75013Z","caller":"traceutil/trace.go:171","msg":"trace[1136519875] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"111.932742ms","start":"2026-04-22T18:36:34.638171Z","end":"2026-04-22T18:36:34.750104Z","steps":["trace[1136519875] 'process raft request' (duration: 49.676726ms)","trace[1136519875] 'compare' (duration: 62.118373ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:36:34.75071Z","caller":"traceutil/trace.go:171","msg":"trace[1985565009] linearizableReadLoop","detail":"{readStateIndex:5674; appliedIndex:5672; }","duration":"102.311793ms","start":"2026-04-22T18:36:34.648385Z","end":"2026-04-22T18:36:34.750697Z","steps":["trace[1985565009] 'read index received' (duration: 39.388855ms)","trace[1985565009] 'applied index is now lower than readState.Index' (duration: 62.919458ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:36:34.750869Z","caller":"traceutil/trace.go:171","msg":"trace[642467832] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"111.948622ms","start":"2026-04-22T18:36:34.638864Z","end":"2026-04-22T18:36:34.750813Z","steps":["trace[642467832] 'process raft request' (duration: 111.747679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:36:34.750895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.490166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-22T18:36:34.750947Z","caller":"traceutil/trace.go:171","msg":"trace[1017300834] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5476; }","duration":"102.552618ms","start":"2026-04-22T18:36:34.648378Z","end":"2026-04-22T18:36:34.750931Z","steps":["trace[1017300834] 'agreement among raft nodes before linearized reading' (duration: 102.372714ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:44.612105Z","caller":"traceutil/trace.go:171","msg":"trace[110043736] linearizableReadLoop","detail":"{readStateIndex:5832; appliedIndex:5831; }","duration":"137.369562ms","start":"2026-04-22T18:36:44.474699Z","end":"2026-04-22T18:36:44.612069Z","steps":["trace[110043736] 'read index received' (duration: 136.999985ms)","trace[110043736] 'applied index is now lower than readState.Index' (duration: 368.087µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:36:44.612213Z","caller":"traceutil/trace.go:171","msg":"trace[2023605888] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"138.322081ms","start":"2026-04-22T18:36:44.473843Z","end":"2026-04-22T18:36:44.612166Z","steps":["trace[2023605888] 'process raft request' (duration: 137.897853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:36:44.612377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.643538ms","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:5"} {"level":"info","ts":"2026-04-22T18:36:44.613006Z","caller":"traceutil/trace.go:171","msg":"trace[328926204] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5628; }","duration":"138.348132ms","start":"2026-04-22T18:36:44.474636Z","end":"2026-04-22T18:36:44.612984Z","steps":["trace[328926204] 'agreement among raft nodes before linearized reading' (duration: 137.617817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:36:44.613213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.964753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-22T18:36:44.613317Z","caller":"traceutil/trace.go:171","msg":"trace[708611410] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5628; }","duration":"107.135527ms","start":"2026-04-22T18:36:44.506155Z","end":"2026-04-22T18:36:44.61329Z","steps":["trace[708611410] 'agreement among raft nodes before linearized reading' (duration: 106.285361ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:23.233952Z","caller":"traceutil/trace.go:171","msg":"trace[1176369421] transaction","detail":"{read_only:false; response_revision:5970; number_of_response:1; }","duration":"116.270404ms","start":"2026-04-22T18:37:23.117646Z","end":"2026-04-22T18:37:23.233917Z","steps":["trace[1176369421] 'process raft request' (duration: 73.735689ms)","trace[1176369421] 'compare' (duration: 42.071805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:37:23.23401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.611224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112313"} {"level":"info","ts":"2026-04-22T18:37:23.234095Z","caller":"traceutil/trace.go:171","msg":"trace[1755904802] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5969; }","duration":"159.754597ms","start":"2026-04-22T18:37:23.074322Z","end":"2026-04-22T18:37:23.234077Z","steps":["trace[1755904802] 'range keys from in-memory index tree' (duration: 159.249317ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:37.842205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3028} {"level":"info","ts":"2026-04-22T18:37:37.935764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3028,"took":"91.709879ms","hash":929601084,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31363072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-22T18:37:37.935853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":929601084,"revision":3028,"compact-revision":-1} {"level":"info","ts":"2026-04-22T18:42:37.850019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6084} {"level":"info","ts":"2026-04-22T18:42:37.952343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6084,"took":"99.675905ms","hash":45327369,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":23257088,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-22T18:42:37.952428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":45327369,"revision":6084,"compact-revision":3028} {"level":"warn","ts":"2026-04-22T18:42:47.191003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.169666ms","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-04-22T18:42:47.191101Z","caller":"traceutil/trace.go:171","msg":"trace[2063199273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8346; }","duration":"107.326429ms","start":"2026-04-22T18:42:47.083752Z","end":"2026-04-22T18:42:47.191078Z","steps":["trace[2063199273] 'range keys from in-memory index tree' (duration: 106.8906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:42:47.191492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.558748ms","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-04-22T18:42:47.191545Z","caller":"traceutil/trace.go:171","msg":"trace[504252779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8346; }","duration":"103.666ms","start":"2026-04-22T18:42:47.087865Z","end":"2026-04-22T18:42:47.191531Z","steps":["trace[504252779] 'range keys from in-memory index tree' (duration: 102.808193ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:42:47.884193Z","caller":"traceutil/trace.go:171","msg":"trace[287675864] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"133.200446ms","start":"2026-04-22T18:42:47.750951Z","end":"2026-04-22T18:42:47.884152Z","steps":["trace[287675864] 'process raft request' (duration: 132.993731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:42:48.954351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.112154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:42:48.954514Z","caller":"traceutil/trace.go:171","msg":"trace[2142721149] linearizableReadLoop","detail":"{readStateIndex:8644; appliedIndex:8643; }","duration":"214.629056ms","start":"2026-04-22T18:42:48.739866Z","end":"2026-04-22T18:42:48.954495Z","steps":["trace[2142721149] 'read index received' (duration: 98.200027ms)","trace[2142721149] 'applied index is now lower than readState.Index' (duration: 116.427279ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:42:48.954621Z","caller":"traceutil/trace.go:171","msg":"trace[1555145553] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"416.419202ms","start":"2026-04-22T18:42:48.53816Z","end":"2026-04-22T18:42:48.954579Z","steps":["trace[1555145553] 'process raft request' (duration: 299.956872ms)","trace[1555145553] 'compare' (duration: 115.562023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:42:48.954684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.83012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:42:48.954718Z","caller":"traceutil/trace.go:171","msg":"trace[1699844719] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8354; }","duration":"214.945782ms","start":"2026-04-22T18:42:48.739762Z","end":"2026-04-22T18:42:48.954708Z","steps":["trace[1699844719] 'agreement among raft nodes before linearized reading' (duration: 214.872801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:42:48.95483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.307827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T18:42:48.954817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:42:48.538136Z","time spent":"416.572264ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T18:42:48.954896Z","caller":"traceutil/trace.go:171","msg":"trace[657171047] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8354; }","duration":"107.381728ms","start":"2026-04-22T18:42:48.8475Z","end":"2026-04-22T18:42:48.954882Z","steps":["trace[657171047] 'agreement among raft nodes before linearized reading' (duration: 107.261806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:42:48.954924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.33303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-22T18:42:48.955009Z","caller":"traceutil/trace.go:171","msg":"trace[651597463] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8354; }","duration":"165.457961ms","start":"2026-04-22T18:42:48.789525Z","end":"2026-04-22T18:42:48.954983Z","steps":["trace[651597463] 'agreement among raft nodes before linearized reading' (duration: 165.193406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:42:48.955623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.574462ms","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-04-22T18:42:48.955678Z","caller":"traceutil/trace.go:171","msg":"trace[56101916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8354; }","duration":"100.699284ms","start":"2026-04-22T18:42:48.854966Z","end":"2026-04-22T18:42:48.955665Z","steps":["trace[56101916] 'agreement among raft nodes before linearized reading' (duration: 100.48801ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:42:50.567601Z","caller":"traceutil/trace.go:171","msg":"trace[1886685227] linearizableReadLoop","detail":"{readStateIndex:8654; appliedIndex:8653; }","duration":"135.115631ms","start":"2026-04-22T18:42:50.432406Z","end":"2026-04-22T18:42:50.567522Z","steps":["trace[1886685227] 'read index received' (duration: 134.860536ms)","trace[1886685227] 'applied index is now lower than readState.Index' (duration: 253.405µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:42:50.567684Z","caller":"traceutil/trace.go:171","msg":"trace[2114596521] transaction","detail":"{read_only:false; response_revision:8364; number_of_response:1; }","duration":"147.950562ms","start":"2026-04-22T18:42:50.419688Z","end":"2026-04-22T18:42:50.567639Z","steps":["trace[2114596521] 'process raft request' (duration: 147.554485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:42:50.568622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.179381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T18:42:50.568708Z","caller":"traceutil/trace.go:171","msg":"trace[20952197] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8364; }","duration":"136.325364ms","start":"2026-04-22T18:42:50.432363Z","end":"2026-04-22T18:42:50.568688Z","steps":["trace[20952197] 'agreement among raft nodes before linearized reading' (duration: 135.369956ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:42:50.56963Z","caller":"traceutil/trace.go:171","msg":"trace[212258694] transaction","detail":"{read_only:false; response_revision:8365; number_of_response:1; }","duration":"125.271457ms","start":"2026-04-22T18:42:50.444341Z","end":"2026-04-22T18:42:50.569612Z","steps":["trace[212258694] 'process raft request' (duration: 125.103404ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:18.791706Z","caller":"traceutil/trace.go:171","msg":"trace[197351739] linearizableReadLoop","detail":"{readStateIndex:8865; appliedIndex:8864; }","duration":"253.500398ms","start":"2026-04-22T18:43:18.538174Z","end":"2026-04-22T18:43:18.791674Z","steps":["trace[197351739] 'read index received' (duration: 253.371546ms)","trace[197351739] 'applied index is now lower than readState.Index' (duration: 127.302µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:18.791869Z","caller":"traceutil/trace.go:171","msg":"trace[898199260] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"254.756352ms","start":"2026-04-22T18:43:18.537061Z","end":"2026-04-22T18:43:18.791817Z","steps":["trace[898199260] 'process raft request' (duration: 254.373375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:18.79198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.768274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:43:18.792039Z","caller":"traceutil/trace.go:171","msg":"trace[311089086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8567; }","duration":"253.899866ms","start":"2026-04-22T18:43:18.538124Z","end":"2026-04-22T18:43:18.792024Z","steps":["trace[311089086] 'agreement among raft nodes before linearized reading' (duration: 253.701752ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:18.984961Z","caller":"traceutil/trace.go:171","msg":"trace[64643353] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"288.585608ms","start":"2026-04-22T18:43:18.696341Z","end":"2026-04-22T18:43:18.984927Z","steps":["trace[64643353] 'process raft request' (duration: 282.654067ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:18.985084Z","caller":"traceutil/trace.go:171","msg":"trace[1406165328] linearizableReadLoop","detail":"{readStateIndex:8867; appliedIndex:8865; }","duration":"193.262875ms","start":"2026-04-22T18:43:18.791791Z","end":"2026-04-22T18:43:18.985054Z","steps":["trace[1406165328] 'read index received' (duration: 187.189151ms)","trace[1406165328] 'applied index is now lower than readState.Index' (duration: 6.072594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:43:18.985252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.855265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T18:43:18.985304Z","caller":"traceutil/trace.go:171","msg":"trace[1634095775] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8569; }","duration":"253.996297ms","start":"2026-04-22T18:43:18.731295Z","end":"2026-04-22T18:43:18.985291Z","steps":["trace[1634095775] 'agreement among raft nodes before linearized reading' (duration: 253.869735ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:18.985281Z","caller":"traceutil/trace.go:171","msg":"trace[1670515261] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"268.44911ms","start":"2026-04-22T18:43:18.716799Z","end":"2026-04-22T18:43:18.985249Z","steps":["trace[1670515261] 'process raft request' (duration: 268.023181ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:20.23176Z","caller":"traceutil/trace.go:171","msg":"trace[1080435905] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"135.168603ms","start":"2026-04-22T18:43:20.096536Z","end":"2026-04-22T18:43:20.231705Z","steps":["trace[1080435905] 'process raft request' (duration: 80.75553ms)","trace[1080435905] 'compare' (duration: 53.976175ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:20.932399Z","caller":"traceutil/trace.go:171","msg":"trace[2127884462] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"133.313548ms","start":"2026-04-22T18:43:20.799047Z","end":"2026-04-22T18:43:20.93236Z","steps":["trace[2127884462] 'process raft request' (duration: 133.060283ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:21.236951Z","caller":"traceutil/trace.go:171","msg":"trace[2073989745] linearizableReadLoop","detail":"{readStateIndex:8879; appliedIndex:8878; }","duration":"107.191196ms","start":"2026-04-22T18:43:21.129729Z","end":"2026-04-22T18:43:21.23692Z","steps":["trace[2073989745] 'read index received' (duration: 106.985863ms)","trace[2073989745] 'applied index is now lower than readState.Index' (duration: 204.173µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:21.237033Z","caller":"traceutil/trace.go:171","msg":"trace[1580266608] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"145.70152ms","start":"2026-04-22T18:43:21.091297Z","end":"2026-04-22T18:43:21.236999Z","steps":["trace[1580266608] 'process raft request' (duration: 145.426235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:21.237293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.529932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.85\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-22T18:43:21.237388Z","caller":"traceutil/trace.go:171","msg":"trace[398202052] range","detail":"{range_begin:/registry/masterleases/199.19.213.85; range_end:; response_count:1; response_revision:8581; }","duration":"107.686076ms","start":"2026-04-22T18:43:21.12968Z","end":"2026-04-22T18:43:21.237366Z","steps":["trace[398202052] 'agreement among raft nodes before linearized reading' (duration: 107.38534ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:26.670673Z","caller":"traceutil/trace.go:171","msg":"trace[1077864466] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"103.413985ms","start":"2026-04-22T18:43:26.567216Z","end":"2026-04-22T18:43:26.67063Z","steps":["trace[1077864466] 'process raft request' (duration: 47.767809ms)","trace[1077864466] 'compare' (duration: 55.32714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:43:27.654721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.865364ms","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-04-22T18:43:27.654852Z","caller":"traceutil/trace.go:171","msg":"trace[600647179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8646; }","duration":"103.041109ms","start":"2026-04-22T18:43:27.551781Z","end":"2026-04-22T18:43:27.654822Z","steps":["trace[600647179] 'range keys from in-memory index tree' (duration: 102.666591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:27.654721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.093604ms","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-04-22T18:43:27.655159Z","caller":"traceutil/trace.go:171","msg":"trace[1196185079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8646; }","duration":"107.584574ms","start":"2026-04-22T18:43:27.547538Z","end":"2026-04-22T18:43:27.655122Z","steps":["trace[1196185079] 'range keys from in-memory index tree' (duration: 106.82667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:27.655985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.0794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2212884"} {"level":"info","ts":"2026-04-22T18:43:27.656083Z","caller":"traceutil/trace.go:171","msg":"trace[1299898771] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8646; }","duration":"152.203812ms","start":"2026-04-22T18:43:27.50386Z","end":"2026-04-22T18:43:27.656064Z","steps":["trace[1299898771] 'range keys from in-memory index tree' (duration: 150.616702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:32.666517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.858948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:43:32.666748Z","caller":"traceutil/trace.go:171","msg":"trace[1133079296] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"229.884214ms","start":"2026-04-22T18:43:32.436827Z","end":"2026-04-22T18:43:32.666711Z","steps":["trace[1133079296] 'process raft request' (duration: 36.727191ms)","trace[1133079296] 'compare' (duration: 192.705515ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:32.666771Z","caller":"traceutil/trace.go:171","msg":"trace[941237612] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"227.695182ms","start":"2026-04-22T18:43:32.439061Z","end":"2026-04-22T18:43:32.666757Z","steps":["trace[941237612] 'process raft request' (duration: 227.60872ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:32.666792Z","caller":"traceutil/trace.go:171","msg":"trace[1664911080] linearizableReadLoop","detail":"{readStateIndex:8982; appliedIndex:8981; }","duration":"227.782115ms","start":"2026-04-22T18:43:32.438974Z","end":"2026-04-22T18:43:32.666756Z","steps":["trace[1664911080] 'read index received' (duration: 34.594481ms)","trace[1664911080] 'applied index is now lower than readState.Index' (duration: 193.183594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:43:32.667229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.170711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-lqvw4\" ","response":"range_response_count:1 size:4742"} {"level":"warn","ts":"2026-04-22T18:43:32.667214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.135321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"warn","ts":"2026-04-22T18:43:32.667212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.203342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-22T18:43:32.667286Z","caller":"traceutil/trace.go:171","msg":"trace[1501945724] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-lqvw4; range_end:; response_count:1; response_revision:8679; }","duration":"220.260414ms","start":"2026-04-22T18:43:32.447009Z","end":"2026-04-22T18:43:32.667269Z","steps":["trace[1501945724] 'agreement among raft nodes before linearized reading' (duration: 220.09377ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:32.667299Z","caller":"traceutil/trace.go:171","msg":"trace[1154982887] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8679; }","duration":"220.266743ms","start":"2026-04-22T18:43:32.447014Z","end":"2026-04-22T18:43:32.667281Z","steps":["trace[1154982887] 'agreement among raft nodes before linearized reading' (duration: 220.084039ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:32.667323Z","caller":"traceutil/trace.go:171","msg":"trace[1668385758] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8679; }","duration":"228.373237ms","start":"2026-04-22T18:43:32.438924Z","end":"2026-04-22T18:43:32.667297Z","steps":["trace[1668385758] 'agreement among raft nodes before linearized reading' (duration: 228.05557ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:32.814474Z","caller":"traceutil/trace.go:171","msg":"trace[477455477] linearizableReadLoop","detail":"{readStateIndex:8984; appliedIndex:8983; }","duration":"138.947684ms","start":"2026-04-22T18:43:32.675498Z","end":"2026-04-22T18:43:32.814446Z","steps":["trace[477455477] 'read index received' (duration: 138.728399ms)","trace[477455477] 'applied index is now lower than readState.Index' (duration: 217.915µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:32.814619Z","caller":"traceutil/trace.go:171","msg":"trace[1414729095] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"140.157895ms","start":"2026-04-22T18:43:32.674416Z","end":"2026-04-22T18:43:32.814574Z","steps":["trace[1414729095] 'process raft request' (duration: 139.785118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:32.814756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.232979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19382"} {"level":"info","ts":"2026-04-22T18:43:32.814809Z","caller":"traceutil/trace.go:171","msg":"trace[1339975839] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8680; }","duration":"139.349191ms","start":"2026-04-22T18:43:32.675446Z","end":"2026-04-22T18:43:32.814795Z","steps":["trace[1339975839] 'agreement among raft nodes before linearized reading' (duration: 139.160958ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:32.830689Z","caller":"traceutil/trace.go:171","msg":"trace[1275158403] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8680; }","duration":"155.026706ms","start":"2026-04-22T18:43:32.675604Z","end":"2026-04-22T18:43:32.83063Z","steps":["trace[1275158403] 'process raft request' (duration: 154.830812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:32.83077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.414144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-04-22T18:43:32.830835Z","caller":"traceutil/trace.go:171","msg":"trace[346260613] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8680; }","duration":"154.498485ms","start":"2026-04-22T18:43:32.676317Z","end":"2026-04-22T18:43:32.830815Z","steps":["trace[346260613] 'agreement among raft nodes before linearized reading' (duration: 154.276871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:32.830916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.156379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-22T18:43:32.831033Z","caller":"traceutil/trace.go:171","msg":"trace[1075132379] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8680; }","duration":"154.304972ms","start":"2026-04-22T18:43:32.676698Z","end":"2026-04-22T18:43:32.831003Z","steps":["trace[1075132379] 'agreement among raft nodes before linearized reading' (duration: 153.945995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:33.521919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.141469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:43:33.522174Z","caller":"traceutil/trace.go:171","msg":"trace[1790071081] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"161.687031ms","start":"2026-04-22T18:43:33.360406Z","end":"2026-04-22T18:43:33.522093Z","steps":["trace[1790071081] 'process raft request' (duration: 15.225806ms)","trace[1790071081] 'compare' (duration: 145.873475ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:33.67925Z","caller":"traceutil/trace.go:171","msg":"trace[1730703178] linearizableReadLoop","detail":"{readStateIndex:8996; appliedIndex:8995; }","duration":"140.130765ms","start":"2026-04-22T18:43:33.539087Z","end":"2026-04-22T18:43:33.679218Z","steps":["trace[1730703178] 'read index received' (duration: 86.041258ms)","trace[1730703178] 'applied index is now lower than readState.Index' (duration: 54.088377ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:33.679425Z","caller":"traceutil/trace.go:171","msg":"trace[31149303] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"151.733583ms","start":"2026-04-22T18:43:33.527657Z","end":"2026-04-22T18:43:33.67939Z","steps":["trace[31149303] 'process raft request' (duration: 97.736948ms)","trace[31149303] 'compare' (duration: 52.984557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:43:33.679597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.479373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:43:33.680324Z","caller":"traceutil/trace.go:171","msg":"trace[1092979541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8691; }","duration":"141.289438ms","start":"2026-04-22T18:43:33.539014Z","end":"2026-04-22T18:43:33.680304Z","steps":["trace[1092979541] 'agreement among raft nodes before linearized reading' (duration: 140.431921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:43:34.10887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.968565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:43:34.109032Z","caller":"traceutil/trace.go:171","msg":"trace[1223913433] linearizableReadLoop","detail":"{readStateIndex:9001; appliedIndex:9000; }","duration":"179.082618ms","start":"2026-04-22T18:43:33.929922Z","end":"2026-04-22T18:43:34.109004Z","steps":["trace[1223913433] 'read index received' (duration: 226.994µs)","trace[1223913433] 'applied index is now lower than readState.Index' (duration: 178.852494ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:34.109035Z","caller":"traceutil/trace.go:171","msg":"trace[1398840052] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"236.421037ms","start":"2026-04-22T18:43:33.872581Z","end":"2026-04-22T18:43:34.109002Z","steps":["trace[1398840052] 'process raft request' (duration: 56.079585ms)","trace[1398840052] 'compare' (duration: 179.68178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:43:34.109337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.375862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-04-22T18:43:34.109308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.334603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:43:34.109408Z","caller":"traceutil/trace.go:171","msg":"trace[1935975346] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8696; }","duration":"105.483385ms","start":"2026-04-22T18:43:34.003904Z","end":"2026-04-22T18:43:34.109388Z","steps":["trace[1935975346] 'agreement among raft nodes before linearized reading' (duration: 105.23858ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:34.109436Z","caller":"traceutil/trace.go:171","msg":"trace[2114711736] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8696; }","duration":"179.572998ms","start":"2026-04-22T18:43:33.929836Z","end":"2026-04-22T18:43:34.109409Z","steps":["trace[2114711736] 'agreement among raft nodes before linearized reading' (duration: 179.289792ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:34.728773Z","caller":"traceutil/trace.go:171","msg":"trace[1491301905] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"177.02109ms","start":"2026-04-22T18:43:34.55172Z","end":"2026-04-22T18:43:34.728741Z","steps":["trace[1491301905] 'process raft request' (duration: 176.287526ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:43:34.880861Z","caller":"traceutil/trace.go:171","msg":"trace[948739660] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"142.160893ms","start":"2026-04-22T18:43:34.73866Z","end":"2026-04-22T18:43:34.880821Z","steps":["trace[948739660] 'process raft request' (duration: 115.731717ms)","trace[948739660] 'compare' (duration: 26.233882ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:43:53.966039Z","caller":"traceutil/trace.go:171","msg":"trace[163412020] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"110.411146ms","start":"2026-04-22T18:43:53.855591Z","end":"2026-04-22T18:43:53.966002Z","steps":["trace[163412020] 'process raft request' (duration: 83.387348ms)","trace[163412020] 'compare' (duration: 26.755143ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:44:23.980543Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000251a.wal"} {"level":"warn","ts":"2026-04-22T18:44:49.844094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.51958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-22T18:44:49.844219Z","caller":"traceutil/trace.go:171","msg":"trace[2034213238] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9435; }","duration":"185.661252ms","start":"2026-04-22T18:44:49.658534Z","end":"2026-04-22T18:44:49.844195Z","steps":["trace[2034213238] 'range keys from in-memory index tree' (duration: 185.247514ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:44:49.844561Z","caller":"traceutil/trace.go:171","msg":"trace[1746978808] transaction","detail":"{read_only:false; response_revision:9436; number_of_response:1; }","duration":"104.463314ms","start":"2026-04-22T18:44:49.740065Z","end":"2026-04-22T18:44:49.844528Z","steps":["trace[1746978808] 'process raft request' (duration: 89.145127ms)","trace[1746978808] 'compare' (duration: 14.556292ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:45:22.683712Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6a0c678c4e560a44","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T18:45:22.690714Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T18:45:22.690897Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-22T18:45:34.16127Z","caller":"traceutil/trace.go:171","msg":"trace[618488295] transaction","detail":"{read_only:false; response_revision:9758; number_of_response:1; }","duration":"123.429908ms","start":"2026-04-22T18:45:34.037811Z","end":"2026-04-22T18:45:34.161241Z","steps":["trace[618488295] 'process raft request' (duration: 123.202524ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:47:12.758638Z","caller":"traceutil/trace.go:171","msg":"trace[284737539] transaction","detail":"{read_only:false; response_revision:10506; number_of_response:1; }","duration":"119.396481ms","start":"2026-04-22T18:47:12.639202Z","end":"2026-04-22T18:47:12.758598Z","steps":["trace[284737539] 'process raft request' (duration: 114.606121ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:47:37.861618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8300} {"level":"info","ts":"2026-04-22T18:47:37.958992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8300,"took":"94.737919ms","hash":3981711723,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":25915392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-22T18:47:37.95908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3981711723,"revision":8300,"compact-revision":6084} {"level":"info","ts":"2026-04-22T18:48:34.19884Z","caller":"traceutil/trace.go:171","msg":"trace[956679974] linearizableReadLoop","detail":"{readStateIndex:11662; appliedIndex:11660; }","duration":"112.808478ms","start":"2026-04-22T18:48:34.085975Z","end":"2026-04-22T18:48:34.198783Z","steps":["trace[956679974] 'read index received' (duration: 35.150537ms)","trace[956679974] 'applied index is now lower than readState.Index' (duration: 77.656761ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:48:34.198831Z","caller":"traceutil/trace.go:171","msg":"trace[23986804] transaction","detail":"{read_only:false; response_revision:11283; number_of_response:1; }","duration":"120.248625ms","start":"2026-04-22T18:48:34.078529Z","end":"2026-04-22T18:48:34.198777Z","steps":["trace[23986804] 'process raft request' (duration: 119.909418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:48:34.199134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.099803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T18:48:34.199407Z","caller":"traceutil/trace.go:171","msg":"trace[230675995] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:11283; }","duration":"113.402408ms","start":"2026-04-22T18:48:34.08596Z","end":"2026-04-22T18:48:34.199362Z","steps":["trace[230675995] 'agreement among raft nodes before linearized reading' (duration: 112.995811ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:52:37.869901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10777} {"level":"info","ts":"2026-04-22T18:52:37.951149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10777,"took":"78.443665ms","hash":433980055,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":26619904,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-22T18:52:37.95126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":433980055,"revision":10777,"compact-revision":8300} {"level":"info","ts":"2026-04-22T18:55:02.886013Z","caller":"traceutil/trace.go:171","msg":"trace[906679611] transaction","detail":"{read_only:false; response_revision:13896; number_of_response:1; }","duration":"204.241764ms","start":"2026-04-22T18:55:02.681743Z","end":"2026-04-22T18:55:02.885985Z","steps":["trace[906679611] 'process raft request' (duration: 204.055301ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:55:10.087829Z","caller":"traceutil/trace.go:171","msg":"trace[587367536] transaction","detail":"{read_only:false; response_revision:13932; number_of_response:1; }","duration":"231.047974ms","start":"2026-04-22T18:55:09.856749Z","end":"2026-04-22T18:55:10.087797Z","steps":["trace[587367536] 'process raft request' (duration: 230.82428ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:55:10.089989Z","caller":"traceutil/trace.go:171","msg":"trace[896356616] transaction","detail":"{read_only:false; response_revision:13933; number_of_response:1; }","duration":"232.428ms","start":"2026-04-22T18:55:09.857528Z","end":"2026-04-22T18:55:10.089956Z","steps":["trace[896356616] 'process raft request' (duration: 232.215046ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:55:10.530826Z","caller":"traceutil/trace.go:171","msg":"trace[2134219788] transaction","detail":"{read_only:false; response_revision:13934; number_of_response:1; }","duration":"435.41491ms","start":"2026-04-22T18:55:10.095315Z","end":"2026-04-22T18:55:10.53073Z","steps":["trace[2134219788] 'process raft request' (duration: 435.227597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:55:10.530987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:55:10.095288Z","time spent":"435.621014ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T18:55:10.584059Z","caller":"traceutil/trace.go:171","msg":"trace[249970207] linearizableReadLoop","detail":"{readStateIndex:14408; appliedIndex:14407; }","duration":"474.251336ms","start":"2026-04-22T18:55:10.109784Z","end":"2026-04-22T18:55:10.584036Z","steps":["trace[249970207] 'read index received' (duration: 420.951711ms)","trace[249970207] 'applied index is now lower than readState.Index' (duration: 53.298895ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:55:10.584455Z","caller":"traceutil/trace.go:171","msg":"trace[331749840] transaction","detail":"{read_only:false; response_revision:13935; number_of_response:1; }","duration":"488.679105ms","start":"2026-04-22T18:55:10.095768Z","end":"2026-04-22T18:55:10.584447Z","steps":["trace[331749840] 'process raft request' (duration: 482.444829ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:55:10.584507Z","caller":"traceutil/trace.go:171","msg":"trace[1187620462] transaction","detail":"{read_only:false; response_revision:13937; number_of_response:1; }","duration":"387.806022ms","start":"2026-04-22T18:55:10.196667Z","end":"2026-04-22T18:55:10.584473Z","steps":["trace[1187620462] 'process raft request' (duration: 387.307842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:55:10.584669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:55:10.196637Z","time spent":"387.947654ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T18:55:10.584719Z","caller":"traceutil/trace.go:171","msg":"trace[209637330] transaction","detail":"{read_only:false; response_revision:13938; number_of_response:1; }","duration":"193.067635ms","start":"2026-04-22T18:55:10.391644Z","end":"2026-04-22T18:55:10.584711Z","steps":["trace[209637330] 'process raft request' (duration: 192.356901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:55:10.584743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.75077ms","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-04-22T18:55:10.58482Z","caller":"traceutil/trace.go:171","msg":"trace[2351497] transaction","detail":"{read_only:false; response_revision:13936; number_of_response:1; }","duration":"388.73566ms","start":"2026-04-22T18:55:10.196075Z","end":"2026-04-22T18:55:10.584811Z","steps":["trace[2351497] 'process raft request' (duration: 387.863873ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:55:10.584834Z","caller":"traceutil/trace.go:171","msg":"trace[1894705902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13938; }","duration":"207.898243ms","start":"2026-04-22T18:55:10.37691Z","end":"2026-04-22T18:55:10.584808Z","steps":["trace[1894705902] 'agreement among raft nodes before linearized reading' (duration: 207.646388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:55:10.584862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:55:10.196047Z","time spent":"388.79041ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T18:55:10.584549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:55:10.095742Z","time spent":"488.740717ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T18:55:10.584791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.99653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-04-22T18:55:10.585041Z","caller":"traceutil/trace.go:171","msg":"trace[1795661092] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:13938; }","duration":"475.297565ms","start":"2026-04-22T18:55:10.109719Z","end":"2026-04-22T18:55:10.585016Z","steps":["trace[1795661092] 'agreement among raft nodes before linearized reading' (duration: 474.731584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:55:10.585145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:55:10.109685Z","time spent":"475.440618ms","remote":"127.0.0.1:59588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"info","ts":"2026-04-22T18:57:37.876612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12854} {"level":"info","ts":"2026-04-22T18:57:37.957912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12854,"took":"78.057232ms","hash":3025502206,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":30167040,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-22T18:57:37.957978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3025502206,"revision":12854,"compact-revision":10777} {"level":"info","ts":"2026-04-22T19:02:33.350721Z","caller":"traceutil/trace.go:171","msg":"trace[1662850700] transaction","detail":"{read_only:false; response_revision:17606; number_of_response:1; }","duration":"195.022054ms","start":"2026-04-22T19:02:33.155656Z","end":"2026-04-22T19:02:33.350678Z","steps":["trace[1662850700] 'process raft request' (duration: 161.466775ms)","trace[1662850700] 'compare' (duration: 33.341885ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:02:33.775451Z","caller":"traceutil/trace.go:171","msg":"trace[1913853943] linearizableReadLoop","detail":"{readStateIndex:18201; appliedIndex:18200; }","duration":"188.98113ms","start":"2026-04-22T19:02:33.586431Z","end":"2026-04-22T19:02:33.775412Z","steps":["trace[1913853943] 'read index received' (duration: 188.655254ms)","trace[1913853943] 'applied index is now lower than readState.Index' (duration: 324.726µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:02:33.775522Z","caller":"traceutil/trace.go:171","msg":"trace[1383283830] transaction","detail":"{read_only:false; response_revision:17607; number_of_response:1; }","duration":"194.018974ms","start":"2026-04-22T19:02:33.581478Z","end":"2026-04-22T19:02:33.775497Z","steps":["trace[1383283830] 'process raft request' (duration: 193.701308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:02:33.775676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.210826ms","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-04-22T19:02:33.775723Z","caller":"traceutil/trace.go:171","msg":"trace[1956994157] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17607; }","duration":"189.337178ms","start":"2026-04-22T19:02:33.586374Z","end":"2026-04-22T19:02:33.775711Z","steps":["trace[1956994157] 'agreement among raft nodes before linearized reading' (duration: 189.194525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:02:33.77601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.526677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-65lmn\" ","response":"range_response_count:1 size:20869"} {"level":"info","ts":"2026-04-22T19:02:33.776122Z","caller":"traceutil/trace.go:171","msg":"trace[1785446168] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-65lmn; range_end:; response_count:1; response_revision:17607; }","duration":"176.68892ms","start":"2026-04-22T19:02:33.599403Z","end":"2026-04-22T19:02:33.776092Z","steps":["trace[1785446168] 'agreement among raft nodes before linearized reading' (duration: 176.349464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:02:34.341412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":739889645727988004,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T19:02:34.368045Z","caller":"traceutil/trace.go:171","msg":"trace[350824969] transaction","detail":"{read_only:false; response_revision:17608; number_of_response:1; }","duration":"573.029526ms","start":"2026-04-22T19:02:33.794986Z","end":"2026-04-22T19:02:34.368015Z","steps":["trace[350824969] 'process raft request' (duration: 572.795231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:02:34.368223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:02:33.794945Z","time spent":"573.166028ms","remote":"127.0.0.1:59620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20984,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:02:34.378025Z","caller":"traceutil/trace.go:171","msg":"trace[1934844833] linearizableReadLoop","detail":"{readStateIndex:18203; appliedIndex:18201; }","duration":"537.427408ms","start":"2026-04-22T19:02:33.840583Z","end":"2026-04-22T19:02:34.37801Z","steps":["trace[1934844833] 'read index received' (duration: 527.156855ms)","trace[1934844833] 'applied index is now lower than readState.Index' (duration: 10.269673ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:02:34.378251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.672152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T19:02:34.378284Z","caller":"traceutil/trace.go:171","msg":"trace[1455816383] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17609; }","duration":"537.761554ms","start":"2026-04-22T19:02:33.840514Z","end":"2026-04-22T19:02:34.378275Z","steps":["trace[1455816383] 'agreement among raft nodes before linearized reading' (duration: 537.55391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:02:34.378309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:02:33.840486Z","time spent":"537.817975ms","remote":"127.0.0.1:59620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-22T19:02:37.883592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15316} {"level":"info","ts":"2026-04-22T19:02:37.985413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15316,"took":"97.899255ms","hash":55970737,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31813632,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-22T19:02:37.985501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":55970737,"revision":15316,"compact-revision":12854} {"level":"info","ts":"2026-04-22T19:05:59.824339Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6a0c678c4e560a44","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T19:05:59.829785Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-22T19:05:59.830109Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-22T19:07:37.89096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17644} {"level":"info","ts":"2026-04-22T19:07:37.998026Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17644,"took":"103.555053ms","hash":887655178,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":34508800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-22T19:07:37.998104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":887655178,"revision":17644,"compact-revision":15316} {"level":"info","ts":"2026-04-22T19:08:14.018134Z","caller":"traceutil/trace.go:171","msg":"trace[1716989399] transaction","detail":"{read_only:false; response_revision:20300; number_of_response:1; }","duration":"120.490901ms","start":"2026-04-22T19:08:13.897602Z","end":"2026-04-22T19:08:14.018093Z","steps":["trace[1716989399] 'process raft request' (duration: 120.262277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:08:19.54462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.521276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-22T19:08:19.544678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.445409ms","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-04-22T19:08:19.544826Z","caller":"traceutil/trace.go:171","msg":"trace[1581488997] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:20328; }","duration":"254.764931ms","start":"2026-04-22T19:08:19.290026Z","end":"2026-04-22T19:08:19.54479Z","steps":["trace[1581488997] 'count revisions from in-memory index tree' (duration: 254.358823ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:08:19.544851Z","caller":"traceutil/trace.go:171","msg":"trace[1900735791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20328; }","duration":"243.712403ms","start":"2026-04-22T19:08:19.301102Z","end":"2026-04-22T19:08:19.544814Z","steps":["trace[1900735791] 'range keys from in-memory index tree' (duration: 243.152934ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:08:19.696426Z","caller":"traceutil/trace.go:171","msg":"trace[273943198] transaction","detail":"{read_only:false; response_revision:20329; number_of_response:1; }","duration":"146.57429ms","start":"2026-04-22T19:08:19.549798Z","end":"2026-04-22T19:08:19.696373Z","steps":["trace[273943198] 'process raft request' (duration: 146.264525ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:08:19.837863Z","caller":"traceutil/trace.go:171","msg":"trace[2114932766] transaction","detail":"{read_only:false; response_revision:20330; number_of_response:1; }","duration":"135.777248ms","start":"2026-04-22T19:08:19.702035Z","end":"2026-04-22T19:08:19.837813Z","steps":["trace[2114932766] 'process raft request' (duration: 124.952686ms)","trace[2114932766] 'compare' (duration: 10.592758ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:08:20.187287Z","caller":"traceutil/trace.go:171","msg":"trace[1657936622] transaction","detail":"{read_only:false; response_revision:20331; number_of_response:1; }","duration":"147.746304ms","start":"2026-04-22T19:08:20.039511Z","end":"2026-04-22T19:08:20.187258Z","steps":["trace[1657936622] 'process raft request' (duration: 147.53147ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:08:20.297162Z","caller":"traceutil/trace.go:171","msg":"trace[585728599] transaction","detail":"{read_only:false; response_revision:20333; number_of_response:1; }","duration":"231.892171ms","start":"2026-04-22T19:08:20.065241Z","end":"2026-04-22T19:08:20.297133Z","steps":["trace[585728599] 'process raft request' (duration: 231.810699ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:08:20.297609Z","caller":"traceutil/trace.go:171","msg":"trace[894159896] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"257.966342ms","start":"2026-04-22T19:08:20.039556Z","end":"2026-04-22T19:08:20.297522Z","steps":["trace[894159896] 'process raft request' (duration: 218.9695ms)","trace[894159896] 'compare' (duration: 38.333169ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:12:10.333875Z","caller":"traceutil/trace.go:171","msg":"trace[1269188111] transaction","detail":"{read_only:false; response_revision:22723; number_of_response:1; }","duration":"353.879031ms","start":"2026-04-22T19:12:09.979949Z","end":"2026-04-22T19:12:10.333828Z","steps":["trace[1269188111] 'process raft request' (duration: 353.562235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:12:10.334179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:12:09.97993Z","time spent":"354.098535ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:12:10.339207Z","caller":"traceutil/trace.go:171","msg":"trace[1731241539] transaction","detail":"{read_only:false; response_revision:22724; number_of_response:1; }","duration":"331.42429ms","start":"2026-04-22T19:12:10.007746Z","end":"2026-04-22T19:12:10.339171Z","steps":["trace[1731241539] 'process raft request' (duration: 331.122683ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:12:10.339357Z","caller":"traceutil/trace.go:171","msg":"trace[2093564175] linearizableReadLoop","detail":"{readStateIndex:23480; appliedIndex:23478; }","duration":"274.333508ms","start":"2026-04-22T19:12:10.064983Z","end":"2026-04-22T19:12:10.339316Z","steps":["trace[2093564175] 'read index received' (duration: 268.523939ms)","trace[2093564175] 'applied index is now lower than readState.Index' (duration: 5.806279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:12:10.339646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:12:10.007717Z","time spent":"331.604424ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:12:10.339843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.789076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-22T19:12:10.340063Z","caller":"traceutil/trace.go:171","msg":"trace[1264765572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22725; }","duration":"275.108462ms","start":"2026-04-22T19:12:10.064926Z","end":"2026-04-22T19:12:10.340035Z","steps":["trace[1264765572] 'agreement among raft nodes before linearized reading' (duration: 274.539982ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:12:10.495982Z","caller":"traceutil/trace.go:171","msg":"trace[1283059668] transaction","detail":"{read_only:false; response_revision:22726; number_of_response:1; }","duration":"149.113379ms","start":"2026-04-22T19:12:10.346829Z","end":"2026-04-22T19:12:10.495942Z","steps":["trace[1283059668] 'process raft request' (duration: 60.858861ms)","trace[1283059668] 'compare' (duration: 88.079533ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:12:10.495951Z","caller":"traceutil/trace.go:171","msg":"trace[22617416] linearizableReadLoop","detail":"{readStateIndex:23482; appliedIndex:23481; }","duration":"135.680206ms","start":"2026-04-22T19:12:10.360241Z","end":"2026-04-22T19:12:10.495921Z","steps":["trace[22617416] 'read index received' (duration: 47.371818ms)","trace[22617416] 'applied index is now lower than readState.Index' (duration: 88.306948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:12:10.496184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.91671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-22T19:12:10.496255Z","caller":"traceutil/trace.go:171","msg":"trace[585341107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22726; }","duration":"136.042993ms","start":"2026-04-22T19:12:10.360201Z","end":"2026-04-22T19:12:10.496244Z","steps":["trace[585341107] 'agreement among raft nodes before linearized reading' (duration: 135.839979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:12:10.49813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.656067ms","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-04-22T19:12:10.498177Z","caller":"traceutil/trace.go:171","msg":"trace[1825304673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22727; }","duration":"126.789929ms","start":"2026-04-22T19:12:10.371375Z","end":"2026-04-22T19:12:10.498165Z","steps":["trace[1825304673] 'agreement among raft nodes before linearized reading' (duration: 126.625186ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:12:37.900645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20120} {"level":"info","ts":"2026-04-22T19:12:38.025786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20120,"took":"120.651389ms","hash":123992668,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":40927232,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-22T19:12:38.025865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123992668,"revision":20120,"compact-revision":17644} {"level":"info","ts":"2026-04-22T19:15:25.774744Z","caller":"traceutil/trace.go:171","msg":"trace[1915640689] linearizableReadLoop","detail":"{readStateIndex:25560; appliedIndex:25559; }","duration":"177.357391ms","start":"2026-04-22T19:15:25.597351Z","end":"2026-04-22T19:15:25.774708Z","steps":["trace[1915640689] 'read index received' (duration: 177.102506ms)","trace[1915640689] 'applied index is now lower than readState.Index' (duration: 253.805µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:25.774899Z","caller":"traceutil/trace.go:171","msg":"trace[1970336420] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"198.243744ms","start":"2026-04-22T19:15:25.576641Z","end":"2026-04-22T19:15:25.774884Z","steps":["trace[1970336420] 'process raft request' (duration: 197.871597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:25.775107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.71218ms","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-04-22T19:15:25.77517Z","caller":"traceutil/trace.go:171","msg":"trace[310467580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24753; }","duration":"174.811101ms","start":"2026-04-22T19:15:25.600343Z","end":"2026-04-22T19:15:25.775155Z","steps":["trace[310467580] 'agreement among raft nodes before linearized reading' (duration: 174.565267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:25.775359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.687475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-04-22T19:15:25.775406Z","caller":"traceutil/trace.go:171","msg":"trace[2002547969] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24753; }","duration":"125.769637ms","start":"2026-04-22T19:15:25.649626Z","end":"2026-04-22T19:15:25.775395Z","steps":["trace[2002547969] 'agreement among raft nodes before linearized reading' (duration: 125.638863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:25.775406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.885854ms","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-04-22T19:15:25.775454Z","caller":"traceutil/trace.go:171","msg":"trace[1459112674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24753; }","duration":"174.942705ms","start":"2026-04-22T19:15:25.600497Z","end":"2026-04-22T19:15:25.77544Z","steps":["trace[1459112674] 'agreement among raft nodes before linearized reading' (duration: 174.837873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:25.775654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.310699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-22T19:15:25.775694Z","caller":"traceutil/trace.go:171","msg":"trace[29255110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24753; }","duration":"178.400201ms","start":"2026-04-22T19:15:25.597284Z","end":"2026-04-22T19:15:25.775684Z","steps":["trace[29255110] 'agreement among raft nodes before linearized reading' (duration: 178.292239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.17807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.077968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:15:26.178166Z","caller":"traceutil/trace.go:171","msg":"trace[1884391948] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"398.63535ms","start":"2026-04-22T19:15:25.779516Z","end":"2026-04-22T19:15:26.178152Z","steps":["trace[1884391948] 'process raft request' (duration: 215.331427ms)","trace[1884391948] 'compare' (duration: 182.942206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:26.178205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:25.779491Z","time spent":"398.699391ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:26.24608Z","caller":"traceutil/trace.go:171","msg":"trace[1006143618] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"460.219555ms","start":"2026-04-22T19:15:25.785835Z","end":"2026-04-22T19:15:26.246055Z","steps":["trace[1006143618] 'process raft request' (duration: 460.123353ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:26.246137Z","caller":"traceutil/trace.go:171","msg":"trace[393760986] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"466.550043ms","start":"2026-04-22T19:15:25.779517Z","end":"2026-04-22T19:15:26.246067Z","steps":["trace[393760986] 'process raft request' (duration: 466.318969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.246249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:25.785829Z","time spent":"460.315436ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:26.246396Z","caller":"traceutil/trace.go:171","msg":"trace[704750075] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"391.659219ms","start":"2026-04-22T19:15:25.854729Z","end":"2026-04-22T19:15:26.246388Z","steps":["trace[704750075] 'process raft request' (duration: 391.257152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.246407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:25.779491Z","time spent":"466.757947ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:26.246441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:25.854702Z","time spent":"391.71505ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:26.246401Z","caller":"traceutil/trace.go:171","msg":"trace[531448309] linearizableReadLoop","detail":"{readStateIndex:25562; appliedIndex:25560; }","duration":"461.495798ms","start":"2026-04-22T19:15:25.784857Z","end":"2026-04-22T19:15:26.246352Z","steps":["trace[531448309] 'read index received' (duration: 209.966906ms)","trace[531448309] 'applied index is now lower than readState.Index' (duration: 251.524512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:26.246742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.865685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-04-22T19:15:26.246823Z","caller":"traceutil/trace.go:171","msg":"trace[444215409] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24757; }","duration":"461.965907ms","start":"2026-04-22T19:15:25.784836Z","end":"2026-04-22T19:15:26.246802Z","steps":["trace[444215409] 'agreement among raft nodes before linearized reading' (duration: 461.656142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.246871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:25.784828Z","time spent":"462.031448ms","remote":"127.0.0.1:59868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-04-22T19:15:26.276248Z","caller":"traceutil/trace.go:171","msg":"trace[192367353] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"258.180797ms","start":"2026-04-22T19:15:26.01803Z","end":"2026-04-22T19:15:26.276211Z","steps":["trace[192367353] 'process raft request' (duration: 257.889482ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:26.276255Z","caller":"traceutil/trace.go:171","msg":"trace[622872422] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"255.670299ms","start":"2026-04-22T19:15:26.020553Z","end":"2026-04-22T19:15:26.276223Z","steps":["trace[622872422] 'process raft request' (duration: 255.577078ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:26.276517Z","caller":"traceutil/trace.go:171","msg":"trace[236713675] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"215.588013ms","start":"2026-04-22T19:15:26.060878Z","end":"2026-04-22T19:15:26.276466Z","steps":["trace[236713675] 'process raft request' (duration: 215.297657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.276541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.052216ms","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-04-22T19:15:26.276757Z","caller":"traceutil/trace.go:171","msg":"trace[198657503] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24760; }","duration":"229.297392ms","start":"2026-04-22T19:15:26.047413Z","end":"2026-04-22T19:15:26.27671Z","steps":["trace[198657503] 'agreement among raft nodes before linearized reading' (duration: 229.002346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.276551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.344552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T19:15:26.277348Z","caller":"traceutil/trace.go:171","msg":"trace[464262897] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:24760; }","duration":"176.80282ms","start":"2026-04-22T19:15:26.100138Z","end":"2026-04-22T19:15:26.276941Z","steps":["trace[464262897] 'agreement among raft nodes before linearized reading' (duration: 176.30416ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:26.667482Z","caller":"traceutil/trace.go:171","msg":"trace[1450380603] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"358.665195ms","start":"2026-04-22T19:15:26.308753Z","end":"2026-04-22T19:15:26.667418Z","steps":["trace[1450380603] 'process raft request' (duration: 260.551622ms)","trace[1450380603] 'compare' (duration: 97.804947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:26.667722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:26.308721Z","time spent":"358.86522ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:26.737941Z","caller":"traceutil/trace.go:171","msg":"trace[1263046005] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"427.506956ms","start":"2026-04-22T19:15:26.310395Z","end":"2026-04-22T19:15:26.737902Z","steps":["trace[1263046005] 'process raft request' (duration: 427.18125ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:26.738057Z","caller":"traceutil/trace.go:171","msg":"trace[153376496] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"427.091608ms","start":"2026-04-22T19:15:26.310917Z","end":"2026-04-22T19:15:26.738009Z","steps":["trace[153376496] 'process raft request' (duration: 426.887665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.738155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:26.310366Z","time spent":"427.673759ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:26.738314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:26.310885Z","time spent":"427.283422ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:26.821746Z","caller":"traceutil/trace.go:171","msg":"trace[1086061494] linearizableReadLoop","detail":"{readStateIndex:25570; appliedIndex:25567; }","duration":"504.417219ms","start":"2026-04-22T19:15:26.31729Z","end":"2026-04-22T19:15:26.821707Z","steps":["trace[1086061494] 'read index received' (duration: 252.087712ms)","trace[1086061494] 'applied index is now lower than readState.Index' (duration: 252.327967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:26.822352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.03863ms","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-04-22T19:15:26.822462Z","caller":"traceutil/trace.go:171","msg":"trace[64640637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24763; }","duration":"505.196473ms","start":"2026-04-22T19:15:26.317242Z","end":"2026-04-22T19:15:26.822438Z","steps":["trace[64640637] 'agreement among raft nodes before linearized reading' (duration: 504.930878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.822515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:26.317222Z","time spent":"505.283814ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T19:15:26.822926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.53937ms","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-04-22T19:15:26.822972Z","caller":"traceutil/trace.go:171","msg":"trace[905261165] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:24763; }","duration":"388.634862ms","start":"2026-04-22T19:15:26.434327Z","end":"2026-04-22T19:15:26.822962Z","steps":["trace[905261165] 'agreement among raft nodes before linearized reading' (duration: 388.559731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.823003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:26.434306Z","time spent":"388.689363ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:15:26.823169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.696392ms","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-04-22T19:15:26.82327Z","caller":"traceutil/trace.go:171","msg":"trace[588010999] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24763; }","duration":"424.861206ms","start":"2026-04-22T19:15:26.398382Z","end":"2026-04-22T19:15:26.823243Z","steps":["trace[588010999] 'agreement among raft nodes before linearized reading' (duration: 424.462158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:26.823345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:26.398361Z","time spent":"424.970208ms","remote":"127.0.0.1:59670","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-04-22T19:15:27.116558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.676618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:15:27.116743Z","caller":"traceutil/trace.go:171","msg":"trace[1955725929] linearizableReadLoop","detail":"{readStateIndex:25573; appliedIndex:25571; }","duration":"278.302048ms","start":"2026-04-22T19:15:26.838414Z","end":"2026-04-22T19:15:27.116716Z","steps":["trace[1955725929] 'read index received' (duration: 40.877052ms)","trace[1955725929] 'applied index is now lower than readState.Index' (duration: 237.422886ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:27.116877Z","caller":"traceutil/trace.go:171","msg":"trace[575706354] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"287.061563ms","start":"2026-04-22T19:15:26.829797Z","end":"2026-04-22T19:15:27.116859Z","steps":["trace[575706354] 'process raft request' (duration: 137.969806ms)","trace[575706354] 'compare' (duration: 148.480755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:27.117004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.69009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T19:15:27.117096Z","caller":"traceutil/trace.go:171","msg":"trace[1659667886] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24765; }","duration":"183.872043ms","start":"2026-04-22T19:15:26.933203Z","end":"2026-04-22T19:15:27.117075Z","steps":["trace[1659667886] 'agreement among raft nodes before linearized reading' (duration: 183.69497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:27.117376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.754104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T19:15:27.117431Z","caller":"traceutil/trace.go:171","msg":"trace[192224307] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24765; }","duration":"145.864336ms","start":"2026-04-22T19:15:26.971544Z","end":"2026-04-22T19:15:27.117408Z","steps":["trace[192224307] 'agreement among raft nodes before linearized reading' (duration: 145.764274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:27.117542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.097102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:27.117626Z","caller":"traceutil/trace.go:171","msg":"trace[1007211205] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24765; }","duration":"279.245655ms","start":"2026-04-22T19:15:26.838359Z","end":"2026-04-22T19:15:27.117604Z","steps":["trace[1007211205] 'agreement among raft nodes before linearized reading' (duration: 279.099993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:27.417134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.218143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:15:27.417244Z","caller":"traceutil/trace.go:171","msg":"trace[220195148] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"266.909532ms","start":"2026-04-22T19:15:27.150316Z","end":"2026-04-22T19:15:27.417225Z","steps":["trace[220195148] 'process raft request' (duration: 148.511445ms)","trace[220195148] 'compare' (duration: 118.064491ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:27.49418Z","caller":"traceutil/trace.go:171","msg":"trace[766601549] linearizableReadLoop","detail":"{readStateIndex:25576; appliedIndex:25574; }","duration":"194.24423ms","start":"2026-04-22T19:15:27.299914Z","end":"2026-04-22T19:15:27.494158Z","steps":["trace[766601549] 'read index received' (duration: 194.012506ms)","trace[766601549] 'applied index is now lower than readState.Index' (duration: 230.944µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:27.494255Z","caller":"traceutil/trace.go:171","msg":"trace[1984290396] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"316.967837ms","start":"2026-04-22T19:15:27.177261Z","end":"2026-04-22T19:15:27.494228Z","steps":["trace[1984290396] 'process raft request' (duration: 316.721253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:27.49437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.447064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T19:15:27.494399Z","caller":"traceutil/trace.go:171","msg":"trace[1472524533] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24768; }","duration":"194.532345ms","start":"2026-04-22T19:15:27.29986Z","end":"2026-04-22T19:15:27.494393Z","steps":["trace[1472524533] 'agreement among raft nodes before linearized reading' (duration: 194.381743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:27.494397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:27.177224Z","time spent":"317.08414ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:27.494586Z","caller":"traceutil/trace.go:171","msg":"trace[2123877202] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"270.068511ms","start":"2026-04-22T19:15:27.224439Z","end":"2026-04-22T19:15:27.494508Z","steps":["trace[2123877202] 'process raft request' (duration: 269.683894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:27.687105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.2359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-22T19:15:27.687149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.728398ms","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-04-22T19:15:27.687257Z","caller":"traceutil/trace.go:171","msg":"trace[2096366385] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24768; }","duration":"122.888092ms","start":"2026-04-22T19:15:27.564346Z","end":"2026-04-22T19:15:27.687234Z","steps":["trace[2096366385] 'range keys from in-memory index tree' (duration: 122.649727ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:27.687915Z","caller":"traceutil/trace.go:171","msg":"trace[1368556175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24768; }","duration":"149.073406ms","start":"2026-04-22T19:15:27.538784Z","end":"2026-04-22T19:15:27.687858Z","steps":["trace[1368556175] 'range keys from in-memory index tree' (duration: 148.140938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:28.474394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.579698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:15:28.47453Z","caller":"traceutil/trace.go:171","msg":"trace[1211652594] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"219.494195ms","start":"2026-04-22T19:15:28.255011Z","end":"2026-04-22T19:15:28.474506Z","steps":["trace[1211652594] 'process raft request' (duration: 117.698953ms)","trace[1211652594] 'compare' (duration: 101.13442ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:28.487483Z","caller":"traceutil/trace.go:171","msg":"trace[2049234547] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"204.426082ms","start":"2026-04-22T19:15:28.283033Z","end":"2026-04-22T19:15:28.487459Z","steps":["trace[2049234547] 'process raft request' (duration: 204.266798ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:28.487428Z","caller":"traceutil/trace.go:171","msg":"trace[306830868] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"231.728688ms","start":"2026-04-22T19:15:28.255636Z","end":"2026-04-22T19:15:28.487365Z","steps":["trace[306830868] 'process raft request' (duration: 231.487793ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:28.487598Z","caller":"traceutil/trace.go:171","msg":"trace[547875765] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"230.111578ms","start":"2026-04-22T19:15:28.25744Z","end":"2026-04-22T19:15:28.487552Z","steps":["trace[547875765] 'process raft request' (duration: 229.798321ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:28.487484Z","caller":"traceutil/trace.go:171","msg":"trace[1277303201] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"205.282859ms","start":"2026-04-22T19:15:28.282162Z","end":"2026-04-22T19:15:28.487445Z","steps":["trace[1277303201] 'process raft request' (duration: 205.111705ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:28.487397Z","caller":"traceutil/trace.go:171","msg":"trace[171936517] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"204.065995ms","start":"2026-04-22T19:15:28.283304Z","end":"2026-04-22T19:15:28.48737Z","steps":["trace[171936517] 'process raft request' (duration: 204.029295ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:28.893072Z","caller":"traceutil/trace.go:171","msg":"trace[416850534] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"149.157397ms","start":"2026-04-22T19:15:28.743885Z","end":"2026-04-22T19:15:28.893043Z","steps":["trace[416850534] 'process raft request' (duration: 98.947589ms)","trace[416850534] 'compare' (duration: 49.551976ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:29.180621Z","caller":"traceutil/trace.go:171","msg":"trace[870640150] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"282.086539ms","start":"2026-04-22T19:15:28.898508Z","end":"2026-04-22T19:15:29.180594Z","steps":["trace[870640150] 'process raft request' (duration: 281.717422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:29.417463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.918267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:15:29.417568Z","caller":"traceutil/trace.go:171","msg":"trace[1910957967] linearizableReadLoop","detail":"{readStateIndex:25588; appliedIndex:25587; }","duration":"224.408679ms","start":"2026-04-22T19:15:29.193145Z","end":"2026-04-22T19:15:29.417554Z","steps":["trace[1910957967] 'read index received' (duration: 87.224187ms)","trace[1910957967] 'applied index is now lower than readState.Index' (duration: 137.183452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:29.417626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.479471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:29.417618Z","caller":"traceutil/trace.go:171","msg":"trace[1976953212] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"231.86404ms","start":"2026-04-22T19:15:29.185719Z","end":"2026-04-22T19:15:29.417583Z","steps":["trace[1976953212] 'process raft request' (duration: 94.75064ms)","trace[1976953212] 'compare' (duration: 136.786784ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:29.417649Z","caller":"traceutil/trace.go:171","msg":"trace[1302500038] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24780; }","duration":"224.546141ms","start":"2026-04-22T19:15:29.193094Z","end":"2026-04-22T19:15:29.41764Z","steps":["trace[1302500038] 'agreement among raft nodes before linearized reading' (duration: 224.49653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:29.729133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.155694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:29.729243Z","caller":"traceutil/trace.go:171","msg":"trace[2110024809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24781; }","duration":"188.331387ms","start":"2026-04-22T19:15:29.540883Z","end":"2026-04-22T19:15:29.729214Z","steps":["trace[2110024809] 'range keys from in-memory index tree' (duration: 188.051932ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:41.097388Z","caller":"traceutil/trace.go:171","msg":"trace[1234873076] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"144.784146ms","start":"2026-04-22T19:15:40.952568Z","end":"2026-04-22T19:15:41.097353Z","steps":["trace[1234873076] 'process raft request' (duration: 144.264225ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:43.848848Z","caller":"traceutil/trace.go:171","msg":"trace[1954342442] linearizableReadLoop","detail":"{readStateIndex:25692; appliedIndex:25691; }","duration":"344.098451ms","start":"2026-04-22T19:15:43.504721Z","end":"2026-04-22T19:15:43.84882Z","steps":["trace[1954342442] 'read index received' (duration: 343.704674ms)","trace[1954342442] 'applied index is now lower than readState.Index' (duration: 392.547µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:43.849177Z","caller":"traceutil/trace.go:171","msg":"trace[600473714] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"590.056236ms","start":"2026-04-22T19:15:43.259107Z","end":"2026-04-22T19:15:43.849163Z","steps":["trace[600473714] 'process raft request' (duration: 589.342413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:43.849282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:43.25909Z","time spent":"590.118188ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:43.849626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.851795ms","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-04-22T19:15:43.849732Z","caller":"traceutil/trace.go:171","msg":"trace[312752655] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24881; }","duration":"345.039579ms","start":"2026-04-22T19:15:43.504665Z","end":"2026-04-22T19:15:43.849705Z","steps":["trace[312752655] 'agreement among raft nodes before linearized reading' (duration: 344.666331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:43.849775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:43.504641Z","time spent":"345.12602ms","remote":"127.0.0.1:59670","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":"info","ts":"2026-04-22T19:15:44.084593Z","caller":"traceutil/trace.go:171","msg":"trace[225079929] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"229.516286ms","start":"2026-04-22T19:15:43.85505Z","end":"2026-04-22T19:15:44.084566Z","steps":["trace[225079929] 'process raft request' (duration: 229.457545ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:44.084927Z","caller":"traceutil/trace.go:171","msg":"trace[712780635] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"545.653239ms","start":"2026-04-22T19:15:43.539266Z","end":"2026-04-22T19:15:44.08492Z","steps":["trace[712780635] 'process raft request' (duration: 543.261863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:44.085029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:43.539237Z","time spent":"545.711409ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:44.085127Z","caller":"traceutil/trace.go:171","msg":"trace[654582323] linearizableReadLoop","detail":"{readStateIndex:25693; appliedIndex:25692; }","duration":"236.195682ms","start":"2026-04-22T19:15:43.848924Z","end":"2026-04-22T19:15:44.085119Z","steps":["trace[654582323] 'read index received' (duration: 233.759316ms)","trace[654582323] 'applied index is now lower than readState.Index' (duration: 2.435566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:44.085286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.412505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:44.085321Z","caller":"traceutil/trace.go:171","msg":"trace[628712429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24883; }","duration":"544.484576ms","start":"2026-04-22T19:15:43.540827Z","end":"2026-04-22T19:15:44.085311Z","steps":["trace[628712429] 'agreement among raft nodes before linearized reading' (duration: 544.418095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:44.085348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:43.540813Z","time spent":"544.529857ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T19:15:44.085525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.290688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:44.085561Z","caller":"traceutil/trace.go:171","msg":"trace[875183542] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24883; }","duration":"152.384949ms","start":"2026-04-22T19:15:43.933167Z","end":"2026-04-22T19:15:44.085552Z","steps":["trace[875183542] 'agreement among raft nodes before linearized reading' (duration: 152.326328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:44.085938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.08446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T19:15:44.085966Z","caller":"traceutil/trace.go:171","msg":"trace[1361531534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24883; }","duration":"127.148632ms","start":"2026-04-22T19:15:43.958811Z","end":"2026-04-22T19:15:44.085959Z","steps":["trace[1361531534] 'agreement among raft nodes before linearized reading' (duration: 127.04658ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:44.305048Z","caller":"traceutil/trace.go:171","msg":"trace[27304483] linearizableReadLoop","detail":"{readStateIndex:25695; appliedIndex:25694; }","duration":"150.28201ms","start":"2026-04-22T19:15:44.154702Z","end":"2026-04-22T19:15:44.304984Z","steps":["trace[27304483] 'read index received' (duration: 105.123816ms)","trace[27304483] 'applied index is now lower than readState.Index' (duration: 45.156424ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:44.305096Z","caller":"traceutil/trace.go:171","msg":"trace[316673430] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"206.96918ms","start":"2026-04-22T19:15:44.098064Z","end":"2026-04-22T19:15:44.305033Z","steps":["trace[316673430] 'process raft request' (duration: 161.772366ms)","trace[316673430] 'compare' (duration: 44.900298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:44.305583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.579595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-04-22T19:15:44.305634Z","caller":"traceutil/trace.go:171","msg":"trace[1376214494] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24884; }","duration":"150.997292ms","start":"2026-04-22T19:15:44.154626Z","end":"2026-04-22T19:15:44.305624Z","steps":["trace[1376214494] 'agreement among raft nodes before linearized reading' (duration: 150.530864ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:44.612503Z","caller":"traceutil/trace.go:171","msg":"trace[1401348089] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"247.541845ms","start":"2026-04-22T19:15:44.364927Z","end":"2026-04-22T19:15:44.612469Z","steps":["trace[1401348089] 'process raft request' (duration: 234.507819ms)","trace[1401348089] 'compare' (duration: 11.855955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:45.188512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.555875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:15:45.188908Z","caller":"traceutil/trace.go:171","msg":"trace[1836114465] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"386.094134ms","start":"2026-04-22T19:15:44.802787Z","end":"2026-04-22T19:15:45.188881Z","steps":["trace[1836114465] 'process raft request' (duration: 386.046953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.189002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.80276Z","time spent":"386.198256ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:45.189141Z","caller":"traceutil/trace.go:171","msg":"trace[1738160511] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"638.554823ms","start":"2026-04-22T19:15:44.550522Z","end":"2026-04-22T19:15:45.189076Z","steps":["trace[1738160511] 'process raft request' (duration: 638.142696ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:45.189562Z","caller":"traceutil/trace.go:171","msg":"trace[1917478496] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"641.334525ms","start":"2026-04-22T19:15:44.548177Z","end":"2026-04-22T19:15:45.189512Z","steps":["trace[1917478496] 'process raft request' (duration: 182.648191ms)","trace[1917478496] 'compare' (duration: 457.396711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:45.18963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.368408ms","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-04-22T19:15:45.189264Z","caller":"traceutil/trace.go:171","msg":"trace[388720914] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"637.946692ms","start":"2026-04-22T19:15:44.551306Z","end":"2026-04-22T19:15:45.189253Z","steps":["trace[388720914] 'process raft request' (duration: 637.423492ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:45.189679Z","caller":"traceutil/trace.go:171","msg":"trace[1835005964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24890; }","duration":"621.45536ms","start":"2026-04-22T19:15:44.568212Z","end":"2026-04-22T19:15:45.189667Z","steps":["trace[1835005964] 'agreement among raft nodes before linearized reading' (duration: 621.272456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.189715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.568184Z","time spent":"621.525082ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T19:15:45.189696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.313314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-22T19:15:45.189939Z","caller":"traceutil/trace.go:171","msg":"trace[2018920147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24890; }","duration":"616.61696ms","start":"2026-04-22T19:15:44.573293Z","end":"2026-04-22T19:15:45.18991Z","steps":["trace[2018920147] 'agreement among raft nodes before linearized reading' (duration: 616.190671ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:45.1893Z","caller":"traceutil/trace.go:171","msg":"trace[237906337] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"625.167519ms","start":"2026-04-22T19:15:44.564125Z","end":"2026-04-22T19:15:45.189293Z","steps":["trace[237906337] 'process raft request' (duration: 624.64593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.189954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.550494Z","time spent":"639.135805ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:45.190005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.573266Z","time spent":"616.723651ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T19:15:45.189353Z","caller":"traceutil/trace.go:171","msg":"trace[1848910425] linearizableReadLoop","detail":"{readStateIndex:25700; appliedIndex:25696; }","duration":"576.905009ms","start":"2026-04-22T19:15:44.612427Z","end":"2026-04-22T19:15:45.189332Z","steps":["trace[1848910425] 'read index received' (duration: 118.322106ms)","trace[1848910425] 'applied index is now lower than readState.Index' (duration: 458.581103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:45.190092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.564083Z","time spent":"625.940815ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:45.189458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.779118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:45.190213Z","caller":"traceutil/trace.go:171","msg":"trace[1584062591] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24890; }","duration":"638.546794ms","start":"2026-04-22T19:15:44.551639Z","end":"2026-04-22T19:15:45.190185Z","steps":["trace[1584062591] 'agreement among raft nodes before linearized reading' (duration: 637.738448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.190272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.551629Z","time spent":"638.625925ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:15:45.189802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.548143Z","time spent":"641.52937ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:45.190632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.551269Z","time spent":"638.485642ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:45.763671Z","caller":"traceutil/trace.go:171","msg":"trace[1845971242] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"921.033379ms","start":"2026-04-22T19:15:44.842605Z","end":"2026-04-22T19:15:45.763638Z","steps":["trace[1845971242] 'process raft request' (duration: 902.166182ms)","trace[1845971242] 'compare' (duration: 17.734496ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:45.763872Z","caller":"traceutil/trace.go:171","msg":"trace[2135881243] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"651.525819ms","start":"2026-04-22T19:15:45.112312Z","end":"2026-04-22T19:15:45.763838Z","steps":["trace[2135881243] 'process raft request' (duration: 651.244803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.763893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:44.842573Z","time spent":"921.225283ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:45.764356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:45.112279Z","time spent":"651.681222ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:45.775335Z","caller":"traceutil/trace.go:171","msg":"trace[648017912] linearizableReadLoop","detail":"{readStateIndex:25705; appliedIndex:25701; }","duration":"375.245009ms","start":"2026-04-22T19:15:45.400062Z","end":"2026-04-22T19:15:45.775307Z","steps":["trace[648017912] 'read index received' (duration: 344.667321ms)","trace[648017912] 'applied index is now lower than readState.Index' (duration: 30.576628ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:45.77549Z","caller":"traceutil/trace.go:171","msg":"trace[1533999617] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"579.207423ms","start":"2026-04-22T19:15:45.196268Z","end":"2026-04-22T19:15:45.775475Z","steps":["trace[1533999617] 'process raft request' (duration: 578.992249ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:45.775469Z","caller":"traceutil/trace.go:171","msg":"trace[537614796] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"579.261354ms","start":"2026-04-22T19:15:45.196113Z","end":"2026-04-22T19:15:45.775374Z","steps":["trace[537614796] 'process raft request' (duration: 578.977729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.77572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:45.196086Z","time spent":"579.498918ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:45.775818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.810684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:45.775905Z","caller":"traceutil/trace.go:171","msg":"trace[1021755557] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24894; }","duration":"308.968426ms","start":"2026-04-22T19:15:45.466915Z","end":"2026-04-22T19:15:45.775883Z","steps":["trace[1021755557] 'agreement among raft nodes before linearized reading' (duration: 308.795993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.775914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.928043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-22T19:15:45.77596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:45.466889Z","time spent":"309.060218ms","remote":"127.0.0.1:35268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:15:45.775801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.32169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:45.775959Z","caller":"traceutil/trace.go:171","msg":"trace[119675397] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24894; }","duration":"194.021814ms","start":"2026-04-22T19:15:45.581925Z","end":"2026-04-22T19:15:45.775947Z","steps":["trace[119675397] 'agreement among raft nodes before linearized reading' (duration: 193.879532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.776003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.378538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:45.776063Z","caller":"traceutil/trace.go:171","msg":"trace[915107810] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24894; }","duration":"159.632546ms","start":"2026-04-22T19:15:45.616408Z","end":"2026-04-22T19:15:45.77604Z","steps":["trace[915107810] 'agreement among raft nodes before linearized reading' (duration: 159.32279ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:45.776131Z","caller":"traceutil/trace.go:171","msg":"trace[344891810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24894; }","duration":"233.557472ms","start":"2026-04-22T19:15:45.542529Z","end":"2026-04-22T19:15:45.776087Z","steps":["trace[344891810] 'agreement among raft nodes before linearized reading' (duration: 233.389619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.776255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:45.196259Z","time spent":"579.260914ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:45.776381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.291289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:45.77643Z","caller":"traceutil/trace.go:171","msg":"trace[519623046] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24894; }","duration":"376.382391ms","start":"2026-04-22T19:15:45.400036Z","end":"2026-04-22T19:15:45.776418Z","steps":["trace[519623046] 'agreement among raft nodes before linearized reading' (duration: 376.264539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:45.776454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:45.39996Z","time spent":"376.487532ms","remote":"127.0.0.1:47596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-04-22T19:15:46.075418Z","caller":"traceutil/trace.go:171","msg":"trace[210824686] linearizableReadLoop","detail":"{readStateIndex:25706; appliedIndex:25705; }","duration":"212.088726ms","start":"2026-04-22T19:15:45.863288Z","end":"2026-04-22T19:15:46.075377Z","steps":["trace[210824686] 'read index received' (duration: 210.068838ms)","trace[210824686] 'applied index is now lower than readState.Index' (duration: 2.017738ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:46.075486Z","caller":"traceutil/trace.go:171","msg":"trace[1326733723] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"285.990022ms","start":"2026-04-22T19:15:45.789459Z","end":"2026-04-22T19:15:46.075449Z","steps":["trace[1326733723] 'process raft request' (duration: 284.046136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:46.075746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.447422ms","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-04-22T19:15:46.075816Z","caller":"traceutil/trace.go:171","msg":"trace[1507431538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24895; }","duration":"212.578665ms","start":"2026-04-22T19:15:45.863215Z","end":"2026-04-22T19:15:46.075794Z","steps":["trace[1507431538] 'agreement among raft nodes before linearized reading' (duration: 212.30214ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:46.441974Z","caller":"traceutil/trace.go:171","msg":"trace[273970241] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"359.876179ms","start":"2026-04-22T19:15:46.082076Z","end":"2026-04-22T19:15:46.441953Z","steps":["trace[273970241] 'process raft request' (duration: 359.537872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:46.442347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.964961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"info","ts":"2026-04-22T19:15:46.442463Z","caller":"traceutil/trace.go:171","msg":"trace[1762667400] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24896; }","duration":"359.128784ms","start":"2026-04-22T19:15:46.083307Z","end":"2026-04-22T19:15:46.442435Z","steps":["trace[1762667400] 'agreement among raft nodes before linearized reading' (duration: 358.755688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:46.442517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.083287Z","time spent":"359.220026ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-22T19:15:46.442512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.082059Z","time spent":"360.242716ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:46.441888Z","caller":"traceutil/trace.go:171","msg":"trace[153760190] linearizableReadLoop","detail":"{readStateIndex:25707; appliedIndex:25706; }","duration":"358.520763ms","start":"2026-04-22T19:15:46.083338Z","end":"2026-04-22T19:15:46.441859Z","steps":["trace[153760190] 'read index received' (duration: 358.323559ms)","trace[153760190] 'applied index is now lower than readState.Index' (duration: 195.954µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:46.725694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.347062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:15:46.72632Z","caller":"traceutil/trace.go:171","msg":"trace[63397567] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"633.949667ms","start":"2026-04-22T19:15:46.092319Z","end":"2026-04-22T19:15:46.726268Z","steps":["trace[63397567] 'process raft request' (duration: 492.950243ms)","trace[63397567] 'compare' (duration: 139.902953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:46.726535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.092303Z","time spent":"634.12777ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:15:46.862929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.13088ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T19:15:46.86318Z","caller":"traceutil/trace.go:171","msg":"trace[548142740] linearizableReadLoop","detail":"{readStateIndex:25709; appliedIndex:25707; }","duration":"420.355321ms","start":"2026-04-22T19:15:46.442806Z","end":"2026-04-22T19:15:46.863161Z","steps":["trace[548142740] 'read index received' (duration: 142.363949ms)","trace[548142740] 'applied index is now lower than readState.Index' (duration: 277.990122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:46.8633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.620038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-22T19:15:46.86336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.474964ms","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-04-22T19:15:46.863389Z","caller":"traceutil/trace.go:171","msg":"trace[506709003] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:24897; }","duration":"437.793941ms","start":"2026-04-22T19:15:46.425571Z","end":"2026-04-22T19:15:46.863365Z","steps":["trace[506709003] 'agreement among raft nodes before linearized reading' (duration: 437.608077ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:46.863401Z","caller":"traceutil/trace.go:171","msg":"trace[851032975] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24897; }","duration":"771.574596ms","start":"2026-04-22T19:15:46.091814Z","end":"2026-04-22T19:15:46.863389Z","steps":["trace[851032975] 'agreement among raft nodes before linearized reading' (duration: 771.387103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:46.86344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.091789Z","time spent":"771.644078ms","remote":"127.0.0.1:59670","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-04-22T19:15:46.863438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.425518Z","time spent":"437.908663ms","remote":"127.0.0.1:59936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:15:46.863555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.008458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T19:15:46.863653Z","caller":"traceutil/trace.go:171","msg":"trace[1422846509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24897; }","duration":"553.163471ms","start":"2026-04-22T19:15:46.310469Z","end":"2026-04-22T19:15:46.863632Z","steps":["trace[1422846509] 'agreement among raft nodes before linearized reading' (duration: 552.853545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:46.863697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.310445Z","time spent":"553.244952ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T19:15:46.863768Z","caller":"traceutil/trace.go:171","msg":"trace[2032903648] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"412.37953ms","start":"2026-04-22T19:15:46.451235Z","end":"2026-04-22T19:15:46.863615Z","steps":["trace[2032903648] 'process raft request' (duration: 411.81466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:46.864057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.451203Z","time spent":"412.686555ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:15:47.042736Z","caller":"traceutil/trace.go:171","msg":"trace[1075815775] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"274.444245ms","start":"2026-04-22T19:15:46.768255Z","end":"2026-04-22T19:15:47.042699Z","steps":["trace[1075815775] 'process raft request' (duration: 274.220251ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:47.042769Z","caller":"traceutil/trace.go:171","msg":"trace[412295750] linearizableReadLoop","detail":"{readStateIndex:25711; appliedIndex:25711; }","duration":"179.402349ms","start":"2026-04-22T19:15:46.863311Z","end":"2026-04-22T19:15:47.042713Z","steps":["trace[412295750] 'read index received' (duration: 179.376808ms)","trace[412295750] 'applied index is now lower than readState.Index' (duration: 21.381µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:15:47.043585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.226312ms","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-04-22T19:15:47.043927Z","caller":"traceutil/trace.go:171","msg":"trace[2072389317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24899; }","duration":"426.527238ms","start":"2026-04-22T19:15:46.617188Z","end":"2026-04-22T19:15:47.043715Z","steps":["trace[2072389317] 'agreement among raft nodes before linearized reading' (duration: 425.658721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:47.044201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:15:46.617157Z","time spent":"427.026167ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T19:15:47.046038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.007499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T19:15:47.046144Z","caller":"traceutil/trace.go:171","msg":"trace[1904272207] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24902; }","duration":"136.139803ms","start":"2026-04-22T19:15:46.90998Z","end":"2026-04-22T19:15:47.04612Z","steps":["trace[1904272207] 'agreement among raft nodes before linearized reading' (duration: 135.879487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:47.046294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.675001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:47.046379Z","caller":"traceutil/trace.go:171","msg":"trace[1877523225] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24902; }","duration":"101.787953ms","start":"2026-04-22T19:15:46.944579Z","end":"2026-04-22T19:15:47.046367Z","steps":["trace[1877523225] 'agreement among raft nodes before linearized reading' (duration: 101.63916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:47.046438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.641204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:15:47.046481Z","caller":"traceutil/trace.go:171","msg":"trace[35441697] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24902; }","duration":"125.729195ms","start":"2026-04-22T19:15:46.920739Z","end":"2026-04-22T19:15:47.046468Z","steps":["trace[35441697] 'agreement among raft nodes before linearized reading' (duration: 125.656804ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:47.046672Z","caller":"traceutil/trace.go:171","msg":"trace[956018107] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"172.449037ms","start":"2026-04-22T19:15:46.874214Z","end":"2026-04-22T19:15:47.046663Z","steps":["trace[956018107] 'process raft request' (duration: 171.56837ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:47.046943Z","caller":"traceutil/trace.go:171","msg":"trace[557342086] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"176.86471ms","start":"2026-04-22T19:15:46.87007Z","end":"2026-04-22T19:15:47.046935Z","steps":["trace[557342086] 'process raft request' (duration: 175.667388ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:47.047048Z","caller":"traceutil/trace.go:171","msg":"trace[801194076] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"178.169247ms","start":"2026-04-22T19:15:46.867953Z","end":"2026-04-22T19:15:47.046123Z","steps":["trace[801194076] 'process raft request' (duration: 177.646746ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:48.924638Z","caller":"traceutil/trace.go:171","msg":"trace[612424086] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"178.9016ms","start":"2026-04-22T19:15:48.745706Z","end":"2026-04-22T19:15:48.924608Z","steps":["trace[612424086] 'process raft request' (duration: 178.701096ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:50.98878Z","caller":"traceutil/trace.go:171","msg":"trace[1129576558] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"165.19245ms","start":"2026-04-22T19:15:50.823545Z","end":"2026-04-22T19:15:50.988738Z","steps":["trace[1129576558] 'process raft request' (duration: 164.685011ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:54.85023Z","caller":"traceutil/trace.go:171","msg":"trace[715455743] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25844; }","duration":"211.449535ms","start":"2026-04-22T19:15:54.638741Z","end":"2026-04-22T19:15:54.850191Z","steps":["trace[715455743] 'read index received' (duration: 211.17849ms)","trace[715455743] 'applied index is now lower than readState.Index' (duration: 269.785µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:15:54.850298Z","caller":"traceutil/trace.go:171","msg":"trace[62830123] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"272.220682ms","start":"2026-04-22T19:15:54.57804Z","end":"2026-04-22T19:15:54.850261Z","steps":["trace[62830123] 'process raft request' (duration: 271.922237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:15:54.850523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.754591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-22T19:15:54.850661Z","caller":"traceutil/trace.go:171","msg":"trace[305698215] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25031; }","duration":"211.979435ms","start":"2026-04-22T19:15:54.638667Z","end":"2026-04-22T19:15:54.850646Z","steps":["trace[305698215] 'agreement among raft nodes before linearized reading' (duration: 211.672618ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:55.023778Z","caller":"traceutil/trace.go:171","msg":"trace[975630633] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"165.593308ms","start":"2026-04-22T19:15:54.858157Z","end":"2026-04-22T19:15:55.02375Z","steps":["trace[975630633] 'process raft request' (duration: 165.474065ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:55.023972Z","caller":"traceutil/trace.go:171","msg":"trace[625091163] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"165.420595ms","start":"2026-04-22T19:15:54.858496Z","end":"2026-04-22T19:15:55.023917Z","steps":["trace[625091163] 'process raft request' (duration: 165.18181ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:55.023809Z","caller":"traceutil/trace.go:171","msg":"trace[1032697268] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"166.179429ms","start":"2026-04-22T19:15:54.857571Z","end":"2026-04-22T19:15:55.023751Z","steps":["trace[1032697268] 'process raft request' (duration: 165.882583ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:15:55.131214Z","caller":"traceutil/trace.go:171","msg":"trace[361683] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"127.517569ms","start":"2026-04-22T19:15:55.003615Z","end":"2026-04-22T19:15:55.131133Z","steps":["trace[361683] 'process raft request' (duration: 124.033033ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:26.143738Z","caller":"traceutil/trace.go:171","msg":"trace[1691433167] linearizableReadLoop","detail":"{readStateIndex:26110; appliedIndex:26109; }","duration":"143.033681ms","start":"2026-04-22T19:16:26.000678Z","end":"2026-04-22T19:16:26.143712Z","steps":["trace[1691433167] 'read index received' (duration: 142.695124ms)","trace[1691433167] 'applied index is now lower than readState.Index' (duration: 337.577µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:26.143872Z","caller":"traceutil/trace.go:171","msg":"trace[642113589] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"217.583511ms","start":"2026-04-22T19:16:25.926235Z","end":"2026-04-22T19:16:26.143818Z","steps":["trace[642113589] 'process raft request' (duration: 217.218715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:26.144039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.311667ms","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":"info","ts":"2026-04-22T19:16:26.144114Z","caller":"traceutil/trace.go:171","msg":"trace[415932003] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25289; }","duration":"143.464819ms","start":"2026-04-22T19:16:26.000628Z","end":"2026-04-22T19:16:26.144093Z","steps":["trace[415932003] 'agreement among raft nodes before linearized reading' (duration: 143.239305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:26.144153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.131353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-04-22T19:16:26.144201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.431442ms","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-04-22T19:16:26.144275Z","caller":"traceutil/trace.go:171","msg":"trace[566426947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25289; }","duration":"143.333037ms","start":"2026-04-22T19:16:26.000917Z","end":"2026-04-22T19:16:26.14425Z","steps":["trace[566426947] 'agreement among raft nodes before linearized reading' (duration: 143.003351ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:26.144297Z","caller":"traceutil/trace.go:171","msg":"trace[516855933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25289; }","duration":"140.616166ms","start":"2026-04-22T19:16:26.003657Z","end":"2026-04-22T19:16:26.144274Z","steps":["trace[516855933] 'agreement among raft nodes before linearized reading' (duration: 140.31735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:26.889982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.844885ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T19:16:26.890325Z","caller":"traceutil/trace.go:171","msg":"trace[227266748] linearizableReadLoop","detail":"{readStateIndex:26113; appliedIndex:26112; }","duration":"349.771498ms","start":"2026-04-22T19:16:26.540511Z","end":"2026-04-22T19:16:26.890283Z","steps":["trace[227266748] 'read index received' (duration: 13.638178ms)","trace[227266748] 'applied index is now lower than readState.Index' (duration: 336.13148ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:26.890375Z","caller":"traceutil/trace.go:171","msg":"trace[1660568318] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"305.613083ms","start":"2026-04-22T19:16:26.584731Z","end":"2026-04-22T19:16:26.890344Z","steps":["trace[1660568318] 'process raft request' (duration: 305.40704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:26.890617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:26.58469Z","time spent":"305.786247ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:26.89064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.091004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-22T19:16:26.890957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.277218ms","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-04-22T19:16:26.891011Z","caller":"traceutil/trace.go:171","msg":"trace[764303417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25292; }","duration":"142.41009ms","start":"2026-04-22T19:16:26.748582Z","end":"2026-04-22T19:16:26.890992Z","steps":["trace[764303417] 'agreement among raft nodes before linearized reading' (duration: 141.891811ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:26.891089Z","caller":"traceutil/trace.go:171","msg":"trace[1682744645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25292; }","duration":"350.570243ms","start":"2026-04-22T19:16:26.540448Z","end":"2026-04-22T19:16:26.891018Z","steps":["trace[1682744645] 'agreement among raft nodes before linearized reading' (duration: 349.978262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:26.891397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:26.540419Z","time spent":"350.743606ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T19:16:29.821865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.057508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T19:16:29.823098Z","caller":"traceutil/trace.go:171","msg":"trace[1613527863] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25309; }","duration":"761.214161ms","start":"2026-04-22T19:16:29.060736Z","end":"2026-04-22T19:16:29.82195Z","steps":["trace[1613527863] 'range keys from in-memory index tree' (duration: 760.873025ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:29.82383Z","caller":"traceutil/trace.go:171","msg":"trace[1378375748] linearizableReadLoop","detail":"{readStateIndex:26132; appliedIndex:26131; }","duration":"470.263504ms","start":"2026-04-22T19:16:29.35351Z","end":"2026-04-22T19:16:29.823773Z","steps":["trace[1378375748] 'read index received' (duration: 438.938733ms)","trace[1378375748] 'applied index is now lower than readState.Index' (duration: 31.322831ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:29.824253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.060715Z","time spent":"763.490643ms","remote":"127.0.0.1:59620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-22T19:16:29.824364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.839535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T19:16:29.824376Z","caller":"traceutil/trace.go:171","msg":"trace[1805529407] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"563.971354ms","start":"2026-04-22T19:16:29.260358Z","end":"2026-04-22T19:16:29.824329Z","steps":["trace[1805529407] 'process raft request' (duration: 531.98886ms)","trace[1805529407] 'compare' (duration: 28.848515ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:29.824483Z","caller":"traceutil/trace.go:171","msg":"trace[1883762354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25310; }","duration":"471.025329ms","start":"2026-04-22T19:16:29.353435Z","end":"2026-04-22T19:16:29.82446Z","steps":["trace[1883762354] 'agreement among raft nodes before linearized reading' (duration: 470.703192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.824538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.353405Z","time spent":"471.121311ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T19:16:29.824627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.260315Z","time spent":"564.168198ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:29.899719Z","caller":"traceutil/trace.go:171","msg":"trace[1607873218] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"517.74467ms","start":"2026-04-22T19:16:29.381922Z","end":"2026-04-22T19:16:29.899667Z","steps":["trace[1607873218] 'process raft request' (duration: 517.497245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.900146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.381899Z","time spent":"517.935215ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:29.900193Z","caller":"traceutil/trace.go:171","msg":"trace[1397215735] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"513.892508ms","start":"2026-04-22T19:16:29.38628Z","end":"2026-04-22T19:16:29.900173Z","steps":["trace[1397215735] 'process raft request' (duration: 513.331277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.90029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.386256Z","time spent":"513.98084ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:29.900301Z","caller":"traceutil/trace.go:171","msg":"trace[227368282] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"513.845608ms","start":"2026-04-22T19:16:29.386422Z","end":"2026-04-22T19:16:29.900268Z","steps":["trace[227368282] 'process raft request' (duration: 513.46161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.900491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.386405Z","time spent":"513.98485ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:29.900509Z","caller":"traceutil/trace.go:171","msg":"trace[1090075001] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"512.273518ms","start":"2026-04-22T19:16:29.388221Z","end":"2026-04-22T19:16:29.900495Z","steps":["trace[1090075001] 'process raft request' (duration: 511.999743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.900581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.388212Z","time spent":"512.329139ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:29.901083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.6185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:29.901211Z","caller":"traceutil/trace.go:171","msg":"trace[518797733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25315; }","duration":"362.812254ms","start":"2026-04-22T19:16:29.538374Z","end":"2026-04-22T19:16:29.901186Z","steps":["trace[518797733] 'agreement among raft nodes before linearized reading' (duration: 362.60805ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:29.901245Z","caller":"traceutil/trace.go:171","msg":"trace[1483748274] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"512.632144ms","start":"2026-04-22T19:16:29.388603Z","end":"2026-04-22T19:16:29.901235Z","steps":["trace[1483748274] 'process raft request' (duration: 512.220937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.901326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.62048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-22T19:16:29.901336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.388587Z","time spent":"512.722676ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:29.901373Z","caller":"traceutil/trace.go:171","msg":"trace[1519540165] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25315; }","duration":"516.724372ms","start":"2026-04-22T19:16:29.384635Z","end":"2026-04-22T19:16:29.901359Z","steps":["trace[1519540165] 'agreement among raft nodes before linearized reading' (duration: 516.6547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.901408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.384608Z","time spent":"516.792464ms","remote":"127.0.0.1:47610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:16:29.901615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.411944ms","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-04-22T19:16:29.901648Z","caller":"traceutil/trace.go:171","msg":"trace[1135464124] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25315; }","duration":"545.496286ms","start":"2026-04-22T19:16:29.35614Z","end":"2026-04-22T19:16:29.901636Z","steps":["trace[1135464124] 'agreement among raft nodes before linearized reading' (duration: 545.352733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.901671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.356113Z","time spent":"545.553136ms","remote":"127.0.0.1:59670","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-04-22T19:16:29.901798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.65468ms","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-04-22T19:16:29.901823Z","caller":"traceutil/trace.go:171","msg":"trace[489154689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25315; }","duration":"544.70393ms","start":"2026-04-22T19:16:29.357113Z","end":"2026-04-22T19:16:29.901817Z","steps":["trace[489154689] 'agreement among raft nodes before linearized reading' (duration: 544.641229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:29.901841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.357099Z","time spent":"544.73774ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T19:16:29.901259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.538345Z","time spent":"362.904776ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T19:16:29.901132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.762073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T19:16:29.902137Z","caller":"traceutil/trace.go:171","msg":"trace[757942671] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25315; }","duration":"164.772543ms","start":"2026-04-22T19:16:29.737358Z","end":"2026-04-22T19:16:29.90213Z","steps":["trace[757942671] 'agreement among raft nodes before linearized reading' (duration: 163.606551ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:30.306512Z","caller":"traceutil/trace.go:171","msg":"trace[1337525534] linearizableReadLoop","detail":"{readStateIndex:26138; appliedIndex:26137; }","duration":"405.535852ms","start":"2026-04-22T19:16:29.900946Z","end":"2026-04-22T19:16:30.306481Z","steps":["trace[1337525534] 'read index received' (duration: 405.41522ms)","trace[1337525534] 'applied index is now lower than readState.Index' (duration: 119.092µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:30.306585Z","caller":"traceutil/trace.go:171","msg":"trace[276125899] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"474.79791ms","start":"2026-04-22T19:16:29.831719Z","end":"2026-04-22T19:16:30.306517Z","steps":["trace[276125899] 'process raft request' (duration: 474.558655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:30.306776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.831668Z","time spent":"475.009823ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:30.306949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.274137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T19:16:30.306986Z","caller":"traceutil/trace.go:171","msg":"trace[326455524] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25316; }","duration":"477.377109ms","start":"2026-04-22T19:16:29.829599Z","end":"2026-04-22T19:16:30.306977Z","steps":["trace[326455524] 'agreement among raft nodes before linearized reading' (duration: 476.988482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:30.307038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.82958Z","time spent":"477.45135ms","remote":"127.0.0.1:59620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-22T19:16:30.641319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.839075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:16:30.641507Z","caller":"traceutil/trace.go:171","msg":"trace[1338541315] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"733.67621ms","start":"2026-04-22T19:16:29.907805Z","end":"2026-04-22T19:16:30.641481Z","steps":["trace[1338541315] 'process raft request' (duration: 551.55538ms)","trace[1338541315] 'compare' (duration: 181.696203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:30.641586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.907765Z","time spent":"733.789503ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:30.642098Z","caller":"traceutil/trace.go:171","msg":"trace[25957726] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"482.399944ms","start":"2026-04-22T19:16:30.159675Z","end":"2026-04-22T19:16:30.642075Z","steps":["trace[25957726] 'process raft request' (duration: 482.332612ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:30.642086Z","caller":"traceutil/trace.go:171","msg":"trace[1860219280] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"733.888386ms","start":"2026-04-22T19:16:29.908157Z","end":"2026-04-22T19:16:30.642045Z","steps":["trace[1860219280] 'process raft request' (duration: 733.286834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:30.642236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.063018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-22T19:16:30.642257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.159644Z","time spent":"482.527706ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:30.642257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.90815Z","time spent":"734.023238ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:30.642291Z","caller":"traceutil/trace.go:171","msg":"trace[1684761130] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25319; }","duration":"437.17114ms","start":"2026-04-22T19:16:30.205105Z","end":"2026-04-22T19:16:30.642276Z","steps":["trace[1684761130] 'agreement among raft nodes before linearized reading' (duration: 437.001727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:30.642345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.205083Z","time spent":"437.246171ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:16:30.642379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.925474ms","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-04-22T19:16:30.642479Z","caller":"traceutil/trace.go:171","msg":"trace[1549082498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25319; }","duration":"411.081786ms","start":"2026-04-22T19:16:30.231372Z","end":"2026-04-22T19:16:30.642454Z","steps":["trace[1549082498] 'agreement among raft nodes before linearized reading' (duration: 410.72954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:30.642489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.502154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-22T19:16:30.642492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.174049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-22T19:16:30.642528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.231352Z","time spent":"411.167518ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T19:16:30.642536Z","caller":"traceutil/trace.go:171","msg":"trace[1729083697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25319; }","duration":"410.22878ms","start":"2026-04-22T19:16:30.232296Z","end":"2026-04-22T19:16:30.642524Z","steps":["trace[1729083697] 'agreement among raft nodes before linearized reading' (duration: 410.056687ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:30.642534Z","caller":"traceutil/trace.go:171","msg":"trace[463654525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25319; }","duration":"735.580617ms","start":"2026-04-22T19:16:29.906942Z","end":"2026-04-22T19:16:30.642522Z","steps":["trace[463654525] 'agreement among raft nodes before linearized reading' (duration: 735.496986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:30.642603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.232287Z","time spent":"410.307992ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T19:16:30.642157Z","caller":"traceutil/trace.go:171","msg":"trace[1560356650] linearizableReadLoop","detail":"{readStateIndex:26141; appliedIndex:26138; }","duration":"335.432887ms","start":"2026-04-22T19:16:30.306649Z","end":"2026-04-22T19:16:30.642082Z","steps":["trace[1560356650] 'read index received' (duration: 152.670824ms)","trace[1560356650] 'applied index is now lower than readState.Index' (duration: 182.760603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:30.642631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:29.906924Z","time spent":"735.696399ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T19:16:30.643139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.665612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18366"} {"level":"info","ts":"2026-04-22T19:16:30.643283Z","caller":"traceutil/trace.go:171","msg":"trace[1221452308] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25319; }","duration":"334.769334ms","start":"2026-04-22T19:16:30.308416Z","end":"2026-04-22T19:16:30.643185Z","steps":["trace[1221452308] 'agreement among raft nodes before linearized reading' (duration: 334.53247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:30.643461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.308393Z","time spent":"334.935527ms","remote":"127.0.0.1:59604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18389,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-22T19:16:31.027065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.989667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:16:31.027569Z","caller":"traceutil/trace.go:171","msg":"trace[1645218054] linearizableReadLoop","detail":"{readStateIndex:26142; appliedIndex:26141; }","duration":"376.984642ms","start":"2026-04-22T19:16:30.650542Z","end":"2026-04-22T19:16:31.027527Z","steps":["trace[1645218054] 'read index received' (duration: 247.489055ms)","trace[1645218054] 'applied index is now lower than readState.Index' (duration: 129.490037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:31.027703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.136264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:31.027767Z","caller":"traceutil/trace.go:171","msg":"trace[409961074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25320; }","duration":"377.279477ms","start":"2026-04-22T19:16:30.65047Z","end":"2026-04-22T19:16:31.027749Z","steps":["trace[409961074] 'agreement among raft nodes before linearized reading' (duration: 377.128644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:31.027819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.65044Z","time spent":"377.36933ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T19:16:31.027834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.992408ms","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-04-22T19:16:31.027817Z","caller":"traceutil/trace.go:171","msg":"trace[113855613] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"376.818009ms","start":"2026-04-22T19:16:30.650939Z","end":"2026-04-22T19:16:31.027757Z","steps":["trace[113855613] 'process raft request' (duration: 376.691067ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:31.027869Z","caller":"traceutil/trace.go:171","msg":"trace[1104250635] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25321; }","duration":"309.069429ms","start":"2026-04-22T19:16:30.718788Z","end":"2026-04-22T19:16:31.027858Z","steps":["trace[1104250635] 'agreement among raft nodes before linearized reading' (duration: 308.972887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:31.027909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.71877Z","time spent":"309.131591ms","remote":"127.0.0.1:59868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-22T19:16:31.027935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.650907Z","time spent":"376.974441ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:31.028247Z","caller":"traceutil/trace.go:171","msg":"trace[130567008] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"378.574493ms","start":"2026-04-22T19:16:30.6489Z","end":"2026-04-22T19:16:31.027475Z","steps":["trace[130567008] 'process raft request' (duration: 249.026855ms)","trace[130567008] 'compare' (duration: 128.781923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:31.02836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:30.648877Z","time spent":"379.412398ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:31.303555Z","caller":"traceutil/trace.go:171","msg":"trace[107643764] linearizableReadLoop","detail":"{readStateIndex:26144; appliedIndex:26143; }","duration":"269.470471ms","start":"2026-04-22T19:16:31.034044Z","end":"2026-04-22T19:16:31.303514Z","steps":["trace[107643764] 'read index received' (duration: 215.612033ms)","trace[107643764] 'applied index is now lower than readState.Index' (duration: 53.856418ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:31.303784Z","caller":"traceutil/trace.go:171","msg":"trace[1509802168] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"270.48576ms","start":"2026-04-22T19:16:31.03327Z","end":"2026-04-22T19:16:31.303756Z","steps":["trace[1509802168] 'process raft request' (duration: 216.404588ms)","trace[1509802168] 'compare' (duration: 53.207535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:31.30401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.904669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T19:16:31.304083Z","caller":"traceutil/trace.go:171","msg":"trace[926024500] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25322; }","duration":"270.037431ms","start":"2026-04-22T19:16:31.034026Z","end":"2026-04-22T19:16:31.304063Z","steps":["trace[926024500] 'agreement among raft nodes before linearized reading' (duration: 269.739186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:31.304263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.821339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"info","ts":"2026-04-22T19:16:31.304313Z","caller":"traceutil/trace.go:171","msg":"trace[1417873861] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:25322; }","duration":"105.933121ms","start":"2026-04-22T19:16:31.198362Z","end":"2026-04-22T19:16:31.304296Z","steps":["trace[1417873861] 'agreement among raft nodes before linearized reading' (duration: 105.768068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:31.633108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.288306ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-22T19:16:31.633225Z","caller":"traceutil/trace.go:171","msg":"trace[477701640] linearizableReadLoop","detail":"{readStateIndex:26145; appliedIndex:26144; }","duration":"326.909365ms","start":"2026-04-22T19:16:31.306296Z","end":"2026-04-22T19:16:31.633205Z","steps":["trace[477701640] 'read index received' (duration: 128.436507ms)","trace[477701640] 'applied index is now lower than readState.Index' (duration: 198.471508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:31.633385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.077339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18366"} {"level":"info","ts":"2026-04-22T19:16:31.633412Z","caller":"traceutil/trace.go:171","msg":"trace[1520871146] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25322; }","duration":"327.12979ms","start":"2026-04-22T19:16:31.306275Z","end":"2026-04-22T19:16:31.633404Z","steps":["trace[1520871146] 'agreement among raft nodes before linearized reading' (duration: 326.974036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:31.633437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.306265Z","time spent":"327.16601ms","remote":"127.0.0.1:59604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18389,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-22T19:16:31.633607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.305978Z","time spent":"327.628109ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-22T19:16:31.936086Z","caller":"traceutil/trace.go:171","msg":"trace[212779361] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"105.025654ms","start":"2026-04-22T19:16:31.831013Z","end":"2026-04-22T19:16:31.936039Z","steps":["trace[212779361] 'compare' (duration: 98.358968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:32.356729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.728589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-22T19:16:32.357047Z","caller":"traceutil/trace.go:171","msg":"trace[1706160880] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"240.306229ms","start":"2026-04-22T19:16:32.116723Z","end":"2026-04-22T19:16:32.357029Z","steps":["trace[1706160880] 'process raft request' (duration: 240.271619ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:32.357132Z","caller":"traceutil/trace.go:171","msg":"trace[841867223] linearizableReadLoop","detail":"{readStateIndex:26152; appliedIndex:26147; }","duration":"421.420181ms","start":"2026-04-22T19:16:31.93569Z","end":"2026-04-22T19:16:32.35711Z","steps":["trace[841867223] 'read index received' (duration: 34.201406ms)","trace[841867223] 'applied index is now lower than readState.Index' (duration: 387.215825ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:32.357167Z","caller":"traceutil/trace.go:171","msg":"trace[1129355777] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"447.616425ms","start":"2026-04-22T19:16:31.909509Z","end":"2026-04-22T19:16:32.357125Z","steps":["trace[1129355777] 'process raft request' (duration: 447.414982ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:32.35716Z","caller":"traceutil/trace.go:171","msg":"trace[1879197027] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"446.842941ms","start":"2026-04-22T19:16:31.910273Z","end":"2026-04-22T19:16:32.357116Z","steps":["trace[1879197027] 'process raft request' (duration: 446.678768ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:32.357177Z","caller":"traceutil/trace.go:171","msg":"trace[738335056] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"447.757927ms","start":"2026-04-22T19:16:31.909378Z","end":"2026-04-22T19:16:32.357136Z","steps":["trace[738335056] 'process raft request' (duration: 135.518209ms)","trace[738335056] 'compare' (duration: 311.324402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:32.357281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.846824ms","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":"warn","ts":"2026-04-22T19:16:32.35732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.924266ms","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-04-22T19:16:32.357346Z","caller":"traceutil/trace.go:171","msg":"trace[492578159] 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:25330; }","duration":"444.949126ms","start":"2026-04-22T19:16:31.912378Z","end":"2026-04-22T19:16:32.357327Z","steps":["trace[492578159] 'agreement among raft nodes before linearized reading' (duration: 444.737821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:32.357348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.909499Z","time spent":"447.757689ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:32.357383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.093001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-04-22T19:16:32.357379Z","caller":"traceutil/trace.go:171","msg":"trace[1360832174] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25330; }","duration":"454.042499ms","start":"2026-04-22T19:16:31.903321Z","end":"2026-04-22T19:16:32.357363Z","steps":["trace[1360832174] 'agreement among raft nodes before linearized reading' (duration: 453.911776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:32.357381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.311304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-22T19:16:32.357391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.90933Z","time spent":"447.960492ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:32.357402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.910265Z","time spent":"447.008435ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:32.357447Z","caller":"traceutil/trace.go:171","msg":"trace[628003276] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25330; }","duration":"399.436397ms","start":"2026-04-22T19:16:31.957996Z","end":"2026-04-22T19:16:32.357432Z","steps":["trace[628003276] 'agreement among raft nodes before linearized reading' (duration: 399.310605ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:32.357411Z","caller":"traceutil/trace.go:171","msg":"trace[1315383840] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25330; }","duration":"217.171482ms","start":"2026-04-22T19:16:32.140231Z","end":"2026-04-22T19:16:32.357403Z","steps":["trace[1315383840] 'agreement among raft nodes before linearized reading' (duration: 217.078891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:32.357465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.050947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:32.35713Z","caller":"traceutil/trace.go:171","msg":"trace[695524246] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"447.556355ms","start":"2026-04-22T19:16:31.909533Z","end":"2026-04-22T19:16:32.357089Z","steps":["trace[695524246] 'process raft request' (duration: 447.30308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:32.357501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.957973Z","time spent":"399.512108ms","remote":"127.0.0.1:35532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:16:32.357522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.909505Z","time spent":"447.996983ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:32.357501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.863331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T19:16:32.357587Z","caller":"traceutil/trace.go:171","msg":"trace[1699544557] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25330; }","duration":"268.021593ms","start":"2026-04-22T19:16:32.089551Z","end":"2026-04-22T19:16:32.357573Z","steps":["trace[1699544557] 'agreement among raft nodes before linearized reading' (duration: 267.883021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:32.357429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.903299Z","time spent":"454.11911ms","remote":"127.0.0.1:44820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:16:32.357398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:31.912365Z","time spent":"445.014827ms","remote":"127.0.0.1:35698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-04-22T19:16:32.357507Z","caller":"traceutil/trace.go:171","msg":"trace[1122672852] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25330; }","duration":"292.140139ms","start":"2026-04-22T19:16:32.065357Z","end":"2026-04-22T19:16:32.357497Z","steps":["trace[1122672852] 'agreement among raft nodes before linearized reading' (duration: 292.081318ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:33.066208Z","caller":"traceutil/trace.go:171","msg":"trace[1773484990] transaction","detail":"{read_only:false; response_revision:25332; number_of_response:1; }","duration":"416.5831ms","start":"2026-04-22T19:16:32.6496Z","end":"2026-04-22T19:16:33.066183Z","steps":["trace[1773484990] 'process raft request' (duration: 416.197563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:33.066391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:32.649569Z","time spent":"416.714373ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:33.068287Z","caller":"traceutil/trace.go:171","msg":"trace[1845130416] linearizableReadLoop","detail":"{readStateIndex:26157; appliedIndex:26154; }","duration":"347.290781ms","start":"2026-04-22T19:16:32.720986Z","end":"2026-04-22T19:16:33.068277Z","steps":["trace[1845130416] 'read index received' (duration: 345.016309ms)","trace[1845130416] 'applied index is now lower than readState.Index' (duration: 2.273712ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:33.068474Z","caller":"traceutil/trace.go:171","msg":"trace[1909009963] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"418.409614ms","start":"2026-04-22T19:16:32.650053Z","end":"2026-04-22T19:16:33.068463Z","steps":["trace[1909009963] 'process raft request' (duration: 418.131889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:33.06854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:32.650043Z","time spent":"418.460976ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:16:33.068663Z","caller":"traceutil/trace.go:171","msg":"trace[356125631] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"415.575191ms","start":"2026-04-22T19:16:32.65308Z","end":"2026-04-22T19:16:33.068655Z","steps":["trace[356125631] 'process raft request' (duration: 415.164283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:33.068718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:32.653035Z","time spent":"415.653153ms","remote":"127.0.0.1:59670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:16:33.068878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.885803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:33.068921Z","caller":"traceutil/trace.go:171","msg":"trace[1637513856] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25334; }","duration":"347.990245ms","start":"2026-04-22T19:16:32.720918Z","end":"2026-04-22T19:16:33.068909Z","steps":["trace[1637513856] 'agreement among raft nodes before linearized reading' (duration: 347.910453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:33.068953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:32.720891Z","time spent":"348.052385ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-04-22T19:16:33.069121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.196634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:33.069155Z","caller":"traceutil/trace.go:171","msg":"trace[1391382232] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25334; }","duration":"163.292585ms","start":"2026-04-22T19:16:32.905852Z","end":"2026-04-22T19:16:33.069145Z","steps":["trace[1391382232] 'agreement among raft nodes before linearized reading' (duration: 163.245664ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:33.173862Z","caller":"traceutil/trace.go:171","msg":"trace[366819229] transaction","detail":"{read_only:false; response_revision:25335; number_of_response:1; }","duration":"100.200104ms","start":"2026-04-22T19:16:33.073605Z","end":"2026-04-22T19:16:33.173806Z","steps":["trace[366819229] 'process raft request' (duration: 97.603744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:33.655635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.633396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:33.656284Z","caller":"traceutil/trace.go:171","msg":"trace[1423540955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25337; }","duration":"115.338578ms","start":"2026-04-22T19:16:33.540918Z","end":"2026-04-22T19:16:33.656257Z","steps":["trace[1423540955] 'range keys from in-memory index tree' (duration: 114.517573ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:34.097344Z","caller":"traceutil/trace.go:171","msg":"trace[24840513] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"155.611119ms","start":"2026-04-22T19:16:33.941678Z","end":"2026-04-22T19:16:34.097289Z","steps":["trace[24840513] 'process raft request' (duration: 155.436546ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:34.137905Z","caller":"traceutil/trace.go:171","msg":"trace[1013629357] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"162.271836ms","start":"2026-04-22T19:16:33.975368Z","end":"2026-04-22T19:16:34.137639Z","steps":["trace[1013629357] 'process raft request' (duration: 162.107483ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:34.52385Z","caller":"traceutil/trace.go:171","msg":"trace[82970537] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"159.237308ms","start":"2026-04-22T19:16:34.364586Z","end":"2026-04-22T19:16:34.523823Z","steps":["trace[82970537] 'process raft request' (duration: 159.023704ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:34.524154Z","caller":"traceutil/trace.go:171","msg":"trace[1733122102] linearizableReadLoop","detail":"{readStateIndex:26166; appliedIndex:26162; }","duration":"105.251168ms","start":"2026-04-22T19:16:34.418864Z","end":"2026-04-22T19:16:34.524115Z","steps":["trace[1733122102] 'read index received' (duration: 22.77639ms)","trace[1733122102] 'applied index is now lower than readState.Index' (duration: 82.473628ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:34.52419Z","caller":"traceutil/trace.go:171","msg":"trace[820221847] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"158.191018ms","start":"2026-04-22T19:16:34.365969Z","end":"2026-04-22T19:16:34.52416Z","steps":["trace[820221847] 'process raft request' (duration: 158.037856ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:34.524208Z","caller":"traceutil/trace.go:171","msg":"trace[1019366034] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"158.274939ms","start":"2026-04-22T19:16:34.365881Z","end":"2026-04-22T19:16:34.524156Z","steps":["trace[1019366034] 'process raft request' (duration: 157.886852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:34.524373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.492523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T19:16:34.524402Z","caller":"traceutil/trace.go:171","msg":"trace[21348117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25343; }","duration":"105.587484ms","start":"2026-04-22T19:16:34.418806Z","end":"2026-04-22T19:16:34.524394Z","steps":["trace[21348117] 'agreement among raft nodes before linearized reading' (duration: 105.431491ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:34.674422Z","caller":"traceutil/trace.go:171","msg":"trace[1570635827] linearizableReadLoop","detail":"{readStateIndex:26167; appliedIndex:26166; }","duration":"132.812448ms","start":"2026-04-22T19:16:34.541579Z","end":"2026-04-22T19:16:34.674392Z","steps":["trace[1570635827] 'read index received' (duration: 75.582848ms)","trace[1570635827] 'applied index is now lower than readState.Index' (duration: 57.22804ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:34.674471Z","caller":"traceutil/trace.go:171","msg":"trace[1509256081] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"145.298085ms","start":"2026-04-22T19:16:34.529109Z","end":"2026-04-22T19:16:34.674407Z","steps":["trace[1509256081] 'process raft request' (duration: 88.151206ms)","trace[1509256081] 'compare' (duration: 56.914804ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:34.674609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.006053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:34.674646Z","caller":"traceutil/trace.go:171","msg":"trace[222986614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25344; }","duration":"133.111265ms","start":"2026-04-22T19:16:34.541526Z","end":"2026-04-22T19:16:34.674637Z","steps":["trace[222986614] 'agreement among raft nodes before linearized reading' (duration: 132.983983ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:35.440609Z","caller":"traceutil/trace.go:171","msg":"trace[2040410330] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"242.377659ms","start":"2026-04-22T19:16:35.198202Z","end":"2026-04-22T19:16:35.440579Z","steps":["trace[2040410330] 'process raft request' (duration: 242.185975ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:35.446459Z","caller":"traceutil/trace.go:171","msg":"trace[350000427] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"246.890964ms","start":"2026-04-22T19:16:35.199548Z","end":"2026-04-22T19:16:35.446439Z","steps":["trace[350000427] 'process raft request' (duration: 246.427536ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:35.604696Z","caller":"traceutil/trace.go:171","msg":"trace[1024473675] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"152.811777ms","start":"2026-04-22T19:16:35.451844Z","end":"2026-04-22T19:16:35.604656Z","steps":["trace[1024473675] 'process raft request' (duration: 73.682802ms)","trace[1024473675] 'compare' (duration: 78.787219ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:36.203927Z","caller":"traceutil/trace.go:171","msg":"trace[1510200317] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"100.897886ms","start":"2026-04-22T19:16:36.102995Z","end":"2026-04-22T19:16:36.203893Z","steps":["trace[1510200317] 'process raft request' (duration: 100.723283ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:36.297368Z","caller":"traceutil/trace.go:171","msg":"trace[1797556045] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"154.090091ms","start":"2026-04-22T19:16:36.143244Z","end":"2026-04-22T19:16:36.297334Z","steps":["trace[1797556045] 'process raft request' (duration: 153.751074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:36.577858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.794032ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T19:16:36.578539Z","caller":"traceutil/trace.go:171","msg":"trace[1294908746] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"130.501955ms","start":"2026-04-22T19:16:36.447984Z","end":"2026-04-22T19:16:36.578486Z","steps":["trace[1294908746] 'process raft request' (duration: 130.038446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:36.865305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.21236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:36.865408Z","caller":"traceutil/trace.go:171","msg":"trace[835291596] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25359; }","duration":"138.365694ms","start":"2026-04-22T19:16:36.727014Z","end":"2026-04-22T19:16:36.86538Z","steps":["trace[835291596] 'count revisions from in-memory index tree' (duration: 138.144039ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:37.035515Z","caller":"traceutil/trace.go:171","msg":"trace[848869150] linearizableReadLoop","detail":"{readStateIndex:26184; appliedIndex:26183; }","duration":"162.580891ms","start":"2026-04-22T19:16:36.872903Z","end":"2026-04-22T19:16:37.035483Z","steps":["trace[848869150] 'read index received' (duration: 162.289976ms)","trace[848869150] 'applied index is now lower than readState.Index' (duration: 289.695µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:37.035618Z","caller":"traceutil/trace.go:171","msg":"trace[751377075] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"165.950545ms","start":"2026-04-22T19:16:36.869617Z","end":"2026-04-22T19:16:37.035567Z","steps":["trace[751377075] 'process raft request' (duration: 165.682921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:37.035851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.921028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T19:16:37.035983Z","caller":"traceutil/trace.go:171","msg":"trace[1519386677] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25360; }","duration":"163.108521ms","start":"2026-04-22T19:16:36.872846Z","end":"2026-04-22T19:16:37.035954Z","steps":["trace[1519386677] 'agreement among raft nodes before linearized reading' (duration: 162.767574ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:37.311435Z","caller":"traceutil/trace.go:171","msg":"trace[272076556] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"108.540201ms","start":"2026-04-22T19:16:37.202866Z","end":"2026-04-22T19:16:37.311406Z","steps":["trace[272076556] 'process raft request' (duration: 105.379492ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:37.311759Z","caller":"traceutil/trace.go:171","msg":"trace[734339162] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"107.886688ms","start":"2026-04-22T19:16:37.203847Z","end":"2026-04-22T19:16:37.311734Z","steps":["trace[734339162] 'process raft request' (duration: 107.503711ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:38.423024Z","caller":"traceutil/trace.go:171","msg":"trace[615770861] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"118.095591ms","start":"2026-04-22T19:16:38.3049Z","end":"2026-04-22T19:16:38.422996Z","steps":["trace[615770861] 'process raft request' (duration: 117.898938ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:16:38.836798Z","caller":"traceutil/trace.go:171","msg":"trace[1199300097] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"117.849097ms","start":"2026-04-22T19:16:38.718907Z","end":"2026-04-22T19:16:38.836756Z","steps":["trace[1199300097] 'process raft request' (duration: 30.603728ms)","trace[1199300097] 'compare' (duration: 86.899632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:42.053839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.702297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-22T19:16:42.054891Z","caller":"traceutil/trace.go:171","msg":"trace[512400769] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25399; }","duration":"323.546173ms","start":"2026-04-22T19:16:41.730991Z","end":"2026-04-22T19:16:42.054538Z","steps":["trace[512400769] 'count revisions from in-memory index tree' (duration: 322.525892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:16:42.055083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:16:41.730962Z","time spent":"324.028162ms","remote":"127.0.0.1:59736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-22T19:16:42.588213Z","caller":"traceutil/trace.go:171","msg":"trace[2142845839] linearizableReadLoop","detail":"{readStateIndex:26227; appliedIndex:26226; }","duration":"139.067897ms","start":"2026-04-22T19:16:42.449114Z","end":"2026-04-22T19:16:42.588182Z","steps":["trace[2142845839] 'read index received' (duration: 81.960608ms)","trace[2142845839] 'applied index is now lower than readState.Index' (duration: 57.106129ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:16:42.588328Z","caller":"traceutil/trace.go:171","msg":"trace[177793663] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"145.65757ms","start":"2026-04-22T19:16:42.442608Z","end":"2026-04-22T19:16:42.588265Z","steps":["trace[177793663] 'process raft request' (duration: 88.545771ms)","trace[177793663] 'compare' (duration: 56.848454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:16:42.588404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.259241ms","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-04-22T19:16:42.588523Z","caller":"traceutil/trace.go:171","msg":"trace[411391794] 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:25402; }","duration":"139.462414ms","start":"2026-04-22T19:16:42.449025Z","end":"2026-04-22T19:16:42.588488Z","steps":["trace[411391794] 'agreement among raft nodes before linearized reading' (duration: 139.267561ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:17:37.912112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23018} {"level":"info","ts":"2026-04-22T19:17:38.058742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23018,"took":"141.956573ms","hash":2627259211,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":36151296,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-22T19:17:38.058827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2627259211,"revision":23018,"compact-revision":20120} {"level":"info","ts":"2026-04-22T19:18:25.936071Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a85.wal"} {"level":"info","ts":"2026-04-22T19:18:31.335301Z","caller":"traceutil/trace.go:171","msg":"trace[328452222] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"105.378062ms","start":"2026-04-22T19:18:31.229873Z","end":"2026-04-22T19:18:31.335251Z","steps":["trace[328452222] 'process raft request' (duration: 104.922643ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:18:31.335638Z","caller":"traceutil/trace.go:171","msg":"trace[1752431837] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"105.527313ms","start":"2026-04-22T19:18:31.230067Z","end":"2026-04-22T19:18:31.335594Z","steps":["trace[1752431837] 'process raft request' (duration: 105.121646ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:18:31.335749Z","caller":"traceutil/trace.go:171","msg":"trace[1619238471] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"100.907056ms","start":"2026-04-22T19:18:31.234799Z","end":"2026-04-22T19:18:31.335706Z","steps":["trace[1619238471] 'process raft request' (duration: 100.668432ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:22:37.919577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25991} {"level":"info","ts":"2026-04-22T19:22:38.069058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25991,"took":"144.143696ms","hash":1295978877,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":34418688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-22T19:22:38.069153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1295978877,"revision":25991,"compact-revision":23018} {"level":"info","ts":"2026-04-22T19:23:32.198883Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6a0c678c4e560a44","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T19:23:32.203708Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-22T19:23:32.203929Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-22T19:27:37.926342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28685} {"level":"info","ts":"2026-04-22T19:27:38.071148Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28685,"took":"139.919107ms","hash":2779612903,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":30625792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-22T19:27:38.071322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2779612903,"revision":28685,"compact-revision":25991} {"level":"info","ts":"2026-04-22T19:32:37.936791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30904} {"level":"info","ts":"2026-04-22T19:32:38.059191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30904,"took":"117.613827ms","hash":3272195231,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31027200,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-22T19:32:38.059265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3272195231,"revision":30904,"compact-revision":28685} {"level":"info","ts":"2026-04-22T19:37:37.942901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33165} {"level":"info","ts":"2026-04-22T19:37:38.099875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33165,"took":"152.637854ms","hash":158600882,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-22T19:37:38.099957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":158600882,"revision":33165,"compact-revision":30904} {"level":"info","ts":"2026-04-22T19:42:37.95201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35456} {"level":"info","ts":"2026-04-22T19:42:38.098654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35456,"took":"141.442424ms","hash":2654456573,"current-db-size-bytes":59604992,"current-db-size":"60 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-22T19:42:38.098722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2654456573,"revision":35456,"compact-revision":33165} {"level":"info","ts":"2026-04-22T19:45:01.89079Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6a0c678c4e560a44","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T19:45:01.896776Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-22T19:45:01.896867Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-22T19:47:37.958572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37681} {"level":"info","ts":"2026-04-22T19:47:38.082939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37681,"took":"119.971552ms","hash":447062363,"current-db-size-bytes":59604992,"current-db-size":"60 MB","current-db-size-in-use-bytes":31383552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-22T19:47:38.083047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":447062363,"revision":37681,"compact-revision":35456}