{"level":"warn","ts":"2026-05-12T16:28:37.972449Z","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-12T16:28:37.972567Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.150:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.150:2380","--initial-cluster=instance=https://199.19.213.150:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.150:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.150: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-12T16:28:37.97287Z","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-12T16:28:37.972903Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-05-12T16:28:37.972939Z","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-12T16:28:37.974132Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"]} {"level":"info","ts":"2026-05-12T16:28:37.974261Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.150: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-12T16:28:37.978296Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.692562ms"} {"level":"info","ts":"2026-05-12T16:28:37.985553Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dbd0ffa4f8c99812","cluster-id":"98aad832cd49bc30"} {"level":"info","ts":"2026-05-12T16:28:37.985712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T16:28:37.985767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 0"} {"level":"info","ts":"2026-05-12T16:28:37.985786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dbd0ffa4f8c99812 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T16:28:37.985815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 1"} {"level":"info","ts":"2026-05-12T16:28:37.985864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"warn","ts":"2026-05-12T16:28:37.991768Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T16:28:37.993391Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T16:28:37.99545Z","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-12T16:28:37.997895Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dbd0ffa4f8c99812","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T16:28:37.998031Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dbd0ffa4f8c99812","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T16:28:37.998073Z","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-12T16:28:37.998201Z","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-12T16:28:37.998218Z","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-12T16:28:37.999874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"info","ts":"2026-05-12T16:28:38.000375Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","added-peer-id":"dbd0ffa4f8c99812","added-peer-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-05-12T16:28:38.003055Z","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-12T16:28:38.003226Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-05-12T16:28:38.003284Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-05-12T16:28:38.003399Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dbd0ffa4f8c99812","initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T16:28:38.003459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T16:28:38.587379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T16:28:38.587453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T16:28:38.587495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgPreVoteResp from dbd0ffa4f8c99812 at term 1"} {"level":"info","ts":"2026-05-12T16:28:38.58752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became candidate at term 2"} {"level":"info","ts":"2026-05-12T16:28:38.587536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgVoteResp from dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-05-12T16:28:38.587553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became leader at term 2"} {"level":"info","ts":"2026-05-12T16:28:38.58757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dbd0ffa4f8c99812 elected leader dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-05-12T16:28:38.595844Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:38.596914Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:28:38.596924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dbd0ffa4f8c99812","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.150:2379]}","request-path":"/0/members/dbd0ffa4f8c99812/attributes","cluster-id":"98aad832cd49bc30","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T16:28:38.597207Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:38.597499Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:38.597542Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:28:38.597565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:28:38.597873Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T16:28:38.597924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T16:28:38.600622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T16:28:38.608014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.150:2379"} {"level":"warn","ts":"2026-05-12T16:29:13.388198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.618599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:29:13.388326Z","caller":"traceutil/trace.go:171","msg":"trace[1868860980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"262.801061ms","start":"2026-05-12T16:29:13.125503Z","end":"2026-05-12T16:29:13.388304Z","steps":["trace[1868860980] 'range keys from in-memory index tree' (duration: 262.503108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:29:13.388461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.469481ms","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-12T16:29:13.388563Z","caller":"traceutil/trace.go:171","msg":"trace[1913800666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"265.629153ms","start":"2026-05-12T16:29:13.12291Z","end":"2026-05-12T16:29:13.388539Z","steps":["trace[1913800666] 'range keys from in-memory index tree' (duration: 265.28634ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:29:13.398267Z","caller":"traceutil/trace.go:171","msg":"trace[1210455312] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"218.968729ms","start":"2026-05-12T16:29:13.179261Z","end":"2026-05-12T16:29:13.39823Z","steps":["trace[1210455312] 'process raft request' (duration: 213.745657ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:29:14.021883Z","caller":"traceutil/trace.go:171","msg":"trace[796873302] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"149.194107ms","start":"2026-05-12T16:29:13.871354Z","end":"2026-05-12T16:29:14.020548Z","steps":["trace[796873302] 'process raft request' (duration: 148.23035ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:29:14.185353Z","caller":"traceutil/trace.go:171","msg":"trace[201706293] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"155.092378ms","start":"2026-05-12T16:29:14.030239Z","end":"2026-05-12T16:29:14.185331Z","steps":["trace[201706293] 'process raft request' (duration: 139.870526ms)","trace[201706293] 'compare' (duration: 15.072121ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:29:14.185413Z","caller":"traceutil/trace.go:171","msg":"trace[1257920738] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"103.294145ms","start":"2026-05-12T16:29:14.082097Z","end":"2026-05-12T16:29:14.185391Z","steps":["trace[1257920738] 'process raft request' (duration: 103.185204ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:29:14.761866Z","caller":"traceutil/trace.go:171","msg":"trace[1898093546] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"130.968435ms","start":"2026-05-12T16:29:14.63087Z","end":"2026-05-12T16:29:14.761839Z","steps":["trace[1898093546] 'process raft request' (duration: 130.35165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:29:40.129903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.544437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T16:29:40.130029Z","caller":"traceutil/trace.go:171","msg":"trace[1157850448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"108.725786ms","start":"2026-05-12T16:29:40.021275Z","end":"2026-05-12T16:29:40.13Z","steps":["trace[1157850448] 'range keys from in-memory index tree' (duration: 108.304825ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:30:54.765008Z","caller":"traceutil/trace.go:171","msg":"trace[1481707524] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"226.122564ms","start":"2026-05-12T16:30:54.538806Z","end":"2026-05-12T16:30:54.764929Z","steps":["trace[1481707524] 'process raft request' (duration: 225.277344ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:30:54.770503Z","caller":"traceutil/trace.go:171","msg":"trace[288472568] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1884; }","duration":"119.138661ms","start":"2026-05-12T16:30:54.651325Z","end":"2026-05-12T16:30:54.770464Z","steps":["trace[288472568] 'read index received' (duration: 112.635757ms)","trace[288472568] 'applied index is now lower than readState.Index' (duration: 6.502074ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:30:54.770606Z","caller":"traceutil/trace.go:171","msg":"trace[701196748] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"220.969862ms","start":"2026-05-12T16:30:54.549602Z","end":"2026-05-12T16:30:54.770571Z","steps":["trace[701196748] 'process raft request' (duration: 220.715616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:30:54.770768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.424757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a24ccc0-547e-4131-be82-eba060d42e8a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-12T16:30:54.77083Z","caller":"traceutil/trace.go:171","msg":"trace[417149851] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a24ccc0-547e-4131-be82-eba060d42e8a; range_end:; response_count:1; response_revision:1815; }","duration":"119.53198ms","start":"2026-05-12T16:30:54.651281Z","end":"2026-05-12T16:30:54.770813Z","steps":["trace[417149851] 'agreement among raft nodes before linearized reading' (duration: 119.309095ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:30:55.029923Z","caller":"traceutil/trace.go:171","msg":"trace[1981593439] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"252.480271ms","start":"2026-05-12T16:30:54.777425Z","end":"2026-05-12T16:30:55.029905Z","steps":["trace[1981593439] 'process raft request' (duration: 252.339407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:31:30.334846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.274899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-12T16:31:30.3349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.079143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-12T16:31:30.334958Z","caller":"traceutil/trace.go:171","msg":"trace[1874381304] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2064; }","duration":"103.178474ms","start":"2026-05-12T16:31:30.231761Z","end":"2026-05-12T16:31:30.33494Z","steps":["trace[1874381304] 'range keys from in-memory index tree' (duration: 102.95097ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:31:30.335153Z","caller":"traceutil/trace.go:171","msg":"trace[1988976966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2064; }","duration":"101.404262ms","start":"2026-05-12T16:31:30.233523Z","end":"2026-05-12T16:31:30.334927Z","steps":["trace[1988976966] 'range keys from in-memory index tree' (duration: 101.123936ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:31:30.499781Z","caller":"traceutil/trace.go:171","msg":"trace[1491131553] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"160.796993ms","start":"2026-05-12T16:31:30.338952Z","end":"2026-05-12T16:31:30.499749Z","steps":["trace[1491131553] 'process raft request' (duration: 160.594339ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:31:30.535034Z","caller":"traceutil/trace.go:171","msg":"trace[937452343] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"195.807934ms","start":"2026-05-12T16:31:30.339211Z","end":"2026-05-12T16:31:30.535019Z","steps":["trace[937452343] 'process raft request' (duration: 195.527798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:33:06.983688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.942232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-12T16:33:07.268933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.405573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:33:07.269021Z","caller":"traceutil/trace.go:171","msg":"trace[728106716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2695; }","duration":"141.505495ms","start":"2026-05-12T16:33:07.127504Z","end":"2026-05-12T16:33:07.269009Z","steps":["trace[728106716] 'range keys from in-memory index tree' (duration: 141.313782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:42.7625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818540235,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:35:43.26317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818540235,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:35:43.763328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818540235,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:35:43.942402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.749748314s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-12T16:35:43.944189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75408918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T16:35:43.944255Z","caller":"traceutil/trace.go:171","msg":"trace[1379960456] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3600; }","duration":"1.754189232s","start":"2026-05-12T16:35:42.190053Z","end":"2026-05-12T16:35:43.944242Z","steps":["trace[1379960456] 'count revisions from in-memory index tree' (duration: 1.754008427s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.944283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.190049Z","time spent":"1.754227593s","remote":"127.0.0.1:33578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":31,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:35:43.944545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754510641s","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-12T16:35:43.94463Z","caller":"traceutil/trace.go:171","msg":"trace[633746268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3600; }","duration":"1.754626015s","start":"2026-05-12T16:35:42.189978Z","end":"2026-05-12T16:35:43.944604Z","steps":["trace[633746268] 'range keys from in-memory index tree' (duration: 1.754225893s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.944658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752336528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T16:35:43.94473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.189965Z","time spent":"1.754750978s","remote":"127.0.0.1:33744","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":"warn","ts":"2026-05-12T16:35:43.944857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754748938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T16:35:43.944895Z","caller":"traceutil/trace.go:171","msg":"trace[422231162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3600; }","duration":"1.75479502s","start":"2026-05-12T16:35:42.190092Z","end":"2026-05-12T16:35:43.944887Z","steps":["trace[422231162] 'range keys from in-memory index tree' (duration: 1.754622055s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.944927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.190089Z","time spent":"1.754830061s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T16:35:43.944739Z","caller":"traceutil/trace.go:171","msg":"trace[2068993500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3600; }","duration":"1.752463752s","start":"2026-05-12T16:35:42.192255Z","end":"2026-05-12T16:35:43.944718Z","steps":["trace[2068993500] 'range keys from in-memory index tree' (duration: 1.752258127s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.945021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.192247Z","time spent":"1.75275218s","remote":"127.0.0.1:33448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T16:35:43.945162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755072038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T16:35:43.945201Z","caller":"traceutil/trace.go:171","msg":"trace[982146966] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:3600; }","duration":"1.755117549s","start":"2026-05-12T16:35:42.190073Z","end":"2026-05-12T16:35:43.945191Z","steps":["trace[982146966] 'range keys from in-memory index tree' (duration: 1.754959114s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.94523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.19007Z","time spent":"1.75515185s","remote":"127.0.0.1:33484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.150\" "} {"level":"warn","ts":"2026-05-12T16:35:43.945572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754420209s","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-12T16:35:43.945664Z","caller":"traceutil/trace.go:171","msg":"trace[922948137] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3600; }","duration":"1.754584984s","start":"2026-05-12T16:35:42.191056Z","end":"2026-05-12T16:35:43.945641Z","steps":["trace[922948137] 'range keys from in-memory index tree' (duration: 1.754191733s)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:43.945681Z","caller":"traceutil/trace.go:171","msg":"trace[1391940007] transaction","detail":"{read_only:false; response_revision:3601; number_of_response:1; }","duration":"1.753120752s","start":"2026-05-12T16:35:42.192549Z","end":"2026-05-12T16:35:43.94567Z","steps":["trace[1391940007] 'process raft request' (duration: 1.750162186s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.945701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.191027Z","time spent":"1.754666016s","remote":"127.0.0.1:33744","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-12T16:35:43.946924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.192521Z","time spent":"1.753189253s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:43.947437Z","caller":"traceutil/trace.go:171","msg":"trace[1440067604] linearizableReadLoop","detail":"{readStateIndex:3740; appliedIndex:3736; }","duration":"1.685742938s","start":"2026-05-12T16:35:42.261679Z","end":"2026-05-12T16:35:43.947422Z","steps":["trace[1440067604] 'read index received' (duration: 1.681038892s)","trace[1440067604] 'applied index is now lower than readState.Index' (duration: 4.703366ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:35:43.947527Z","caller":"traceutil/trace.go:171","msg":"trace[1235009938] transaction","detail":"{read_only:false; response_revision:3603; number_of_response:1; }","duration":"1.754726618s","start":"2026-05-12T16:35:42.192793Z","end":"2026-05-12T16:35:43.947519Z","steps":["trace[1235009938] 'process raft request' (duration: 1.754551443s)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:43.947516Z","caller":"traceutil/trace.go:171","msg":"trace[2072203713] transaction","detail":"{read_only:false; response_revision:3602; number_of_response:1; }","duration":"1.75481134s","start":"2026-05-12T16:35:42.192675Z","end":"2026-05-12T16:35:43.947487Z","steps":["trace[2072203713] 'process raft request' (duration: 1.754592814s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.947617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.192786Z","time spent":"1.75478195s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:43.947725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.192658Z","time spent":"1.754985575s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:43.947785Z","caller":"traceutil/trace.go:171","msg":"trace[1148968205] transaction","detail":"{read_only:false; response_revision:3604; number_of_response:1; }","duration":"1.208186447s","start":"2026-05-12T16:35:42.739591Z","end":"2026-05-12T16:35:43.947777Z","steps":["trace[1148968205] 'process raft request' (duration: 1.207780535s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.947845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.739555Z","time spent":"1.208265739s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:35:43.948071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686407687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T16:35:43.948102Z","caller":"traceutil/trace.go:171","msg":"trace[1320023448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3604; }","duration":"1.686442598s","start":"2026-05-12T16:35:42.261653Z","end":"2026-05-12T16:35:43.948096Z","steps":["trace[1320023448] 'agreement among raft nodes before linearized reading' (duration: 1.686331135s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.948123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.261566Z","time spent":"1.686552142s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T16:35:43.948212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521559626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T16:35:43.94823Z","caller":"traceutil/trace.go:171","msg":"trace[1151696311] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:3604; }","duration":"1.521618918s","start":"2026-05-12T16:35:42.426604Z","end":"2026-05-12T16:35:43.948223Z","steps":["trace[1151696311] 'agreement among raft nodes before linearized reading' (duration: 1.521563346s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.948246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.42658Z","time spent":"1.521661129s","remote":"127.0.0.1:34032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":38,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:35:43.948465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.746797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-05-12T16:35:43.948489Z","caller":"traceutil/trace.go:171","msg":"trace[1284890291] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:1; response_revision:3604; }","duration":"150.810389ms","start":"2026-05-12T16:35:43.797672Z","end":"2026-05-12T16:35:43.948482Z","steps":["trace[1284890291] 'agreement among raft nodes before linearized reading' (duration: 150.686205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.948658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.158777ms","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-12T16:35:43.948894Z","caller":"traceutil/trace.go:171","msg":"trace[1149602451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3604; }","duration":"563.225819ms","start":"2026-05-12T16:35:43.385457Z","end":"2026-05-12T16:35:43.948683Z","steps":["trace[1149602451] 'agreement among raft nodes before linearized reading' (duration: 563.108975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.948928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:43.385438Z","time spent":"563.482566ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T16:35:43.948999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.15162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-12T16:35:43.949025Z","caller":"traceutil/trace.go:171","msg":"trace[1262559151] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3604; }","duration":"405.211902ms","start":"2026-05-12T16:35:43.543807Z","end":"2026-05-12T16:35:43.949019Z","steps":["trace[1262559151] 'agreement among raft nodes before linearized reading' (duration: 405.109509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.949065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:43.543787Z","time spent":"405.272344ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-12T16:35:43.94867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093021356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-12T16:35:43.949029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.868523ms","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-12T16:35:43.949224Z","caller":"traceutil/trace.go:171","msg":"trace[432127713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3604; }","duration":"559.109719ms","start":"2026-05-12T16:35:43.390108Z","end":"2026-05-12T16:35:43.949218Z","steps":["trace[432127713] 'agreement among raft nodes before linearized reading' (duration: 558.876283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.949242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:43.390089Z","time spent":"559.148091ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T16:35:43.949227Z","caller":"traceutil/trace.go:171","msg":"trace[741729689] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:3604; }","duration":"1.093615713s","start":"2026-05-12T16:35:42.855593Z","end":"2026-05-12T16:35:43.949209Z","steps":["trace[741729689] 'agreement among raft nodes before linearized reading' (duration: 1.093057297s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:43.949299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:42.855559Z","time spent":"1.093719576s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:36:10.040161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818540713,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:36:10.541113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818540713,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:36:10.55883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092209021s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T16:36:10.55937Z","caller":"traceutil/trace.go:171","msg":"trace[729355197] transaction","detail":"{read_only:false; response_revision:3809; number_of_response:1; }","duration":"1.093001491s","start":"2026-05-12T16:36:09.466341Z","end":"2026-05-12T16:36:10.559343Z","steps":["trace[729355197] 'process raft request' (duration: 1.092787626s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.559522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:09.466324Z","time spent":"1.093119064s","remote":"127.0.0.1:33590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118319,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T16:36:10.561807Z","caller":"traceutil/trace.go:171","msg":"trace[227153082] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"529.060998ms","start":"2026-05-12T16:36:10.032727Z","end":"2026-05-12T16:36:10.561788Z","steps":["trace[227153082] 'process raft request' (duration: 528.903554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.561916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.032704Z","time spent":"529.143631ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:36:10.56204Z","caller":"traceutil/trace.go:171","msg":"trace[1207594197] transaction","detail":"{read_only:false; response_revision:3811; number_of_response:1; }","duration":"527.965789ms","start":"2026-05-12T16:36:10.034059Z","end":"2026-05-12T16:36:10.562025Z","steps":["trace[1207594197] 'process raft request' (duration: 527.698322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.562152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.034031Z","time spent":"528.060332ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:36:10.562415Z","caller":"traceutil/trace.go:171","msg":"trace[1670985617] linearizableReadLoop","detail":"{readStateIndex:3958; appliedIndex:3953; }","duration":"1.02247148s","start":"2026-05-12T16:36:09.539899Z","end":"2026-05-12T16:36:10.56237Z","steps":["trace[1670985617] 'read index received' (duration: 1.019192844s)","trace[1670985617] 'applied index is now lower than readState.Index' (duration: 3.277856ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:36:10.562517Z","caller":"traceutil/trace.go:171","msg":"trace[625693907] transaction","detail":"{read_only:false; response_revision:3813; number_of_response:1; }","duration":"374.606154ms","start":"2026-05-12T16:36:10.187899Z","end":"2026-05-12T16:36:10.562505Z","steps":["trace[625693907] 'process raft request' (duration: 374.242304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.562579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.187874Z","time spent":"374.674556ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:36:10.562722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022827909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-12T16:36:10.562754Z","caller":"traceutil/trace.go:171","msg":"trace[9687514] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3813; }","duration":"1.022898651s","start":"2026-05-12T16:36:09.539847Z","end":"2026-05-12T16:36:10.562746Z","steps":["trace[9687514] 'agreement among raft nodes before linearized reading' (duration: 1.022779988s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.562775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:09.539817Z","time spent":"1.022953143s","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-12T16:36:10.562838Z","caller":"traceutil/trace.go:171","msg":"trace[1428671980] transaction","detail":"{read_only:false; response_revision:3812; number_of_response:1; }","duration":"523.554932ms","start":"2026-05-12T16:36:10.039265Z","end":"2026-05-12T16:36:10.56282Z","steps":["trace[1428671980] 'process raft request' (duration: 522.683279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.563097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.039247Z","time spent":"523.647224ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:36:10.563531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.002337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T16:36:10.56357Z","caller":"traceutil/trace.go:171","msg":"trace[1821733297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3813; }","duration":"484.083119ms","start":"2026-05-12T16:36:10.079478Z","end":"2026-05-12T16:36:10.563561Z","steps":["trace[1821733297] 'agreement among raft nodes before linearized reading' (duration: 483.958245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.563618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.07946Z","time spent":"484.15075ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T16:36:10.563872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.809214ms","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-12T16:36:10.563905Z","caller":"traceutil/trace.go:171","msg":"trace[850259930] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:3813; }","duration":"193.909067ms","start":"2026-05-12T16:36:10.369988Z","end":"2026-05-12T16:36:10.563897Z","steps":["trace[850259930] 'agreement among raft nodes before linearized reading' (duration: 193.808074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.563955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.936075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T16:36:10.564002Z","caller":"traceutil/trace.go:171","msg":"trace[1911763956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3813; }","duration":"470.007336ms","start":"2026-05-12T16:36:10.093984Z","end":"2026-05-12T16:36:10.563991Z","steps":["trace[1911763956] 'agreement among raft nodes before linearized reading' (duration: 469.77565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.564025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.093976Z","time spent":"470.044918ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T16:36:10.564131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.643142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T16:36:10.564158Z","caller":"traceutil/trace.go:171","msg":"trace[1731744138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3813; }","duration":"476.690093ms","start":"2026-05-12T16:36:10.087461Z","end":"2026-05-12T16:36:10.564151Z","steps":["trace[1731744138] 'agreement among raft nodes before linearized reading' (duration: 476.622391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.564177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.087451Z","time spent":"476.721964ms","remote":"127.0.0.1:33744","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-12T16:36:10.564226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.726327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:36:10.564256Z","caller":"traceutil/trace.go:171","msg":"trace[1648281316] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:3813; }","duration":"197.793529ms","start":"2026-05-12T16:36:10.366454Z","end":"2026-05-12T16:36:10.564247Z","steps":["trace[1648281316] 'agreement among raft nodes before linearized reading' (duration: 197.746818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.564416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.749952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T16:36:10.564426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.254771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:36:10.564444Z","caller":"traceutil/trace.go:171","msg":"trace[689233726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3813; }","duration":"474.805924ms","start":"2026-05-12T16:36:10.089631Z","end":"2026-05-12T16:36:10.564437Z","steps":["trace[689233726] 'agreement among raft nodes before linearized reading' (duration: 474.717581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.564468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.089618Z","time spent":"474.845444ms","remote":"127.0.0.1:33744","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-12T16:36:10.564466Z","caller":"traceutil/trace.go:171","msg":"trace[283003445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3813; }","duration":"434.318763ms","start":"2026-05-12T16:36:10.130136Z","end":"2026-05-12T16:36:10.564455Z","steps":["trace[283003445] 'agreement among raft nodes before linearized reading' (duration: 434.19074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.564508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.130125Z","time spent":"434.376974ms","remote":"127.0.0.1:33448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T16:36:10.564724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.086679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-12T16:36:10.564831Z","caller":"traceutil/trace.go:171","msg":"trace[1757632823] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3813; }","duration":"467.203553ms","start":"2026-05-12T16:36:10.097606Z","end":"2026-05-12T16:36:10.56481Z","steps":["trace[1757632823] 'agreement among raft nodes before linearized reading' (duration: 466.983867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.564922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.097598Z","time spent":"467.307945ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-12T16:36:10.564895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.622259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T16:36:10.565793Z","caller":"traceutil/trace.go:171","msg":"trace[1858867744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3813; }","duration":"472.575914ms","start":"2026-05-12T16:36:10.093207Z","end":"2026-05-12T16:36:10.565783Z","steps":["trace[1858867744] 'agreement among raft nodes before linearized reading' (duration: 470.445258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:10.565928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:10.093197Z","time spent":"472.643876ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T16:36:13.379149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.136026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:36:13.379461Z","caller":"traceutil/trace.go:171","msg":"trace[1306696534] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:3832; }","duration":"265.486665ms","start":"2026-05-12T16:36:13.11395Z","end":"2026-05-12T16:36:13.379436Z","steps":["trace[1306696534] 'count revisions from in-memory index tree' (duration: 265.059584ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:36:17.002477Z","caller":"traceutil/trace.go:171","msg":"trace[320542780] linearizableReadLoop","detail":"{readStateIndex:3994; appliedIndex:3993; }","duration":"428.939375ms","start":"2026-05-12T16:36:16.57351Z","end":"2026-05-12T16:36:17.00245Z","steps":["trace[320542780] 'read index received' (duration: 428.75087ms)","trace[320542780] 'applied index is now lower than readState.Index' (duration: 187.035µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:36:17.002629Z","caller":"traceutil/trace.go:171","msg":"trace[2081929224] transaction","detail":"{read_only:false; response_revision:3848; number_of_response:1; }","duration":"643.285609ms","start":"2026-05-12T16:36:16.359327Z","end":"2026-05-12T16:36:17.002613Z","steps":["trace[2081929224] 'process raft request' (duration: 642.856558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.002751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.359297Z","time spent":"643.382362ms","remote":"127.0.0.1:33590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26133,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T16:36:17.002862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.314165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:36:17.002958Z","caller":"traceutil/trace.go:171","msg":"trace[1543649109] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:3848; }","duration":"429.460879ms","start":"2026-05-12T16:36:16.573469Z","end":"2026-05-12T16:36:17.002929Z","steps":["trace[1543649109] 'agreement among raft nodes before linearized reading' (duration: 429.222142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.003032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.573447Z","time spent":"429.564522ms","remote":"127.0.0.1:59610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:36:17.009072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.752915ms","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-12T16:36:17.009126Z","caller":"traceutil/trace.go:171","msg":"trace[1293247196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3851; }","duration":"417.835007ms","start":"2026-05-12T16:36:16.591279Z","end":"2026-05-12T16:36:17.009114Z","steps":["trace[1293247196] 'agreement among raft nodes before linearized reading' (duration: 417.686413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.009155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.591266Z","time spent":"417.882598ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T16:36:17.009438Z","caller":"traceutil/trace.go:171","msg":"trace[1063896077] transaction","detail":"{read_only:false; response_revision:3849; number_of_response:1; }","duration":"428.35299ms","start":"2026-05-12T16:36:16.581073Z","end":"2026-05-12T16:36:17.009426Z","steps":["trace[1063896077] 'process raft request' (duration: 427.745144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.009524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.581057Z","time spent":"428.413061ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:36:17.009656Z","caller":"traceutil/trace.go:171","msg":"trace[997707551] transaction","detail":"{read_only:false; response_revision:3850; number_of_response:1; }","duration":"428.319719ms","start":"2026-05-12T16:36:16.581326Z","end":"2026-05-12T16:36:17.009645Z","steps":["trace[997707551] 'process raft request' (duration: 427.58469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.009715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.581305Z","time spent":"428.380121ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:36:17.009852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.307855ms","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-12T16:36:17.00991Z","caller":"traceutil/trace.go:171","msg":"trace[646371922] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3851; }","duration":"414.364827ms","start":"2026-05-12T16:36:16.595514Z","end":"2026-05-12T16:36:17.009878Z","steps":["trace[646371922] 'agreement among raft nodes before linearized reading' (duration: 414.269874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.009943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.595503Z","time spent":"414.431678ms","remote":"127.0.0.1:33744","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":"info","ts":"2026-05-12T16:36:17.010087Z","caller":"traceutil/trace.go:171","msg":"trace[1694019625] transaction","detail":"{read_only:false; response_revision:3851; number_of_response:1; }","duration":"423.783721ms","start":"2026-05-12T16:36:16.58629Z","end":"2026-05-12T16:36:17.010074Z","steps":["trace[1694019625] 'process raft request' (duration: 422.644191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.010147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.586273Z","time spent":"423.842143ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:36:17.010267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.611521ms","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-12T16:36:17.0103Z","caller":"traceutil/trace.go:171","msg":"trace[1344403441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3851; }","duration":"412.643031ms","start":"2026-05-12T16:36:16.597648Z","end":"2026-05-12T16:36:17.010291Z","steps":["trace[1344403441] 'agreement among raft nodes before linearized reading' (duration: 412.561459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.010329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.597628Z","time spent":"412.693643ms","remote":"127.0.0.1:33744","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-12T16:36:17.010489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.986112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-12T16:36:17.010544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.266544ms","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-12T16:36:17.01059Z","caller":"traceutil/trace.go:171","msg":"trace[1987824259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3851; }","duration":"414.364716ms","start":"2026-05-12T16:36:16.596215Z","end":"2026-05-12T16:36:17.010579Z","steps":["trace[1987824259] 'agreement among raft nodes before linearized reading' (duration: 414.258773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.010567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.653014ms","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-12T16:36:17.010662Z","caller":"traceutil/trace.go:171","msg":"trace[97838393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3851; }","duration":"414.800228ms","start":"2026-05-12T16:36:16.595844Z","end":"2026-05-12T16:36:17.010644Z","steps":["trace[97838393] 'agreement among raft nodes before linearized reading' (duration: 414.433608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.010622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.596205Z","time spent":"414.409688ms","remote":"127.0.0.1:33744","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-12T16:36:17.010549Z","caller":"traceutil/trace.go:171","msg":"trace[1193195131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3851; }","duration":"415.067025ms","start":"2026-05-12T16:36:16.595468Z","end":"2026-05-12T16:36:17.010535Z","steps":["trace[1193195131] 'agreement among raft nodes before linearized reading' (duration: 414.86643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:17.010724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.595835Z","time spent":"414.872109ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T16:36:17.010749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:36:16.595462Z","time spent":"415.2761ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T16:37:03.317663Z","caller":"traceutil/trace.go:171","msg":"trace[2111733473] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"101.714238ms","start":"2026-05-12T16:37:03.21592Z","end":"2026-05-12T16:37:03.317634Z","steps":["trace[2111733473] 'process raft request' (duration: 70.76725ms)","trace[2111733473] 'compare' (duration: 30.58657ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:37:03.317966Z","caller":"traceutil/trace.go:171","msg":"trace[265976888] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"101.578304ms","start":"2026-05-12T16:37:03.216362Z","end":"2026-05-12T16:37:03.31794Z","steps":["trace[265976888] 'process raft request' (duration: 101.152575ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:05.379013Z","caller":"traceutil/trace.go:171","msg":"trace[581629443] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"202.218016ms","start":"2026-05-12T16:37:05.176776Z","end":"2026-05-12T16:37:05.378994Z","steps":["trace[581629443] 'process raft request' (duration: 202.062073ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:05.538113Z","caller":"traceutil/trace.go:171","msg":"trace[1272662882] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"125.54889ms","start":"2026-05-12T16:37:05.412516Z","end":"2026-05-12T16:37:05.538065Z","steps":["trace[1272662882] 'process raft request' (duration: 114.084549ms)","trace[1272662882] 'compare' (duration: 11.331038ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:37:05.543097Z","caller":"traceutil/trace.go:171","msg":"trace[1711878824] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"130.042542ms","start":"2026-05-12T16:37:05.413026Z","end":"2026-05-12T16:37:05.543069Z","steps":["trace[1711878824] 'process raft request' (duration: 129.95338ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:05.543492Z","caller":"traceutil/trace.go:171","msg":"trace[202517602] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"130.642727ms","start":"2026-05-12T16:37:05.412832Z","end":"2026-05-12T16:37:05.543475Z","steps":["trace[202517602] 'process raft request' (duration: 129.980812ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:05.543629Z","caller":"traceutil/trace.go:171","msg":"trace[1610083149] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"130.649817ms","start":"2026-05-12T16:37:05.41296Z","end":"2026-05-12T16:37:05.54361Z","steps":["trace[1610083149] 'process raft request' (duration: 129.970641ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:05.543493Z","caller":"traceutil/trace.go:171","msg":"trace[1992380856] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"129.547432ms","start":"2026-05-12T16:37:05.413929Z","end":"2026-05-12T16:37:05.543477Z","steps":["trace[1992380856] 'process raft request' (duration: 129.095291ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:05.544035Z","caller":"traceutil/trace.go:171","msg":"trace[1045287874] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4920; }","duration":"127.327991ms","start":"2026-05-12T16:37:05.416687Z","end":"2026-05-12T16:37:05.544015Z","steps":["trace[1045287874] 'read index received' (duration: 109.925594ms)","trace[1045287874] 'applied index is now lower than readState.Index' (duration: 17.401317ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:37:05.544078Z","caller":"traceutil/trace.go:171","msg":"trace[1685902286] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"127.234629ms","start":"2026-05-12T16:37:05.416811Z","end":"2026-05-12T16:37:05.544045Z","steps":["trace[1685902286] 'process raft request' (duration: 126.552453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:37:05.544498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.690119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-336eef07-5679-4873-9ec4-c0faa43f83b7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-12T16:37:05.544577Z","caller":"traceutil/trace.go:171","msg":"trace[1117294843] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-336eef07-5679-4873-9ec4-c0faa43f83b7; range_end:; response_count:1; response_revision:4749; }","duration":"127.886323ms","start":"2026-05-12T16:37:05.416662Z","end":"2026-05-12T16:37:05.544548Z","steps":["trace[1117294843] 'agreement among raft nodes before linearized reading' (duration: 127.431623ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:38.629836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2860} {"level":"info","ts":"2026-05-12T16:38:38.696639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2860,"took":"65.372159ms","hash":1012688556,"current-db-size-bytes":53997568,"current-db-size":"54 MB","current-db-size-in-use-bytes":28954624,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-12T16:38:38.697018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1012688556,"revision":2860,"compact-revision":-1} {"level":"warn","ts":"2026-05-12T16:38:52.496923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818545009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:38:52.997403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818545009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T16:38:53.087851Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.304440151s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T16:38:53.088527Z","caller":"traceutil/trace.go:171","msg":"trace[933399816] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"1.305193556s","start":"2026-05-12T16:38:51.783304Z","end":"2026-05-12T16:38:53.088498Z","steps":["trace[933399816] 'process raft request' (duration: 1.30489726s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.088731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:51.783281Z","time spent":"1.305338758s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:38:53.089636Z","caller":"traceutil/trace.go:171","msg":"trace[1338927885] linearizableReadLoop","detail":"{readStateIndex:5843; appliedIndex:5839; }","duration":"1.093698567s","start":"2026-05-12T16:38:51.995922Z","end":"2026-05-12T16:38:53.089621Z","steps":["trace[1338927885] 'read index received' (duration: 1.092204379s)","trace[1338927885] 'applied index is now lower than readState.Index' (duration: 1.493578ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:38:53.089875Z","caller":"traceutil/trace.go:171","msg":"trace[1358040161] transaction","detail":"{read_only:false; response_revision:5636; number_of_response:1; }","duration":"1.302436663s","start":"2026-05-12T16:38:51.787424Z","end":"2026-05-12T16:38:53.089861Z","steps":["trace[1358040161] 'process raft request' (duration: 1.302027326s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.089968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:51.787393Z","time spent":"1.302505764s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:38:53.090168Z","caller":"traceutil/trace.go:171","msg":"trace[1852298248] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"1.117844085s","start":"2026-05-12T16:38:51.972316Z","end":"2026-05-12T16:38:53.090161Z","steps":["trace[1852298248] 'process raft request' (duration: 1.117234764s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.090214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:51.972292Z","time spent":"1.117895866s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:38:53.090284Z","caller":"traceutil/trace.go:171","msg":"trace[532459776] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"815.805607ms","start":"2026-05-12T16:38:52.274466Z","end":"2026-05-12T16:38:53.090272Z","steps":["trace[532459776] 'process raft request' (duration: 815.121044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.090336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.274441Z","time spent":"815.860349ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:38:53.090599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094657425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-12T16:38:53.090675Z","caller":"traceutil/trace.go:171","msg":"trace[99202072] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5638; }","duration":"1.094770917s","start":"2026-05-12T16:38:51.995884Z","end":"2026-05-12T16:38:53.090655Z","steps":["trace[99202072] 'agreement among raft nodes before linearized reading' (duration: 1.094566173s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.090723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:51.995871Z","time spent":"1.094842138s","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":699,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-12T16:38:53.090806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076247737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:38:53.090844Z","caller":"traceutil/trace.go:171","msg":"trace[228652733] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5638; }","duration":"1.076313018s","start":"2026-05-12T16:38:52.014525Z","end":"2026-05-12T16:38:53.090838Z","steps":["trace[228652733] 'agreement among raft nodes before linearized reading' (duration: 1.076230706s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.090871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.014513Z","time spent":"1.076345988s","remote":"127.0.0.1:40866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:38:53.091026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.10454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T16:38:53.091059Z","caller":"traceutil/trace.go:171","msg":"trace[1056238124] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5638; }","duration":"362.180552ms","start":"2026-05-12T16:38:52.728873Z","end":"2026-05-12T16:38:53.091054Z","steps":["trace[1056238124] 'agreement among raft nodes before linearized reading' (duration: 362.131161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.091074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.728857Z","time spent":"362.213863ms","remote":"127.0.0.1:36692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:38:53.091214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.308169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T16:38:53.091251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.448359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T16:38:53.091261Z","caller":"traceutil/trace.go:171","msg":"trace[998052419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5638; }","duration":"814.38327ms","start":"2026-05-12T16:38:52.276865Z","end":"2026-05-12T16:38:53.091248Z","steps":["trace[998052419] 'agreement among raft nodes before linearized reading' (duration: 814.269298ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:53.091274Z","caller":"traceutil/trace.go:171","msg":"trace[1313971276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5638; }","duration":"816.504871ms","start":"2026-05-12T16:38:52.274763Z","end":"2026-05-12T16:38:53.091268Z","steps":["trace[1313971276] 'agreement among raft nodes before linearized reading' (duration: 816.406269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.091295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.276826Z","time spent":"814.462352ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T16:38:53.09138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.149313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T16:38:53.091412Z","caller":"traceutil/trace.go:171","msg":"trace[445011242] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:5638; }","duration":"891.233474ms","start":"2026-05-12T16:38:52.200167Z","end":"2026-05-12T16:38:53.091401Z","steps":["trace[445011242] 'agreement among raft nodes before linearized reading' (duration: 891.151523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.091429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.200139Z","time spent":"891.285636ms","remote":"127.0.0.1:33484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.150\" "} {"level":"warn","ts":"2026-05-12T16:38:53.091467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.715683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:38:53.09152Z","caller":"traceutil/trace.go:171","msg":"trace[1195910667] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5638; }","duration":"705.814955ms","start":"2026-05-12T16:38:52.385692Z","end":"2026-05-12T16:38:53.091507Z","steps":["trace[1195910667] 'agreement among raft nodes before linearized reading' (duration: 705.737444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.091563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.571401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:38:53.09159Z","caller":"traceutil/trace.go:171","msg":"trace[1293007252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5638; }","duration":"965.643033ms","start":"2026-05-12T16:38:52.125942Z","end":"2026-05-12T16:38:53.091585Z","steps":["trace[1293007252] 'agreement among raft nodes before linearized reading' (duration: 965.599272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.091604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.125915Z","time spent":"965.684644ms","remote":"127.0.0.1:33448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T16:38:53.091663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.403868ms","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-12T16:38:53.091702Z","caller":"traceutil/trace.go:171","msg":"trace[582675571] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5638; }","duration":"807.4545ms","start":"2026-05-12T16:38:52.284237Z","end":"2026-05-12T16:38:53.091691Z","steps":["trace[582675571] 'agreement among raft nodes before linearized reading' (duration: 807.348098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.091734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.284222Z","time spent":"807.50231ms","remote":"127.0.0.1:33744","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-12T16:38:53.091564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.38567Z","time spent":"705.881696ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:38:53.091308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.274753Z","time spent":"816.549212ms","remote":"127.0.0.1:33744","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-12T16:38:53.09192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.662473ms","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-12T16:38:53.092047Z","caller":"traceutil/trace.go:171","msg":"trace[487508883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5638; }","duration":"807.809506ms","start":"2026-05-12T16:38:52.284222Z","end":"2026-05-12T16:38:53.092031Z","steps":["trace[487508883] 'agreement among raft nodes before linearized reading' (duration: 807.635643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.092105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.861921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-12T16:38:53.09211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.284208Z","time spent":"807.887777ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T16:38:53.092128Z","caller":"traceutil/trace.go:171","msg":"trace[2040394612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5638; }","duration":"813.896951ms","start":"2026-05-12T16:38:52.278219Z","end":"2026-05-12T16:38:53.092116Z","steps":["trace[2040394612] 'agreement among raft nodes before linearized reading' (duration: 813.85847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.092143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.27821Z","time spent":"813.930041ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T16:38:53.092066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.267631ms","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-12T16:38:53.092213Z","caller":"traceutil/trace.go:171","msg":"trace[2054245900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5638; }","duration":"812.470555ms","start":"2026-05-12T16:38:52.279735Z","end":"2026-05-12T16:38:53.092205Z","steps":["trace[2054245900] 'agreement among raft nodes before linearized reading' (duration: 812.279871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:53.092237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:52.279721Z","time spent":"812.509625ms","remote":"127.0.0.1:33744","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-12T16:38:57.614962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957994690818545063,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T16:38:57.792033Z","caller":"traceutil/trace.go:171","msg":"trace[179873824] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"690.467261ms","start":"2026-05-12T16:38:57.101536Z","end":"2026-05-12T16:38:57.792003Z","steps":["trace[179873824] 'process raft request' (duration: 690.218086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.792238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.101508Z","time spent":"690.621604ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:38:57.796136Z","caller":"traceutil/trace.go:171","msg":"trace[420803089] transaction","detail":"{read_only:false; response_revision:5657; number_of_response:1; }","duration":"692.852976ms","start":"2026-05-12T16:38:57.103248Z","end":"2026-05-12T16:38:57.796101Z","steps":["trace[420803089] 'process raft request' (duration: 692.01407ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:57.79617Z","caller":"traceutil/trace.go:171","msg":"trace[1596706074] transaction","detail":"{read_only:false; response_revision:5658; number_of_response:1; }","duration":"691.97567ms","start":"2026-05-12T16:38:57.104173Z","end":"2026-05-12T16:38:57.796149Z","steps":["trace[1596706074] 'process raft request' (duration: 691.174565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.796275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.104158Z","time spent":"692.058571ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:38:57.796324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.103219Z","time spent":"692.977879ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:38:57.798017Z","caller":"traceutil/trace.go:171","msg":"trace[381017554] linearizableReadLoop","detail":"{readStateIndex:5865; appliedIndex:5861; }","duration":"683.806996ms","start":"2026-05-12T16:38:57.114175Z","end":"2026-05-12T16:38:57.797982Z","steps":["trace[381017554] 'read index received' (duration: 677.608749ms)","trace[381017554] 'applied index is now lower than readState.Index' (duration: 6.196097ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:38:57.798268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.073381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T16:38:57.798324Z","caller":"traceutil/trace.go:171","msg":"trace[445244537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5658; }","duration":"684.168592ms","start":"2026-05-12T16:38:57.114139Z","end":"2026-05-12T16:38:57.798307Z","steps":["trace[445244537] 'agreement among raft nodes before linearized reading' (duration: 683.993739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.798358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.114103Z","time spent":"684.244724ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T16:38:57.798595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.755518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T16:38:57.798628Z","caller":"traceutil/trace.go:171","msg":"trace[55692757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5658; }","duration":"681.806388ms","start":"2026-05-12T16:38:57.116809Z","end":"2026-05-12T16:38:57.798616Z","steps":["trace[55692757] 'agreement among raft nodes before linearized reading' (duration: 681.723897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.798652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.116798Z","time spent":"681.847459ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T16:38:57.79883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.227225ms","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-12T16:38:57.79886Z","caller":"traceutil/trace.go:171","msg":"trace[1851712934] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5658; }","duration":"135.277865ms","start":"2026-05-12T16:38:57.66357Z","end":"2026-05-12T16:38:57.798848Z","steps":["trace[1851712934] 'agreement among raft nodes before linearized reading' (duration: 135.230465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.799003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.89739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:38:57.799033Z","caller":"traceutil/trace.go:171","msg":"trace[1301671692] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5658; }","duration":"259.963562ms","start":"2026-05-12T16:38:57.539062Z","end":"2026-05-12T16:38:57.799025Z","steps":["trace[1301671692] 'agreement among raft nodes before linearized reading' (duration: 259.916461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.799195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.302648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-12T16:38:57.799205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.302319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T16:38:57.79923Z","caller":"traceutil/trace.go:171","msg":"trace[1585297992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5658; }","duration":"682.368578ms","start":"2026-05-12T16:38:57.116852Z","end":"2026-05-12T16:38:57.79922Z","steps":["trace[1585297992] 'agreement among raft nodes before linearized reading' (duration: 682.247206ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:57.799241Z","caller":"traceutil/trace.go:171","msg":"trace[889370237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5658; }","duration":"681.35282ms","start":"2026-05-12T16:38:57.117879Z","end":"2026-05-12T16:38:57.799232Z","steps":["trace[889370237] 'agreement among raft nodes before linearized reading' (duration: 681.248698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.799258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.116837Z","time spent":"682.4133ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T16:38:57.799266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.117874Z","time spent":"681.38567ms","remote":"127.0.0.1:33744","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-12T16:38:57.799302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.444002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-12T16:38:57.79935Z","caller":"traceutil/trace.go:171","msg":"trace[427809221] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5658; }","duration":"681.509873ms","start":"2026-05-12T16:38:57.117827Z","end":"2026-05-12T16:38:57.799337Z","steps":["trace[427809221] 'agreement among raft nodes before linearized reading' (duration: 681.38294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.799394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.117817Z","time spent":"681.567874ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-12T16:38:57.799404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.455611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T16:38:57.799449Z","caller":"traceutil/trace.go:171","msg":"trace[425784117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5658; }","duration":"681.536214ms","start":"2026-05-12T16:38:57.117903Z","end":"2026-05-12T16:38:57.799439Z","steps":["trace[425784117] 'agreement among raft nodes before linearized reading' (duration: 681.35706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:57.799476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:57.117898Z","time spent":"681.572444ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T16:43:38.683851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5549} {"level":"info","ts":"2026-05-12T16:43:38.772473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5549,"took":"87.594037ms","hash":343583077,"current-db-size-bytes":53997568,"current-db-size":"54 MB","current-db-size-in-use-bytes":17981440,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-12T16:43:38.772534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":343583077,"revision":5549,"compact-revision":2860}