{"level":"warn","ts":"2026-05-05T06:48:34.594185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:48:34.59431Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T06:48:34.59439Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:48:34.594406Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-05T06:48:34.594426Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T06:48:34.595342Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-05-05T06:48:34.595539Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T06:48:34.599682Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.840299ms"} {"level":"info","ts":"2026-05-05T06:48:34.606571Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-05-05T06:48:34.606684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:48:34.606724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-05-05T06:48:34.606741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:48:34.606816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-05-05T06:48:34.606904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-05-05T06:48:34.612875Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:48:34.614719Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:48:34.615996Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T06:48:34.61994Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:48:34.620133Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:48:34.620311Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:48:34.620454Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:48:34.620487Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:48:34.621198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-05-05T06:48:34.623854Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-05T06:48:34.6282Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T06:48:34.628351Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-05T06:48:34.62846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-05T06:48:34.62852Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:48:34.628555Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:48:34.707276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:48:34.707377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:48:34.707439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-05-05T06:48:34.707464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:48:34.707475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-05T06:48:34.707506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-05-05T06:48:34.70752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-05T06:48:34.708554Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:48:34.708613Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:48:34.708648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:48:34.708908Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:48:34.708957Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:48:34.709227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:34.710155Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:34.710315Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:34.710355Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:48:34.712065Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-05-05T06:48:34.713722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:49:09.790215Z","caller":"traceutil/trace.go:171","msg":"trace[726736037] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"104.192792ms","start":"2026-05-05T06:49:09.685979Z","end":"2026-05-05T06:49:09.790172Z","steps":["trace[726736037] 'process raft request' (duration: 103.543418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:11.349468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.598462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:11.349619Z","caller":"traceutil/trace.go:171","msg":"trace[1799573526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"134.838427ms","start":"2026-05-05T06:49:11.214755Z","end":"2026-05-05T06:49:11.349594Z","steps":["trace[1799573526] 'range keys from in-memory index tree' (duration: 134.503689ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:11.592684Z","caller":"traceutil/trace.go:171","msg":"trace[365539042] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"241.806993ms","start":"2026-05-05T06:49:11.350848Z","end":"2026-05-05T06:49:11.592655Z","steps":["trace[365539042] 'read index received' (duration: 239.373557ms)","trace[365539042] 'applied index is now lower than readState.Index' (duration: 2.432276ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:11.592793Z","caller":"traceutil/trace.go:171","msg":"trace[1839756125] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"326.877171ms","start":"2026-05-05T06:49:11.265883Z","end":"2026-05-05T06:49:11.59276Z","steps":["trace[1839756125] 'process raft request' (duration: 325.240794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:11.592955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.055228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:11.593108Z","caller":"traceutil/trace.go:171","msg":"trace[2100780112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"242.280273ms","start":"2026-05-05T06:49:11.350788Z","end":"2026-05-05T06:49:11.593068Z","steps":["trace[2100780112] 'agreement among raft nodes before linearized reading' (duration: 242.027727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:11.593511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:11.265857Z","time spent":"327.012803ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:49:11.894693Z","caller":"traceutil/trace.go:171","msg":"trace[58870698] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"236.580443ms","start":"2026-05-05T06:49:11.658069Z","end":"2026-05-05T06:49:11.89465Z","steps":["trace[58870698] 'process raft request' (duration: 236.284907ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:12.12846Z","caller":"traceutil/trace.go:171","msg":"trace[1939461067] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"219.435925ms","start":"2026-05-05T06:49:11.90898Z","end":"2026-05-05T06:49:12.128416Z","steps":["trace[1939461067] 'process raft request' (duration: 217.400339ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:07.361412Z","caller":"traceutil/trace.go:171","msg":"trace[193652130] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"139.635736ms","start":"2026-05-05T06:50:07.221708Z","end":"2026-05-05T06:50:07.361344Z","steps":["trace[193652130] 'process raft request' (duration: 139.33855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:58.036253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.705807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:50:58.036386Z","caller":"traceutil/trace.go:171","msg":"trace[571447409] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1926; }","duration":"225.537171ms","start":"2026-05-05T06:50:57.810832Z","end":"2026-05-05T06:50:58.036369Z","steps":["trace[571447409] 'read index received' (duration: 50.421µs)","trace[571447409] 'applied index is now lower than readState.Index' (duration: 225.48575ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:58.036464Z","caller":"traceutil/trace.go:171","msg":"trace[391547921] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"316.27673ms","start":"2026-05-05T06:50:57.720145Z","end":"2026-05-05T06:50:58.036422Z","steps":["trace[391547921] 'process raft request' (duration: 66.154843ms)","trace[391547921] 'compare' (duration: 249.536643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:58.036567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.744795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T06:50:58.036593Z","caller":"traceutil/trace.go:171","msg":"trace[1474492689] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"225.824258ms","start":"2026-05-05T06:50:57.810759Z","end":"2026-05-05T06:50:58.036584Z","steps":["trace[1474492689] 'agreement among raft nodes before linearized reading' (duration: 225.698715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:58.036655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:57.720131Z","time spent":"316.421143ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:58.036913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.495475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8afafe1f-2381-4944-bac6-354b898fe963\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T06:50:58.036945Z","caller":"traceutil/trace.go:171","msg":"trace[990440781] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8afafe1f-2381-4944-bac6-354b898fe963; range_end:; response_count:1; response_revision:1859; }","duration":"168.575448ms","start":"2026-05-05T06:50:57.868361Z","end":"2026-05-05T06:50:58.036937Z","steps":["trace[990440781] 'agreement among raft nodes before linearized reading' (duration: 168.499586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:58.408827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.589117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:50:58.408976Z","caller":"traceutil/trace.go:171","msg":"trace[328297222] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"154.44189ms","start":"2026-05-05T06:50:58.25451Z","end":"2026-05-05T06:50:58.408952Z","steps":["trace[328297222] 'process raft request' (duration: 24.615977ms)","trace[328297222] 'compare' (duration: 129.380342ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:47.819987Z","caller":"traceutil/trace.go:171","msg":"trace[1518491320] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"120.441323ms","start":"2026-05-05T06:51:47.699512Z","end":"2026-05-05T06:51:47.819954Z","steps":["trace[1518491320] 'process raft request' (duration: 120.202617ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:47.899224Z","caller":"traceutil/trace.go:171","msg":"trace[1676241930] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"159.921401ms","start":"2026-05-05T06:51:47.739242Z","end":"2026-05-05T06:51:47.899164Z","steps":["trace[1676241930] 'process raft request' (duration: 130.939873ms)","trace[1676241930] 'compare' (duration: 28.869005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:54.529135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.6213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T06:51:54.529235Z","caller":"traceutil/trace.go:171","msg":"trace[604110392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2211; }","duration":"460.763493ms","start":"2026-05-05T06:51:54.06844Z","end":"2026-05-05T06:51:54.529204Z","steps":["trace[604110392] 'range keys from in-memory index tree' (duration: 460.428286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:54.52928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:54.068415Z","time spent":"460.855956ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:51:54.529372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.583205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:51:54.529425Z","caller":"traceutil/trace.go:171","msg":"trace[2068842360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2211; }","duration":"313.703227ms","start":"2026-05-05T06:51:54.21571Z","end":"2026-05-05T06:51:54.529413Z","steps":["trace[2068842360] 'range keys from in-memory index tree' (duration: 313.403481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:54.529459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:54.21568Z","time spent":"313.771069ms","remote":"127.0.0.1:56396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:51:54.969985Z","caller":"traceutil/trace.go:171","msg":"trace[939136075] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2292; }","duration":"419.161353ms","start":"2026-05-05T06:51:54.550779Z","end":"2026-05-05T06:51:54.96994Z","steps":["trace[939136075] 'read index received' (duration: 58.141442ms)","trace[939136075] 'applied index is now lower than readState.Index' (duration: 361.017941ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:54.97004Z","caller":"traceutil/trace.go:171","msg":"trace[1713037227] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"433.810841ms","start":"2026-05-05T06:51:54.536169Z","end":"2026-05-05T06:51:54.96998Z","steps":["trace[1713037227] 'process raft request' (duration: 433.716979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:54.97033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:54.536132Z","time spent":"434.053957ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:54.970313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.513601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:51:54.970012Z","caller":"traceutil/trace.go:171","msg":"trace[1375312481] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"434.134348ms","start":"2026-05-05T06:51:54.535822Z","end":"2026-05-05T06:51:54.969957Z","steps":["trace[1375312481] 'process raft request' (duration: 433.871382ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:54.970415Z","caller":"traceutil/trace.go:171","msg":"trace[60934430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2214; }","duration":"419.653645ms","start":"2026-05-05T06:51:54.55074Z","end":"2026-05-05T06:51:54.970393Z","steps":["trace[60934430] 'agreement among raft nodes before linearized reading' (duration: 419.287346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:54.970471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:54.550723Z","time spent":"419.736846ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:51:54.970601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:54.535792Z","time spent":"434.670621ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:51:54.988326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.348421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:51:54.988403Z","caller":"traceutil/trace.go:171","msg":"trace[1564334979] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2215; }","duration":"303.459684ms","start":"2026-05-05T06:51:54.684926Z","end":"2026-05-05T06:51:54.988386Z","steps":["trace[1564334979] 'agreement among raft nodes before linearized reading' (duration: 303.30682ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:54.988372Z","caller":"traceutil/trace.go:171","msg":"trace[266560934] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"279.690328ms","start":"2026-05-05T06:51:54.708639Z","end":"2026-05-05T06:51:54.98833Z","steps":["trace[266560934] 'process raft request' (duration: 279.426372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:54.988445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:54.684911Z","time spent":"303.524415ms","remote":"127.0.0.1:56898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:57:05.635756Z","caller":"traceutil/trace.go:171","msg":"trace[2128210496] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"121.471449ms","start":"2026-05-05T06:57:05.514257Z","end":"2026-05-05T06:57:05.635728Z","steps":["trace[2128210496] 'process raft request' (duration: 120.612969ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:10.012446Z","caller":"traceutil/trace.go:171","msg":"trace[22058217] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"100.606567ms","start":"2026-05-05T06:57:09.911807Z","end":"2026-05-05T06:57:10.012413Z","steps":["trace[22058217] 'process raft request' (duration: 100.243569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:11.050504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.409049ms","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-05-05T06:57:11.050603Z","caller":"traceutil/trace.go:171","msg":"trace[87462314] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4906; }","duration":"103.588253ms","start":"2026-05-05T06:57:10.946994Z","end":"2026-05-05T06:57:11.050583Z","steps":["trace[87462314] 'range keys from in-memory index tree' (duration: 103.293216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:15.328604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.493058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-04b95ec5-ab40-4303-801f-19d06fb0a704\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T06:57:15.328726Z","caller":"traceutil/trace.go:171","msg":"trace[559803059] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-04b95ec5-ab40-4303-801f-19d06fb0a704; range_end:; response_count:1; response_revision:5046; }","duration":"261.692902ms","start":"2026-05-05T06:57:15.067012Z","end":"2026-05-05T06:57:15.328705Z","steps":["trace[559803059] 'range keys from in-memory index tree' (duration: 261.273773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:15.328729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.946012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:57:15.328802Z","caller":"traceutil/trace.go:171","msg":"trace[987554465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5046; }","duration":"114.034264ms","start":"2026-05-05T06:57:15.214732Z","end":"2026-05-05T06:57:15.328766Z","steps":["trace[987554465] 'range keys from in-memory index tree' (duration: 113.85ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:15.328838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.092161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:778237"} {"level":"info","ts":"2026-05-05T06:57:15.328892Z","caller":"traceutil/trace.go:171","msg":"trace[94626814] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5046; }","duration":"243.165322ms","start":"2026-05-05T06:57:15.085715Z","end":"2026-05-05T06:57:15.32888Z","steps":["trace[94626814] 'range keys from in-memory index tree' (duration: 242.710643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:15.328989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.427659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-05-05T06:57:15.329057Z","caller":"traceutil/trace.go:171","msg":"trace[1057721012] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5046; }","duration":"286.532841ms","start":"2026-05-05T06:57:15.042508Z","end":"2026-05-05T06:57:15.329041Z","steps":["trace[1057721012] 'range keys from in-memory index tree' (duration: 286.295436ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:15.715701Z","caller":"traceutil/trace.go:171","msg":"trace[936054269] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"135.534569ms","start":"2026-05-05T06:57:15.580133Z","end":"2026-05-05T06:57:15.715668Z","steps":["trace[936054269] 'process raft request' (duration: 135.361755ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:15.722071Z","caller":"traceutil/trace.go:171","msg":"trace[1380650894] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"110.118667ms","start":"2026-05-05T06:57:15.611914Z","end":"2026-05-05T06:57:15.722033Z","steps":["trace[1380650894] 'process raft request' (duration: 109.930223ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:18.778899Z","caller":"traceutil/trace.go:171","msg":"trace[148319321] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"100.961794ms","start":"2026-05-05T06:57:18.677906Z","end":"2026-05-05T06:57:18.778867Z","steps":["trace[148319321] 'process raft request' (duration: 91.492985ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:25.814868Z","caller":"traceutil/trace.go:171","msg":"trace[416853707] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"134.655039ms","start":"2026-05-05T06:57:25.680158Z","end":"2026-05-05T06:57:25.814813Z","steps":["trace[416853707] 'process raft request' (duration: 71.42647ms)","trace[416853707] 'compare' (duration: 62.932543ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:57:25.814916Z","caller":"traceutil/trace.go:171","msg":"trace[2072432646] linearizableReadLoop","detail":"{readStateIndex:5454; appliedIndex:5453; }","duration":"121.484728ms","start":"2026-05-05T06:57:25.693401Z","end":"2026-05-05T06:57:25.814886Z","steps":["trace[2072432646] 'read index received' (duration: 58.135996ms)","trace[2072432646] 'applied index is now lower than readState.Index' (duration: 63.344542ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:57:25.815142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.729044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-jrmxv\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-05-05T06:57:25.815184Z","caller":"traceutil/trace.go:171","msg":"trace[1012686390] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-jrmxv; range_end:; response_count:1; response_revision:5262; }","duration":"121.846556ms","start":"2026-05-05T06:57:25.693327Z","end":"2026-05-05T06:57:25.815173Z","steps":["trace[1012686390] 'agreement among raft nodes before linearized reading' (duration: 121.666372ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:26.070411Z","caller":"traceutil/trace.go:171","msg":"trace[757413607] linearizableReadLoop","detail":"{readStateIndex:5460; appliedIndex:5458; }","duration":"201.027748ms","start":"2026-05-05T06:57:25.869352Z","end":"2026-05-05T06:57:26.07038Z","steps":["trace[757413607] 'read index received' (duration: 11.968215ms)","trace[757413607] 'applied index is now lower than readState.Index' (duration: 189.058133ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:57:26.070474Z","caller":"traceutil/trace.go:171","msg":"trace[598530053] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"199.817032ms","start":"2026-05-05T06:57:25.870616Z","end":"2026-05-05T06:57:26.070433Z","steps":["trace[598530053] 'process raft request' (duration: 199.715309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:26.070468Z","caller":"traceutil/trace.go:171","msg":"trace[390367398] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"200.817943ms","start":"2026-05-05T06:57:25.869603Z","end":"2026-05-05T06:57:26.070421Z","steps":["trace[390367398] 'process raft request' (duration: 200.66863ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:26.070702Z","caller":"traceutil/trace.go:171","msg":"trace[470184277] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"201.09743ms","start":"2026-05-05T06:57:25.869277Z","end":"2026-05-05T06:57:26.070374Z","steps":["trace[470184277] 'process raft request' (duration: 173.487589ms)","trace[470184277] 'compare' (duration: 27.323165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:57:26.070826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.451908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-n7v77\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-05-05T06:57:26.070867Z","caller":"traceutil/trace.go:171","msg":"trace[1929974196] range","detail":"{range_begin:/registry/pods/monitoring/vector-n7v77; range_end:; response_count:1; response_revision:5269; }","duration":"201.576831ms","start":"2026-05-05T06:57:25.869281Z","end":"2026-05-05T06:57:26.070858Z","steps":["trace[1929974196] 'agreement among raft nodes before linearized reading' (duration: 201.247394ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:26.39808Z","caller":"traceutil/trace.go:171","msg":"trace[933242236] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"251.461956ms","start":"2026-05-05T06:57:26.146589Z","end":"2026-05-05T06:57:26.398051Z","steps":["trace[933242236] 'process raft request' (duration: 246.491015ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:26.398236Z","caller":"traceutil/trace.go:171","msg":"trace[1783036838] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"251.564368ms","start":"2026-05-05T06:57:26.146626Z","end":"2026-05-05T06:57:26.398191Z","steps":["trace[1783036838] 'process raft request' (duration: 251.318392ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:26.398324Z","caller":"traceutil/trace.go:171","msg":"trace[298977261] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"247.088749ms","start":"2026-05-05T06:57:26.151207Z","end":"2026-05-05T06:57:26.398296Z","steps":["trace[298977261] 'process raft request' (duration: 246.881825ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:26.398568Z","caller":"traceutil/trace.go:171","msg":"trace[551231402] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5464; }","duration":"184.651596ms","start":"2026-05-05T06:57:26.213893Z","end":"2026-05-05T06:57:26.398544Z","steps":["trace[551231402] 'read index received' (duration: 179.208216ms)","trace[551231402] 'applied index is now lower than readState.Index' (duration: 5.44212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:57:26.398746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.82625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:57:26.399013Z","caller":"traceutil/trace.go:171","msg":"trace[960466273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5275; }","duration":"185.131088ms","start":"2026-05-05T06:57:26.213852Z","end":"2026-05-05T06:57:26.398983Z","steps":["trace[960466273] 'agreement among raft nodes before linearized reading' (duration: 184.81043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:26.398871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.146306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T06:57:26.399098Z","caller":"traceutil/trace.go:171","msg":"trace[729065866] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5275; }","duration":"109.452392ms","start":"2026-05-05T06:57:26.289633Z","end":"2026-05-05T06:57:26.399085Z","steps":["trace[729065866] 'agreement among raft nodes before linearized reading' (duration: 109.058953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.708351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.912474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:57:34.708466Z","caller":"traceutil/trace.go:171","msg":"trace[44096788] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5445; }","duration":"392.083768ms","start":"2026-05-05T06:57:34.316356Z","end":"2026-05-05T06:57:34.70844Z","steps":["trace[44096788] 'count revisions from in-memory index tree' (duration: 391.836242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.708509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.873634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b554c21e-36f0-451d-99be-4417d5bd45bf\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T06:57:34.708655Z","caller":"traceutil/trace.go:171","msg":"trace[2002287142] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b554c21e-36f0-451d-99be-4417d5bd45bf; range_end:; response_count:1; response_revision:5445; }","duration":"444.098979ms","start":"2026-05-05T06:57:34.264524Z","end":"2026-05-05T06:57:34.708623Z","steps":["trace[2002287142] 'range keys from in-memory index tree' (duration: 443.499796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.708517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:34.316338Z","time spent":"392.166929ms","remote":"127.0.0.1:35766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:57:34.708724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:34.264495Z","time spent":"444.216112ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b554c21e-36f0-451d-99be-4417d5bd45bf\" "} {"level":"warn","ts":"2026-05-05T06:57:34.70946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.299306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:57:34.709664Z","caller":"traceutil/trace.go:171","msg":"trace[1265168354] linearizableReadLoop","detail":"{readStateIndex:5644; appliedIndex:5643; }","duration":"270.150758ms","start":"2026-05-05T06:57:34.439453Z","end":"2026-05-05T06:57:34.709603Z","steps":["trace[1265168354] 'read index received' (duration: 72.573056ms)","trace[1265168354] 'applied index is now lower than readState.Index' (duration: 197.575882ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:57:34.709789Z","caller":"traceutil/trace.go:171","msg":"trace[1900824112] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"283.056965ms","start":"2026-05-05T06:57:34.426559Z","end":"2026-05-05T06:57:34.709616Z","steps":["trace[1900824112] 'process raft request' (duration: 85.450442ms)","trace[1900824112] 'compare' (duration: 196.471788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:57:34.709852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.398933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fcn4n\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T06:57:34.710005Z","caller":"traceutil/trace.go:171","msg":"trace[1762813127] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fcn4n; range_end:; response_count:1; response_revision:5446; }","duration":"270.593368ms","start":"2026-05-05T06:57:34.439389Z","end":"2026-05-05T06:57:34.709982Z","steps":["trace[1762813127] 'agreement among raft nodes before linearized reading' (duration: 270.275291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.710366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.198423ms","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-05-05T06:57:34.710518Z","caller":"traceutil/trace.go:171","msg":"trace[882593237] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5446; }","duration":"164.377758ms","start":"2026-05-05T06:57:34.54608Z","end":"2026-05-05T06:57:34.710458Z","steps":["trace[882593237] 'agreement among raft nodes before linearized reading' (duration: 163.878356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.971942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.234113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:57:34.972112Z","caller":"traceutil/trace.go:171","msg":"trace[1263720458] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"256.909806ms","start":"2026-05-05T06:57:34.71517Z","end":"2026-05-05T06:57:34.972079Z","steps":["trace[1263720458] 'process raft request' (duration: 140.422007ms)","trace[1263720458] 'compare' (duration: 116.08512ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:57:34.972203Z","caller":"traceutil/trace.go:171","msg":"trace[1272064725] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"139.081408ms","start":"2026-05-05T06:57:34.833105Z","end":"2026-05-05T06:57:34.972186Z","steps":["trace[1272064725] 'process raft request' (duration: 139.030137ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:34.972323Z","caller":"traceutil/trace.go:171","msg":"trace[1337059979] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"256.074727ms","start":"2026-05-05T06:57:34.716238Z","end":"2026-05-05T06:57:34.972313Z","steps":["trace[1337059979] 'process raft request' (duration: 255.834872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.972725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.517437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80137"} {"level":"info","ts":"2026-05-05T06:57:34.972775Z","caller":"traceutil/trace.go:171","msg":"trace[1339742549] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5449; }","duration":"256.607209ms","start":"2026-05-05T06:57:34.716153Z","end":"2026-05-05T06:57:34.972761Z","steps":["trace[1339742549] 'agreement among raft nodes before linearized reading' (duration: 256.339863ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:34.972331Z","caller":"traceutil/trace.go:171","msg":"trace[2081787017] linearizableReadLoop","detail":"{readStateIndex:5645; appliedIndex:5644; }","duration":"255.935424ms","start":"2026-05-05T06:57:34.716211Z","end":"2026-05-05T06:57:34.972146Z","steps":["trace[2081787017] 'read index received' (duration: 139.384774ms)","trace[2081787017] 'applied index is now lower than readState.Index' (duration: 116.54667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:57:34.973942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.676598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T06:57:34.974006Z","caller":"traceutil/trace.go:171","msg":"trace[643066048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5449; }","duration":"100.799691ms","start":"2026-05-05T06:57:34.873195Z","end":"2026-05-05T06:57:34.973995Z","steps":["trace[643066048] 'agreement among raft nodes before linearized reading' (duration: 100.632437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.974241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.314427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T06:57:34.974326Z","caller":"traceutil/trace.go:171","msg":"trace[1223327266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5449; }","duration":"103.453849ms","start":"2026-05-05T06:57:34.870852Z","end":"2026-05-05T06:57:34.974306Z","steps":["trace[1223327266] 'agreement among raft nodes before linearized reading' (duration: 103.180213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:34.974651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.080567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b554c21e-36f0-451d-99be-4417d5bd45bf\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T06:57:34.97471Z","caller":"traceutil/trace.go:171","msg":"trace[2033702488] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b554c21e-36f0-451d-99be-4417d5bd45bf; range_end:; response_count:1; response_revision:5449; }","duration":"162.849444ms","start":"2026-05-05T06:57:34.811843Z","end":"2026-05-05T06:57:34.974692Z","steps":["trace[2033702488] 'agreement among raft nodes before linearized reading' (duration: 161.80723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:35.289755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.433116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:57:35.289879Z","caller":"traceutil/trace.go:171","msg":"trace[707797474] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"310.802788ms","start":"2026-05-05T06:57:34.979055Z","end":"2026-05-05T06:57:35.289857Z","steps":["trace[707797474] 'process raft request' (duration: 138.181839ms)","trace[707797474] 'compare' (duration: 172.292902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:57:35.28994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:34.979029Z","time spent":"310.883841ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:57:35.431639Z","caller":"traceutil/trace.go:171","msg":"trace[525136017] linearizableReadLoop","detail":"{readStateIndex:5651; appliedIndex:5647; }","duration":"435.971039ms","start":"2026-05-05T06:57:34.995629Z","end":"2026-05-05T06:57:35.431601Z","steps":["trace[525136017] 'read index received' (duration: 121.602772ms)","trace[525136017] 'applied index is now lower than readState.Index' (duration: 314.366777ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:57:35.431825Z","caller":"traceutil/trace.go:171","msg":"trace[1750032256] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"450.816738ms","start":"2026-05-05T06:57:34.980965Z","end":"2026-05-05T06:57:35.431782Z","steps":["trace[1750032256] 'process raft request' (duration: 450.47039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:35.432044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.383139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T06:57:35.432099Z","caller":"traceutil/trace.go:171","msg":"trace[194921686] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"452.010723ms","start":"2026-05-05T06:57:34.980063Z","end":"2026-05-05T06:57:35.432073Z","steps":["trace[194921686] 'process raft request' (duration: 451.059553ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:35.432114Z","caller":"traceutil/trace.go:171","msg":"trace[1183517353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5453; }","duration":"436.497941ms","start":"2026-05-05T06:57:34.995598Z","end":"2026-05-05T06:57:35.432096Z","steps":["trace[1183517353] 'agreement among raft nodes before linearized reading' (duration: 436.194364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:35.432168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:34.995583Z","time spent":"436.570732ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T06:57:35.431825Z","caller":"traceutil/trace.go:171","msg":"trace[42884141] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"447.77815ms","start":"2026-05-05T06:57:34.984018Z","end":"2026-05-05T06:57:35.431796Z","steps":["trace[42884141] 'process raft request' (duration: 447.499834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:35.432263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:34.980042Z","time spent":"452.111866ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:57:35.432318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:34.983977Z","time spent":"448.250921ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:57:35.432381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:57:34.980956Z","time spent":"450.950431ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:57:35.432473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.256324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:57:35.432579Z","caller":"traceutil/trace.go:171","msg":"trace[157799849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5454; }","duration":"215.436018ms","start":"2026-05-05T06:57:35.217117Z","end":"2026-05-05T06:57:35.432553Z","steps":["trace[157799849] 'agreement among raft nodes before linearized reading' (duration: 215.236893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:35.432586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.082382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-05T06:57:35.432717Z","caller":"traceutil/trace.go:171","msg":"trace[413942944] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5454; }","duration":"103.210835ms","start":"2026-05-05T06:57:35.329488Z","end":"2026-05-05T06:57:35.432699Z","steps":["trace[413942944] 'agreement among raft nodes before linearized reading' (duration: 102.99609ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:58:35.141605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2955} {"level":"info","ts":"2026-05-05T06:58:35.216106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2955,"took":"72.406469ms","hash":384645614,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":33525760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T06:58:35.216179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":384645614,"revision":2955,"compact-revision":-1} {"level":"info","ts":"2026-05-05T07:03:35.149034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6016} {"level":"info","ts":"2026-05-05T07:03:35.251362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6016,"took":"99.86868ms","hash":3001854489,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":21622784,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-05T07:03:35.251459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3001854489,"revision":6016,"compact-revision":2955} {"level":"info","ts":"2026-05-05T07:03:45.370143Z","caller":"traceutil/trace.go:171","msg":"trace[1261860694] linearizableReadLoop","detail":"{readStateIndex:8580; appliedIndex:8579; }","duration":"154.998352ms","start":"2026-05-05T07:03:45.215109Z","end":"2026-05-05T07:03:45.370107Z","steps":["trace[1261860694] 'read index received' (duration: 154.762737ms)","trace[1261860694] 'applied index is now lower than readState.Index' (duration: 234.345µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:45.370265Z","caller":"traceutil/trace.go:171","msg":"trace[96423286] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"185.920377ms","start":"2026-05-05T07:03:45.184293Z","end":"2026-05-05T07:03:45.370214Z","steps":["trace[96423286] 'process raft request' (duration: 185.61117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:45.370358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.214026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:03:45.370465Z","caller":"traceutil/trace.go:171","msg":"trace[598590587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8292; }","duration":"155.37702ms","start":"2026-05-05T07:03:45.215063Z","end":"2026-05-05T07:03:45.37044Z","steps":["trace[598590587] 'agreement among raft nodes before linearized reading' (duration: 155.187986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:57.318865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.554962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:03:57.319015Z","caller":"traceutil/trace.go:171","msg":"trace[1174447693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8355; }","duration":"104.761956ms","start":"2026-05-05T07:03:57.214206Z","end":"2026-05-05T07:03:57.318968Z","steps":["trace[1174447693] 'range keys from in-memory index tree' (duration: 104.43664ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:04:26.557486Z","caller":"traceutil/trace.go:171","msg":"trace[1222101577] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"128.389961ms","start":"2026-05-05T07:04:26.429058Z","end":"2026-05-05T07:04:26.557448Z","steps":["trace[1222101577] 'process raft request' (duration: 123.130546ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:04:40.044356Z","caller":"traceutil/trace.go:171","msg":"trace[77830989] linearizableReadLoop","detail":"{readStateIndex:8985; appliedIndex:8984; }","duration":"138.973052ms","start":"2026-05-05T07:04:39.905343Z","end":"2026-05-05T07:04:40.044316Z","steps":["trace[77830989] 'read index received' (duration: 133.574674ms)","trace[77830989] 'applied index is now lower than readState.Index' (duration: 5.396878ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:04:40.044737Z","caller":"traceutil/trace.go:171","msg":"trace[1197526802] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"232.090185ms","start":"2026-05-05T07:04:39.812631Z","end":"2026-05-05T07:04:40.044721Z","steps":["trace[1197526802] 'process raft request' (duration: 226.37215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.045131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.776589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T07:04:40.045226Z","caller":"traceutil/trace.go:171","msg":"trace[262197789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8680; }","duration":"139.940263ms","start":"2026-05-05T07:04:39.905271Z","end":"2026-05-05T07:04:40.045212Z","steps":["trace[262197789] 'agreement among raft nodes before linearized reading' (duration: 139.703978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.04534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.272018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T07:04:40.045721Z","caller":"traceutil/trace.go:171","msg":"trace[1852060073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8680; }","duration":"111.450112ms","start":"2026-05-05T07:04:39.933988Z","end":"2026-05-05T07:04:40.045438Z","steps":["trace[1852060073] 'agreement among raft nodes before linearized reading' (duration: 111.009932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.045101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.151729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:04:40.046116Z","caller":"traceutil/trace.go:171","msg":"trace[567569692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8680; }","duration":"125.236573ms","start":"2026-05-05T07:04:39.920865Z","end":"2026-05-05T07:04:40.046102Z","steps":["trace[567569692] 'agreement among raft nodes before linearized reading' (duration: 124.045097ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:04:40.610629Z","caller":"traceutil/trace.go:171","msg":"trace[2068522592] linearizableReadLoop","detail":"{readStateIndex:8988; appliedIndex:8986; }","duration":"395.384287ms","start":"2026-05-05T07:04:40.215211Z","end":"2026-05-05T07:04:40.610595Z","steps":["trace[2068522592] 'read index received' (duration: 395.152722ms)","trace[2068522592] 'applied index is now lower than readState.Index' (duration: 230.065µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:04:40.610688Z","caller":"traceutil/trace.go:171","msg":"trace[746676706] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"559.441056ms","start":"2026-05-05T07:04:40.051198Z","end":"2026-05-05T07:04:40.610639Z","steps":["trace[746676706] 'process raft request' (duration: 559.140069ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:04:40.610771Z","caller":"traceutil/trace.go:171","msg":"trace[1942530564] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"559.143029ms","start":"2026-05-05T07:04:40.051616Z","end":"2026-05-05T07:04:40.610759Z","steps":["trace[1942530564] 'process raft request' (duration: 558.920245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.610909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:04:40.051183Z","time spent":"559.59324ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:04:40.610931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:04:40.051562Z","time spent":"559.254243ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:04:40.61102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.766605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:04:40.611136Z","caller":"traceutil/trace.go:171","msg":"trace[1816445511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8683; }","duration":"395.904068ms","start":"2026-05-05T07:04:40.215202Z","end":"2026-05-05T07:04:40.611106Z","steps":["trace[1816445511] 'agreement among raft nodes before linearized reading' (duration: 395.668173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.611217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:04:40.215127Z","time spent":"396.075453ms","remote":"127.0.0.1:56396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T07:04:40.646027Z","caller":"traceutil/trace.go:171","msg":"trace[1648206641] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"279.737434ms","start":"2026-05-05T07:04:40.366266Z","end":"2026-05-05T07:04:40.646003Z","steps":["trace[1648206641] 'process raft request' (duration: 279.53008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.646314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.501822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-05-05T07:04:40.646438Z","caller":"traceutil/trace.go:171","msg":"trace[1255357293] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8684; }","duration":"308.706896ms","start":"2026-05-05T07:04:40.33771Z","end":"2026-05-05T07:04:40.646417Z","steps":["trace[1255357293] 'agreement among raft nodes before linearized reading' (duration: 308.387409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.646487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:04:40.337679Z","time spent":"308.797338ms","remote":"127.0.0.1:47966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5169,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-05-05T07:04:40.737612Z","caller":"traceutil/trace.go:171","msg":"trace[833296885] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"113.802094ms","start":"2026-05-05T07:04:40.62376Z","end":"2026-05-05T07:04:40.737562Z","steps":["trace[833296885] 'process raft request' (duration: 103.511269ms)","trace[833296885] 'compare' (duration: 10.130722ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:04:40.737674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.194052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:04:40.737756Z","caller":"traceutil/trace.go:171","msg":"trace[1102843843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8685; }","duration":"125.336255ms","start":"2026-05-05T07:04:40.612403Z","end":"2026-05-05T07:04:40.73774Z","steps":["trace[1102843843] 'agreement among raft nodes before linearized reading' (duration: 125.164111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.73781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.579451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T07:04:40.737887Z","caller":"traceutil/trace.go:171","msg":"trace[836491822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8685; }","duration":"109.726994ms","start":"2026-05-05T07:04:40.628144Z","end":"2026-05-05T07:04:40.737871Z","steps":["trace[836491822] 'agreement among raft nodes before linearized reading' (duration: 109.418467ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:04:40.76511Z","caller":"traceutil/trace.go:171","msg":"trace[1991417428] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"106.424132ms","start":"2026-05-05T07:04:40.65866Z","end":"2026-05-05T07:04:40.765084Z","steps":["trace[1991417428] 'process raft request' (duration: 106.191397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:04:40.765338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.172073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T07:04:40.765471Z","caller":"traceutil/trace.go:171","msg":"trace[1418113112] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8686; }","duration":"115.350198ms","start":"2026-05-05T07:04:40.650097Z","end":"2026-05-05T07:04:40.765447Z","steps":["trace[1418113112] 'agreement among raft nodes before linearized reading' (duration: 114.911127ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:05:09.049252Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002456.wal"} {"level":"info","ts":"2026-05-05T07:05:56.628733Z","caller":"traceutil/trace.go:171","msg":"trace[115579690] linearizableReadLoop","detail":"{readStateIndex:9752; appliedIndex:9751; }","duration":"294.287478ms","start":"2026-05-05T07:05:56.334411Z","end":"2026-05-05T07:05:56.628698Z","steps":["trace[115579690] 'read index received' (duration: 294.067143ms)","trace[115579690] 'applied index is now lower than readState.Index' (duration: 218.635µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:05:56.628791Z","caller":"traceutil/trace.go:171","msg":"trace[865465879] transaction","detail":"{read_only:false; response_revision:9426; number_of_response:1; }","duration":"300.774258ms","start":"2026-05-05T07:05:56.327974Z","end":"2026-05-05T07:05:56.628748Z","steps":["trace[865465879] 'process raft request' (duration: 300.521142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:05:56.629101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:05:56.327889Z","time spent":"300.995172ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:05:56.629199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.746639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fcn4n\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T07:05:56.629329Z","caller":"traceutil/trace.go:171","msg":"trace[1201466629] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fcn4n; range_end:; response_count:1; response_revision:9426; }","duration":"294.951703ms","start":"2026-05-05T07:05:56.334347Z","end":"2026-05-05T07:05:56.629299Z","steps":["trace[1201466629] 'agreement among raft nodes before linearized reading' (duration: 294.539554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:05:56.643877Z","caller":"traceutil/trace.go:171","msg":"trace[1700233027] transaction","detail":"{read_only:false; response_revision:9427; number_of_response:1; }","duration":"176.305077ms","start":"2026-05-05T07:05:56.467545Z","end":"2026-05-05T07:05:56.64385Z","steps":["trace[1700233027] 'process raft request' (duration: 175.93298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:05:56.644398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.060403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T07:05:56.644468Z","caller":"traceutil/trace.go:171","msg":"trace[1148942401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9427; }","duration":"125.200947ms","start":"2026-05-05T07:05:56.51925Z","end":"2026-05-05T07:05:56.644451Z","steps":["trace[1148942401] 'agreement among raft nodes before linearized reading' (duration: 124.976052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:05:56.811155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.630516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:05:56.811624Z","caller":"traceutil/trace.go:171","msg":"trace[1490267358] transaction","detail":"{read_only:false; response_revision:9429; number_of_response:1; }","duration":"153.186079ms","start":"2026-05-05T07:05:56.658373Z","end":"2026-05-05T07:05:56.811559Z","steps":["trace[1490267358] 'process raft request' (duration: 153.051577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:05:56.811611Z","caller":"traceutil/trace.go:171","msg":"trace[291043620] transaction","detail":"{read_only:false; response_revision:9428; number_of_response:1; }","duration":"160.423586ms","start":"2026-05-05T07:05:56.651149Z","end":"2026-05-05T07:05:56.811572Z","steps":["trace[291043620] 'process raft request' (duration: 20.256917ms)","trace[291043620] 'compare' (duration: 139.448723ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:06:29.346442Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:06:29.354308Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T07:06:29.354488Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T07:08:35.157946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8102} {"level":"info","ts":"2026-05-05T07:08:35.219977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8102,"took":"59.762648ms","hash":2843469798,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":28823552,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T07:08:35.220038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2843469798,"revision":8102,"compact-revision":6016} {"level":"info","ts":"2026-05-05T07:13:35.164912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10558} {"level":"info","ts":"2026-05-05T07:13:35.248649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10558,"took":"81.56795ms","hash":2778505201,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":26783744,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T07:13:35.248731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2778505201,"revision":10558,"compact-revision":8102} {"level":"info","ts":"2026-05-05T07:18:35.172044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12753} {"level":"info","ts":"2026-05-05T07:18:35.250598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12753,"took":"75.510855ms","hash":4073137244,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":25448448,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-05T07:18:35.250658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4073137244,"revision":12753,"compact-revision":10558} {"level":"info","ts":"2026-05-05T07:20:13.249158Z","caller":"traceutil/trace.go:171","msg":"trace[178324119] transaction","detail":"{read_only:false; response_revision:15119; number_of_response:1; }","duration":"124.711218ms","start":"2026-05-05T07:20:13.124419Z","end":"2026-05-05T07:20:13.24913Z","steps":["trace[178324119] 'process raft request' (duration: 124.539784ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:20:29.315086Z","caller":"traceutil/trace.go:171","msg":"trace[146795485] linearizableReadLoop","detail":"{readStateIndex:15746; appliedIndex:15745; }","duration":"194.125092ms","start":"2026-05-05T07:20:29.120932Z","end":"2026-05-05T07:20:29.315057Z","steps":["trace[146795485] 'read index received' (duration: 193.880297ms)","trace[146795485] 'applied index is now lower than readState.Index' (duration: 243.865µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:20:29.315151Z","caller":"traceutil/trace.go:171","msg":"trace[302078119] transaction","detail":"{read_only:false; response_revision:15217; number_of_response:1; }","duration":"253.315747ms","start":"2026-05-05T07:20:29.061802Z","end":"2026-05-05T07:20:29.315118Z","steps":["trace[302078119] 'process raft request' (duration: 253.079822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:20:29.315344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.333096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:20:29.315397Z","caller":"traceutil/trace.go:171","msg":"trace[1952649476] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15217; }","duration":"194.49264ms","start":"2026-05-05T07:20:29.120889Z","end":"2026-05-05T07:20:29.315382Z","steps":["trace[1952649476] 'agreement among raft nodes before linearized reading' (duration: 194.326256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:20:29.315407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.088943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:20:29.3155Z","caller":"traceutil/trace.go:171","msg":"trace[1601458695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15217; }","duration":"102.252037ms","start":"2026-05-05T07:20:29.213228Z","end":"2026-05-05T07:20:29.31548Z","steps":["trace[1601458695] 'agreement among raft nodes before linearized reading' (duration: 102.044572ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:20:29.485246Z","caller":"traceutil/trace.go:171","msg":"trace[507747057] linearizableReadLoop","detail":"{readStateIndex:15747; appliedIndex:15746; }","duration":"111.898903ms","start":"2026-05-05T07:20:29.373318Z","end":"2026-05-05T07:20:29.485217Z","steps":["trace[507747057] 'read index received' (duration: 27.899287ms)","trace[507747057] 'applied index is now lower than readState.Index' (duration: 83.998696ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:20:29.485312Z","caller":"traceutil/trace.go:171","msg":"trace[2140124477] transaction","detail":"{read_only:false; response_revision:15218; number_of_response:1; }","duration":"153.885521ms","start":"2026-05-05T07:20:29.331379Z","end":"2026-05-05T07:20:29.485265Z","steps":["trace[2140124477] 'process raft request' (duration: 69.787613ms)","trace[2140124477] 'compare' (duration: 83.952904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:20:29.48542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.086977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:20:29.485451Z","caller":"traceutil/trace.go:171","msg":"trace[1896346835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15218; }","duration":"112.189289ms","start":"2026-05-05T07:20:29.373251Z","end":"2026-05-05T07:20:29.485441Z","steps":["trace[1896346835] 'agreement among raft nodes before linearized reading' (duration: 112.108827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:22:22.323931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.309684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:22:22.323947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.484502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:22:22.32401Z","caller":"traceutil/trace.go:171","msg":"trace[232346368] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16495; }","duration":"136.394176ms","start":"2026-05-05T07:22:22.187596Z","end":"2026-05-05T07:22:22.32399Z","steps":["trace[232346368] 'range keys from in-memory index tree' (duration: 136.212133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:22:22.323995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.033239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p5r2w\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-05-05T07:22:22.324042Z","caller":"traceutil/trace.go:171","msg":"trace[1080785819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16495; }","duration":"110.640415ms","start":"2026-05-05T07:22:22.213381Z","end":"2026-05-05T07:22:22.324021Z","steps":["trace[1080785819] 'range keys from in-memory index tree' (duration: 110.3792ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:22:22.324062Z","caller":"traceutil/trace.go:171","msg":"trace[1314946608] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p5r2w; range_end:; response_count:1; response_revision:16495; }","duration":"100.09413ms","start":"2026-05-05T07:22:22.223947Z","end":"2026-05-05T07:22:22.324041Z","steps":["trace[1314946608] 'range keys from in-memory index tree' (duration: 99.841074ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:23:35.179557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14622} {"level":"info","ts":"2026-05-05T07:23:35.252935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14622,"took":"70.254053ms","hash":3836745529,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":28614656,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T07:23:35.253026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3836745529,"revision":14622,"compact-revision":12753} {"level":"info","ts":"2026-05-05T07:27:55.530099Z","caller":"traceutil/trace.go:171","msg":"trace[255047157] transaction","detail":"{read_only:false; response_revision:19016; number_of_response:1; }","duration":"199.947214ms","start":"2026-05-05T07:27:55.330128Z","end":"2026-05-05T07:27:55.530075Z","steps":["trace[255047157] 'process raft request' (duration: 199.796041ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:27:55.530646Z","caller":"traceutil/trace.go:171","msg":"trace[466121025] transaction","detail":"{read_only:false; response_revision:19017; number_of_response:1; }","duration":"195.196113ms","start":"2026-05-05T07:27:55.33543Z","end":"2026-05-05T07:27:55.530626Z","steps":["trace[466121025] 'process raft request' (duration: 195.101081ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:28:01.947368Z","caller":"traceutil/trace.go:171","msg":"trace[1237915894] linearizableReadLoop","detail":"{readStateIndex:19730; appliedIndex:19729; }","duration":"139.631385ms","start":"2026-05-05T07:28:01.80771Z","end":"2026-05-05T07:28:01.947341Z","steps":["trace[1237915894] 'read index received' (duration: 139.403461ms)","trace[1237915894] 'applied index is now lower than readState.Index' (duration: 226.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:28:01.947426Z","caller":"traceutil/trace.go:171","msg":"trace[1440201250] transaction","detail":"{read_only:false; response_revision:19081; number_of_response:1; }","duration":"140.647296ms","start":"2026-05-05T07:28:01.806728Z","end":"2026-05-05T07:28:01.947375Z","steps":["trace[1440201250] 'process raft request' (duration: 140.404511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:01.947738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.156412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T07:28:01.947754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.031024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T07:28:01.947796Z","caller":"traceutil/trace.go:171","msg":"trace[1628935557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19081; }","duration":"130.249274ms","start":"2026-05-05T07:28:01.817532Z","end":"2026-05-05T07:28:01.947781Z","steps":["trace[1628935557] 'agreement among raft nodes before linearized reading' (duration: 130.06529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:01.947752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.276446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T07:28:01.947804Z","caller":"traceutil/trace.go:171","msg":"trace[508792340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19081; }","duration":"140.091465ms","start":"2026-05-05T07:28:01.807697Z","end":"2026-05-05T07:28:01.947788Z","steps":["trace[508792340] 'agreement among raft nodes before linearized reading' (duration: 139.786328ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:28:01.947922Z","caller":"traceutil/trace.go:171","msg":"trace[924882003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19081; }","duration":"139.500901ms","start":"2026-05-05T07:28:01.808398Z","end":"2026-05-05T07:28:01.947899Z","steps":["trace[924882003] 'agreement among raft nodes before linearized reading' (duration: 139.167174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:01.947963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.119594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-05T07:28:01.949245Z","caller":"traceutil/trace.go:171","msg":"trace[1321594068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19081; }","duration":"133.522695ms","start":"2026-05-05T07:28:01.815706Z","end":"2026-05-05T07:28:01.949228Z","steps":["trace[1321594068] 'agreement among raft nodes before linearized reading' (duration: 131.960631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:02.352921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.477241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:28:02.353153Z","caller":"traceutil/trace.go:171","msg":"trace[1963861076] transaction","detail":"{read_only:false; response_revision:19083; number_of_response:1; }","duration":"400.766807ms","start":"2026-05-05T07:28:01.952345Z","end":"2026-05-05T07:28:02.353112Z","steps":["trace[1963861076] 'process raft request' (duration: 224.714934ms)","trace[1963861076] 'compare' (duration: 175.105634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:28:02.35333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:01.952314Z","time spent":"400.966881ms","remote":"127.0.0.1:56656","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-05-05T07:28:02.3538Z","caller":"traceutil/trace.go:171","msg":"trace[768869217] linearizableReadLoop","detail":"{readStateIndex:19735; appliedIndex:19730; }","duration":"397.872326ms","start":"2026-05-05T07:28:01.955913Z","end":"2026-05-05T07:28:02.353786Z","steps":["trace[768869217] 'read index received' (duration: 28.069781ms)","trace[768869217] 'applied index is now lower than readState.Index' (duration: 369.801715ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:28:02.353926Z","caller":"traceutil/trace.go:171","msg":"trace[267830489] transaction","detail":"{read_only:false; response_revision:19085; number_of_response:1; }","duration":"399.793766ms","start":"2026-05-05T07:28:01.954106Z","end":"2026-05-05T07:28:02.353899Z","steps":["trace[267830489] 'process raft request' (duration: 399.555471ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:28:02.353816Z","caller":"traceutil/trace.go:171","msg":"trace[779038360] transaction","detail":"{read_only:false; response_revision:19084; number_of_response:1; }","duration":"400.513082ms","start":"2026-05-05T07:28:01.953276Z","end":"2026-05-05T07:28:02.353789Z","steps":["trace[779038360] 'process raft request' (duration: 399.782476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:02.354054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:01.954094Z","time spent":"399.887318ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:28:02.354055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:01.953264Z","time spent":"400.719286ms","remote":"127.0.0.1:56656","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-05-05T07:28:02.353892Z","caller":"traceutil/trace.go:171","msg":"trace[269900455] transaction","detail":"{read_only:false; response_revision:19086; number_of_response:1; }","duration":"398.529319ms","start":"2026-05-05T07:28:01.955346Z","end":"2026-05-05T07:28:02.353875Z","steps":["trace[269900455] 'process raft request' (duration: 398.363786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:02.354246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:01.955334Z","time spent":"398.827215ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:28:02.35457Z","caller":"traceutil/trace.go:171","msg":"trace[61755057] transaction","detail":"{read_only:false; response_revision:19087; number_of_response:1; }","duration":"372.019983ms","start":"2026-05-05T07:28:01.981864Z","end":"2026-05-05T07:28:02.353884Z","steps":["trace[61755057] 'process raft request' (duration: 371.88419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:02.354687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:01.981844Z","time spent":"372.774399ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:28:02.354805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.868567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942965"} {"level":"info","ts":"2026-05-05T07:28:02.354875Z","caller":"traceutil/trace.go:171","msg":"trace[429731894] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19087; }","duration":"398.968379ms","start":"2026-05-05T07:28:01.955887Z","end":"2026-05-05T07:28:02.354856Z","steps":["trace[429731894] 'agreement among raft nodes before linearized reading' (duration: 398.022468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:02.35492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:01.955877Z","time spent":"399.03107ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":942988,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-05T07:28:02.492712Z","caller":"traceutil/trace.go:171","msg":"trace[817102966] linearizableReadLoop","detail":"{readStateIndex:19737; appliedIndex:19736; }","duration":"138.740536ms","start":"2026-05-05T07:28:02.353948Z","end":"2026-05-05T07:28:02.492689Z","steps":["trace[817102966] 'read index received' (duration: 117.947041ms)","trace[817102966] 'applied index is now lower than readState.Index' (duration: 20.792515ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:28:02.492919Z","caller":"traceutil/trace.go:171","msg":"trace[1179393109] transaction","detail":"{read_only:false; response_revision:19088; number_of_response:1; }","duration":"506.932206ms","start":"2026-05-05T07:28:01.985956Z","end":"2026-05-05T07:28:02.492889Z","steps":["trace[1179393109] 'process raft request' (duration: 485.884896ms)","trace[1179393109] 'compare' (duration: 20.735704ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:28:02.492963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.710756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p5r2w\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-05-05T07:28:02.492998Z","caller":"traceutil/trace.go:171","msg":"trace[745100549] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p5r2w; range_end:; response_count:1; response_revision:19088; }","duration":"393.793278ms","start":"2026-05-05T07:28:02.099194Z","end":"2026-05-05T07:28:02.492987Z","steps":["trace[745100549] 'agreement among raft nodes before linearized reading' (duration: 393.592994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:02.493033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:02.099177Z","time spent":"393.847659ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-p5r2w\" "} {"level":"warn","ts":"2026-05-05T07:28:02.493073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:28:01.985942Z","time spent":"507.047808ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:28:02.493284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.941192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:28:02.493324Z","caller":"traceutil/trace.go:171","msg":"trace[910370762] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:19088; }","duration":"199.022304ms","start":"2026-05-05T07:28:02.29429Z","end":"2026-05-05T07:28:02.493313Z","steps":["trace[910370762] 'agreement among raft nodes before linearized reading' (duration: 198.958343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:28:02.49356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.892091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:28:02.493628Z","caller":"traceutil/trace.go:171","msg":"trace[1204813966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19088; }","duration":"278.989073ms","start":"2026-05-05T07:28:02.21461Z","end":"2026-05-05T07:28:02.493599Z","steps":["trace[1204813966] 'agreement among raft nodes before linearized reading' (duration: 278.913652ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:28:10.916551Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":20006,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:28:10.923436Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20006} {"level":"info","ts":"2026-05-05T07:28:10.923531Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15006} {"level":"info","ts":"2026-05-05T07:28:35.18649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16985} {"level":"info","ts":"2026-05-05T07:28:35.278768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16985,"took":"88.706455ms","hash":1154772450,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":33476608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T07:28:35.278825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1154772450,"revision":16985,"compact-revision":14622} {"level":"warn","ts":"2026-05-05T07:33:05.590468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.106933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:33:05.590556Z","caller":"traceutil/trace.go:171","msg":"trace[974259679] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:21437; }","duration":"102.214595ms","start":"2026-05-05T07:33:05.488321Z","end":"2026-05-05T07:33:05.590536Z","steps":["trace[974259679] 'count revisions from in-memory index tree' (duration: 102.03443ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:33:35.194173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19563} {"level":"info","ts":"2026-05-05T07:33:35.307851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19563,"took":"108.715164ms","hash":334983739,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":29163520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T07:33:35.307919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":334983739,"revision":19563,"compact-revision":16985} {"level":"warn","ts":"2026-05-05T07:34:08.030973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.593593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:34:08.031103Z","caller":"traceutil/trace.go:171","msg":"trace[1152069821] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:21757; }","duration":"284.773137ms","start":"2026-05-05T07:34:07.746309Z","end":"2026-05-05T07:34:08.031083Z","steps":["trace[1152069821] 'count revisions from in-memory index tree' (duration: 284.489631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.031489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.044355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:34:08.031652Z","caller":"traceutil/trace.go:171","msg":"trace[532849900] linearizableReadLoop","detail":"{readStateIndex:22498; appliedIndex:22497; }","duration":"131.9382ms","start":"2026-05-05T07:34:07.89969Z","end":"2026-05-05T07:34:08.031628Z","steps":["trace[532849900] 'read index received' (duration: 5.643001ms)","trace[532849900] 'applied index is now lower than readState.Index' (duration: 126.290789ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:34:08.031701Z","caller":"traceutil/trace.go:171","msg":"trace[262052812] transaction","detail":"{read_only:false; response_revision:21758; number_of_response:1; }","duration":"206.749459ms","start":"2026-05-05T07:34:07.824934Z","end":"2026-05-05T07:34:08.031683Z","steps":["trace[262052812] 'process raft request' (duration: 80.388129ms)","trace[262052812] 'compare' (duration: 125.808758ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:34:08.03191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.222036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T07:34:08.031963Z","caller":"traceutil/trace.go:171","msg":"trace[1404482840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21758; }","duration":"132.306998ms","start":"2026-05-05T07:34:07.899636Z","end":"2026-05-05T07:34:08.031943Z","steps":["trace[1404482840] 'agreement among raft nodes before linearized reading' (duration: 132.123544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.031959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.739886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T07:34:08.032023Z","caller":"traceutil/trace.go:171","msg":"trace[1856896955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21758; }","duration":"131.844728ms","start":"2026-05-05T07:34:07.900161Z","end":"2026-05-05T07:34:08.032005Z","steps":["trace[1856896955] 'agreement among raft nodes before linearized reading' (duration: 131.626324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.358823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.379077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:34:08.359047Z","caller":"traceutil/trace.go:171","msg":"trace[332536971] linearizableReadLoop","detail":"{readStateIndex:22500; appliedIndex:22498; }","duration":"177.28647ms","start":"2026-05-05T07:34:08.181739Z","end":"2026-05-05T07:34:08.359026Z","steps":["trace[332536971] 'read index received' (duration: 10.580536ms)","trace[332536971] 'applied index is now lower than readState.Index' (duration: 166.704794ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:34:08.359068Z","caller":"traceutil/trace.go:171","msg":"trace[1952593012] transaction","detail":"{read_only:false; response_revision:21759; number_of_response:1; }","duration":"322.336001ms","start":"2026-05-05T07:34:08.036693Z","end":"2026-05-05T07:34:08.359029Z","steps":["trace[1952593012] 'process raft request' (duration: 155.648417ms)","trace[1952593012] 'compare' (duration: 166.202603ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:34:08.359078Z","caller":"traceutil/trace.go:171","msg":"trace[1294140298] transaction","detail":"{read_only:false; response_revision:21760; number_of_response:1; }","duration":"322.339591ms","start":"2026-05-05T07:34:08.0367Z","end":"2026-05-05T07:34:08.35904Z","steps":["trace[1294140298] 'process raft request' (duration: 322.242148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.35917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:08.036668Z","time spent":"322.455894ms","remote":"127.0.0.1:56656","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-05-05T07:34:08.359258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:08.036676Z","time spent":"322.488825ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:34:08.359372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.461221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T07:34:08.359431Z","caller":"traceutil/trace.go:171","msg":"trace[801550712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21760; }","duration":"175.553573ms","start":"2026-05-05T07:34:08.183861Z","end":"2026-05-05T07:34:08.359414Z","steps":["trace[801550712] 'agreement among raft nodes before linearized reading' (duration: 175.312578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.359458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.31069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:34:08.359489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.964139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:34:08.359533Z","caller":"traceutil/trace.go:171","msg":"trace[211475049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21760; }","duration":"145.005851ms","start":"2026-05-05T07:34:08.214516Z","end":"2026-05-05T07:34:08.359522Z","steps":["trace[211475049] 'agreement among raft nodes before linearized reading' (duration: 144.939729ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:08.359565Z","caller":"traceutil/trace.go:171","msg":"trace[1446330414] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:21760; }","duration":"155.436863ms","start":"2026-05-05T07:34:08.204081Z","end":"2026-05-05T07:34:08.359518Z","steps":["trace[1446330414] 'agreement among raft nodes before linearized reading' (duration: 155.26384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.359379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.634918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-05T07:34:08.359751Z","caller":"traceutil/trace.go:171","msg":"trace[1913849200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21760; }","duration":"178.041456ms","start":"2026-05-05T07:34:08.181692Z","end":"2026-05-05T07:34:08.359733Z","steps":["trace[1913849200] 'agreement among raft nodes before linearized reading' (duration: 177.389712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.359933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.646427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-05T07:34:08.359985Z","caller":"traceutil/trace.go:171","msg":"trace[74484146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21760; }","duration":"162.737439ms","start":"2026-05-05T07:34:08.197236Z","end":"2026-05-05T07:34:08.359974Z","steps":["trace[74484146] 'agreement among raft nodes before linearized reading' (duration: 162.178967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:08.61219Z","caller":"traceutil/trace.go:171","msg":"trace[1692400638] transaction","detail":"{read_only:false; response_revision:21761; number_of_response:1; }","duration":"249.004693ms","start":"2026-05-05T07:34:08.363162Z","end":"2026-05-05T07:34:08.612167Z","steps":["trace[1692400638] 'process raft request' (duration: 248.809259ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:08.612547Z","caller":"traceutil/trace.go:171","msg":"trace[778623502] transaction","detail":"{read_only:false; response_revision:21762; number_of_response:1; }","duration":"248.742837ms","start":"2026-05-05T07:34:08.363771Z","end":"2026-05-05T07:34:08.612514Z","steps":["trace[778623502] 'process raft request' (duration: 248.353859ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:08.612616Z","caller":"traceutil/trace.go:171","msg":"trace[70648654] transaction","detail":"{read_only:false; response_revision:21764; number_of_response:1; }","duration":"248.41573ms","start":"2026-05-05T07:34:08.364189Z","end":"2026-05-05T07:34:08.612604Z","steps":["trace[70648654] 'process raft request' (duration: 248.304928ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:08.612557Z","caller":"traceutil/trace.go:171","msg":"trace[570926757] transaction","detail":"{read_only:false; response_revision:21763; number_of_response:1; }","duration":"248.763208ms","start":"2026-05-05T07:34:08.363771Z","end":"2026-05-05T07:34:08.612535Z","steps":["trace[570926757] 'process raft request' (duration: 248.633005ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:08.687694Z","caller":"traceutil/trace.go:171","msg":"trace[1809853431] linearizableReadLoop","detail":"{readStateIndex:22507; appliedIndex:22501; }","duration":"172.733573ms","start":"2026-05-05T07:34:08.514936Z","end":"2026-05-05T07:34:08.687669Z","steps":["trace[1809853431] 'read index received' (duration: 97.037805ms)","trace[1809853431] 'applied index is now lower than readState.Index' (duration: 75.694788ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:34:08.687868Z","caller":"traceutil/trace.go:171","msg":"trace[313898030] transaction","detail":"{read_only:false; response_revision:21765; number_of_response:1; }","duration":"269.364188ms","start":"2026-05-05T07:34:08.418489Z","end":"2026-05-05T07:34:08.687853Z","steps":["trace[313898030] 'process raft request' (duration: 259.818043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.687931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.953228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:34:08.688002Z","caller":"traceutil/trace.go:171","msg":"trace[908720569] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:21766; }","duration":"173.08172ms","start":"2026-05-05T07:34:08.514901Z","end":"2026-05-05T07:34:08.687982Z","steps":["trace[908720569] 'agreement among raft nodes before linearized reading' (duration: 172.882635ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:08.688308Z","caller":"traceutil/trace.go:171","msg":"trace[231839514] transaction","detail":"{read_only:false; response_revision:21766; number_of_response:1; }","duration":"247.119702ms","start":"2026-05-05T07:34:08.44117Z","end":"2026-05-05T07:34:08.68829Z","steps":["trace[231839514] 'process raft request' (duration: 246.401107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:08.951174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.179403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:34:08.951268Z","caller":"traceutil/trace.go:171","msg":"trace[369826016] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:21766; }","duration":"137.334606ms","start":"2026-05-05T07:34:08.813908Z","end":"2026-05-05T07:34:08.951243Z","steps":["trace[369826016] 'count revisions from in-memory index tree' (duration: 137.05579ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:37:59.69003Z","caller":"traceutil/trace.go:171","msg":"trace[1463822613] linearizableReadLoop","detail":"{readStateIndex:25014; appliedIndex:25013; }","duration":"102.791908ms","start":"2026-05-05T07:37:59.587207Z","end":"2026-05-05T07:37:59.689999Z","steps":["trace[1463822613] 'read index received' (duration: 102.41961ms)","trace[1463822613] 'applied index is now lower than readState.Index' (duration: 370.878µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:37:59.690458Z","caller":"traceutil/trace.go:171","msg":"trace[1948410826] transaction","detail":"{read_only:false; response_revision:24201; number_of_response:1; }","duration":"129.432808ms","start":"2026-05-05T07:37:59.561012Z","end":"2026-05-05T07:37:59.690445Z","steps":["trace[1948410826] 'process raft request' (duration: 128.701953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:37:59.690836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.613706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T07:37:59.690881Z","caller":"traceutil/trace.go:171","msg":"trace[1736815447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24201; }","duration":"103.670877ms","start":"2026-05-05T07:37:59.587199Z","end":"2026-05-05T07:37:59.69087Z","steps":["trace[1736815447] 'agreement among raft nodes before linearized reading' (duration: 103.527554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:38:35.200913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21592} {"level":"info","ts":"2026-05-05T07:38:35.311753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21592,"took":"106.899006ms","hash":2317549088,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":40050688,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-05-05T07:38:35.311838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2317549088,"revision":21592,"compact-revision":19563} {"level":"info","ts":"2026-05-05T07:41:25.056514Z","caller":"traceutil/trace.go:171","msg":"trace[1316504363] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"125.871962ms","start":"2026-05-05T07:41:24.930609Z","end":"2026-05-05T07:41:25.056481Z","steps":["trace[1316504363] 'process raft request' (duration: 125.713279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:25.24022Z","caller":"traceutil/trace.go:171","msg":"trace[153456507] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"173.771847ms","start":"2026-05-05T07:41:25.066414Z","end":"2026-05-05T07:41:25.240186Z","steps":["trace[153456507] 'process raft request' (duration: 146.096785ms)","trace[153456507] 'compare' (duration: 27.283404ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:25.530548Z","caller":"traceutil/trace.go:171","msg":"trace[1221024682] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"279.203062ms","start":"2026-05-05T07:41:25.251332Z","end":"2026-05-05T07:41:25.530535Z","steps":["trace[1221024682] 'process raft request' (duration: 278.991328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:25.530792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.836129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:25.53083Z","caller":"traceutil/trace.go:171","msg":"trace[181736877] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26275; }","duration":"224.91176ms","start":"2026-05-05T07:41:25.30591Z","end":"2026-05-05T07:41:25.530822Z","steps":["trace[181736877] 'agreement among raft nodes before linearized reading' (duration: 224.814138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:25.530474Z","caller":"traceutil/trace.go:171","msg":"trace[266789722] linearizableReadLoop","detail":"{readStateIndex:27143; appliedIndex:27142; }","duration":"224.505353ms","start":"2026-05-05T07:41:25.305947Z","end":"2026-05-05T07:41:25.530453Z","steps":["trace[266789722] 'read index received' (duration: 224.354189ms)","trace[266789722] 'applied index is now lower than readState.Index' (duration: 150.494µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:25.546157Z","caller":"traceutil/trace.go:171","msg":"trace[1034064995] transaction","detail":"{read_only:false; response_revision:26276; number_of_response:1; }","duration":"230.51944ms","start":"2026-05-05T07:41:25.315603Z","end":"2026-05-05T07:41:25.546122Z","steps":["trace[1034064995] 'process raft request' (duration: 230.350537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:25.546328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.000227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:41:25.546433Z","caller":"traceutil/trace.go:171","msg":"trace[516317033] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26277; }","duration":"199.159491ms","start":"2026-05-05T07:41:25.347255Z","end":"2026-05-05T07:41:25.546415Z","steps":["trace[516317033] 'agreement among raft nodes before linearized reading' (duration: 198.868764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:25.703614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.873352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:25.703704Z","caller":"traceutil/trace.go:171","msg":"trace[1253867852] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26277; }","duration":"126.000485ms","start":"2026-05-05T07:41:25.577686Z","end":"2026-05-05T07:41:25.703686Z","steps":["trace[1253867852] 'count revisions from in-memory index tree' (duration: 125.805491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:25.703722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.903464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T07:41:25.703801Z","caller":"traceutil/trace.go:171","msg":"trace[1064531859] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26277; }","duration":"155.013796ms","start":"2026-05-05T07:41:25.548768Z","end":"2026-05-05T07:41:25.703782Z","steps":["trace[1064531859] 'range keys from in-memory index tree' (duration: 154.745991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:25.844355Z","caller":"traceutil/trace.go:171","msg":"trace[1214916719] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"150.669993ms","start":"2026-05-05T07:41:25.693665Z","end":"2026-05-05T07:41:25.844335Z","steps":["trace[1214916719] 'process raft request' (duration: 150.55004ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:25.867762Z","caller":"traceutil/trace.go:171","msg":"trace[1492718778] linearizableReadLoop","detail":"{readStateIndex:27147; appliedIndex:27146; }","duration":"162.664329ms","start":"2026-05-05T07:41:25.705073Z","end":"2026-05-05T07:41:25.867738Z","steps":["trace[1492718778] 'read index received' (duration: 139.266839ms)","trace[1492718778] 'applied index is now lower than readState.Index' (duration: 23.39685ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:25.867797Z","caller":"traceutil/trace.go:171","msg":"trace[861166505] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"165.952529ms","start":"2026-05-05T07:41:25.701813Z","end":"2026-05-05T07:41:25.867765Z","steps":["trace[861166505] 'process raft request' (duration: 165.748055ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:25.86788Z","caller":"traceutil/trace.go:171","msg":"trace[761914873] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"106.791645ms","start":"2026-05-05T07:41:25.761078Z","end":"2026-05-05T07:41:25.867869Z","steps":["trace[761914873] 'process raft request' (duration: 106.621741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:25.868296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.217021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18572"} {"level":"info","ts":"2026-05-05T07:41:25.868343Z","caller":"traceutil/trace.go:171","msg":"trace[1512129624] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26280; }","duration":"163.303522ms","start":"2026-05-05T07:41:25.705027Z","end":"2026-05-05T07:41:25.868331Z","steps":["trace[1512129624] 'agreement among raft nodes before linearized reading' (duration: 163.18908ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:29.667819Z","caller":"traceutil/trace.go:171","msg":"trace[1480830144] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"118.012775ms","start":"2026-05-05T07:41:29.549777Z","end":"2026-05-05T07:41:29.66779Z","steps":["trace[1480830144] 'process raft request' (duration: 117.851731ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:29.907114Z","caller":"traceutil/trace.go:171","msg":"trace[95254688] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"351.267614ms","start":"2026-05-05T07:41:29.555816Z","end":"2026-05-05T07:41:29.907084Z","steps":["trace[95254688] 'process raft request' (duration: 350.749603ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:29.907268Z","caller":"traceutil/trace.go:171","msg":"trace[1343801587] linearizableReadLoop","detail":"{readStateIndex:27180; appliedIndex:27178; }","duration":"166.872499ms","start":"2026-05-05T07:41:29.740366Z","end":"2026-05-05T07:41:29.907238Z","steps":["trace[1343801587] 'read index received' (duration: 166.237486ms)","trace[1343801587] 'applied index is now lower than readState.Index' (duration: 632.683µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:29.907328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:29.555797Z","time spent":"351.399166ms","remote":"127.0.0.1:56656","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-05-05T07:41:29.907482Z","caller":"traceutil/trace.go:171","msg":"trace[1451792578] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"350.846415ms","start":"2026-05-05T07:41:29.556603Z","end":"2026-05-05T07:41:29.90745Z","steps":["trace[1451792578] 'process raft request' (duration: 350.372705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:29.907516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.126654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-05T07:41:29.907556Z","caller":"traceutil/trace.go:171","msg":"trace[408430057] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26311; }","duration":"167.210037ms","start":"2026-05-05T07:41:29.740334Z","end":"2026-05-05T07:41:29.907544Z","steps":["trace[408430057] 'agreement among raft nodes before linearized reading' (duration: 167.025283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:29.907606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:29.556599Z","time spent":"350.948087ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:41:30.516683Z","caller":"traceutil/trace.go:171","msg":"trace[720605573] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"270.406164ms","start":"2026-05-05T07:41:30.24624Z","end":"2026-05-05T07:41:30.516647Z","steps":["trace[720605573] 'process raft request' (duration: 270.154299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:30.735365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.858856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:41:30.735483Z","caller":"traceutil/trace.go:171","msg":"trace[1871751867] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"233.940094ms","start":"2026-05-05T07:41:30.501521Z","end":"2026-05-05T07:41:30.735461Z","steps":["trace[1871751867] 'process raft request' (duration: 104.848453ms)","trace[1871751867] 'compare' (duration: 128.660622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:31.027399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.845205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:41:31.027522Z","caller":"traceutil/trace.go:171","msg":"trace[163310815] linearizableReadLoop","detail":"{readStateIndex:27186; appliedIndex:27185; }","duration":"213.774943ms","start":"2026-05-05T07:41:30.81372Z","end":"2026-05-05T07:41:31.027495Z","steps":["trace[163310815] 'read index received' (duration: 70.57936ms)","trace[163310815] 'applied index is now lower than readState.Index' (duration: 143.194303ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:31.027631Z","caller":"traceutil/trace.go:171","msg":"trace[1846767011] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"215.769425ms","start":"2026-05-05T07:41:30.811845Z","end":"2026-05-05T07:41:31.027615Z","steps":["trace[1846767011] 'process raft request' (duration: 72.593533ms)","trace[1846767011] 'compare' (duration: 142.59057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:31.027874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.620548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T07:41:31.027964Z","caller":"traceutil/trace.go:171","msg":"trace[161237097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26317; }","duration":"205.71584ms","start":"2026-05-05T07:41:30.822223Z","end":"2026-05-05T07:41:31.027938Z","steps":["trace[161237097] 'agreement among raft nodes before linearized reading' (duration: 205.471665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.028067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.499983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T07:41:31.028112Z","caller":"traceutil/trace.go:171","msg":"trace[1854677410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26317; }","duration":"182.569315ms","start":"2026-05-05T07:41:30.845533Z","end":"2026-05-05T07:41:31.028102Z","steps":["trace[1854677410] 'agreement among raft nodes before linearized reading' (duration: 182.397271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.028091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.442119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T07:41:31.028174Z","caller":"traceutil/trace.go:171","msg":"trace[2085615824] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26317; }","duration":"144.558682ms","start":"2026-05-05T07:41:30.883596Z","end":"2026-05-05T07:41:31.028155Z","steps":["trace[2085615824] 'agreement among raft nodes before linearized reading' (duration: 144.326947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.028102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.372985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-05T07:41:31.028189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.695797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-05T07:41:31.028285Z","caller":"traceutil/trace.go:171","msg":"trace[2034284341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26317; }","duration":"214.5814ms","start":"2026-05-05T07:41:30.813671Z","end":"2026-05-05T07:41:31.028252Z","steps":["trace[2034284341] 'agreement among raft nodes before linearized reading' (duration: 214.270743ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:31.028309Z","caller":"traceutil/trace.go:171","msg":"trace[662004440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26317; }","duration":"182.863381ms","start":"2026-05-05T07:41:30.845419Z","end":"2026-05-05T07:41:31.028282Z","steps":["trace[662004440] 'agreement among raft nodes before linearized reading' (duration: 182.520354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.345897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.27635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:41:31.346414Z","caller":"traceutil/trace.go:171","msg":"trace[468486798] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"313.386904ms","start":"2026-05-05T07:41:31.032966Z","end":"2026-05-05T07:41:31.346353Z","steps":["trace[468486798] 'process raft request' (duration: 172.607473ms)","trace[468486798] 'compare' (duration: 139.921693ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:31.346483Z","caller":"traceutil/trace.go:171","msg":"trace[1550973713] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"313.452375ms","start":"2026-05-05T07:41:31.033004Z","end":"2026-05-05T07:41:31.346456Z","steps":["trace[1550973713] 'process raft request' (duration: 312.989585ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:31.346521Z","caller":"traceutil/trace.go:171","msg":"trace[2145844630] linearizableReadLoop","detail":"{readStateIndex:27190; appliedIndex:27187; }","duration":"300.729473ms","start":"2026-05-05T07:41:31.045772Z","end":"2026-05-05T07:41:31.346501Z","steps":["trace[2145844630] 'read index received' (duration: 159.808129ms)","trace[2145844630] 'applied index is now lower than readState.Index' (duration: 140.919814ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:31.346551Z","caller":"traceutil/trace.go:171","msg":"trace[1496360193] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"312.31832ms","start":"2026-05-05T07:41:31.034211Z","end":"2026-05-05T07:41:31.346529Z","steps":["trace[1496360193] 'process raft request' (duration: 311.84069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.346677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:31.03419Z","time spent":"312.413172ms","remote":"127.0.0.1:56656","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-05-05T07:41:31.34689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.543407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:31.346932Z","caller":"traceutil/trace.go:171","msg":"trace[741881037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26321; }","duration":"184.63765ms","start":"2026-05-05T07:41:31.162282Z","end":"2026-05-05T07:41:31.34692Z","steps":["trace[741881037] 'agreement among raft nodes before linearized reading' (duration: 184.538067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.347133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.541228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:41:31.347341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:31.032975Z","time spent":"314.336894ms","remote":"127.0.0.1:56656","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-05-05T07:41:31.347227Z","caller":"traceutil/trace.go:171","msg":"trace[1180552630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26321; }","duration":"113.701701ms","start":"2026-05-05T07:41:31.233501Z","end":"2026-05-05T07:41:31.347203Z","steps":["trace[1180552630] 'agreement among raft nodes before linearized reading' (duration: 113.290353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.347641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:31.032944Z","time spent":"313.550588ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:41:31.347722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.881199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T07:41:31.347766Z","caller":"traceutil/trace.go:171","msg":"trace[2117551756] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26321; }","duration":"267.956921ms","start":"2026-05-05T07:41:31.079797Z","end":"2026-05-05T07:41:31.347754Z","steps":["trace[2117551756] 'agreement among raft nodes before linearized reading' (duration: 266.808037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.347825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.01844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18572"} {"level":"info","ts":"2026-05-05T07:41:31.347921Z","caller":"traceutil/trace.go:171","msg":"trace[768596281] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26321; }","duration":"302.164042ms","start":"2026-05-05T07:41:31.04574Z","end":"2026-05-05T07:41:31.347904Z","steps":["trace[768596281] 'agreement among raft nodes before linearized reading' (duration: 300.956147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.348044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:31.045723Z","time spent":"302.273606ms","remote":"127.0.0.1:56568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18595,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T07:41:31.624961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.180847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:41:31.625296Z","caller":"traceutil/trace.go:171","msg":"trace[1183404541] linearizableReadLoop","detail":"{readStateIndex:27191; appliedIndex:27190; }","duration":"246.022392ms","start":"2026-05-05T07:41:31.379256Z","end":"2026-05-05T07:41:31.625279Z","steps":["trace[1183404541] 'read index received' (duration: 113.428896ms)","trace[1183404541] 'applied index is now lower than readState.Index' (duration: 132.591816ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:31.625335Z","caller":"traceutil/trace.go:171","msg":"trace[680470180] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"270.238641ms","start":"2026-05-05T07:41:31.355066Z","end":"2026-05-05T07:41:31.625305Z","steps":["trace[680470180] 'process raft request' (duration: 137.594093ms)","trace[680470180] 'compare' (duration: 131.907701ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:31.625492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.230747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-05T07:41:31.625531Z","caller":"traceutil/trace.go:171","msg":"trace[415148848] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26322; }","duration":"246.314948ms","start":"2026-05-05T07:41:31.379207Z","end":"2026-05-05T07:41:31.625522Z","steps":["trace[415148848] 'agreement among raft nodes before linearized reading' (duration: 246.160105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:31.625536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.780036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:31.625652Z","caller":"traceutil/trace.go:171","msg":"trace[808211199] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26322; }","duration":"215.95495ms","start":"2026-05-05T07:41:31.409672Z","end":"2026-05-05T07:41:31.625627Z","steps":["trace[808211199] 'agreement among raft nodes before linearized reading' (duration: 215.752605ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:32.13831Z","caller":"traceutil/trace.go:171","msg":"trace[302988635] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"215.745705ms","start":"2026-05-05T07:41:31.922534Z","end":"2026-05-05T07:41:32.13828Z","steps":["trace[302988635] 'process raft request' (duration: 215.345665ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:32.138349Z","caller":"traceutil/trace.go:171","msg":"trace[610359134] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"215.641772ms","start":"2026-05-05T07:41:31.922683Z","end":"2026-05-05T07:41:32.138324Z","steps":["trace[610359134] 'process raft request' (duration: 215.591161ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:32.13835Z","caller":"traceutil/trace.go:171","msg":"trace[1884323680] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"215.683843ms","start":"2026-05-05T07:41:31.922638Z","end":"2026-05-05T07:41:32.138322Z","steps":["trace[1884323680] 'process raft request' (duration: 215.53596ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:32.440907Z","caller":"traceutil/trace.go:171","msg":"trace[1900457128] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"115.731776ms","start":"2026-05-05T07:41:32.325148Z","end":"2026-05-05T07:41:32.44088Z","steps":["trace[1900457128] 'process raft request' (duration: 115.556143ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:32.831618Z","caller":"traceutil/trace.go:171","msg":"trace[2060415081] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"184.558068ms","start":"2026-05-05T07:41:32.647032Z","end":"2026-05-05T07:41:32.83159Z","steps":["trace[2060415081] 'process raft request' (duration: 184.361054ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.161597Z","caller":"traceutil/trace.go:171","msg":"trace[157299777] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"513.074595ms","start":"2026-05-05T07:41:32.648481Z","end":"2026-05-05T07:41:33.161556Z","steps":["trace[157299777] 'process raft request' (duration: 512.757708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.161717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.45982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:33.161705Z","caller":"traceutil/trace.go:171","msg":"trace[283218344] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"494.457777ms","start":"2026-05-05T07:41:32.667221Z","end":"2026-05-05T07:41:33.161679Z","steps":["trace[283218344] 'process raft request' (duration: 494.239533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.161742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:32.648465Z","time spent":"513.204177ms","remote":"127.0.0.1:56692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:41:33.161762Z","caller":"traceutil/trace.go:171","msg":"trace[1339215498] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26333; }","duration":"338.561942ms","start":"2026-05-05T07:41:32.823183Z","end":"2026-05-05T07:41:33.161745Z","steps":["trace[1339215498] 'agreement among raft nodes before linearized reading' (duration: 338.45677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.161793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:32.823161Z","time spent":"338.624534ms","remote":"127.0.0.1:46378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:41:33.161834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:32.667203Z","time spent":"494.557419ms","remote":"127.0.0.1:47966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7516,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:41:33.161602Z","caller":"traceutil/trace.go:171","msg":"trace[1745699036] linearizableReadLoop","detail":"{readStateIndex:27202; appliedIndex:27198; }","duration":"338.340387ms","start":"2026-05-05T07:41:32.823243Z","end":"2026-05-05T07:41:33.161583Z","steps":["trace[1745699036] 'read index received' (duration: 8.194315ms)","trace[1745699036] 'applied index is now lower than readState.Index' (duration: 330.145222ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:33.16207Z","caller":"traceutil/trace.go:171","msg":"trace[2029809036] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"420.932714ms","start":"2026-05-05T07:41:32.741127Z","end":"2026-05-05T07:41:33.16206Z","steps":["trace[2029809036] 'process raft request' (duration: 420.410832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.162141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:32.741084Z","time spent":"421.009055ms","remote":"127.0.0.1:56656","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-05-05T07:41:33.27745Z","caller":"traceutil/trace.go:171","msg":"trace[8649423] linearizableReadLoop","detail":"{readStateIndex:27203; appliedIndex:27202; }","duration":"115.47734ms","start":"2026-05-05T07:41:33.161948Z","end":"2026-05-05T07:41:33.277425Z","steps":["trace[8649423] 'read index received' (duration: 111.24883ms)","trace[8649423] 'applied index is now lower than readState.Index' (duration: 4.22753ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:33.277637Z","caller":"traceutil/trace.go:171","msg":"trace[1133574476] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"175.901472ms","start":"2026-05-05T07:41:33.101705Z","end":"2026-05-05T07:41:33.277606Z","steps":["trace[1133574476] 'process raft request' (duration: 171.56163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.277683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.667913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-05T07:41:33.27819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.81656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-05T07:41:33.278212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.819183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18ac9b7356a46f05\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-05-05T07:41:33.278238Z","caller":"traceutil/trace.go:171","msg":"trace[1102709862] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26334; }","duration":"102.859381ms","start":"2026-05-05T07:41:33.175363Z","end":"2026-05-05T07:41:33.278222Z","steps":["trace[1102709862] 'agreement among raft nodes before linearized reading' (duration: 102.699346ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.278154Z","caller":"traceutil/trace.go:171","msg":"trace[282901858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26334; }","duration":"245.174375ms","start":"2026-05-05T07:41:33.032954Z","end":"2026-05-05T07:41:33.278128Z","steps":["trace[282901858] 'agreement among raft nodes before linearized reading' (duration: 244.612072ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.278267Z","caller":"traceutil/trace.go:171","msg":"trace[756256159] range","detail":"{range_begin:/registry/events/openstack/container-infra.18ac9b7356a46f05; range_end:; response_count:1; response_revision:26334; }","duration":"442.986766ms","start":"2026-05-05T07:41:32.835264Z","end":"2026-05-05T07:41:33.278251Z","steps":["trace[756256159] 'agreement among raft nodes before linearized reading' (duration: 442.647288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.278484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:32.835246Z","time spent":"443.220102ms","remote":"127.0.0.1:56478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":718,"request content":"key:\"/registry/events/openstack/container-infra.18ac9b7356a46f05\" "} {"level":"warn","ts":"2026-05-05T07:41:33.278808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.36939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18572"} {"level":"info","ts":"2026-05-05T07:41:33.278856Z","caller":"traceutil/trace.go:171","msg":"trace[2077284097] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26334; }","duration":"109.442031ms","start":"2026-05-05T07:41:33.169402Z","end":"2026-05-05T07:41:33.278844Z","steps":["trace[2077284097] 'agreement among raft nodes before linearized reading' (duration: 108.569011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.277826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.207874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:33.279035Z","caller":"traceutil/trace.go:171","msg":"trace[990183964] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26334; }","duration":"325.40586ms","start":"2026-05-05T07:41:32.953605Z","end":"2026-05-05T07:41:33.279011Z","steps":["trace[990183964] 'agreement among raft nodes before linearized reading' (duration: 324.191564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.277834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.497089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:33.279108Z","caller":"traceutil/trace.go:171","msg":"trace[1623287814] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26334; }","duration":"309.827878ms","start":"2026-05-05T07:41:32.969263Z","end":"2026-05-05T07:41:33.279091Z","steps":["trace[1623287814] 'agreement among raft nodes before linearized reading' (duration: 308.431987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.279164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:32.969239Z","time spent":"309.906219ms","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:41:33.277764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.081029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T07:41:33.27931Z","caller":"traceutil/trace.go:171","msg":"trace[640916007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26334; }","duration":"230.663164ms","start":"2026-05-05T07:41:33.048609Z","end":"2026-05-05T07:41:33.279272Z","steps":["trace[640916007] 'agreement among raft nodes before linearized reading' (duration: 229.10038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.279631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:32.953582Z","time spent":"325.497302ms","remote":"127.0.0.1:46500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"info","ts":"2026-05-05T07:41:33.500976Z","caller":"traceutil/trace.go:171","msg":"trace[1929284981] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"217.664576ms","start":"2026-05-05T07:41:33.283265Z","end":"2026-05-05T07:41:33.50093Z","steps":["trace[1929284981] 'process raft request' (duration: 204.183338ms)","trace[1929284981] 'compare' (duration: 13.057259ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:33.501369Z","caller":"traceutil/trace.go:171","msg":"trace[1514165728] linearizableReadLoop","detail":"{readStateIndex:27206; appliedIndex:27203; }","duration":"188.779418ms","start":"2026-05-05T07:41:33.312571Z","end":"2026-05-05T07:41:33.50135Z","steps":["trace[1514165728] 'read index received' (duration: 14.097082ms)","trace[1514165728] 'applied index is now lower than readState.Index' (duration: 174.680646ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:33.501389Z","caller":"traceutil/trace.go:171","msg":"trace[318079110] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"217.990453ms","start":"2026-05-05T07:41:33.283369Z","end":"2026-05-05T07:41:33.501359Z","steps":["trace[318079110] 'process raft request' (duration: 217.38577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.50154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.945611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:41:33.501583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.853633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18ac9b73ef0d2df4\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-05-05T07:41:33.50161Z","caller":"traceutil/trace.go:171","msg":"trace[1034078030] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18ac9b73ef0d2df4; range_end:; response_count:1; response_revision:26337; }","duration":"169.928874ms","start":"2026-05-05T07:41:33.331673Z","end":"2026-05-05T07:41:33.501602Z","steps":["trace[1034078030] 'agreement among raft nodes before linearized reading' (duration: 169.795481ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.5016Z","caller":"traceutil/trace.go:171","msg":"trace[846180697] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:26337; }","duration":"189.062864ms","start":"2026-05-05T07:41:33.312519Z","end":"2026-05-05T07:41:33.501582Z","steps":["trace[846180697] 'agreement among raft nodes before linearized reading' (duration: 188.933071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.501655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.358765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T07:41:33.501706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.921157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T07:41:33.50173Z","caller":"traceutil/trace.go:171","msg":"trace[351727790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26337; }","duration":"149.455016ms","start":"2026-05-05T07:41:33.352255Z","end":"2026-05-05T07:41:33.50171Z","steps":["trace[351727790] 'agreement among raft nodes before linearized reading' (duration: 149.236122ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.501741Z","caller":"traceutil/trace.go:171","msg":"trace[2077540721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26337; }","duration":"150.018239ms","start":"2026-05-05T07:41:33.351712Z","end":"2026-05-05T07:41:33.50173Z","steps":["trace[2077540721] 'agreement among raft nodes before linearized reading' (duration: 149.863735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.501809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.494054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-05T07:41:33.501845Z","caller":"traceutil/trace.go:171","msg":"trace[2005211584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26337; }","duration":"147.533795ms","start":"2026-05-05T07:41:33.354301Z","end":"2026-05-05T07:41:33.501835Z","steps":["trace[2005211584] 'agreement among raft nodes before linearized reading' (duration: 147.389082ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.650528Z","caller":"traceutil/trace.go:171","msg":"trace[143178116] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"144.48617ms","start":"2026-05-05T07:41:33.506016Z","end":"2026-05-05T07:41:33.650502Z","steps":["trace[143178116] 'process raft request' (duration: 140.767071ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.650827Z","caller":"traceutil/trace.go:171","msg":"trace[1034380478] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"144.649974ms","start":"2026-05-05T07:41:33.50614Z","end":"2026-05-05T07:41:33.65079Z","steps":["trace[1034380478] 'process raft request' (duration: 144.296576ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:33.650856Z","caller":"traceutil/trace.go:171","msg":"trace[1512853955] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"144.587163ms","start":"2026-05-05T07:41:33.506247Z","end":"2026-05-05T07:41:33.650834Z","steps":["trace[1512853955] 'process raft request' (duration: 144.418009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:33.788021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.238295ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T07:41:33.788129Z","caller":"traceutil/trace.go:171","msg":"trace[609596078] linearizableReadLoop","detail":"{readStateIndex:27211; appliedIndex:27207; }","duration":"157.660173ms","start":"2026-05-05T07:41:33.630452Z","end":"2026-05-05T07:41:33.788112Z","steps":["trace[609596078] 'read index received' (duration: 16.285999ms)","trace[609596078] 'applied index is now lower than readState.Index' (duration: 141.372884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:33.788286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.821997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T07:41:33.78833Z","caller":"traceutil/trace.go:171","msg":"trace[69723336] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26341; }","duration":"157.918029ms","start":"2026-05-05T07:41:33.630402Z","end":"2026-05-05T07:41:33.78832Z","steps":["trace[69723336] 'agreement among raft nodes before linearized reading' (duration: 157.754545ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:34.063596Z","caller":"traceutil/trace.go:171","msg":"trace[827842821] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"349.789692ms","start":"2026-05-05T07:41:33.713775Z","end":"2026-05-05T07:41:34.063564Z","steps":["trace[827842821] 'process raft request' (duration: 349.586328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:34.063744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:33.713751Z","time spent":"349.914875ms","remote":"127.0.0.1:56656","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-05-05T07:41:34.282822Z","caller":"traceutil/trace.go:171","msg":"trace[1362771326] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"490.44082ms","start":"2026-05-05T07:41:33.792356Z","end":"2026-05-05T07:41:34.282797Z","steps":["trace[1362771326] 'process raft request' (duration: 397.794159ms)","trace[1362771326] 'compare' (duration: 92.488648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:34.282946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:33.792334Z","time spent":"490.546413ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:41:34.283039Z","caller":"traceutil/trace.go:171","msg":"trace[291726334] linearizableReadLoop","detail":"{readStateIndex:27215; appliedIndex:27212; }","duration":"198.915825ms","start":"2026-05-05T07:41:34.084106Z","end":"2026-05-05T07:41:34.283022Z","steps":["trace[291726334] 'read index received' (duration: 106.036689ms)","trace[291726334] 'applied index is now lower than readState.Index' (duration: 92.878166ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:34.283098Z","caller":"traceutil/trace.go:171","msg":"trace[754769559] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"284.483655ms","start":"2026-05-05T07:41:33.998569Z","end":"2026-05-05T07:41:34.283053Z","steps":["trace[754769559] 'process raft request' (duration: 284.182568ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:34.28313Z","caller":"traceutil/trace.go:171","msg":"trace[1342392593] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"283.381221ms","start":"2026-05-05T07:41:33.999741Z","end":"2026-05-05T07:41:34.283122Z","steps":["trace[1342392593] 'process raft request' (duration: 283.193027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:34.283263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.15313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:41:34.283286Z","caller":"traceutil/trace.go:171","msg":"trace[838570956] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:26345; }","duration":"199.229051ms","start":"2026-05-05T07:41:34.08405Z","end":"2026-05-05T07:41:34.283279Z","steps":["trace[838570956] 'agreement among raft nodes before linearized reading' (duration: 199.17159ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:34.310519Z","caller":"traceutil/trace.go:171","msg":"trace[1860804988] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"166.159804ms","start":"2026-05-05T07:41:34.144333Z","end":"2026-05-05T07:41:34.310493Z","steps":["trace[1860804988] 'process raft request' (duration: 165.9717ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:34.310559Z","caller":"traceutil/trace.go:171","msg":"trace[1945835975] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"166.541482ms","start":"2026-05-05T07:41:34.14396Z","end":"2026-05-05T07:41:34.310501Z","steps":["trace[1945835975] 'process raft request' (duration: 166.264446ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:34.310611Z","caller":"traceutil/trace.go:171","msg":"trace[936394352] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"166.069722ms","start":"2026-05-05T07:41:34.144508Z","end":"2026-05-05T07:41:34.310578Z","steps":["trace[936394352] 'process raft request' (duration: 165.842647ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:44.364412Z","caller":"traceutil/trace.go:171","msg":"trace[1942130379] linearizableReadLoop","detail":"{readStateIndex:27288; appliedIndex:27287; }","duration":"122.212545ms","start":"2026-05-05T07:41:44.242169Z","end":"2026-05-05T07:41:44.364382Z","steps":["trace[1942130379] 'read index received' (duration: 122.174524ms)","trace[1942130379] 'applied index is now lower than readState.Index' (duration: 36.801µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:44.364431Z","caller":"traceutil/trace.go:171","msg":"trace[668994834] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"266.632453ms","start":"2026-05-05T07:41:44.097765Z","end":"2026-05-05T07:41:44.364398Z","steps":["trace[668994834] 'process raft request' (duration: 266.415919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:44.364606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.400989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:44.36472Z","caller":"traceutil/trace.go:171","msg":"trace[1804431485] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:26416; }","duration":"122.579362ms","start":"2026-05-05T07:41:44.242115Z","end":"2026-05-05T07:41:44.364695Z","steps":["trace[1804431485] 'agreement among raft nodes before linearized reading' (duration: 122.378138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:44.865525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052469322567662,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T07:41:44.924364Z","caller":"traceutil/trace.go:171","msg":"trace[1269835923] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"601.035227ms","start":"2026-05-05T07:41:44.323287Z","end":"2026-05-05T07:41:44.924322Z","steps":["trace[1269835923] 'process raft request' (duration: 569.793169ms)","trace[1269835923] 'compare' (duration: 30.523543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:44.924573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:44.323255Z","time spent":"601.20385ms","remote":"127.0.0.1:56656","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-05-05T07:41:44.924689Z","caller":"traceutil/trace.go:171","msg":"trace[314858647] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"598.225846ms","start":"2026-05-05T07:41:44.326429Z","end":"2026-05-05T07:41:44.924655Z","steps":["trace[314858647] 'process raft request' (duration: 597.770747ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:44.924754Z","caller":"traceutil/trace.go:171","msg":"trace[180779782] linearizableReadLoop","detail":"{readStateIndex:27293; appliedIndex:27288; }","duration":"560.231184ms","start":"2026-05-05T07:41:44.364499Z","end":"2026-05-05T07:41:44.924731Z","steps":["trace[180779782] 'read index received' (duration: 528.534416ms)","trace[180779782] 'applied index is now lower than readState.Index' (duration: 31.695728ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:44.924869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:44.326406Z","time spent":"598.3583ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:41:44.924903Z","caller":"traceutil/trace.go:171","msg":"trace[2054774453] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"580.463076ms","start":"2026-05-05T07:41:44.344425Z","end":"2026-05-05T07:41:44.924888Z","steps":["trace[2054774453] 'process raft request' (duration: 580.262672ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:44.924916Z","caller":"traceutil/trace.go:171","msg":"trace[1475755050] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"580.553488ms","start":"2026-05-05T07:41:44.34433Z","end":"2026-05-05T07:41:44.924883Z","steps":["trace[1475755050] 'process raft request' (duration: 580.312513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:44.925009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:44.34438Z","time spent":"580.549078ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:41:44.925069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:44.34431Z","time spent":"580.68194ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:41:44.925284Z","caller":"traceutil/trace.go:171","msg":"trace[1284314346] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"582.446879ms","start":"2026-05-05T07:41:44.342815Z","end":"2026-05-05T07:41:44.925262Z","steps":["trace[1284314346] 'process raft request' (duration: 581.692083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:44.925408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:44.342797Z","time spent":"582.542271ms","remote":"127.0.0.1:56656","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-05-05T07:41:44.925707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.475806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-05T07:41:44.925713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.473462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:41:44.925821Z","caller":"traceutil/trace.go:171","msg":"trace[1860743798] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26421; }","duration":"584.633915ms","start":"2026-05-05T07:41:44.341159Z","end":"2026-05-05T07:41:44.925793Z","steps":["trace[1860743798] 'agreement among raft nodes before linearized reading' (duration: 584.302828ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:44.925794Z","caller":"traceutil/trace.go:171","msg":"trace[673753786] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:26421; }","duration":"594.624689ms","start":"2026-05-05T07:41:44.331148Z","end":"2026-05-05T07:41:44.925773Z","steps":["trace[673753786] 'agreement among raft nodes before linearized reading' (duration: 594.272872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:44.9259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:44.341124Z","time spent":"584.759567ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T07:41:44.926386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:44.331104Z","time spent":"594.789272ms","remote":"127.0.0.1:56752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:41:45.486817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.762343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:45.486908Z","caller":"traceutil/trace.go:171","msg":"trace[1484272957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26422; }","duration":"271.920006ms","start":"2026-05-05T07:41:45.214969Z","end":"2026-05-05T07:41:45.486889Z","steps":["trace[1484272957] 'range keys from in-memory index tree' (duration: 271.665441ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:45.765001Z","caller":"traceutil/trace.go:171","msg":"trace[2144924445] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"541.160345ms","start":"2026-05-05T07:41:45.223809Z","end":"2026-05-05T07:41:45.76497Z","steps":["trace[2144924445] 'process raft request' (duration: 540.92702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:45.765179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.223786Z","time spent":"541.302369ms","remote":"127.0.0.1:56656","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-05-05T07:41:45.765188Z","caller":"traceutil/trace.go:171","msg":"trace[1906552075] linearizableReadLoop","detail":"{readStateIndex:27295; appliedIndex:27295; }","duration":"276.617547ms","start":"2026-05-05T07:41:45.488527Z","end":"2026-05-05T07:41:45.765145Z","steps":["trace[1906552075] 'read index received' (duration: 276.601767ms)","trace[1906552075] 'applied index is now lower than readState.Index' (duration: 12.83µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:45.765335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.778201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:45.765465Z","caller":"traceutil/trace.go:171","msg":"trace[987915300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26423; }","duration":"276.965194ms","start":"2026-05-05T07:41:45.48848Z","end":"2026-05-05T07:41:45.765445Z","steps":["trace[987915300] 'agreement among raft nodes before linearized reading' (duration: 276.75532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:46.120083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.858538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:41:46.120198Z","caller":"traceutil/trace.go:171","msg":"trace[365372373] linearizableReadLoop","detail":"{readStateIndex:27296; appliedIndex:27295; }","duration":"354.914343ms","start":"2026-05-05T07:41:45.765265Z","end":"2026-05-05T07:41:46.120179Z","steps":["trace[365372373] 'read index received' (duration: 37.84995ms)","trace[365372373] 'applied index is now lower than readState.Index' (duration: 317.062713ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:46.120339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.553137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:46.120371Z","caller":"traceutil/trace.go:171","msg":"trace[2004295106] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26424; }","duration":"399.584827ms","start":"2026-05-05T07:41:45.720773Z","end":"2026-05-05T07:41:46.120358Z","steps":["trace[2004295106] 'agreement among raft nodes before linearized reading' (duration: 399.518786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:46.120353Z","caller":"traceutil/trace.go:171","msg":"trace[158090002] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"584.802849ms","start":"2026-05-05T07:41:45.535512Z","end":"2026-05-05T07:41:46.120315Z","steps":["trace[158090002] 'process raft request' (duration: 267.583564ms)","trace[158090002] 'compare' (duration: 316.48634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:46.120407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.720733Z","time spent":"399.664679ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:41:46.120457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.727208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T07:41:46.120461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.82868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-05T07:41:46.120539Z","caller":"traceutil/trace.go:171","msg":"trace[346985879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26424; }","duration":"397.858321ms","start":"2026-05-05T07:41:45.722662Z","end":"2026-05-05T07:41:46.120521Z","steps":["trace[346985879] 'agreement among raft nodes before linearized reading' (duration: 397.651166ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:46.120568Z","caller":"traceutil/trace.go:171","msg":"trace[172909281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26424; }","duration":"397.968483ms","start":"2026-05-05T07:41:45.722577Z","end":"2026-05-05T07:41:46.120545Z","steps":["trace[172909281] 'agreement among raft nodes before linearized reading' (duration: 397.645297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:46.120585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.722637Z","time spent":"397.939152ms","remote":"127.0.0.1:56656","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-05-05T07:41:46.120623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.73974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-05T07:41:46.120621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.535495Z","time spent":"584.919753ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:41:46.120622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.762605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T07:41:46.120692Z","caller":"traceutil/trace.go:171","msg":"trace[1820179918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26424; }","duration":"557.831082ms","start":"2026-05-05T07:41:45.562843Z","end":"2026-05-05T07:41:46.120674Z","steps":["trace[1820179918] 'agreement among raft nodes before linearized reading' (duration: 557.691699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:46.120713Z","caller":"traceutil/trace.go:171","msg":"trace[83793883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26424; }","duration":"402.908048ms","start":"2026-05-05T07:41:45.717783Z","end":"2026-05-05T07:41:46.120691Z","steps":["trace[83793883] 'agreement among raft nodes before linearized reading' (duration: 402.648533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:46.120746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.717762Z","time spent":"402.97832ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T07:41:46.120744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.562824Z","time spent":"557.907993ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T07:41:46.120637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.722563Z","time spent":"398.056885ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T07:41:46.120495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.105564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T07:41:46.120915Z","caller":"traceutil/trace.go:171","msg":"trace[305329978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26424; }","duration":"562.542563ms","start":"2026-05-05T07:41:45.558332Z","end":"2026-05-05T07:41:46.120874Z","steps":["trace[305329978] 'agreement among raft nodes before linearized reading' (duration: 561.988782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:46.12102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:45.558307Z","time spent":"562.677897ms","remote":"127.0.0.1:56656","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-05-05T07:41:46.759883Z","caller":"traceutil/trace.go:171","msg":"trace[1148156860] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"634.002351ms","start":"2026-05-05T07:41:46.125847Z","end":"2026-05-05T07:41:46.759849Z","steps":["trace[1148156860] 'process raft request' (duration: 593.838802ms)","trace[1148156860] 'compare' (duration: 39.936204ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:46.75995Z","caller":"traceutil/trace.go:171","msg":"trace[164092162] linearizableReadLoop","detail":"{readStateIndex:27301; appliedIndex:27297; }","duration":"484.894042ms","start":"2026-05-05T07:41:46.275029Z","end":"2026-05-05T07:41:46.759923Z","steps":["trace[164092162] 'read index received' (duration: 444.674031ms)","trace[164092162] 'applied index is now lower than readState.Index' (duration: 40.218911ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:46.759943Z","caller":"traceutil/trace.go:171","msg":"trace[860824274] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"634.019302ms","start":"2026-05-05T07:41:46.125876Z","end":"2026-05-05T07:41:46.759895Z","steps":["trace[860824274] 'process raft request' (duration: 633.872459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:46.76003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.125814Z","time spent":"634.135644ms","remote":"127.0.0.1:56656","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-05-05T07:41:46.760087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.036725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:41:46.760128Z","caller":"traceutil/trace.go:171","msg":"trace[961419272] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26429; }","duration":"485.157898ms","start":"2026-05-05T07:41:46.274957Z","end":"2026-05-05T07:41:46.760115Z","steps":["trace[961419272] 'agreement among raft nodes before linearized reading' (duration: 485.066916ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:46.760132Z","caller":"traceutil/trace.go:171","msg":"trace[178147273] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"634.148434ms","start":"2026-05-05T07:41:46.125954Z","end":"2026-05-05T07:41:46.760103Z","steps":["trace[178147273] 'process raft request' (duration: 633.834788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:46.760174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.274929Z","time spent":"485.23069ms","remote":"127.0.0.1:51168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:41:46.760218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.125748Z","time spent":"634.314849ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:41:46.760285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.125926Z","time spent":"634.278747ms","remote":"127.0.0.1:56656","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-05-05T07:41:46.760423Z","caller":"traceutil/trace.go:171","msg":"trace[1919607387] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"634.419331ms","start":"2026-05-05T07:41:46.125983Z","end":"2026-05-05T07:41:46.760403Z","steps":["trace[1919607387] 'process raft request' (duration: 633.886879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:46.760521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.125956Z","time spent":"634.515452ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:41:47.202964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.317649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:41:47.203035Z","caller":"traceutil/trace.go:171","msg":"trace[1138902395] linearizableReadLoop","detail":"{readStateIndex:27302; appliedIndex:27301; }","duration":"430.000197ms","start":"2026-05-05T07:41:46.773024Z","end":"2026-05-05T07:41:47.203024Z","steps":["trace[1138902395] 'read index received' (duration: 218.596966ms)","trace[1138902395] 'applied index is now lower than readState.Index' (duration: 211.402361ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:47.203066Z","caller":"traceutil/trace.go:171","msg":"trace[1059719751] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"833.469008ms","start":"2026-05-05T07:41:46.369576Z","end":"2026-05-05T07:41:47.203045Z","steps":["trace[1059719751] 'process raft request' (duration: 622.004425ms)","trace[1059719751] 'compare' (duration: 211.164017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:47.203104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.083099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:47.20312Z","caller":"traceutil/trace.go:171","msg":"trace[1263205096] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26430; }","duration":"430.12767ms","start":"2026-05-05T07:41:46.772986Z","end":"2026-05-05T07:41:47.203113Z","steps":["trace[1263205096] 'agreement among raft nodes before linearized reading' (duration: 430.088119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.203141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.772967Z","time spent":"430.166301ms","remote":"127.0.0.1:46424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:41:47.203165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.369547Z","time spent":"833.559769ms","remote":"127.0.0.1:56656","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-05-05T07:41:47.205379Z","caller":"traceutil/trace.go:171","msg":"trace[1026360834] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"273.673434ms","start":"2026-05-05T07:41:46.931671Z","end":"2026-05-05T07:41:47.205344Z","steps":["trace[1026360834] 'process raft request' (duration: 273.403529ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:47.205408Z","caller":"traceutil/trace.go:171","msg":"trace[484131198] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"273.628443ms","start":"2026-05-05T07:41:46.931744Z","end":"2026-05-05T07:41:47.205373Z","steps":["trace[484131198] 'process raft request' (duration: 273.515951ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:47.205681Z","caller":"traceutil/trace.go:171","msg":"trace[1950145696] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"273.828807ms","start":"2026-05-05T07:41:46.931838Z","end":"2026-05-05T07:41:47.205667Z","steps":["trace[1950145696] 'process raft request' (duration: 273.47656ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:47.205798Z","caller":"traceutil/trace.go:171","msg":"trace[2141093427] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"273.91623ms","start":"2026-05-05T07:41:46.931869Z","end":"2026-05-05T07:41:47.205785Z","steps":["trace[2141093427] 'process raft request' (duration: 273.702975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.205851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.612317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-05T07:41:47.205911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.886578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:41:47.205935Z","caller":"traceutil/trace.go:171","msg":"trace[1905292128] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26435; }","duration":"100.94598ms","start":"2026-05-05T07:41:47.104981Z","end":"2026-05-05T07:41:47.205927Z","steps":["trace[1905292128] 'agreement among raft nodes before linearized reading' (duration: 100.898279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:47.20592Z","caller":"traceutil/trace.go:171","msg":"trace[117270121] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26435; }","duration":"391.74333ms","start":"2026-05-05T07:41:46.814159Z","end":"2026-05-05T07:41:47.205902Z","steps":["trace[117270121] 'agreement among raft nodes before linearized reading' (duration: 391.636288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.205982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.814135Z","time spent":"391.832921ms","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-05T07:41:47.20603Z","caller":"traceutil/trace.go:171","msg":"trace[1318922575] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"274.155745ms","start":"2026-05-05T07:41:46.931869Z","end":"2026-05-05T07:41:47.206024Z","steps":["trace[1318922575] 'process raft request' (duration: 273.856908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.206117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.566024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:41:47.20618Z","caller":"traceutil/trace.go:171","msg":"trace[2132382239] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26435; }","duration":"253.651696ms","start":"2026-05-05T07:41:46.952514Z","end":"2026-05-05T07:41:47.206165Z","steps":["trace[2132382239] 'agreement among raft nodes before linearized reading' (duration: 253.433141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.206351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.833285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T07:41:47.206396Z","caller":"traceutil/trace.go:171","msg":"trace[782732417] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26435; }","duration":"366.902358ms","start":"2026-05-05T07:41:46.839483Z","end":"2026-05-05T07:41:47.206385Z","steps":["trace[782732417] 'agreement among raft nodes before linearized reading' (duration: 366.698984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:47.206426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:41:46.839471Z","time spent":"366.948359ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T07:41:52.085409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.207572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d1678ada-e68b-4b31-a509-3aa34e25769b\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-05T07:41:52.0855Z","caller":"traceutil/trace.go:171","msg":"trace[188369881] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d1678ada-e68b-4b31-a509-3aa34e25769b; range_end:; response_count:1; response_revision:26522; }","duration":"221.344035ms","start":"2026-05-05T07:41:51.864135Z","end":"2026-05-05T07:41:52.085479Z","steps":["trace[188369881] 'range keys from in-memory index tree' (duration: 220.999217ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:52.229485Z","caller":"traceutil/trace.go:171","msg":"trace[900033569] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"127.568019ms","start":"2026-05-05T07:41:52.101877Z","end":"2026-05-05T07:41:52.229445Z","steps":["trace[900033569] 'process raft request' (duration: 127.346903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:52.521385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.69013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T07:41:52.521471Z","caller":"traceutil/trace.go:171","msg":"trace[1500617570] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26524; }","duration":"212.827223ms","start":"2026-05-05T07:41:52.308624Z","end":"2026-05-05T07:41:52.521452Z","steps":["trace[1500617570] 'range keys from in-memory index tree' (duration: 212.520306ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:52.883043Z","caller":"traceutil/trace.go:171","msg":"trace[1844903011] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"265.767445ms","start":"2026-05-05T07:41:52.617252Z","end":"2026-05-05T07:41:52.883019Z","steps":["trace[1844903011] 'process raft request' (duration: 265.588071ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:52.883236Z","caller":"traceutil/trace.go:171","msg":"trace[1244253016] linearizableReadLoop","detail":"{readStateIndex:27398; appliedIndex:27398; }","duration":"219.099287ms","start":"2026-05-05T07:41:52.664107Z","end":"2026-05-05T07:41:52.883206Z","steps":["trace[1244253016] 'read index received' (duration: 219.071766ms)","trace[1244253016] 'applied index is now lower than readState.Index' (duration: 11.49µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:41:52.88352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.404443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-05T07:41:52.883549Z","caller":"traceutil/trace.go:171","msg":"trace[109643853] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26525; }","duration":"219.488035ms","start":"2026-05-05T07:41:52.664052Z","end":"2026-05-05T07:41:52.88354Z","steps":["trace[109643853] 'agreement among raft nodes before linearized reading' (duration: 219.335131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:52.911391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.433868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-05T07:41:52.911422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.526349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T07:41:52.911431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.901774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T07:41:52.911494Z","caller":"traceutil/trace.go:171","msg":"trace[680606193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26526; }","duration":"127.621141ms","start":"2026-05-05T07:41:52.783854Z","end":"2026-05-05T07:41:52.911475Z","steps":["trace[680606193] 'agreement among raft nodes before linearized reading' (duration: 127.388326ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:52.911505Z","caller":"traceutil/trace.go:171","msg":"trace[863006866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26526; }","duration":"126.994287ms","start":"2026-05-05T07:41:52.784492Z","end":"2026-05-05T07:41:52.911486Z","steps":["trace[863006866] 'agreement among raft nodes before linearized reading' (duration: 126.713991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:52.911505Z","caller":"traceutil/trace.go:171","msg":"trace[1433461368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26526; }","duration":"128.56083ms","start":"2026-05-05T07:41:52.782899Z","end":"2026-05-05T07:41:52.91146Z","steps":["trace[1433461368] 'agreement among raft nodes before linearized reading' (duration: 128.268504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:52.911628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.267854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T07:41:52.911727Z","caller":"traceutil/trace.go:171","msg":"trace[418716112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26526; }","duration":"128.432288ms","start":"2026-05-05T07:41:52.783275Z","end":"2026-05-05T07:41:52.911708Z","steps":["trace[418716112] 'agreement among raft nodes before linearized reading' (duration: 128.047409ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:52.999978Z","caller":"traceutil/trace.go:171","msg":"trace[17576327] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"110.986954ms","start":"2026-05-05T07:41:52.888966Z","end":"2026-05-05T07:41:52.999953Z","steps":["trace[17576327] 'process raft request' (duration: 94.634075ms)","trace[17576327] 'compare' (duration: 16.224837ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:55.123717Z","caller":"traceutil/trace.go:171","msg":"trace[541013243] transaction","detail":"{read_only:false; response_revision:26545; number_of_response:1; }","duration":"113.811664ms","start":"2026-05-05T07:41:55.009882Z","end":"2026-05-05T07:41:55.123694Z","steps":["trace[541013243] 'process raft request' (duration: 113.703212ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:55.12375Z","caller":"traceutil/trace.go:171","msg":"trace[1887272102] transaction","detail":"{read_only:false; response_revision:26544; number_of_response:1; }","duration":"113.983668ms","start":"2026-05-05T07:41:55.009719Z","end":"2026-05-05T07:41:55.123702Z","steps":["trace[1887272102] 'process raft request' (duration: 113.739283ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:55.123997Z","caller":"traceutil/trace.go:171","msg":"trace[1092899751] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"113.936157ms","start":"2026-05-05T07:41:55.010041Z","end":"2026-05-05T07:41:55.123977Z","steps":["trace[1092899751] 'process raft request' (duration: 113.60748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:41:58.483037Z","caller":"traceutil/trace.go:171","msg":"trace[198902992] linearizableReadLoop","detail":"{readStateIndex:27460; appliedIndex:27459; }","duration":"136.139223ms","start":"2026-05-05T07:41:58.346875Z","end":"2026-05-05T07:41:58.483014Z","steps":["trace[198902992] 'read index received' (duration: 135.99298ms)","trace[198902992] 'applied index is now lower than readState.Index' (duration: 145.323µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:41:58.483102Z","caller":"traceutil/trace.go:171","msg":"trace[662544549] transaction","detail":"{read_only:false; response_revision:26586; number_of_response:1; }","duration":"169.914195ms","start":"2026-05-05T07:41:58.313135Z","end":"2026-05-05T07:41:58.483049Z","steps":["trace[662544549] 'process raft request' (duration: 169.71654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:41:58.483357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.469099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-05T07:41:58.483438Z","caller":"traceutil/trace.go:171","msg":"trace[923356361] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:26586; }","duration":"136.600282ms","start":"2026-05-05T07:41:58.346817Z","end":"2026-05-05T07:41:58.483418Z","steps":["trace[923356361] 'agreement among raft nodes before linearized reading' (duration: 136.312746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:23.201981Z","caller":"traceutil/trace.go:171","msg":"trace[797181622] linearizableReadLoop","detail":"{readStateIndex:27671; appliedIndex:27670; }","duration":"140.536685ms","start":"2026-05-05T07:42:23.061423Z","end":"2026-05-05T07:42:23.201959Z","steps":["trace[797181622] 'read index received' (duration: 140.349442ms)","trace[797181622] 'applied index is now lower than readState.Index' (duration: 186.453µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:23.202284Z","caller":"traceutil/trace.go:171","msg":"trace[1261555709] transaction","detail":"{read_only:false; response_revision:26791; number_of_response:1; }","duration":"183.460915ms","start":"2026-05-05T07:42:23.01881Z","end":"2026-05-05T07:42:23.202271Z","steps":["trace[1261555709] 'process raft request' (duration: 183.024436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.202506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.634317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:42:23.202566Z","caller":"traceutil/trace.go:171","msg":"trace[313645140] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:26791; }","duration":"118.74535ms","start":"2026-05-05T07:42:23.083806Z","end":"2026-05-05T07:42:23.202552Z","steps":["trace[313645140] 'agreement among raft nodes before linearized reading' (duration: 118.629647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.202613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.18927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T07:42:23.202659Z","caller":"traceutil/trace.go:171","msg":"trace[253660998] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26791; }","duration":"141.281582ms","start":"2026-05-05T07:42:23.061366Z","end":"2026-05-05T07:42:23.202648Z","steps":["trace[253660998] 'agreement among raft nodes before linearized reading' (duration: 141.125109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.372233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.520848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:42:23.372493Z","caller":"traceutil/trace.go:171","msg":"trace[579059231] linearizableReadLoop","detail":"{readStateIndex:27672; appliedIndex:27671; }","duration":"164.994969ms","start":"2026-05-05T07:42:23.207476Z","end":"2026-05-05T07:42:23.372471Z","steps":["trace[579059231] 'read index received' (duration: 30.032061ms)","trace[579059231] 'applied index is now lower than readState.Index' (duration: 134.960708ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:23.372526Z","caller":"traceutil/trace.go:171","msg":"trace[1844721510] transaction","detail":"{read_only:false; response_revision:26792; number_of_response:1; }","duration":"165.755066ms","start":"2026-05-05T07:42:23.206625Z","end":"2026-05-05T07:42:23.37238Z","steps":["trace[1844721510] 'process raft request' (duration: 30.972942ms)","trace[1844721510] 'compare' (duration: 134.356894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:23.372722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.223794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18572"} {"level":"info","ts":"2026-05-05T07:42:23.372835Z","caller":"traceutil/trace.go:171","msg":"trace[1298215208] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26793; }","duration":"165.355677ms","start":"2026-05-05T07:42:23.207449Z","end":"2026-05-05T07:42:23.372805Z","steps":["trace[1298215208] 'agreement among raft nodes before linearized reading' (duration: 165.105482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.590151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.693052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T07:42:23.590417Z","caller":"traceutil/trace.go:171","msg":"trace[1508874152] transaction","detail":"{read_only:false; response_revision:26794; number_of_response:1; }","duration":"259.526281ms","start":"2026-05-05T07:42:23.330865Z","end":"2026-05-05T07:42:23.590391Z","steps":["trace[1508874152] 'process raft request' (duration: 152.508102ms)","trace[1508874152] 'compare' (duration: 106.555479ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:23.590554Z","caller":"traceutil/trace.go:171","msg":"trace[1236215523] transaction","detail":"{read_only:false; response_revision:26795; number_of_response:1; }","duration":"258.402367ms","start":"2026-05-05T07:42:23.332142Z","end":"2026-05-05T07:42:23.590545Z","steps":["trace[1236215523] 'process raft request' (duration: 258.184582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:23.590567Z","caller":"traceutil/trace.go:171","msg":"trace[2131871613] linearizableReadLoop","detail":"{readStateIndex:27678; appliedIndex:27673; }","duration":"217.998592ms","start":"2026-05-05T07:42:23.372553Z","end":"2026-05-05T07:42:23.590552Z","steps":["trace[2131871613] 'read index received' (duration: 110.611576ms)","trace[2131871613] 'applied index is now lower than readState.Index' (duration: 107.386196ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:23.590703Z","caller":"traceutil/trace.go:171","msg":"trace[332353587] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"257.302954ms","start":"2026-05-05T07:42:23.333389Z","end":"2026-05-05T07:42:23.590692Z","steps":["trace[332353587] 'process raft request' (duration: 256.997377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.590907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.078436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T07:42:23.590983Z","caller":"traceutil/trace.go:171","msg":"trace[1328924246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26798; }","duration":"327.173969ms","start":"2026-05-05T07:42:23.263798Z","end":"2026-05-05T07:42:23.590972Z","steps":["trace[1328924246] 'agreement among raft nodes before linearized reading' (duration: 327.022056ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:23.590967Z","caller":"traceutil/trace.go:171","msg":"trace[1972086158] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"255.943616ms","start":"2026-05-05T07:42:23.335008Z","end":"2026-05-05T07:42:23.590952Z","steps":["trace[1972086158] 'process raft request' (duration: 255.414803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.591104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:23.263789Z","time spent":"327.24343ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T07:42:23.591221Z","caller":"traceutil/trace.go:171","msg":"trace[1415455585] transaction","detail":"{read_only:false; response_revision:26798; number_of_response:1; }","duration":"254.2938ms","start":"2026-05-05T07:42:23.336912Z","end":"2026-05-05T07:42:23.591206Z","steps":["trace[1415455585] 'process raft request' (duration: 253.542153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.590913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.570887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-05T07:42:23.591359Z","caller":"traceutil/trace.go:171","msg":"trace[665713165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26798; }","duration":"327.030456ms","start":"2026-05-05T07:42:23.264287Z","end":"2026-05-05T07:42:23.591317Z","steps":["trace[665713165] 'agreement among raft nodes before linearized reading' (duration: 326.475395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.591488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:23.264267Z","time spent":"327.182439ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T07:42:23.591681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.896011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T07:42:23.591848Z","caller":"traceutil/trace.go:171","msg":"trace[421787478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26798; }","duration":"333.096935ms","start":"2026-05-05T07:42:23.258736Z","end":"2026-05-05T07:42:23.591833Z","steps":["trace[421787478] 'agreement among raft nodes before linearized reading' (duration: 332.475202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.591932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:23.258725Z","time spent":"333.192817ms","remote":"127.0.0.1:56656","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-05-05T07:42:23.653883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.467996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:42:23.653961Z","caller":"traceutil/trace.go:171","msg":"trace[814561561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26799; }","duration":"184.582238ms","start":"2026-05-05T07:42:23.469354Z","end":"2026-05-05T07:42:23.653936Z","steps":["trace[814561561] 'agreement among raft nodes before linearized reading' (duration: 184.427985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.653947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.702382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:42:23.654011Z","caller":"traceutil/trace.go:171","msg":"trace[2125407471] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26799; }","duration":"206.814304ms","start":"2026-05-05T07:42:23.447181Z","end":"2026-05-05T07:42:23.653995Z","steps":["trace[2125407471] 'agreement among raft nodes before linearized reading' (duration: 206.695541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:23.653937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.521931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:42:23.654089Z","caller":"traceutil/trace.go:171","msg":"trace[1141395975] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26799; }","duration":"280.711354ms","start":"2026-05-05T07:42:23.373353Z","end":"2026-05-05T07:42:23.654065Z","steps":["trace[1141395975] 'agreement among raft nodes before linearized reading' (duration: 280.404988ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:24.776298Z","caller":"traceutil/trace.go:171","msg":"trace[677130562] transaction","detail":"{read_only:false; response_revision:26805; number_of_response:1; }","duration":"107.139703ms","start":"2026-05-05T07:42:24.669127Z","end":"2026-05-05T07:42:24.776267Z","steps":["trace[677130562] 'process raft request' (duration: 107.01825ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:25.506965Z","caller":"traceutil/trace.go:171","msg":"trace[2045877883] linearizableReadLoop","detail":"{readStateIndex:27688; appliedIndex:27687; }","duration":"129.388439ms","start":"2026-05-05T07:42:25.377558Z","end":"2026-05-05T07:42:25.506947Z","steps":["trace[2045877883] 'read index received' (duration: 128.5246ms)","trace[2045877883] 'applied index is now lower than readState.Index' (duration: 863.229µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:25.507053Z","caller":"traceutil/trace.go:171","msg":"trace[892917346] transaction","detail":"{read_only:false; response_revision:26807; number_of_response:1; }","duration":"129.691255ms","start":"2026-05-05T07:42:25.377317Z","end":"2026-05-05T07:42:25.507008Z","steps":["trace[892917346] 'process raft request' (duration: 129.495701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.507113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.540022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-05T07:42:25.507146Z","caller":"traceutil/trace.go:171","msg":"trace[1435964002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26807; }","duration":"129.619514ms","start":"2026-05-05T07:42:25.377518Z","end":"2026-05-05T07:42:25.507138Z","steps":["trace[1435964002] 'agreement among raft nodes before linearized reading' (duration: 129.503961ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:25.856846Z","caller":"traceutil/trace.go:171","msg":"trace[988229862] transaction","detail":"{read_only:false; response_revision:26809; number_of_response:1; }","duration":"259.193824ms","start":"2026-05-05T07:42:25.597629Z","end":"2026-05-05T07:42:25.856822Z","steps":["trace[988229862] 'process raft request' (duration: 259.051831ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:25.926945Z","caller":"traceutil/trace.go:171","msg":"trace[1692870501] transaction","detail":"{read_only:false; response_revision:26810; number_of_response:1; }","duration":"328.969116ms","start":"2026-05-05T07:42:25.597954Z","end":"2026-05-05T07:42:25.926924Z","steps":["trace[1692870501] 'process raft request' (duration: 328.823473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.927052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:25.59793Z","time spent":"329.068779ms","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:42:25.927232Z","caller":"traceutil/trace.go:171","msg":"trace[802163852] transaction","detail":"{read_only:false; response_revision:26811; number_of_response:1; }","duration":"329.09231ms","start":"2026-05-05T07:42:25.598119Z","end":"2026-05-05T07:42:25.927212Z","steps":["trace[802163852] 'process raft request' (duration: 328.768653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.927375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:25.598086Z","time spent":"329.224362ms","remote":"127.0.0.1:56656","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-05-05T07:42:25.927623Z","caller":"traceutil/trace.go:171","msg":"trace[1989930849] transaction","detail":"{read_only:false; response_revision:26812; number_of_response:1; }","duration":"329.153202ms","start":"2026-05-05T07:42:25.598447Z","end":"2026-05-05T07:42:25.9276Z","steps":["trace[1989930849] 'process raft request' (duration: 328.688542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.927802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:25.598437Z","time spent":"329.258564ms","remote":"127.0.0.1:56656","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-05-05T07:42:25.927997Z","caller":"traceutil/trace.go:171","msg":"trace[589710730] transaction","detail":"{read_only:false; response_revision:26813; number_of_response:1; }","duration":"328.766414ms","start":"2026-05-05T07:42:25.599216Z","end":"2026-05-05T07:42:25.927983Z","steps":["trace[589710730] 'process raft request' (duration: 328.069539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.928071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:42:25.599209Z","time spent":"328.819395ms","remote":"127.0.0.1:56656","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-05-05T07:42:25.927437Z","caller":"traceutil/trace.go:171","msg":"trace[1292312383] linearizableReadLoop","detail":"{readStateIndex:27694; appliedIndex:27690; }","duration":"268.778449ms","start":"2026-05-05T07:42:25.658627Z","end":"2026-05-05T07:42:25.927405Z","steps":["trace[1292312383] 'read index received' (duration: 198.376624ms)","trace[1292312383] 'applied index is now lower than readState.Index' (duration: 70.400655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:25.928439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.817051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-05T07:42:25.92846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.24964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:42:25.928502Z","caller":"traceutil/trace.go:171","msg":"trace[1596695756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26813; }","duration":"269.880852ms","start":"2026-05-05T07:42:25.658604Z","end":"2026-05-05T07:42:25.928485Z","steps":["trace[1596695756] 'agreement among raft nodes before linearized reading' (duration: 269.599616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:25.928521Z","caller":"traceutil/trace.go:171","msg":"trace[1151872137] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26813; }","duration":"262.339422ms","start":"2026-05-05T07:42:25.666166Z","end":"2026-05-05T07:42:25.928505Z","steps":["trace[1151872137] 'agreement among raft nodes before linearized reading' (duration: 262.234029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.928534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.73151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:42:25.928601Z","caller":"traceutil/trace.go:171","msg":"trace[1051465] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26813; }","duration":"226.856382ms","start":"2026-05-05T07:42:25.701726Z","end":"2026-05-05T07:42:25.928583Z","steps":["trace[1051465] 'agreement among raft nodes before linearized reading' (duration: 226.647768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.928652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.676977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T07:42:25.928812Z","caller":"traceutil/trace.go:171","msg":"trace[1451519918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26813; }","duration":"268.79451ms","start":"2026-05-05T07:42:25.659908Z","end":"2026-05-05T07:42:25.928703Z","steps":["trace[1451519918] 'agreement among raft nodes before linearized reading' (duration: 268.576015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:25.929345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.468314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T07:42:25.929417Z","caller":"traceutil/trace.go:171","msg":"trace[808216406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26813; }","duration":"269.567186ms","start":"2026-05-05T07:42:25.65983Z","end":"2026-05-05T07:42:25.929398Z","steps":["trace[808216406] 'agreement among raft nodes before linearized reading' (duration: 268.524784ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:26.233135Z","caller":"traceutil/trace.go:171","msg":"trace[1646295075] transaction","detail":"{read_only:false; response_revision:26818; number_of_response:1; }","duration":"235.996947ms","start":"2026-05-05T07:42:25.997111Z","end":"2026-05-05T07:42:26.233108Z","steps":["trace[1646295075] 'process raft request' (duration: 235.813464ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:26.422665Z","caller":"traceutil/trace.go:171","msg":"trace[522204004] linearizableReadLoop","detail":"{readStateIndex:27700; appliedIndex:27698; }","duration":"210.523833ms","start":"2026-05-05T07:42:26.212109Z","end":"2026-05-05T07:42:26.422632Z","steps":["trace[522204004] 'read index received' (duration: 20.792125ms)","trace[522204004] 'applied index is now lower than readState.Index' (duration: 189.730208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:26.422826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.698766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:42:26.42286Z","caller":"traceutil/trace.go:171","msg":"trace[18784443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26819; }","duration":"210.784699ms","start":"2026-05-05T07:42:26.212063Z","end":"2026-05-05T07:42:26.422848Z","steps":["trace[18784443] 'agreement among raft nodes before linearized reading' (duration: 210.671346ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:26.423248Z","caller":"traceutil/trace.go:171","msg":"trace[1173719832] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"241.728201ms","start":"2026-05-05T07:42:26.181505Z","end":"2026-05-05T07:42:26.423233Z","steps":["trace[1173719832] 'process raft request' (duration: 162.513926ms)","trace[1173719832] 'compare' (duration: 78.413658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:26.975691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.27624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:42:26.975818Z","caller":"traceutil/trace.go:171","msg":"trace[1662611228] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26820; }","duration":"103.468484ms","start":"2026-05-05T07:42:26.872315Z","end":"2026-05-05T07:42:26.975783Z","steps":["trace[1662611228] 'count revisions from in-memory index tree' (duration: 103.149707ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:28.005586Z","caller":"traceutil/trace.go:171","msg":"trace[914938312] transaction","detail":"{read_only:false; response_revision:26823; number_of_response:1; }","duration":"143.943838ms","start":"2026-05-05T07:42:27.861613Z","end":"2026-05-05T07:42:28.005557Z","steps":["trace[914938312] 'process raft request' (duration: 143.660632ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:28.158689Z","caller":"traceutil/trace.go:171","msg":"trace[1257980709] transaction","detail":"{read_only:false; response_revision:26828; number_of_response:1; }","duration":"132.599066ms","start":"2026-05-05T07:42:28.026058Z","end":"2026-05-05T07:42:28.158657Z","steps":["trace[1257980709] 'process raft request' (duration: 107.952888ms)","trace[1257980709] 'compare' (duration: 24.418023ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:28.207469Z","caller":"traceutil/trace.go:171","msg":"trace[558363698] transaction","detail":"{read_only:false; response_revision:26831; number_of_response:1; }","duration":"180.473731ms","start":"2026-05-05T07:42:28.026954Z","end":"2026-05-05T07:42:28.207428Z","steps":["trace[558363698] 'process raft request' (duration: 180.073092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:28.207686Z","caller":"traceutil/trace.go:171","msg":"trace[1010347388] transaction","detail":"{read_only:false; response_revision:26829; number_of_response:1; }","duration":"181.141825ms","start":"2026-05-05T07:42:28.026526Z","end":"2026-05-05T07:42:28.207668Z","steps":["trace[1010347388] 'process raft request' (duration: 180.371208ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:28.207726Z","caller":"traceutil/trace.go:171","msg":"trace[2073790768] transaction","detail":"{read_only:false; response_revision:26830; number_of_response:1; }","duration":"180.91551ms","start":"2026-05-05T07:42:28.026799Z","end":"2026-05-05T07:42:28.207714Z","steps":["trace[2073790768] 'process raft request' (duration: 180.188854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:30.293615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.608863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:42:30.293685Z","caller":"traceutil/trace.go:171","msg":"trace[1280908039] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26842; }","duration":"130.725155ms","start":"2026-05-05T07:42:30.162942Z","end":"2026-05-05T07:42:30.293668Z","steps":["trace[1280908039] 'range keys from in-memory index tree' (duration: 130.44342ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:32.153974Z","caller":"traceutil/trace.go:171","msg":"trace[22674293] transaction","detail":"{read_only:false; response_revision:26853; number_of_response:1; }","duration":"121.432037ms","start":"2026-05-05T07:42:32.032516Z","end":"2026-05-05T07:42:32.153948Z","steps":["trace[22674293] 'process raft request' (duration: 120.897546ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:32.154251Z","caller":"traceutil/trace.go:171","msg":"trace[775106680] transaction","detail":"{read_only:false; response_revision:26854; number_of_response:1; }","duration":"118.844732ms","start":"2026-05-05T07:42:32.035382Z","end":"2026-05-05T07:42:32.154226Z","steps":["trace[775106680] 'process raft request' (duration: 118.516935ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:32.154479Z","caller":"traceutil/trace.go:171","msg":"trace[280345984] transaction","detail":"{read_only:false; response_revision:26856; number_of_response:1; }","duration":"118.69005ms","start":"2026-05-05T07:42:32.035774Z","end":"2026-05-05T07:42:32.154464Z","steps":["trace[280345984] 'process raft request' (duration: 118.621937ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:32.154564Z","caller":"traceutil/trace.go:171","msg":"trace[175717287] transaction","detail":"{read_only:false; response_revision:26855; number_of_response:1; }","duration":"119.081708ms","start":"2026-05-05T07:42:32.035405Z","end":"2026-05-05T07:42:32.154486Z","steps":["trace[175717287] 'process raft request' (duration: 118.76053ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:47.744389Z","caller":"traceutil/trace.go:171","msg":"trace[1579136946] transaction","detail":"{read_only:false; response_revision:26979; number_of_response:1; }","duration":"115.332878ms","start":"2026-05-05T07:42:47.629032Z","end":"2026-05-05T07:42:47.744365Z","steps":["trace[1579136946] 'process raft request' (duration: 51.410851ms)","trace[1579136946] 'compare' (duration: 63.780294ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:52.700649Z","caller":"traceutil/trace.go:171","msg":"trace[553836169] linearizableReadLoop","detail":"{readStateIndex:27906; appliedIndex:27905; }","duration":"141.464867ms","start":"2026-05-05T07:42:52.559151Z","end":"2026-05-05T07:42:52.700616Z","steps":["trace[553836169] 'read index received' (duration: 69.783123ms)","trace[553836169] 'applied index is now lower than readState.Index' (duration: 71.680114ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:52.700732Z","caller":"traceutil/trace.go:171","msg":"trace[1758068635] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"221.504238ms","start":"2026-05-05T07:42:52.479182Z","end":"2026-05-05T07:42:52.700686Z","steps":["trace[1758068635] 'process raft request' (duration: 149.708952ms)","trace[1758068635] 'compare' (duration: 71.529051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:42:52.700877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.700841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:42:52.700914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.376021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:42:52.701006Z","caller":"traceutil/trace.go:171","msg":"trace[2128420544] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:27020; }","duration":"103.521844ms","start":"2026-05-05T07:42:52.597462Z","end":"2026-05-05T07:42:52.700984Z","steps":["trace[2128420544] 'agreement among raft nodes before linearized reading' (duration: 103.372091ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:52.700941Z","caller":"traceutil/trace.go:171","msg":"trace[746923478] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:27020; }","duration":"141.816994ms","start":"2026-05-05T07:42:52.559106Z","end":"2026-05-05T07:42:52.700923Z","steps":["trace[746923478] 'agreement among raft nodes before linearized reading' (duration: 141.690381ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:53.169454Z","caller":"traceutil/trace.go:171","msg":"trace[1897644799] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"204.359661ms","start":"2026-05-05T07:42:52.965058Z","end":"2026-05-05T07:42:53.169417Z","steps":["trace[1897644799] 'process raft request' (duration: 204.178627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:42:53.872892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.790042ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T07:42:53.877166Z","caller":"traceutil/trace.go:171","msg":"trace[1629114112] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"115.265516ms","start":"2026-05-05T07:42:53.761868Z","end":"2026-05-05T07:42:53.877133Z","steps":["trace[1629114112] 'process raft request' (duration: 115.104893ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:54.066198Z","caller":"traceutil/trace.go:171","msg":"trace[1284058935] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"184.768352ms","start":"2026-05-05T07:42:53.881366Z","end":"2026-05-05T07:42:54.066135Z","steps":["trace[1284058935] 'process raft request' (duration: 133.032046ms)","trace[1284058935] 'compare' (duration: 51.550962ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:42:54.26143Z","caller":"traceutil/trace.go:171","msg":"trace[1467266308] transaction","detail":"{read_only:false; response_revision:27026; number_of_response:1; }","duration":"116.134744ms","start":"2026-05-05T07:42:54.145251Z","end":"2026-05-05T07:42:54.261385Z","steps":["trace[1467266308] 'process raft request' (duration: 115.909589ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:43:16.196945Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006e43.wal"} {"level":"info","ts":"2026-05-05T07:43:35.207654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24538} {"level":"info","ts":"2026-05-05T07:43:35.35572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24538,"took":"141.967985ms","hash":1635092917,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":35295232,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-05T07:43:35.355806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1635092917,"revision":24538,"compact-revision":21592} {"level":"warn","ts":"2026-05-05T07:46:13.678515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.370282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:46:13.678541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.459881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T07:46:13.678582Z","caller":"traceutil/trace.go:171","msg":"trace[1264892025] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:29032; }","duration":"143.465903ms","start":"2026-05-05T07:46:13.535102Z","end":"2026-05-05T07:46:13.678568Z","steps":["trace[1264892025] 'range keys from in-memory index tree' (duration: 143.30835ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:46:13.678608Z","caller":"traceutil/trace.go:171","msg":"trace[1708931940] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29032; }","duration":"108.562403ms","start":"2026-05-05T07:46:13.570029Z","end":"2026-05-05T07:46:13.678592Z","steps":["trace[1708931940] 'range keys from in-memory index tree' (duration: 108.290797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:46:13.678654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.094806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T07:46:13.678751Z","caller":"traceutil/trace.go:171","msg":"trace[1035480334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29032; }","duration":"144.23296ms","start":"2026-05-05T07:46:13.534491Z","end":"2026-05-05T07:46:13.678724Z","steps":["trace[1035480334] 'range keys from in-memory index tree' (duration: 143.903422ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:46:18.148643Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":30007,"local-member-snapshot-index":20006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:46:18.153626Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30007} {"level":"info","ts":"2026-05-05T07:46:18.153718Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25007} {"level":"info","ts":"2026-05-05T07:48:35.215048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27502} {"level":"info","ts":"2026-05-05T07:48:35.369099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27502,"took":"147.308036ms","hash":103575546,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":34197504,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T07:48:35.369193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":103575546,"revision":27502,"compact-revision":24538} {"level":"info","ts":"2026-05-05T07:53:35.222476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30195} {"level":"info","ts":"2026-05-05T07:53:35.3614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30195,"took":"133.344487ms","hash":2495812245,"current-db-size-bytes":58269696,"current-db-size":"58 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T07:53:35.361591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2495812245,"revision":30195,"compact-revision":27502} {"level":"info","ts":"2026-05-05T07:58:35.228761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32450} {"level":"info","ts":"2026-05-05T07:58:35.379029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32450,"took":"146.408569ms","hash":3867945437,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":30642176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T07:58:35.379105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3867945437,"revision":32450,"compact-revision":30195} {"level":"info","ts":"2026-05-05T08:01:01.442322Z","caller":"traceutil/trace.go:171","msg":"trace[1723146565] linearizableReadLoop","detail":"{readStateIndex:37049; appliedIndex:37048; }","duration":"230.019883ms","start":"2026-05-05T08:01:01.212276Z","end":"2026-05-05T08:01:01.442296Z","steps":["trace[1723146565] 'read index received' (duration: 157.247653ms)","trace[1723146565] 'applied index is now lower than readState.Index' (duration: 72.77125ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T08:01:01.44239Z","caller":"traceutil/trace.go:171","msg":"trace[1820932143] transaction","detail":"{read_only:false; response_revision:35918; number_of_response:1; }","duration":"301.783083ms","start":"2026-05-05T08:01:01.140555Z","end":"2026-05-05T08:01:01.442338Z","steps":["trace[1820932143] 'process raft request' (duration: 229.108785ms)","trace[1820932143] 'compare' (duration: 72.527155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T08:01:01.442496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.014691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T08:01:01.442523Z","caller":"traceutil/trace.go:171","msg":"trace[545650426] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:35918; }","duration":"222.060972ms","start":"2026-05-05T08:01:01.220452Z","end":"2026-05-05T08:01:01.442513Z","steps":["trace[545650426] 'agreement among raft nodes before linearized reading' (duration: 221.989961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T08:01:01.442498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.209298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T08:01:01.442561Z","caller":"traceutil/trace.go:171","msg":"trace[1325521998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:35918; }","duration":"230.32376ms","start":"2026-05-05T08:01:01.212222Z","end":"2026-05-05T08:01:01.442546Z","steps":["trace[1325521998] 'agreement among raft nodes before linearized reading' (duration: 230.194747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T08:01:01.442575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T08:01:01.140526Z","time spent":"301.934056ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10240,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T08:03:35.241206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34686} {"level":"info","ts":"2026-05-05T08:03:35.385453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34686,"took":"140.270897ms","hash":958625539,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":31444992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T08:03:35.385532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":958625539,"revision":34686,"compact-revision":32450} {"level":"info","ts":"2026-05-05T08:07:19.052855Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":40008,"local-member-snapshot-index":30007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T08:07:19.060584Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40008} {"level":"info","ts":"2026-05-05T08:07:19.060679Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35008} {"level":"info","ts":"2026-05-05T08:08:35.247823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37044} {"level":"info","ts":"2026-05-05T08:08:35.382416Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37044,"took":"128.590445ms","hash":2371079400,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":32219136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T08:08:35.382495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2371079400,"revision":37044,"compact-revision":34686} {"level":"info","ts":"2026-05-05T08:13:35.253914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39351} {"level":"info","ts":"2026-05-05T08:13:35.382919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39351,"took":"124.35271ms","hash":1989582973,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T08:13:35.383016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1989582973,"revision":39351,"compact-revision":37044}