{"level":"warn","ts":"2026-04-20T11:46:15.740884Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-20T11:46:15.740977Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-04-20T11:46:15.741051Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-20T11:46:15.741061Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]}
{"level":"info","ts":"2026-04-20T11:46:15.741082Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-20T11:46:15.743813Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]}
{"level":"info","ts":"2026-04-20T11:46:15.743892Z","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.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-04-20T11:46:15.750889Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.386499ms"}
{"level":"info","ts":"2026-04-20T11:46:15.762884Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"}
{"level":"info","ts":"2026-04-20T11:46:15.762959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"}
{"level":"info","ts":"2026-04-20T11:46:15.762983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"}
{"level":"info","ts":"2026-04-20T11:46:15.762996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-20T11:46:15.763006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"}
{"level":"info","ts":"2026-04-20T11:46:15.763041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"}
{"level":"warn","ts":"2026-04-20T11:46:15.771469Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-20T11:46:15.77611Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-20T11:46:15.778458Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-04-20T11:46:15.781807Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-20T11:46:15.781942Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-20T11:46:15.782684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"}
{"level":"info","ts":"2026-04-20T11:46:15.782621Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-20T11:46:15.782783Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]}
{"level":"info","ts":"2026-04-20T11:46:15.782853Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-20T11:46:15.782957Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-20T11:46:15.783933Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-20T11:46:15.784066Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"}
{"level":"info","ts":"2026-04-20T11:46:15.784111Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"}
{"level":"info","ts":"2026-04-20T11:46:15.784342Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-20T11:46:15.78438Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-20T11:46:16.264476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"}
{"level":"info","ts":"2026-04-20T11:46:16.264721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-20T11:46:16.264792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"}
{"level":"info","ts":"2026-04-20T11:46:16.264866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"}
{"level":"info","ts":"2026-04-20T11:46:16.264879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"}
{"level":"info","ts":"2026-04-20T11:46:16.264984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"}
{"level":"info","ts":"2026-04-20T11:46:16.265025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"}
{"level":"info","ts":"2026-04-20T11:46:16.266009Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-20T11:46:16.266109Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-20T11:46:16.266204Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-20T11:46:16.266278Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-20T11:46:16.266544Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-20T11:46:16.266642Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-20T11:46:16.273849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-20T11:46:16.274014Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-20T11:46:16.274078Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-20T11:46:16.277875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-20T11:46:16.278335Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"}
{"level":"info","ts":"2026-04-20T11:46:17.668612Z","caller":"traceutil/trace.go:171","msg":"trace[1663710996] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"200.097124ms","start":"2026-04-20T11:46:17.46849Z","end":"2026-04-20T11:46:17.668587Z","steps":["trace[1663710996] 'read index received'  (duration: 199.923272ms)","trace[1663710996] 'applied index is now lower than readState.Index'  (duration: 172.601µs)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:46:17.668641Z","caller":"traceutil/trace.go:171","msg":"trace[1112966482] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"201.034183ms","start":"2026-04-20T11:46:17.467585Z","end":"2026-04-20T11:46:17.668619Z","steps":["trace[1112966482] 'process raft request'  (duration: 200.79632ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:17.668814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.302306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-20T11:46:17.668872Z","caller":"traceutil/trace.go:171","msg":"trace[39680380] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"200.636769ms","start":"2026-04-20T11:46:17.468222Z","end":"2026-04-20T11:46:17.668858Z","steps":["trace[39680380] 'agreement among raft nodes before linearized reading'  (duration: 200.493778ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.712647Z","caller":"traceutil/trace.go:171","msg":"trace[1287168841] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"173.033398ms","start":"2026-04-20T11:46:17.539599Z","end":"2026-04-20T11:46:17.712632Z","steps":["trace[1287168841] 'process raft request'  (duration: 172.704394ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:17.712679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.403009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"}
{"level":"info","ts":"2026-04-20T11:46:17.713014Z","caller":"traceutil/trace.go:171","msg":"trace[1575624087] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"163.141353ms","start":"2026-04-20T11:46:17.549574Z","end":"2026-04-20T11:46:17.712716Z","steps":["trace[1575624087] 'process raft request'  (duration: 162.802599ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.713032Z","caller":"traceutil/trace.go:171","msg":"trace[844943160] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"240.769993ms","start":"2026-04-20T11:46:17.472244Z","end":"2026-04-20T11:46:17.713014Z","steps":["trace[844943160] 'agreement among raft nodes before linearized reading'  (duration: 240.342958ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.713124Z","caller":"traceutil/trace.go:171","msg":"trace[1547814253] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"163.016042ms","start":"2026-04-20T11:46:17.550096Z","end":"2026-04-20T11:46:17.713112Z","steps":["trace[1547814253] 'process raft request'  (duration: 162.403575ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.713153Z","caller":"traceutil/trace.go:171","msg":"trace[1498650746] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"163.439246ms","start":"2026-04-20T11:46:17.549686Z","end":"2026-04-20T11:46:17.713125Z","steps":["trace[1498650746] 'process raft request'  (duration: 162.746459ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.713213Z","caller":"traceutil/trace.go:171","msg":"trace[1513670577] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"163.191734ms","start":"2026-04-20T11:46:17.550013Z","end":"2026-04-20T11:46:17.713205Z","steps":["trace[1513670577] 'process raft request'  (duration: 162.462046ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.713346Z","caller":"traceutil/trace.go:171","msg":"trace[653464067] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"154.664743ms","start":"2026-04-20T11:46:17.558506Z","end":"2026-04-20T11:46:17.713171Z","steps":["trace[653464067] 'process raft request'  (duration: 154.045437ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.713464Z","caller":"traceutil/trace.go:171","msg":"trace[850492132] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"163.766739ms","start":"2026-04-20T11:46:17.549679Z","end":"2026-04-20T11:46:17.713446Z","steps":["trace[850492132] 'process raft request'  (duration: 162.726238ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:17.713713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.248152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:0 size:4"}
{"level":"warn","ts":"2026-04-20T11:46:17.713737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.191084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-20T11:46:17.713763Z","caller":"traceutil/trace.go:171","msg":"trace[1454025506] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:0; response_revision:11; }","duration":"176.342833ms","start":"2026-04-20T11:46:17.537407Z","end":"2026-04-20T11:46:17.71375Z","steps":["trace[1454025506] 'agreement among raft nodes before linearized reading'  (duration: 176.14453ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:17.713773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.742497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-20T11:46:17.713813Z","caller":"traceutil/trace.go:171","msg":"trace[1877686896] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"123.817367ms","start":"2026-04-20T11:46:17.589986Z","end":"2026-04-20T11:46:17.713803Z","steps":["trace[1877686896] 'agreement among raft nodes before linearized reading'  (duration: 123.759896ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.713805Z","caller":"traceutil/trace.go:171","msg":"trace[986365892] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"164.323526ms","start":"2026-04-20T11:46:17.549458Z","end":"2026-04-20T11:46:17.713781Z","steps":["trace[986365892] 'agreement among raft nodes before linearized reading'  (duration: 164.147034ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:17.713832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.754815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"}
{"level":"warn","ts":"2026-04-20T11:46:17.71395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.699546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"}
{"level":"warn","ts":"2026-04-20T11:46:17.713954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.627757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-20T11:46:17.713991Z","caller":"traceutil/trace.go:171","msg":"trace[970595420] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"193.800437ms","start":"2026-04-20T11:46:17.520181Z","end":"2026-04-20T11:46:17.713981Z","steps":["trace[970595420] 'agreement among raft nodes before linearized reading'  (duration: 193.746286ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.714017Z","caller":"traceutil/trace.go:171","msg":"trace[956612884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"233.712598ms","start":"2026-04-20T11:46:17.480288Z","end":"2026-04-20T11:46:17.714001Z","steps":["trace[956612884] 'agreement among raft nodes before linearized reading'  (duration: 233.619787ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:17.714079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.806899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-20T11:46:17.713961Z","caller":"traceutil/trace.go:171","msg":"trace[765636934] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"173.980138ms","start":"2026-04-20T11:46:17.53996Z","end":"2026-04-20T11:46:17.71394Z","steps":["trace[765636934] 'agreement among raft nodes before linearized reading'  (duration: 173.746045ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.714193Z","caller":"traceutil/trace.go:171","msg":"trace[668680942] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"159.738657ms","start":"2026-04-20T11:46:17.554445Z","end":"2026-04-20T11:46:17.714183Z","steps":["trace[668680942] 'process raft request'  (duration: 158.07569ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:17.714129Z","caller":"traceutil/trace.go:171","msg":"trace[1897216652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"233.87286ms","start":"2026-04-20T11:46:17.480248Z","end":"2026-04-20T11:46:17.714121Z","steps":["trace[1897216652] 'agreement among raft nodes before linearized reading'  (duration: 233.81253ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:44.310946Z","caller":"traceutil/trace.go:171","msg":"trace[102586787] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"151.514433ms","start":"2026-04-20T11:46:44.159402Z","end":"2026-04-20T11:46:44.310916Z","steps":["trace[102586787] 'read index received'  (duration: 92.132055ms)","trace[102586787] 'applied index is now lower than readState.Index'  (duration: 59.381518ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:46:44.310982Z","caller":"traceutil/trace.go:171","msg":"trace[1632488202] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"277.896076ms","start":"2026-04-20T11:46:44.033055Z","end":"2026-04-20T11:46:44.310951Z","steps":["trace[1632488202] 'process raft request'  (duration: 218.538778ms)","trace[1632488202] 'compare'  (duration: 59.229617ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:46:44.311145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.725794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12661"}
{"level":"info","ts":"2026-04-20T11:46:44.311188Z","caller":"traceutil/trace.go:171","msg":"trace[682016870] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:430; }","duration":"151.822535ms","start":"2026-04-20T11:46:44.159352Z","end":"2026-04-20T11:46:44.311175Z","steps":["trace[682016870] 'agreement among raft nodes before linearized reading'  (duration: 151.710764ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.830011Z","caller":"traceutil/trace.go:171","msg":"trace[821101218] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"108.236802ms","start":"2026-04-20T11:46:46.721751Z","end":"2026-04-20T11:46:46.829988Z","steps":["trace[821101218] 'process raft request'  (duration: 108.07606ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.836081Z","caller":"traceutil/trace.go:171","msg":"trace[381982062] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"108.635655ms","start":"2026-04-20T11:46:46.727423Z","end":"2026-04-20T11:46:46.836059Z","steps":["trace[381982062] 'process raft request'  (duration: 108.329983ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.836207Z","caller":"traceutil/trace.go:171","msg":"trace[1754007871] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"100.673456ms","start":"2026-04-20T11:46:46.735524Z","end":"2026-04-20T11:46:46.836197Z","steps":["trace[1754007871] 'process raft request'  (duration: 100.346104ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.836298Z","caller":"traceutil/trace.go:171","msg":"trace[382214164] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"108.778407ms","start":"2026-04-20T11:46:46.727498Z","end":"2026-04-20T11:46:46.836276Z","steps":["trace[382214164] 'process raft request'  (duration: 108.342403ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.836504Z","caller":"traceutil/trace.go:171","msg":"trace[1553037905] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"100.552705ms","start":"2026-04-20T11:46:46.735942Z","end":"2026-04-20T11:46:46.836495Z","steps":["trace[1553037905] 'process raft request'  (duration: 100.098472ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.836492Z","caller":"traceutil/trace.go:171","msg":"trace[47879543] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"100.673986ms","start":"2026-04-20T11:46:46.735791Z","end":"2026-04-20T11:46:46.836465Z","steps":["trace[47879543] 'process raft request'  (duration: 100.191612ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.836596Z","caller":"traceutil/trace.go:171","msg":"trace[1484166857] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"100.627146ms","start":"2026-04-20T11:46:46.735962Z","end":"2026-04-20T11:46:46.836589Z","steps":["trace[1484166857] 'process raft request'  (duration: 100.157352ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:46.836672Z","caller":"traceutil/trace.go:171","msg":"trace[754146416] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"100.598105ms","start":"2026-04-20T11:46:46.736067Z","end":"2026-04-20T11:46:46.836665Z","steps":["trace[754146416] 'process raft request'  (duration: 100.300173ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:53.480418Z","caller":"traceutil/trace.go:171","msg":"trace[473252594] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"194.775292ms","start":"2026-04-20T11:46:53.285616Z","end":"2026-04-20T11:46:53.480391Z","steps":["trace[473252594] 'process raft request'  (duration: 194.158458ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:54.114841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225628274546635,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-04-20T11:46:54.205864Z","caller":"traceutil/trace.go:171","msg":"trace[2046367152] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"649.479322ms","start":"2026-04-20T11:46:53.556361Z","end":"2026-04-20T11:46:54.20584Z","steps":["trace[2046367152] 'process raft request'  (duration: 649.320451ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:46:54.206326Z","caller":"traceutil/trace.go:171","msg":"trace[814970915] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:600; }","duration":"591.705078ms","start":"2026-04-20T11:46:53.614607Z","end":"2026-04-20T11:46:54.206312Z","steps":["trace[814970915] 'read index received'  (duration: 591.699868ms)","trace[814970915] 'applied index is now lower than readState.Index'  (duration: 3.92µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:46:54.206525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.612318ms","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-04-20T11:46:54.206558Z","caller":"traceutil/trace.go:171","msg":"trace[1809660435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"258.694539ms","start":"2026-04-20T11:46:53.947855Z","end":"2026-04-20T11:46:54.20655Z","steps":["trace[1809660435] 'agreement among raft nodes before linearized reading'  (duration: 258.515367ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:54.206958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.229202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q8xn8\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-04-20T11:46:54.207074Z","caller":"traceutil/trace.go:171","msg":"trace[1038723112] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q8xn8; range_end:; response_count:1; response_revision:580; }","duration":"592.675935ms","start":"2026-04-20T11:46:53.61437Z","end":"2026-04-20T11:46:54.207046Z","steps":["trace[1038723112] 'agreement among raft nodes before linearized reading'  (duration: 592.090411ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:46:54.207133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:46:53.61435Z","time spent":"592.767286ms","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-q8xn8\" "}
{"level":"warn","ts":"2026-04-20T11:46:54.206676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:46:53.55634Z","time spent":"649.607193ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" mod_revision:0 > success:<request_put:<key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" value_size:2525 >> failure:<>"}
{"level":"info","ts":"2026-04-20T11:47:17.740888Z","caller":"traceutil/trace.go:171","msg":"trace[2103707993] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"220.983908ms","start":"2026-04-20T11:47:17.519883Z","end":"2026-04-20T11:47:17.740867Z","steps":["trace[2103707993] 'process raft request'  (duration: 220.823407ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:47:18.159583Z","caller":"traceutil/trace.go:171","msg":"trace[87339250] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"410.3078ms","start":"2026-04-20T11:47:17.749256Z","end":"2026-04-20T11:47:18.159563Z","steps":["trace[87339250] 'process raft request'  (duration: 410.157459ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:47:18.159953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:47:17.749235Z","time spent":"410.413581ms","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-shlgn\" mod_revision:728 > success:<request_put:<key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-shlgn\" value_size:3944 >> failure:<request_range:<key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-shlgn\" > >"}
{"level":"info","ts":"2026-04-20T11:48:24.18171Z","caller":"traceutil/trace.go:171","msg":"trace[1510377397] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"103.077127ms","start":"2026-04-20T11:48:24.078606Z","end":"2026-04-20T11:48:24.181683Z","steps":["trace[1510377397] 'process raft request'  (duration: 102.943345ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:48:49.299965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.015703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-20T11:48:49.300073Z","caller":"traceutil/trace.go:171","msg":"trace[428480837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1876; }","duration":"343.155414ms","start":"2026-04-20T11:48:48.956898Z","end":"2026-04-20T11:48:49.300054Z","steps":["trace[428480837] 'range keys from in-memory index tree'  (duration: 342.87456ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:48:49.300112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:48:48.956887Z","time spent":"343.216445ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-04-20T11:48:49.300012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.208461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-20T11:48:49.300251Z","caller":"traceutil/trace.go:171","msg":"trace[610862887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1876; }","duration":"348.481996ms","start":"2026-04-20T11:48:48.951728Z","end":"2026-04-20T11:48:49.30021Z","steps":["trace[610862887] 'range keys from in-memory index tree'  (duration: 348.047099ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:48:49.300354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:48:48.95171Z","time spent":"348.610797ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-04-20T11:48:49.300395Z","caller":"traceutil/trace.go:171","msg":"trace[404992767] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"179.046102ms","start":"2026-04-20T11:48:49.121321Z","end":"2026-04-20T11:48:49.300368Z","steps":["trace[404992767] 'read index received'  (duration: 153.03852ms)","trace[404992767] 'applied index is now lower than readState.Index'  (duration: 26.003522ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:48:49.300502Z","caller":"traceutil/trace.go:171","msg":"trace[314769838] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"200.637489ms","start":"2026-04-20T11:48:49.099848Z","end":"2026-04-20T11:48:49.300485Z","steps":["trace[314769838] 'process raft request'  (duration: 174.408223ms)","trace[314769838] 'compare'  (duration: 25.256081ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:48:49.300596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.261776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9a0d2fa-c6b2-4ad9-8042-c7e2142d0bbd\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-04-20T11:48:49.30063Z","caller":"traceutil/trace.go:171","msg":"trace[1537187120] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9a0d2fa-c6b2-4ad9-8042-c7e2142d0bbd; range_end:; response_count:1; response_revision:1877; }","duration":"179.337827ms","start":"2026-04-20T11:48:49.12128Z","end":"2026-04-20T11:48:49.300618Z","steps":["trace[1537187120] 'agreement among raft nodes before linearized reading'  (duration: 179.173124ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:48:49.429506Z","caller":"traceutil/trace.go:171","msg":"trace[853838745] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"124.191405ms","start":"2026-04-20T11:48:49.305284Z","end":"2026-04-20T11:48:49.429476Z","steps":["trace[853838745] 'process raft request'  (duration: 114.273565ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:48:49.429701Z","caller":"traceutil/trace.go:171","msg":"trace[21641959] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"121.941471ms","start":"2026-04-20T11:48:49.30773Z","end":"2026-04-20T11:48:49.429672Z","steps":["trace[21641959] 'process raft request'  (duration: 121.670567ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:49:00.602675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.906303ms","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":"warn","ts":"2026-04-20T11:49:00.602696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.667201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-20T11:49:00.602772Z","caller":"traceutil/trace.go:171","msg":"trace[1144972551] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1974; }","duration":"102.057695ms","start":"2026-04-20T11:49:00.500691Z","end":"2026-04-20T11:49:00.602749Z","steps":["trace[1144972551] 'range keys from in-memory index tree'  (duration: 101.70976ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:00.602781Z","caller":"traceutil/trace.go:171","msg":"trace[330528402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1974; }","duration":"185.801573ms","start":"2026-04-20T11:49:00.416959Z","end":"2026-04-20T11:49:00.60276Z","steps":["trace[330528402] 'range keys from in-memory index tree'  (duration: 185.496249ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:22.69246Z","caller":"traceutil/trace.go:171","msg":"trace[697738844] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"203.175753ms","start":"2026-04-20T11:49:22.489269Z","end":"2026-04-20T11:49:22.692445Z","steps":["trace[697738844] 'process raft request'  (duration: 203.081532ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:22.790071Z","caller":"traceutil/trace.go:171","msg":"trace[116830436] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2195; }","duration":"101.851479ms","start":"2026-04-20T11:49:22.688191Z","end":"2026-04-20T11:49:22.790043Z","steps":["trace[116830436] 'read index received'  (duration: 4.159031ms)","trace[116830436] 'applied index is now lower than readState.Index'  (duration: 97.691598ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:49:22.790135Z","caller":"traceutil/trace.go:171","msg":"trace[1645844589] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"300.338744ms","start":"2026-04-20T11:49:22.489768Z","end":"2026-04-20T11:49:22.790107Z","steps":["trace[1645844589] 'process raft request'  (duration: 292.525737ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:22.790188Z","caller":"traceutil/trace.go:171","msg":"trace[1686872003] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"112.863757ms","start":"2026-04-20T11:49:22.677313Z","end":"2026-04-20T11:49:22.790177Z","steps":["trace[1686872003] 'process raft request'  (duration: 112.685175ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:49:22.79028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:49:22.489755Z","time spent":"300.448286ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:2032 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-controller\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-controller\" > >"}
{"level":"warn","ts":"2026-04-20T11:49:22.79053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.329956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"}
{"level":"info","ts":"2026-04-20T11:49:22.79057Z","caller":"traceutil/trace.go:171","msg":"trace[1747012235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2111; }","duration":"102.413887ms","start":"2026-04-20T11:49:22.688147Z","end":"2026-04-20T11:49:22.790561Z","steps":["trace[1747012235] 'agreement among raft nodes before linearized reading'  (duration: 102.246545ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:49:23.199833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.07554ms","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-04-20T11:49:23.199893Z","caller":"traceutil/trace.go:171","msg":"trace[1366822723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2112; }","duration":"231.177121ms","start":"2026-04-20T11:49:22.9687Z","end":"2026-04-20T11:49:23.199877Z","steps":["trace[1366822723] 'range keys from in-memory index tree'  (duration: 230.922848ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:49:23.200132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.116996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-20T11:49:23.200215Z","caller":"traceutil/trace.go:171","msg":"trace[1761183959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2112; }","duration":"225.233736ms","start":"2026-04-20T11:49:22.974953Z","end":"2026-04-20T11:49:23.200187Z","steps":["trace[1761183959] 'range keys from in-memory index tree'  (duration: 224.968114ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:23.308974Z","caller":"traceutil/trace.go:171","msg":"trace[1935544349] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"109.050923ms","start":"2026-04-20T11:49:23.199897Z","end":"2026-04-20T11:49:23.308948Z","steps":["trace[1935544349] 'process raft request'  (duration: 108.871571ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:23.333433Z","caller":"traceutil/trace.go:171","msg":"trace[1223515011] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"129.545707ms","start":"2026-04-20T11:49:23.203795Z","end":"2026-04-20T11:49:23.333341Z","steps":["trace[1223515011] 'process raft request'  (duration: 129.495847ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:23.333452Z","caller":"traceutil/trace.go:171","msg":"trace[1394362939] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"130.377417ms","start":"2026-04-20T11:49:23.203039Z","end":"2026-04-20T11:49:23.333416Z","steps":["trace[1394362939] 'process raft request'  (duration: 130.105644ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:49:23.6783Z","caller":"traceutil/trace.go:171","msg":"trace[115544560] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"136.417592ms","start":"2026-04-20T11:49:23.541856Z","end":"2026-04-20T11:49:23.678273Z","steps":["trace[115544560] 'read index received'  (duration: 106.879266ms)","trace[115544560] 'applied index is now lower than readState.Index'  (duration: 29.537196ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:49:23.678692Z","caller":"traceutil/trace.go:171","msg":"trace[1655229482] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"167.766402ms","start":"2026-04-20T11:49:23.510567Z","end":"2026-04-20T11:49:23.678333Z","steps":["trace[1655229482] 'process raft request'  (duration: 138.240456ms)","trace[1655229482] 'compare'  (duration: 28.204229ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:49:23.678713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.846427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-20T11:49:23.678848Z","caller":"traceutil/trace.go:171","msg":"trace[1473609659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2116; }","duration":"137.02637ms","start":"2026-04-20T11:49:23.541805Z","end":"2026-04-20T11:49:23.678831Z","steps":["trace[1473609659] 'agreement among raft nodes before linearized reading'  (duration: 136.606105ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:50:19.756318Z","caller":"traceutil/trace.go:171","msg":"trace[1194433719] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"125.64813ms","start":"2026-04-20T11:50:19.630644Z","end":"2026-04-20T11:50:19.756292Z","steps":["trace[1194433719] 'process raft request'  (duration: 39.727715ms)","trace[1194433719] 'compare'  (duration: 85.787934ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:51:01.591664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.609408ms","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-04-20T11:51:01.591755Z","caller":"traceutil/trace.go:171","msg":"trace[987499103] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2749; }","duration":"239.788099ms","start":"2026-04-20T11:51:01.351946Z","end":"2026-04-20T11:51:01.591734Z","steps":["trace[987499103] 'range keys from in-memory index tree'  (duration: 239.403886ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:51:01.591743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.00598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-20T11:51:01.591825Z","caller":"traceutil/trace.go:171","msg":"trace[826074608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2749; }","duration":"174.158141ms","start":"2026-04-20T11:51:01.417646Z","end":"2026-04-20T11:51:01.591804Z","steps":["trace[826074608] 'range keys from in-memory index tree'  (duration: 173.847158ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:51:01.711256Z","caller":"traceutil/trace.go:171","msg":"trace[1015949307] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"114.099146ms","start":"2026-04-20T11:51:01.597125Z","end":"2026-04-20T11:51:01.711224Z","steps":["trace[1015949307] 'process raft request'  (duration: 113.425779ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:54:32.467238Z","caller":"traceutil/trace.go:171","msg":"trace[1975069335] transaction","detail":"{read_only:false; response_revision:4026; number_of_response:1; }","duration":"102.238913ms","start":"2026-04-20T11:54:32.36497Z","end":"2026-04-20T11:54:32.467209Z","steps":["trace[1975069335] 'process raft request'  (duration: 102.025549ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:54:51.53922Z","caller":"traceutil/trace.go:171","msg":"trace[805427329] linearizableReadLoop","detail":"{readStateIndex:4723; appliedIndex:4722; }","duration":"286.857603ms","start":"2026-04-20T11:54:51.252328Z","end":"2026-04-20T11:54:51.539185Z","steps":["trace[805427329] 'read index received'  (duration: 284.99371ms)","trace[805427329] 'applied index is now lower than readState.Index'  (duration: 1.862182ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:54:51.53934Z","caller":"traceutil/trace.go:171","msg":"trace[25685317] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"318.910804ms","start":"2026-04-20T11:54:51.220418Z","end":"2026-04-20T11:54:51.539329Z","steps":["trace[25685317] 'process raft request'  (duration: 316.776587ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:54:51.539441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:51.220397Z","time spent":"318.969375ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:4481 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-04-20T11:54:51.539827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.473854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:16 size:486089"}
{"level":"info","ts":"2026-04-20T11:54:51.539906Z","caller":"traceutil/trace.go:171","msg":"trace[385122673] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:16; response_revision:4539; }","duration":"287.601196ms","start":"2026-04-20T11:54:51.252284Z","end":"2026-04-20T11:54:51.539885Z","steps":["trace[385122673] 'agreement among raft nodes before linearized reading'  (duration: 287.058086ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:54:51.541515Z","caller":"traceutil/trace.go:171","msg":"trace[801334069] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"173.347095ms","start":"2026-04-20T11:54:51.36815Z","end":"2026-04-20T11:54:51.541497Z","steps":["trace[801334069] 'process raft request'  (duration: 173.232853ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:54:51.541539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.154825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-20T11:54:51.542115Z","caller":"traceutil/trace.go:171","msg":"trace[1479378518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4540; }","duration":"124.230495ms","start":"2026-04-20T11:54:51.417347Z","end":"2026-04-20T11:54:51.541578Z","steps":["trace[1479378518] 'agreement among raft nodes before linearized reading'  (duration: 124.134904ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:01.399488Z","caller":"traceutil/trace.go:171","msg":"trace[1003560339] linearizableReadLoop","detail":"{readStateIndex:4963; appliedIndex:4962; }","duration":"117.511787ms","start":"2026-04-20T11:55:01.281948Z","end":"2026-04-20T11:55:01.39946Z","steps":["trace[1003560339] 'read index received'  (duration: 117.212712ms)","trace[1003560339] 'applied index is now lower than readState.Index'  (duration: 297.945µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:55:01.399869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.890685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-20T11:55:01.399956Z","caller":"traceutil/trace.go:171","msg":"trace[855172813] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4774; }","duration":"118.026487ms","start":"2026-04-20T11:55:01.281909Z","end":"2026-04-20T11:55:01.399935Z","steps":["trace[855172813] 'agreement among raft nodes before linearized reading'  (duration: 117.786232ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:01.40082Z","caller":"traceutil/trace.go:171","msg":"trace[631380756] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"206.280884ms","start":"2026-04-20T11:55:01.19363Z","end":"2026-04-20T11:55:01.399911Z","steps":["trace[631380756] 'process raft request'  (duration: 205.649623ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:01.693191Z","caller":"traceutil/trace.go:171","msg":"trace[592214408] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"100.709851ms","start":"2026-04-20T11:55:01.592461Z","end":"2026-04-20T11:55:01.693171Z","steps":["trace[592214408] 'process raft request'  (duration: 100.554978ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:02.431929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.986501ms","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-04-20T11:55:02.432066Z","caller":"traceutil/trace.go:171","msg":"trace[1165913871] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4783; }","duration":"436.173524ms","start":"2026-04-20T11:55:01.99586Z","end":"2026-04-20T11:55:02.432034Z","steps":["trace[1165913871] 'count revisions from in-memory index tree'  (duration: 435.89411ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:02.432124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:01.995836Z","time spent":"436.274217ms","remote":"127.0.0.1:59032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "}
{"level":"warn","ts":"2026-04-20T11:55:02.432203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.183445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-8896cc55b-hj759.18a80e9876bc78ee\" ","response":"range_response_count:1 size:895"}
{"level":"info","ts":"2026-04-20T11:55:02.432269Z","caller":"traceutil/trace.go:171","msg":"trace[212605616] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-8896cc55b-hj759.18a80e9876bc78ee; range_end:; response_count:1; response_revision:4783; }","duration":"429.274226ms","start":"2026-04-20T11:55:02.002981Z","end":"2026-04-20T11:55:02.432255Z","steps":["trace[212605616] 'range keys from in-memory index tree'  (duration: 429.014582ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:02.432319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.799366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c559b556-0a09-4ae0-acda-2341d8b3a173\" ","response":"range_response_count:1 size:3646"}
{"level":"warn","ts":"2026-04-20T11:55:02.432364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:02.002976Z","time spent":"429.380828ms","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":917,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-8896cc55b-hj759.18a80e9876bc78ee\" "}
{"level":"info","ts":"2026-04-20T11:55:02.432432Z","caller":"traceutil/trace.go:171","msg":"trace[1295910027] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c559b556-0a09-4ae0-acda-2341d8b3a173; range_end:; response_count:1; response_revision:4783; }","duration":"429.933978ms","start":"2026-04-20T11:55:02.002486Z","end":"2026-04-20T11:55:02.43242Z","steps":["trace[1295910027] 'range keys from in-memory index tree'  (duration: 429.657933ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:02.432511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:02.002475Z","time spent":"430.023939ms","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c559b556-0a09-4ae0-acda-2341d8b3a173\" "}
{"level":"info","ts":"2026-04-20T11:55:02.432678Z","caller":"traceutil/trace.go:171","msg":"trace[506292053] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"181.464223ms","start":"2026-04-20T11:55:02.251178Z","end":"2026-04-20T11:55:02.432642Z","steps":["trace[506292053] 'process raft request'  (duration: 157.056917ms)","trace[506292053] 'compare'  (duration: 23.444179ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:55:02.630078Z","caller":"traceutil/trace.go:171","msg":"trace[132904747] linearizableReadLoop","detail":"{readStateIndex:4975; appliedIndex:4974; }","duration":"120.705017ms","start":"2026-04-20T11:55:02.509347Z","end":"2026-04-20T11:55:02.630052Z","steps":["trace[132904747] 'read index received'  (duration: 120.026045ms)","trace[132904747] 'applied index is now lower than readState.Index'  (duration: 677.852µs)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:55:02.630254Z","caller":"traceutil/trace.go:171","msg":"trace[1125229217] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"191.717537ms","start":"2026-04-20T11:55:02.438416Z","end":"2026-04-20T11:55:02.630133Z","steps":["trace[1125229217] 'process raft request'  (duration: 190.982155ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:02.63031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.946922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"}
{"level":"info","ts":"2026-04-20T11:55:02.630534Z","caller":"traceutil/trace.go:171","msg":"trace[1186504350] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4786; }","duration":"121.201125ms","start":"2026-04-20T11:55:02.509301Z","end":"2026-04-20T11:55:02.630502Z","steps":["trace[1186504350] 'agreement among raft nodes before linearized reading'  (duration: 120.843569ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:14.325237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.697211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"}
{"level":"warn","ts":"2026-04-20T11:55:14.32526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.96965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"}
{"level":"info","ts":"2026-04-20T11:55:14.325301Z","caller":"traceutil/trace.go:171","msg":"trace[1296724642] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4950; }","duration":"208.809752ms","start":"2026-04-20T11:55:14.116477Z","end":"2026-04-20T11:55:14.325287Z","steps":["trace[1296724642] 'range keys from in-memory index tree'  (duration: 208.379615ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.325321Z","caller":"traceutil/trace.go:171","msg":"trace[1429420691] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4950; }","duration":"155.097091ms","start":"2026-04-20T11:55:14.170209Z","end":"2026-04-20T11:55:14.325306Z","steps":["trace[1429420691] 'range keys from in-memory index tree'  (duration: 154.805867ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:14.325399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.461955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-20T11:55:14.32546Z","caller":"traceutil/trace.go:171","msg":"trace[205566826] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4950; }","duration":"159.565646ms","start":"2026-04-20T11:55:14.165876Z","end":"2026-04-20T11:55:14.325442Z","steps":["trace[205566826] 'count revisions from in-memory index tree'  (duration: 159.359032ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.612818Z","caller":"traceutil/trace.go:171","msg":"trace[391402760] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5144; }","duration":"194.961532ms","start":"2026-04-20T11:55:14.41784Z","end":"2026-04-20T11:55:14.612802Z","steps":["trace[391402760] 'read index received'  (duration: 194.752909ms)","trace[391402760] 'applied index is now lower than readState.Index'  (duration: 207.713µs)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:55:14.612904Z","caller":"traceutil/trace.go:171","msg":"trace[1304565720] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"278.802713ms","start":"2026-04-20T11:55:14.334092Z","end":"2026-04-20T11:55:14.612894Z","steps":["trace[1304565720] 'process raft request'  (duration: 278.510238ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:14.613042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.177476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-20T11:55:14.613137Z","caller":"traceutil/trace.go:171","msg":"trace[1094150685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4951; }","duration":"195.322509ms","start":"2026-04-20T11:55:14.417795Z","end":"2026-04-20T11:55:14.613118Z","steps":["trace[1094150685] 'agreement among raft nodes before linearized reading'  (duration: 195.157926ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.736266Z","caller":"traceutil/trace.go:171","msg":"trace[1262124474] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"123.374915ms","start":"2026-04-20T11:55:14.612866Z","end":"2026-04-20T11:55:14.736241Z","steps":["trace[1262124474] 'read index received'  (duration: 102.153903ms)","trace[1262124474] 'applied index is now lower than readState.Index'  (duration: 21.220042ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:55:14.736448Z","caller":"traceutil/trace.go:171","msg":"trace[122618308] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"241.041637ms","start":"2026-04-20T11:55:14.495379Z","end":"2026-04-20T11:55:14.73642Z","steps":["trace[122618308] 'process raft request'  (duration: 219.627922ms)","trace[122618308] 'compare'  (duration: 21.087179ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:55:14.737067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.354551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-20T11:55:14.737075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.531393ms","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-04-20T11:55:14.737128Z","caller":"traceutil/trace.go:171","msg":"trace[1668394896] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:4952; }","duration":"133.470442ms","start":"2026-04-20T11:55:14.603644Z","end":"2026-04-20T11:55:14.737114Z","steps":["trace[1668394896] 'agreement among raft nodes before linearized reading'  (duration: 132.72382ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.737291Z","caller":"traceutil/trace.go:171","msg":"trace[305844063] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4952; }","duration":"136.645605ms","start":"2026-04-20T11:55:14.600474Z","end":"2026-04-20T11:55:14.73712Z","steps":["trace[305844063] 'agreement among raft nodes before linearized reading'  (duration: 135.915113ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.789148Z","caller":"traceutil/trace.go:171","msg":"trace[873605442] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"169.552651ms","start":"2026-04-20T11:55:14.619574Z","end":"2026-04-20T11:55:14.789126Z","steps":["trace[873605442] 'process raft request'  (duration: 169.398008ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:14.789263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.855789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-20T11:55:14.789363Z","caller":"traceutil/trace.go:171","msg":"trace[1653068695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4956; }","duration":"175.070142ms","start":"2026-04-20T11:55:14.614273Z","end":"2026-04-20T11:55:14.789343Z","steps":["trace[1653068695] 'agreement among raft nodes before linearized reading'  (duration: 174.845579ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.789383Z","caller":"traceutil/trace.go:171","msg":"trace[316798241] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"169.740434ms","start":"2026-04-20T11:55:14.619618Z","end":"2026-04-20T11:55:14.789358Z","steps":["trace[316798241] 'process raft request'  (duration: 169.414199ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.789363Z","caller":"traceutil/trace.go:171","msg":"trace[1981105734] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"169.174805ms","start":"2026-04-20T11:55:14.620131Z","end":"2026-04-20T11:55:14.789305Z","steps":["trace[1981105734] 'process raft request'  (duration: 168.945371ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:14.789125Z","caller":"traceutil/trace.go:171","msg":"trace[648730408] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"169.692163ms","start":"2026-04-20T11:55:14.619396Z","end":"2026-04-20T11:55:14.789089Z","steps":["trace[648730408] 'process raft request'  (duration: 169.191505ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:15.980306Z","caller":"traceutil/trace.go:171","msg":"trace[1301128271] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5158; }","duration":"116.892553ms","start":"2026-04-20T11:55:15.863391Z","end":"2026-04-20T11:55:15.980284Z","steps":["trace[1301128271] 'read index received'  (duration: 88.85896ms)","trace[1301128271] 'applied index is now lower than readState.Index'  (duration: 28.032713ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:55:15.980426Z","caller":"traceutil/trace.go:171","msg":"trace[1980628112] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"128.222121ms","start":"2026-04-20T11:55:15.852166Z","end":"2026-04-20T11:55:15.980388Z","steps":["trace[1980628112] 'process raft request'  (duration: 100.084576ms)","trace[1980628112] 'compare'  (duration: 27.918351ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:55:15.980488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.090346ms","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-04-20T11:55:15.980547Z","caller":"traceutil/trace.go:171","msg":"trace[1013079253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4964; }","duration":"117.204168ms","start":"2026-04-20T11:55:15.863332Z","end":"2026-04-20T11:55:15.980536Z","steps":["trace[1013079253] 'agreement among raft nodes before linearized reading'  (duration: 117.060726ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:33.102525Z","caller":"traceutil/trace.go:171","msg":"trace[1760954123] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"118.61304ms","start":"2026-04-20T11:55:32.983884Z","end":"2026-04-20T11:55:33.102497Z","steps":["trace[1760954123] 'process raft request'  (duration: 118.456638ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:50.98192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.691049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61963"}
{"level":"info","ts":"2026-04-20T11:55:50.981994Z","caller":"traceutil/trace.go:171","msg":"trace[1887779296] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5283; }","duration":"173.80717ms","start":"2026-04-20T11:55:50.80817Z","end":"2026-04-20T11:55:50.981977Z","steps":["trace[1887779296] 'range keys from in-memory index tree'  (duration: 173.466604ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:55:50.982203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.7157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"info","ts":"2026-04-20T11:55:50.982272Z","caller":"traceutil/trace.go:171","msg":"trace[1970828076] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5283; }","duration":"151.829431ms","start":"2026-04-20T11:55:50.830427Z","end":"2026-04-20T11:55:50.982256Z","steps":["trace[1970828076] 'range keys from in-memory index tree'  (duration: 151.564557ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:55:51.865615Z","caller":"traceutil/trace.go:171","msg":"trace[142344836] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"170.062106ms","start":"2026-04-20T11:55:51.695491Z","end":"2026-04-20T11:55:51.865553Z","steps":["trace[142344836] 'process raft request'  (duration: 169.760011ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:56:06.12545Z","caller":"traceutil/trace.go:171","msg":"trace[933930436] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"102.970565ms","start":"2026-04-20T11:56:06.022449Z","end":"2026-04-20T11:56:06.125419Z","steps":["trace[933930436] 'process raft request'  (duration: 102.846573ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:56:06.125621Z","caller":"traceutil/trace.go:171","msg":"trace[1118908930] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"103.749196ms","start":"2026-04-20T11:56:06.021824Z","end":"2026-04-20T11:56:06.125573Z","steps":["trace[1118908930] 'process raft request'  (duration: 59.477982ms)","trace[1118908930] 'compare'  (duration: 43.814517ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:56:16.311542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2829}
{"level":"info","ts":"2026-04-20T11:56:16.380284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2829,"took":"67.06775ms","hash":104440843,"current-db-size-bytes":53223424,"current-db-size":"53 MB","current-db-size-in-use-bytes":28692480,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-04-20T11:56:16.380354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":104440843,"revision":2829,"compact-revision":-1}
{"level":"info","ts":"2026-04-20T11:56:35.777684Z","caller":"traceutil/trace.go:171","msg":"trace[353053976] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"119.062791ms","start":"2026-04-20T11:56:35.658595Z","end":"2026-04-20T11:56:35.777658Z","steps":["trace[353053976] 'process raft request'  (duration: 118.900318ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:07.321751Z","caller":"traceutil/trace.go:171","msg":"trace[1376000737] transaction","detail":"{read_only:false; response_revision:6153; number_of_response:1; }","duration":"100.90497ms","start":"2026-04-20T11:58:07.220814Z","end":"2026-04-20T11:58:07.321719Z","steps":["trace[1376000737] 'process raft request'  (duration: 93.587255ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:38.243812Z","caller":"traceutil/trace.go:171","msg":"trace[337155567] transaction","detail":"{read_only:false; response_revision:6466; number_of_response:1; }","duration":"259.753709ms","start":"2026-04-20T11:58:37.984033Z","end":"2026-04-20T11:58:38.243786Z","steps":["trace[337155567] 'process raft request'  (duration: 259.615278ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:38.58939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.087554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-04-20T11:58:38.589499Z","caller":"traceutil/trace.go:171","msg":"trace[43896262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6467; }","duration":"104.243536ms","start":"2026-04-20T11:58:38.485218Z","end":"2026-04-20T11:58:38.589461Z","steps":["trace[43896262] 'range keys from in-memory index tree'  (duration: 103.825461ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:38.928025Z","caller":"traceutil/trace.go:171","msg":"trace[1992230588] transaction","detail":"{read_only:false; response_revision:6469; number_of_response:1; }","duration":"106.940611ms","start":"2026-04-20T11:58:38.821003Z","end":"2026-04-20T11:58:38.927943Z","steps":["trace[1992230588] 'process raft request'  (duration: 106.785829ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:51.615116Z","caller":"traceutil/trace.go:171","msg":"trace[897074767] linearizableReadLoop","detail":"{readStateIndex:6818; appliedIndex:6817; }","duration":"107.471598ms","start":"2026-04-20T11:58:51.507625Z","end":"2026-04-20T11:58:51.615097Z","steps":["trace[897074767] 'read index received'  (duration: 103.935763ms)","trace[897074767] 'applied index is now lower than readState.Index'  (duration: 3.535275ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:58:51.615323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.673071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-20T11:58:51.615429Z","caller":"traceutil/trace.go:171","msg":"trace[139668919] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:6571; }","duration":"107.832012ms","start":"2026-04-20T11:58:51.50758Z","end":"2026-04-20T11:58:51.615412Z","steps":["trace[139668919] 'agreement among raft nodes before linearized reading'  (duration: 107.62055ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:51.615671Z","caller":"traceutil/trace.go:171","msg":"trace[1021204158] transaction","detail":"{read_only:false; response_revision:6571; number_of_response:1; }","duration":"108.230397ms","start":"2026-04-20T11:58:51.507427Z","end":"2026-04-20T11:58:51.615657Z","steps":["trace[1021204158] 'process raft request'  (duration: 104.124395ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:51.624671Z","caller":"traceutil/trace.go:171","msg":"trace[185492470] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6571; }","duration":"113.1262ms","start":"2026-04-20T11:58:51.511529Z","end":"2026-04-20T11:58:51.624655Z","steps":["trace[185492470] 'process raft request'  (duration: 113.060239ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:51.625308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.513122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-20T11:58:51.625385Z","caller":"traceutil/trace.go:171","msg":"trace[409561105] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6571; }","duration":"101.648664ms","start":"2026-04-20T11:58:51.523715Z","end":"2026-04-20T11:58:51.625364Z","steps":["trace[409561105] 'agreement among raft nodes before linearized reading'  (duration: 101.465672ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:51.797714Z","caller":"traceutil/trace.go:171","msg":"trace[297882431] linearizableReadLoop","detail":"{readStateIndex:6825; appliedIndex:6824; }","duration":"132.156842ms","start":"2026-04-20T11:58:51.665533Z","end":"2026-04-20T11:58:51.797689Z","steps":["trace[297882431] 'read index received'  (duration: 70.004361ms)","trace[297882431] 'applied index is now lower than readState.Index'  (duration: 62.151611ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:58:51.797862Z","caller":"traceutil/trace.go:171","msg":"trace[1575562506] transaction","detail":"{read_only:false; response_revision:6577; number_of_response:1; }","duration":"132.79712ms","start":"2026-04-20T11:58:51.66504Z","end":"2026-04-20T11:58:51.797837Z","steps":["trace[1575562506] 'process raft request'  (duration: 70.567928ms)","trace[1575562506] 'compare'  (duration: 61.973499ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:58:51.798261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.058094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"}
{"level":"warn","ts":"2026-04-20T11:58:51.798269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.401969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/horizon-int-certs\" ","response":"range_response_count:1 size:2460"}
{"level":"info","ts":"2026-04-20T11:58:51.798322Z","caller":"traceutil/trace.go:171","msg":"trace[709917918] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:6577; }","duration":"126.160045ms","start":"2026-04-20T11:58:51.672148Z","end":"2026-04-20T11:58:51.798308Z","steps":["trace[709917918] 'agreement among raft nodes before linearized reading'  (duration: 125.797491ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:51.79833Z","caller":"traceutil/trace.go:171","msg":"trace[219588448] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/horizon-int-certs; range_end:; response_count:1; response_revision:6577; }","duration":"130.501861ms","start":"2026-04-20T11:58:51.667814Z","end":"2026-04-20T11:58:51.798316Z","steps":["trace[219588448] 'agreement among raft nodes before linearized reading'  (duration: 130.166657ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:51.798311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.051701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"}
{"level":"warn","ts":"2026-04-20T11:58:51.798366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.813471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"}
{"level":"info","ts":"2026-04-20T11:58:51.798452Z","caller":"traceutil/trace.go:171","msg":"trace[1580307962] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:6577; }","duration":"132.917592ms","start":"2026-04-20T11:58:51.665513Z","end":"2026-04-20T11:58:51.79843Z","steps":["trace[1580307962] 'agreement among raft nodes before linearized reading'  (duration: 132.717579ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:51.798387Z","caller":"traceutil/trace.go:171","msg":"trace[2124362828] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:6577; }","duration":"132.153602ms","start":"2026-04-20T11:58:51.666211Z","end":"2026-04-20T11:58:51.798365Z","steps":["trace[2124362828] 'agreement among raft nodes before linearized reading'  (duration: 131.732447ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:57.307494Z","caller":"traceutil/trace.go:171","msg":"trace[2105482197] transaction","detail":"{read_only:false; response_revision:6619; number_of_response:1; }","duration":"130.778819ms","start":"2026-04-20T11:58:57.176697Z","end":"2026-04-20T11:58:57.307476Z","steps":["trace[2105482197] 'process raft request'  (duration: 130.667788ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:57.764289Z","caller":"traceutil/trace.go:171","msg":"trace[380648112] linearizableReadLoop","detail":"{readStateIndex:6869; appliedIndex:6867; }","duration":"541.540631ms","start":"2026-04-20T11:58:57.222719Z","end":"2026-04-20T11:58:57.76426Z","steps":["trace[380648112] 'read index received'  (duration: 84.723365ms)","trace[380648112] 'applied index is now lower than readState.Index'  (duration: 456.812466ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:58:57.765188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.441863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-20T11:58:57.765234Z","caller":"traceutil/trace.go:171","msg":"trace[227425363] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:6620; }","duration":"542.531073ms","start":"2026-04-20T11:58:57.222695Z","end":"2026-04-20T11:58:57.765226Z","steps":["trace[227425363] 'agreement among raft nodes before linearized reading'  (duration: 541.712493ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.765293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.222687Z","time spent":"542.596124ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":42,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "}
{"level":"info","ts":"2026-04-20T11:58:57.765689Z","caller":"traceutil/trace.go:171","msg":"trace[303063607] transaction","detail":"{read_only:false; response_revision:6620; number_of_response:1; }","duration":"544.347127ms","start":"2026-04-20T11:58:57.221327Z","end":"2026-04-20T11:58:57.765674Z","steps":["trace[303063607] 'process raft request'  (duration: 542.605535ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.765797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.221308Z","time spent":"544.415848ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:6610 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-04-20T11:58:57.805411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.567493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-20T11:58:57.805766Z","caller":"traceutil/trace.go:171","msg":"trace[822443872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6621; }","duration":"389.961427ms","start":"2026-04-20T11:58:57.415789Z","end":"2026-04-20T11:58:57.80575Z","steps":["trace[822443872] 'agreement among raft nodes before linearized reading'  (duration: 389.565752ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.805826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.415771Z","time spent":"390.046008ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-20T11:58:57.806273Z","caller":"traceutil/trace.go:171","msg":"trace[2044712671] transaction","detail":"{read_only:false; response_revision:6621; number_of_response:1; }","duration":"405.987231ms","start":"2026-04-20T11:58:57.400276Z","end":"2026-04-20T11:58:57.806264Z","steps":["trace[2044712671] 'process raft request'  (duration: 404.972998ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.80637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.40026Z","time spent":"406.063722ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12283,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:5534 > success:<request_put:<key:\"/registry/minions/instance\" value_size:12249 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"warn","ts":"2026-04-20T11:58:57.806548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.93944ms","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-04-20T11:58:57.806595Z","caller":"traceutil/trace.go:171","msg":"trace[413902581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6621; }","duration":"116.035732ms","start":"2026-04-20T11:58:57.690552Z","end":"2026-04-20T11:58:57.806588Z","steps":["trace[413902581] 'agreement among raft nodes before linearized reading'  (duration: 115.928261ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.806747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.692253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-20T11:58:57.806793Z","caller":"traceutil/trace.go:171","msg":"trace[166338413] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6621; }","duration":"132.769475ms","start":"2026-04-20T11:58:57.674016Z","end":"2026-04-20T11:58:57.806785Z","steps":["trace[166338413] 'agreement among raft nodes before linearized reading'  (duration: 132.687113ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.806945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.746831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-04-20T11:58:57.806994Z","caller":"traceutil/trace.go:171","msg":"trace[780786306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6621; }","duration":"302.827992ms","start":"2026-04-20T11:58:57.504159Z","end":"2026-04-20T11:58:57.806987Z","steps":["trace[780786306] 'agreement among raft nodes before linearized reading'  (duration: 302.742461ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.807036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.504143Z","time spent":"302.886323ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-04-20T11:58:57.807162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.994738ms","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-04-20T11:58:57.807208Z","caller":"traceutil/trace.go:171","msg":"trace[2091781600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6621; }","duration":"338.07592ms","start":"2026-04-20T11:58:57.469125Z","end":"2026-04-20T11:58:57.807201Z","steps":["trace[2091781600] 'agreement among raft nodes before linearized reading'  (duration: 337.997659ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:57.807241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.469103Z","time spent":"338.13077ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-04-20T11:58:58.176177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.225404ms","expected-duration":"100ms","prefix":"","request":"header:<ID:11521225628274561056 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:6614 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-20T11:58:58.176589Z","caller":"traceutil/trace.go:171","msg":"trace[2095320285] linearizableReadLoop","detail":"{readStateIndex:6874; appliedIndex:6870; }","duration":"351.166773ms","start":"2026-04-20T11:58:57.825408Z","end":"2026-04-20T11:58:58.176574Z","steps":["trace[2095320285] 'read index received'  (duration: 107.345272ms)","trace[2095320285] 'applied index is now lower than readState.Index'  (duration: 243.820391ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:58:58.176703Z","caller":"traceutil/trace.go:171","msg":"trace[1372583990] transaction","detail":"{read_only:false; response_revision:6623; number_of_response:1; }","duration":"356.531682ms","start":"2026-04-20T11:58:57.820161Z","end":"2026-04-20T11:58:58.176693Z","steps":["trace[1372583990] 'process raft request'  (duration: 356.102336ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:58.176794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.820085Z","time spent":"356.646292ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:6611 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-04-20T11:58:58.176885Z","caller":"traceutil/trace.go:171","msg":"trace[852245946] transaction","detail":"{read_only:false; response_revision:6624; number_of_response:1; }","duration":"353.470503ms","start":"2026-04-20T11:58:57.823383Z","end":"2026-04-20T11:58:58.176853Z","steps":["trace[852245946] 'process raft request'  (duration: 353.113998ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:58.176918Z","caller":"traceutil/trace.go:171","msg":"trace[1202285301] transaction","detail":"{read_only:false; response_revision:6622; number_of_response:1; }","duration":"357.412942ms","start":"2026-04-20T11:58:57.819464Z","end":"2026-04-20T11:58:58.176877Z","steps":["trace[1202285301] 'process raft request'  (duration: 113.279247ms)","trace[1202285301] 'compare'  (duration: 243.025611ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:58:58.177039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.823367Z","time spent":"353.598284ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:6612 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-04-20T11:58:58.177062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.819446Z","time spent":"357.540775ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:6614 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-04-20T11:58:58.17713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.66421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-20T11:58:58.177209Z","caller":"traceutil/trace.go:171","msg":"trace[1717234110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6625; }","duration":"351.816881ms","start":"2026-04-20T11:58:57.825378Z","end":"2026-04-20T11:58:58.177195Z","steps":["trace[1717234110] 'agreement among raft nodes before linearized reading'  (duration: 351.501847ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:58:58.177241Z","caller":"traceutil/trace.go:171","msg":"trace[1136840078] transaction","detail":"{read_only:false; response_revision:6625; number_of_response:1; }","duration":"352.743334ms","start":"2026-04-20T11:58:57.824483Z","end":"2026-04-20T11:58:58.177227Z","steps":["trace[1136840078] 'process raft request'  (duration: 352.058245ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T11:58:58.177253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.82537Z","time spent":"351.875373ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-20T11:58:58.177369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:57.824471Z","time spent":"352.805224ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:6613 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-20T11:58:58.177694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.856994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-20T11:58:58.177724Z","caller":"traceutil/trace.go:171","msg":"trace[2078340889] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6625; }","duration":"199.895525ms","start":"2026-04-20T11:58:57.977822Z","end":"2026-04-20T11:58:58.177718Z","steps":["trace[2078340889] 'agreement among raft nodes before linearized reading'  (duration: 199.820564ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:59:34.606261Z","caller":"traceutil/trace.go:171","msg":"trace[1755938288] linearizableReadLoop","detail":"{readStateIndex:7068; appliedIndex:7067; }","duration":"150.344777ms","start":"2026-04-20T11:59:34.455899Z","end":"2026-04-20T11:59:34.606244Z","steps":["trace[1755938288] 'read index received'  (duration: 129.910942ms)","trace[1755938288] 'applied index is now lower than readState.Index'  (duration: 20.433025ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T11:59:34.606405Z","caller":"traceutil/trace.go:171","msg":"trace[1175709740] transaction","detail":"{read_only:false; response_revision:6812; number_of_response:1; }","duration":"184.22777ms","start":"2026-04-20T11:59:34.422169Z","end":"2026-04-20T11:59:34.606397Z","steps":["trace[1175709740] 'process raft request'  (duration: 163.626133ms)","trace[1175709740] 'compare'  (duration: 20.099591ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T11:59:34.606551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.651271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-20T11:59:34.60657Z","caller":"traceutil/trace.go:171","msg":"trace[675561560] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6812; }","duration":"150.719652ms","start":"2026-04-20T11:59:34.455842Z","end":"2026-04-20T11:59:34.606562Z","steps":["trace[675561560] 'agreement among raft nodes before linearized reading'  (duration: 150.647101ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:59:34.607776Z","caller":"traceutil/trace.go:171","msg":"trace[55010958] transaction","detail":"{read_only:false; response_revision:6813; number_of_response:1; }","duration":"144.619555ms","start":"2026-04-20T11:59:34.463144Z","end":"2026-04-20T11:59:34.607763Z","steps":["trace[55010958] 'process raft request'  (duration: 143.988867ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T11:59:53.364346Z","caller":"traceutil/trace.go:171","msg":"trace[552746235] transaction","detail":"{read_only:false; response_revision:6931; number_of_response:1; }","duration":"115.514842ms","start":"2026-04-20T11:59:53.248807Z","end":"2026-04-20T11:59:53.364322Z","steps":["trace[552746235] 'process raft request'  (duration: 115.30093ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:00:33.088508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.047937ms","expected-duration":"100ms","prefix":"","request":"header:<ID:11521225628274562642 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:7153 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-20T12:00:33.088658Z","caller":"traceutil/trace.go:171","msg":"trace[497365613] transaction","detail":"{read_only:false; response_revision:7162; number_of_response:1; }","duration":"222.707052ms","start":"2026-04-20T12:00:32.865934Z","end":"2026-04-20T12:00:33.088641Z","steps":["trace[497365613] 'process raft request'  (duration: 74.425107ms)","trace[497365613] 'compare'  (duration: 147.912651ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:00:33.239098Z","caller":"traceutil/trace.go:171","msg":"trace[1563571580] transaction","detail":"{read_only:false; response_revision:7164; number_of_response:1; }","duration":"146.434733ms","start":"2026-04-20T12:00:33.092644Z","end":"2026-04-20T12:00:33.239079Z","steps":["trace[1563571580] 'process raft request'  (duration: 106.667964ms)","trace[1563571580] 'compare'  (duration: 39.648324ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:00:33.239456Z","caller":"traceutil/trace.go:171","msg":"trace[1666053508] transaction","detail":"{read_only:false; response_revision:7165; number_of_response:1; }","duration":"146.630969ms","start":"2026-04-20T12:00:33.092801Z","end":"2026-04-20T12:00:33.239432Z","steps":["trace[1666053508] 'process raft request'  (duration: 146.247025ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:00:33.240252Z","caller":"traceutil/trace.go:171","msg":"trace[248119829] transaction","detail":"{read_only:false; response_revision:7166; number_of_response:1; }","duration":"146.132832ms","start":"2026-04-20T12:00:33.094071Z","end":"2026-04-20T12:00:33.240204Z","steps":["trace[248119829] 'process raft request'  (duration: 145.283451ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:00:45.492963Z","caller":"traceutil/trace.go:171","msg":"trace[326724407] transaction","detail":"{read_only:false; response_revision:7225; number_of_response:1; }","duration":"116.136834ms","start":"2026-04-20T12:00:45.376792Z","end":"2026-04-20T12:00:45.492929Z","steps":["trace[326724407] 'process raft request'  (duration: 115.507082ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:03.711175Z","caller":"traceutil/trace.go:171","msg":"trace[1071570506] transaction","detail":"{read_only:false; response_revision:7316; number_of_response:1; }","duration":"140.318764ms","start":"2026-04-20T12:01:03.570832Z","end":"2026-04-20T12:01:03.711151Z","steps":["trace[1071570506] 'process raft request'  (duration: 140.19904ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:03.715722Z","caller":"traceutil/trace.go:171","msg":"trace[1314183318] linearizableReadLoop","detail":"{readStateIndex:7592; appliedIndex:7591; }","duration":"113.908586ms","start":"2026-04-20T12:01:03.601804Z","end":"2026-04-20T12:01:03.715713Z","steps":["trace[1314183318] 'read index received'  (duration: 109.561088ms)","trace[1314183318] 'applied index is now lower than readState.Index'  (duration: 4.346548ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:01:03.71588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.063541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-20T12:01:03.715936Z","caller":"traceutil/trace.go:171","msg":"trace[581641824] transaction","detail":"{read_only:false; response_revision:7317; number_of_response:1; }","duration":"142.932053ms","start":"2026-04-20T12:01:03.572979Z","end":"2026-04-20T12:01:03.715912Z","steps":["trace[581641824] 'process raft request'  (duration: 142.684835ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:03.715907Z","caller":"traceutil/trace.go:171","msg":"trace[1162539010] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7317; }","duration":"114.137673ms","start":"2026-04-20T12:01:03.601759Z","end":"2026-04-20T12:01:03.715897Z","steps":["trace[1162539010] 'agreement among raft nodes before linearized reading'  (duration: 113.990378ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:06.622852Z","caller":"traceutil/trace.go:171","msg":"trace[1162024991] transaction","detail":"{read_only:false; response_revision:7331; number_of_response:1; }","duration":"100.370713ms","start":"2026-04-20T12:01:06.522439Z","end":"2026-04-20T12:01:06.62281Z","steps":["trace[1162024991] 'process raft request'  (duration: 38.948717ms)","trace[1162024991] 'compare'  (duration: 61.193428ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:01:06.714824Z","caller":"traceutil/trace.go:171","msg":"trace[1491373373] transaction","detail":"{read_only:false; response_revision:7332; number_of_response:1; }","duration":"121.838768ms","start":"2026-04-20T12:01:06.592953Z","end":"2026-04-20T12:01:06.714792Z","steps":["trace[1491373373] 'process raft request'  (duration: 121.680272ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:07.281505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225628274563107,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-04-20T12:01:07.379845Z","caller":"traceutil/trace.go:171","msg":"trace[1919577242] linearizableReadLoop","detail":"{readStateIndex:7609; appliedIndex:7608; }","duration":"599.37424ms","start":"2026-04-20T12:01:06.780454Z","end":"2026-04-20T12:01:07.379828Z","steps":["trace[1919577242] 'read index received'  (duration: 599.215014ms)","trace[1919577242] 'applied index is now lower than readState.Index'  (duration: 158.746µs)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:01:07.379923Z","caller":"traceutil/trace.go:171","msg":"trace[1309025047] transaction","detail":"{read_only:false; response_revision:7333; number_of_response:1; }","duration":"701.614875ms","start":"2026-04-20T12:01:06.678276Z","end":"2026-04-20T12:01:07.379891Z","steps":["trace[1309025047] 'process raft request'  (duration: 701.444509ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:07.380151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.678019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"warn","ts":"2026-04-20T12:01:07.380203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:01:06.678236Z","time spent":"701.887785ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:7281 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-04-20T12:01:07.380287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.62749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-20T12:01:07.380352Z","caller":"traceutil/trace.go:171","msg":"trace[1505613559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7333; }","duration":"338.708003ms","start":"2026-04-20T12:01:07.041633Z","end":"2026-04-20T12:01:07.380341Z","steps":["trace[1505613559] 'agreement among raft nodes before linearized reading'  (duration: 338.517717ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:07.38039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:01:07.041564Z","time spent":"338.817696ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-20T12:01:07.380252Z","caller":"traceutil/trace.go:171","msg":"trace[1427010811] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7333; }","duration":"599.834995ms","start":"2026-04-20T12:01:06.780393Z","end":"2026-04-20T12:01:07.380228Z","steps":["trace[1427010811] 'agreement among raft nodes before linearized reading'  (duration: 599.538805ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:07.380495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:01:06.780351Z","time spent":"600.129215ms","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-04-20T12:01:07.587818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.272232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"}
{"level":"info","ts":"2026-04-20T12:01:07.587884Z","caller":"traceutil/trace.go:171","msg":"trace[1015889575] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7336; }","duration":"108.372935ms","start":"2026-04-20T12:01:07.479494Z","end":"2026-04-20T12:01:07.587866Z","steps":["trace[1015889575] 'range keys from in-memory index tree'  (duration: 108.123977ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:13.590302Z","caller":"traceutil/trace.go:171","msg":"trace[1684858449] linearizableReadLoop","detail":"{readStateIndex:7642; appliedIndex:7641; }","duration":"102.772891ms","start":"2026-04-20T12:01:13.487499Z","end":"2026-04-20T12:01:13.590271Z","steps":["trace[1684858449] 'read index received'  (duration: 46.212338ms)","trace[1684858449] 'applied index is now lower than readState.Index'  (duration: 56.559193ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:01:13.590539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.02542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-20T12:01:13.590574Z","caller":"traceutil/trace.go:171","msg":"trace[913288018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7365; }","duration":"103.122663ms","start":"2026-04-20T12:01:13.487442Z","end":"2026-04-20T12:01:13.590564Z","steps":["trace[913288018] 'agreement among raft nodes before linearized reading'  (duration: 102.937216ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:13.5905Z","caller":"traceutil/trace.go:171","msg":"trace[1618138283] transaction","detail":"{read_only:false; response_revision:7365; number_of_response:1; }","duration":"144.170529ms","start":"2026-04-20T12:01:13.446283Z","end":"2026-04-20T12:01:13.590453Z","steps":["trace[1618138283] 'process raft request'  (duration: 87.466112ms)","trace[1618138283] 'compare'  (duration: 56.371196ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:01:16.329728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5474}
{"level":"info","ts":"2026-04-20T12:01:16.548942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5474,"took":"217.252415ms","hash":3705435785,"current-db-size-bytes":53223424,"current-db-size":"53 MB","current-db-size-in-use-bytes":18530304,"current-db-size-in-use":"18 MB"}
{"level":"info","ts":"2026-04-20T12:01:16.549001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3705435785,"revision":5474,"compact-revision":2829}
{"level":"warn","ts":"2026-04-20T12:01:19.982322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.338856ms","expected-duration":"100ms","prefix":"","request":"header:<ID:11521225628274563507 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:7388 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-20T12:01:19.982427Z","caller":"traceutil/trace.go:171","msg":"trace[1487975536] linearizableReadLoop","detail":"{readStateIndex:7678; appliedIndex:7677; }","duration":"124.639986ms","start":"2026-04-20T12:01:19.857772Z","end":"2026-04-20T12:01:19.982412Z","steps":["trace[1487975536] 'read index received'  (duration: 14.071482ms)","trace[1487975536] 'applied index is now lower than readState.Index'  (duration: 110.567404ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:01:19.982447Z","caller":"traceutil/trace.go:171","msg":"trace[233850203] transaction","detail":"{read_only:false; response_revision:7398; number_of_response:1; }","duration":"125.610108ms","start":"2026-04-20T12:01:19.856807Z","end":"2026-04-20T12:01:19.982417Z","steps":["trace[233850203] 'process raft request'  (duration: 15.083276ms)","trace[233850203] 'compare'  (duration: 110.186351ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:01:19.982686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.810791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx\" ","response":"range_response_count:1 size:1075"}
{"level":"info","ts":"2026-04-20T12:01:19.982755Z","caller":"traceutil/trace.go:171","msg":"trace[1125300816] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx; range_end:; response_count:1; response_revision:7398; }","duration":"124.965236ms","start":"2026-04-20T12:01:19.857769Z","end":"2026-04-20T12:01:19.982735Z","steps":["trace[1125300816] 'agreement among raft nodes before linearized reading'  (duration: 124.707728ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:20.019386Z","caller":"traceutil/trace.go:171","msg":"trace[66231411] transaction","detail":"{read_only:false; response_revision:7399; number_of_response:1; }","duration":"124.426668ms","start":"2026-04-20T12:01:19.894927Z","end":"2026-04-20T12:01:20.019354Z","steps":["trace[66231411] 'process raft request'  (duration: 124.1856ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:20.019489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.979558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"}
{"level":"info","ts":"2026-04-20T12:01:20.019574Z","caller":"traceutil/trace.go:171","msg":"trace[1630741177] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:7399; }","duration":"119.113184ms","start":"2026-04-20T12:01:19.900441Z","end":"2026-04-20T12:01:20.019554Z","steps":["trace[1630741177] 'agreement among raft nodes before linearized reading'  (duration: 118.959068ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:21.644689Z","caller":"traceutil/trace.go:171","msg":"trace[1721807267] linearizableReadLoop","detail":"{readStateIndex:7686; appliedIndex:7685; }","duration":"132.01736ms","start":"2026-04-20T12:01:21.51264Z","end":"2026-04-20T12:01:21.644658Z","steps":["trace[1721807267] 'read index received'  (duration: 101.138959ms)","trace[1721807267] 'applied index is now lower than readState.Index'  (duration: 30.876621ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:01:21.645015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.34947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:835"}
{"level":"info","ts":"2026-04-20T12:01:21.645303Z","caller":"traceutil/trace.go:171","msg":"trace[437578105] range","detail":"{range_begin:/registry/clusterroles/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:7406; }","duration":"132.678501ms","start":"2026-04-20T12:01:21.512606Z","end":"2026-04-20T12:01:21.645284Z","steps":["trace[437578105] 'agreement among raft nodes before linearized reading'  (duration: 132.280228ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:21.645299Z","caller":"traceutil/trace.go:171","msg":"trace[1675921413] transaction","detail":"{read_only:false; response_revision:7406; number_of_response:1; }","duration":"132.762424ms","start":"2026-04-20T12:01:21.512132Z","end":"2026-04-20T12:01:21.644894Z","steps":["trace[1675921413] 'process raft request'  (duration: 101.664186ms)","trace[1675921413] 'compare'  (duration: 30.634702ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:01:21.646207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.961002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-04-20T12:01:21.646255Z","caller":"traceutil/trace.go:171","msg":"trace[766933294] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:7407; }","duration":"104.055345ms","start":"2026-04-20T12:01:21.542186Z","end":"2026-04-20T12:01:21.646241Z","steps":["trace[766933294] 'agreement among raft nodes before linearized reading'  (duration: 103.867898ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:24.105855Z","caller":"traceutil/trace.go:171","msg":"trace[1931839407] transaction","detail":"{read_only:false; response_revision:7420; number_of_response:1; }","duration":"107.973521ms","start":"2026-04-20T12:01:23.997846Z","end":"2026-04-20T12:01:24.10582Z","steps":["trace[1931839407] 'process raft request'  (duration: 107.663821ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:24.221097Z","caller":"traceutil/trace.go:171","msg":"trace[1157842094] linearizableReadLoop","detail":"{readStateIndex:7702; appliedIndex:7700; }","duration":"109.816371ms","start":"2026-04-20T12:01:24.111257Z","end":"2026-04-20T12:01:24.221073Z","steps":["trace[1157842094] 'read index received'  (duration: 19.636139ms)","trace[1157842094] 'applied index is now lower than readState.Index'  (duration: 90.179202ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:01:24.221171Z","caller":"traceutil/trace.go:171","msg":"trace[1232505768] transaction","detail":"{read_only:false; response_revision:7422; number_of_response:1; }","duration":"110.868965ms","start":"2026-04-20T12:01:24.11027Z","end":"2026-04-20T12:01:24.221139Z","steps":["trace[1232505768] 'process raft request'  (duration: 110.72196ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:24.221309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.027497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:807"}
{"level":"info","ts":"2026-04-20T12:01:24.221394Z","caller":"traceutil/trace.go:171","msg":"trace[149940579] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:7422; }","duration":"110.12972ms","start":"2026-04-20T12:01:24.11124Z","end":"2026-04-20T12:01:24.22137Z","steps":["trace[149940579] 'agreement among raft nodes before linearized reading'  (duration: 109.947674ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:24.680907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.368621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:node-controller\" ","response":"range_response_count:1 size:835"}
{"level":"info","ts":"2026-04-20T12:01:24.680978Z","caller":"traceutil/trace.go:171","msg":"trace[1123713655] range","detail":"{range_begin:/registry/clusterroles/system:controller:node-controller; range_end:; response_count:1; response_revision:7424; }","duration":"103.476554ms","start":"2026-04-20T12:01:24.577486Z","end":"2026-04-20T12:01:24.680962Z","steps":["trace[1123713655] 'range keys from in-memory index tree'  (duration: 103.239146ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:40.042791Z","caller":"traceutil/trace.go:171","msg":"trace[1047964851] linearizableReadLoop","detail":"{readStateIndex:7780; appliedIndex:7779; }","duration":"233.736581ms","start":"2026-04-20T12:01:39.80902Z","end":"2026-04-20T12:01:40.042757Z","steps":["trace[1047964851] 'read index received'  (duration: 233.586546ms)","trace[1047964851] 'applied index is now lower than readState.Index'  (duration: 148.395µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:01:40.04295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.913746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"}
{"level":"info","ts":"2026-04-20T12:01:40.043008Z","caller":"traceutil/trace.go:171","msg":"trace[1965113224] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:7496; }","duration":"234.000979ms","start":"2026-04-20T12:01:39.808992Z","end":"2026-04-20T12:01:40.042992Z","steps":["trace[1965113224] 'agreement among raft nodes before linearized reading'  (duration: 233.851195ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:40.078607Z","caller":"traceutil/trace.go:171","msg":"trace[610249141] transaction","detail":"{read_only:false; response_revision:7497; number_of_response:1; }","duration":"221.0984ms","start":"2026-04-20T12:01:39.857483Z","end":"2026-04-20T12:01:40.078582Z","steps":["trace[610249141] 'process raft request'  (duration: 220.817411ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:40.078737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.673604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-04-20T12:01:40.078807Z","caller":"traceutil/trace.go:171","msg":"trace[567651366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7497; }","duration":"205.771108ms","start":"2026-04-20T12:01:39.873019Z","end":"2026-04-20T12:01:40.07879Z","steps":["trace[567651366] 'agreement among raft nodes before linearized reading'  (duration: 205.53245ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:40.078957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.531711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-20T12:01:40.079039Z","caller":"traceutil/trace.go:171","msg":"trace[457670008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7497; }","duration":"206.650825ms","start":"2026-04-20T12:01:39.872366Z","end":"2026-04-20T12:01:40.079017Z","steps":["trace[457670008] 'agreement among raft nodes before linearized reading'  (duration: 206.438988ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:40.079036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.425813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/\" range_end:\"/registry/serviceaccounts/kube-system0\" limit:500 ","response":"range_response_count:37 size:8042"}
{"level":"info","ts":"2026-04-20T12:01:40.079162Z","caller":"traceutil/trace.go:171","msg":"trace[724445806] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/; range_end:/registry/serviceaccounts/kube-system0; response_count:37; response_revision:7497; }","duration":"163.523307ms","start":"2026-04-20T12:01:39.91556Z","end":"2026-04-20T12:01:40.079083Z","steps":["trace[724445806] 'agreement among raft nodes before linearized reading'  (duration: 163.178336ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:40.079194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.783669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1910"}
{"level":"info","ts":"2026-04-20T12:01:40.079252Z","caller":"traceutil/trace.go:171","msg":"trace[676103716] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:7497; }","duration":"219.874812ms","start":"2026-04-20T12:01:39.859362Z","end":"2026-04-20T12:01:40.079237Z","steps":["trace[676103716] 'agreement among raft nodes before linearized reading'  (duration: 219.678735ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:42.263663Z","caller":"traceutil/trace.go:171","msg":"trace[280517760] linearizableReadLoop","detail":"{readStateIndex:7794; appliedIndex:7793; }","duration":"122.196376ms","start":"2026-04-20T12:01:42.141434Z","end":"2026-04-20T12:01:42.26363Z","steps":["trace[280517760] 'read index received'  (duration: 43.392628ms)","trace[280517760] 'applied index is now lower than readState.Index'  (duration: 78.802338ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:01:42.263729Z","caller":"traceutil/trace.go:171","msg":"trace[1837193745] transaction","detail":"{read_only:false; response_revision:7510; number_of_response:1; }","duration":"140.569041ms","start":"2026-04-20T12:01:42.123128Z","end":"2026-04-20T12:01:42.263697Z","steps":["trace[1837193745] 'process raft request'  (duration: 61.605938ms)","trace[1837193745] 'compare'  (duration: 78.633072ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:01:42.264406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.947279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller-admission\" ","response":"range_response_count:1 size:1526"}
{"level":"info","ts":"2026-04-20T12:01:42.26449Z","caller":"traceutil/trace.go:171","msg":"trace[1940960658] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller-admission; range_end:; response_count:1; response_revision:7510; }","duration":"123.059793ms","start":"2026-04-20T12:01:42.141398Z","end":"2026-04-20T12:01:42.264457Z","steps":["trace[1940960658] 'agreement among raft nodes before linearized reading'  (duration: 122.409892ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:01:42.264397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.489744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" limit:500 ","response":"range_response_count:21 size:176446"}
{"level":"info","ts":"2026-04-20T12:01:42.264619Z","caller":"traceutil/trace.go:171","msg":"trace[1250242594] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:21; response_revision:7510; }","duration":"106.724721ms","start":"2026-04-20T12:01:42.157878Z","end":"2026-04-20T12:01:42.264602Z","steps":["trace[1250242594] 'agreement among raft nodes before linearized reading'  (duration: 106.05039ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:01:42.437533Z","caller":"traceutil/trace.go:171","msg":"trace[1717441026] transaction","detail":"{read_only:false; response_revision:7511; number_of_response:1; }","duration":"143.075539ms","start":"2026-04-20T12:01:42.29443Z","end":"2026-04-20T12:01:42.437505Z","steps":["trace[1717441026] 'process raft request'  (duration: 136.750701ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:02:03.098858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.952088ms","expected-duration":"100ms","prefix":"","request":"header:<ID:11521225628274565887 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:7605 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-20T12:02:03.098987Z","caller":"traceutil/trace.go:171","msg":"trace[1935362536] linearizableReadLoop","detail":"{readStateIndex:7903; appliedIndex:7902; }","duration":"400.069514ms","start":"2026-04-20T12:02:02.698904Z","end":"2026-04-20T12:02:03.098974Z","steps":["trace[1935362536] 'read index received'  (duration: 32.950051ms)","trace[1935362536] 'applied index is now lower than readState.Index'  (duration: 367.118783ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:02:03.099008Z","caller":"traceutil/trace.go:171","msg":"trace[1477970440] transaction","detail":"{read_only:false; response_revision:7615; number_of_response:1; }","duration":"438.659644ms","start":"2026-04-20T12:02:02.660314Z","end":"2026-04-20T12:02:03.098974Z","steps":["trace[1477970440] 'process raft request'  (duration: 71.535591ms)","trace[1477970440] 'compare'  (duration: 366.875656ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:02:03.099116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:02.660283Z","time spent":"438.779858ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:7605 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-20T12:02:03.099305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.399504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:908"}
{"level":"info","ts":"2026-04-20T12:02:03.099379Z","caller":"traceutil/trace.go:171","msg":"trace[1019566482] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:7616; }","duration":"400.500627ms","start":"2026-04-20T12:02:02.698867Z","end":"2026-04-20T12:02:03.099368Z","steps":["trace[1019566482] 'agreement among raft nodes before linearized reading'  (duration: 400.164417ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:02:03.099428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:02.698852Z","time spent":"400.568098ms","remote":"127.0.0.1:40326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":930,"request content":"key:\"/registry/services/endpoints/openstack/rabbitmq-keystone\" "}
{"level":"warn","ts":"2026-04-20T12:02:03.099433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.711863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:966"}
{"level":"info","ts":"2026-04-20T12:02:03.099461Z","caller":"traceutil/trace.go:171","msg":"trace[1216545141] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:7616; }","duration":"391.739123ms","start":"2026-04-20T12:02:02.707713Z","end":"2026-04-20T12:02:03.099452Z","steps":["trace[1216545141] 'agreement among raft nodes before linearized reading'  (duration: 391.669681ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:02:03.099485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:02.707699Z","time spent":"391.781164ms","remote":"127.0.0.1:40326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":988,"request content":"key:\"/registry/services/endpoints/openstack/rabbitmq-keystone-nodes\" "}
{"level":"warn","ts":"2026-04-20T12:02:03.099593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.300284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-04-20T12:02:03.09962Z","caller":"traceutil/trace.go:171","msg":"trace[1016797981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7616; }","duration":"307.385726ms","start":"2026-04-20T12:02:02.792227Z","end":"2026-04-20T12:02:03.099613Z","steps":["trace[1016797981] 'agreement among raft nodes before linearized reading'  (duration: 307.300304ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:02:03.099662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:02.792206Z","time spent":"307.448849ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-04-20T12:02:14.852767Z","caller":"traceutil/trace.go:171","msg":"trace[1455795229] linearizableReadLoop","detail":"{readStateIndex:7963; appliedIndex:7962; }","duration":"123.883207ms","start":"2026-04-20T12:02:14.728863Z","end":"2026-04-20T12:02:14.852747Z","steps":["trace[1455795229] 'read index received'  (duration: 30.529816ms)","trace[1455795229] 'applied index is now lower than readState.Index'  (duration: 93.352701ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:02:14.852877Z","caller":"traceutil/trace.go:171","msg":"trace[1697484494] transaction","detail":"{read_only:false; response_revision:7673; number_of_response:1; }","duration":"145.223974ms","start":"2026-04-20T12:02:14.707617Z","end":"2026-04-20T12:02:14.852841Z","steps":["trace[1697484494] 'process raft request'  (duration: 51.829502ms)","trace[1697484494] 'compare'  (duration: 93.202347ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-20T12:02:14.852972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.087974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-keystone-test\" ","response":"range_response_count:1 size:1076"}
{"level":"info","ts":"2026-04-20T12:02:14.853Z","caller":"traceutil/trace.go:171","msg":"trace[1895483861] range","detail":"{range_begin:/registry/secrets/openstack/keystone-keystone-test; range_end:; response_count:1; response_revision:7673; }","duration":"124.157486ms","start":"2026-04-20T12:02:14.728835Z","end":"2026-04-20T12:02:14.852993Z","steps":["trace[1895483861] 'agreement among raft nodes before linearized reading'  (duration: 124.036802ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:02:14.85306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.63347ms","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-04-20T12:02:14.853131Z","caller":"traceutil/trace.go:171","msg":"trace[1119689642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7673; }","duration":"108.742133ms","start":"2026-04-20T12:02:14.744369Z","end":"2026-04-20T12:02:14.853111Z","steps":["trace[1119689642] 'agreement among raft nodes before linearized reading'  (duration: 108.532277ms)"],"step_count":1}
{"level":"info","ts":"2026-04-20T12:02:23.546274Z","caller":"traceutil/trace.go:171","msg":"trace[1151971147] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"112.071746ms","start":"2026-04-20T12:02:23.43418Z","end":"2026-04-20T12:02:23.546252Z","steps":["trace[1151971147] 'process raft request'  (duration: 100.975495ms)","trace[1151971147] 'compare'  (duration: 10.854904ms)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:02:28.497055Z","caller":"traceutil/trace.go:171","msg":"trace[892995684] linearizableReadLoop","detail":"{readStateIndex:8032; appliedIndex:8031; }","duration":"122.05707ms","start":"2026-04-20T12:02:28.374966Z","end":"2026-04-20T12:02:28.497023Z","steps":["trace[892995684] 'read index received'  (duration: 121.820403ms)","trace[892995684] 'applied index is now lower than readState.Index'  (duration: 235.757µs)"],"step_count":2}
{"level":"info","ts":"2026-04-20T12:02:28.497129Z","caller":"traceutil/trace.go:171","msg":"trace[608943502] transaction","detail":"{read_only:false; response_revision:7739; number_of_response:1; }","duration":"123.306806ms","start":"2026-04-20T12:02:28.373792Z","end":"2026-04-20T12:02:28.497099Z","steps":["trace[608943502] 'process raft request'  (duration: 123.08442ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-20T12:02:28.497334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.335138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-wnnbb\" ","response":"range_response_count:1 size:4908"}
{"level":"info","ts":"2026-04-20T12:02:28.497379Z","caller":"traceutil/trace.go:171","msg":"trace[100493463] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-wnnbb; range_end:; response_count:1; response_revision:7739; }","duration":"122.438821ms","start":"2026-04-20T12:02:28.374926Z","end":"2026-04-20T12:02:28.497365Z","steps":["trace[100493463] 'agreement among raft nodes before linearized reading'  (duration: 122.231475ms)"],"step_count":1}