{"level":"warn","ts":"2026-03-23T05:21:01.18569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-23T05:21:01.185836Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.187:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.187:2380","--initial-cluster=instance=https://199.19.213.187:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.187:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.187: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-03-23T05:21:01.185906Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-23T05:21:01.185919Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.187:2380"]} {"level":"info","ts":"2026-03-23T05:21:01.185937Z","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-03-23T05:21:01.186568Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"]} {"level":"info","ts":"2026-03-23T05:21:01.18664Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.187:2380"],"listen-peer-urls":["https://199.19.213.187:2380"],"advertise-client-urls":["https://199.19.213.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.187: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-03-23T05:21:01.203856Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.97308ms"} {"level":"info","ts":"2026-03-23T05:21:01.216219Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bf16572acb5c7f39","cluster-id":"acf20a96895e2b74"} {"level":"info","ts":"2026-03-23T05:21:01.216362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=()"} {"level":"info","ts":"2026-03-23T05:21:01.216398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became follower at term 0"} {"level":"info","ts":"2026-03-23T05:21:01.216423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bf16572acb5c7f39 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-23T05:21:01.216441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became follower at term 1"} {"level":"info","ts":"2026-03-23T05:21:01.216499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=(13769288752043949881)"} {"level":"warn","ts":"2026-03-23T05:21:01.222124Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-23T05:21:01.223696Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-23T05:21:01.22493Z","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-03-23T05:21:01.228515Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bf16572acb5c7f39","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-23T05:21:01.228766Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bf16572acb5c7f39","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-23T05:21:01.229098Z","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-03-23T05:21:01.231254Z","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-03-23T05:21:01.231331Z","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-03-23T05:21:01.232662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=(13769288752043949881)"} {"level":"info","ts":"2026-03-23T05:21:01.232898Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"acf20a96895e2b74","local-member-id":"bf16572acb5c7f39","added-peer-id":"bf16572acb5c7f39","added-peer-peer-urls":["https://199.19.213.187:2380"]} {"level":"info","ts":"2026-03-23T05:21:01.233917Z","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-03-23T05:21:01.234142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.187:2380"} {"level":"info","ts":"2026-03-23T05:21:01.234244Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.187:2380"} {"level":"info","ts":"2026-03-23T05:21:01.234333Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bf16572acb5c7f39","initial-advertise-peer-urls":["https://199.19.213.187:2380"],"listen-peer-urls":["https://199.19.213.187:2380"],"advertise-client-urls":["https://199.19.213.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-23T05:21:01.234399Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-23T05:21:01.517005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 is starting a new election at term 1"} {"level":"info","ts":"2026-03-23T05:21:01.517067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-23T05:21:01.51709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 received MsgPreVoteResp from bf16572acb5c7f39 at term 1"} {"level":"info","ts":"2026-03-23T05:21:01.51711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became candidate at term 2"} {"level":"info","ts":"2026-03-23T05:21:01.517122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 received MsgVoteResp from bf16572acb5c7f39 at term 2"} {"level":"info","ts":"2026-03-23T05:21:01.517155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became leader at term 2"} {"level":"info","ts":"2026-03-23T05:21:01.517169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bf16572acb5c7f39 elected leader bf16572acb5c7f39 at term 2"} {"level":"info","ts":"2026-03-23T05:21:01.518157Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:21:01.519338Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-23T05:21:01.519337Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bf16572acb5c7f39","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.187:2379]}","request-path":"/0/members/bf16572acb5c7f39/attributes","cluster-id":"acf20a96895e2b74","publish-timeout":"7s"} {"level":"info","ts":"2026-03-23T05:21:01.51953Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-23T05:21:01.519694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"acf20a96895e2b74","local-member-id":"bf16572acb5c7f39","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:21:01.519802Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-23T05:21:01.519838Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:21:01.519853Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-23T05:21:01.519892Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:21:01.522711Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-23T05:21:01.523316Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.187:2379"} {"level":"info","ts":"2026-03-23T05:21:03.994086Z","caller":"traceutil/trace.go:171","msg":"trace[726250269] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"159.806106ms","start":"2026-03-23T05:21:03.834256Z","end":"2026-03-23T05:21:03.994062Z","steps":["trace[726250269] 'read index received' (duration: 159.55849ms)","trace[726250269] 'applied index is now lower than readState.Index' (duration: 246.486µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:21:03.994155Z","caller":"traceutil/trace.go:171","msg":"trace[79582418] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"161.501519ms","start":"2026-03-23T05:21:03.832619Z","end":"2026-03-23T05:21:03.994121Z","steps":["trace[79582418] 'process raft request' (duration: 161.300154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:21:03.994388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.104064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-23T05:21:03.99451Z","caller":"traceutil/trace.go:171","msg":"trace[1157560906] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"160.264969ms","start":"2026-03-23T05:21:03.834225Z","end":"2026-03-23T05:21:03.99449Z","steps":["trace[1157560906] 'agreement among raft nodes before linearized reading' (duration: 159.988931ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:21:15.63326Z","caller":"traceutil/trace.go:171","msg":"trace[1849037383] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"200.469031ms","start":"2026-03-23T05:21:15.432758Z","end":"2026-03-23T05:21:15.633227Z","steps":["trace[1849037383] 'process raft request' (duration: 200.223465ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:21:15.91692Z","caller":"traceutil/trace.go:171","msg":"trace[648590214] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"191.678863ms","start":"2026-03-23T05:21:15.725225Z","end":"2026-03-23T05:21:15.916904Z","steps":["trace[648590214] 'read index received' (duration: 98.008243ms)","trace[648590214] 'applied index is now lower than readState.Index' (duration: 93.66999ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:21:15.916978Z","caller":"traceutil/trace.go:171","msg":"trace[1362627565] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"230.422399ms","start":"2026-03-23T05:21:15.686534Z","end":"2026-03-23T05:21:15.916956Z","steps":["trace[1362627565] 'process raft request' (duration: 136.778259ms)","trace[1362627565] 'compare' (duration: 93.492225ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:21:15.91707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.814887ms","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-03-23T05:21:15.917185Z","caller":"traceutil/trace.go:171","msg":"trace[2082673432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"192.018522ms","start":"2026-03-23T05:21:15.725142Z","end":"2026-03-23T05:21:15.917161Z","steps":["trace[2082673432] 'agreement among raft nodes before linearized reading' (duration: 191.822107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:21:16.28218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.334619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:21:16.282346Z","caller":"traceutil/trace.go:171","msg":"trace[368262802] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"360.475863ms","start":"2026-03-23T05:21:15.921839Z","end":"2026-03-23T05:21:16.282315Z","steps":["trace[368262802] 'process raft request' (duration: 147.451356ms)","trace[368262802] 'compare' (duration: 212.204216ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:21:16.282659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:21:15.921813Z","time spent":"360.690899ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:21:35.28316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.838164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-23T05:21:35.283253Z","caller":"traceutil/trace.go:171","msg":"trace[2030291385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"115.977558ms","start":"2026-03-23T05:21:35.167246Z","end":"2026-03-23T05:21:35.283224Z","steps":["trace[2030291385] 'range keys from in-memory index tree' (duration: 115.66901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:21:35.283455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.574396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4379"} {"level":"info","ts":"2026-03-23T05:21:35.283536Z","caller":"traceutil/trace.go:171","msg":"trace[199599758] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:474; }","duration":"193.697949ms","start":"2026-03-23T05:21:35.089819Z","end":"2026-03-23T05:21:35.283517Z","steps":["trace[199599758] 'range keys from in-memory index tree' (duration: 193.491633ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:21:35.394446Z","caller":"traceutil/trace.go:171","msg":"trace[812351309] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"103.17438ms","start":"2026-03-23T05:21:35.291239Z","end":"2026-03-23T05:21:35.394413Z","steps":["trace[812351309] 'process raft request' (duration: 98.186448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:21:40.568499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.416018ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-23T05:21:40.568658Z","caller":"traceutil/trace.go:171","msg":"trace[2115354921] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"290.05596ms","start":"2026-03-23T05:21:40.278577Z","end":"2026-03-23T05:21:40.568632Z","steps":["trace[2115354921] 'read index received' (duration: 101.524689ms)","trace[2115354921] 'applied index is now lower than readState.Index' (duration: 188.529311ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:21:40.568827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.243075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:21:40.568864Z","caller":"traceutil/trace.go:171","msg":"trace[879716389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"290.335467ms","start":"2026-03-23T05:21:40.278517Z","end":"2026-03-23T05:21:40.568852Z","steps":["trace[879716389] 'agreement among raft nodes before linearized reading' (duration: 290.243945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:21:40.569037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.734408ms","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-03-23T05:21:40.569214Z","caller":"traceutil/trace.go:171","msg":"trace[1743874447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"146.964684ms","start":"2026-03-23T05:21:40.422219Z","end":"2026-03-23T05:21:40.569183Z","steps":["trace[1743874447] 'agreement among raft nodes before linearized reading' (duration: 146.573114ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:21:41.54755Z","caller":"traceutil/trace.go:171","msg":"trace[537821909] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"187.875445ms","start":"2026-03-23T05:21:41.359651Z","end":"2026-03-23T05:21:41.547526Z","steps":["trace[537821909] 'process raft request' (duration: 187.702871ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:21:41.980079Z","caller":"traceutil/trace.go:171","msg":"trace[258575438] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"226.033948ms","start":"2026-03-23T05:21:41.754015Z","end":"2026-03-23T05:21:41.980049Z","steps":["trace[258575438] 'read index received' (duration: 225.841963ms)","trace[258575438] 'applied index is now lower than readState.Index' (duration: 190.535µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:21:41.980126Z","caller":"traceutil/trace.go:171","msg":"trace[2078656879] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"371.955425ms","start":"2026-03-23T05:21:41.608141Z","end":"2026-03-23T05:21:41.980097Z","steps":["trace[2078656879] 'process raft request' (duration: 371.720979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:21:41.980306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:21:41.608128Z","time spent":"372.062638ms","remote":"127.0.0.1:53686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:21:41.980296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.240683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-23T05:21:41.980558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.390307ms","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-03-23T05:21:41.980601Z","caller":"traceutil/trace.go:171","msg":"trace[1678570933] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:515; }","duration":"226.605762ms","start":"2026-03-23T05:21:41.753974Z","end":"2026-03-23T05:21:41.980579Z","steps":["trace[1678570933] 'agreement among raft nodes before linearized reading' (duration: 226.246533ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:21:41.980675Z","caller":"traceutil/trace.go:171","msg":"trace[2037497582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"167.546661ms","start":"2026-03-23T05:21:41.813103Z","end":"2026-03-23T05:21:41.98065Z","steps":["trace[2037497582] 'agreement among raft nodes before linearized reading' (duration: 167.161982ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:21:47.768639Z","caller":"traceutil/trace.go:171","msg":"trace[783428832] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"104.666148ms","start":"2026-03-23T05:21:47.663941Z","end":"2026-03-23T05:21:47.768608Z","steps":["trace[783428832] 'process raft request' (duration: 104.413341ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:33.098249Z","caller":"traceutil/trace.go:171","msg":"trace[462254295] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"142.5341ms","start":"2026-03-23T05:22:32.955686Z","end":"2026-03-23T05:22:33.09822Z","steps":["trace[462254295] 'process raft request' (duration: 142.368586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:55.103722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.528513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.187\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-23T05:22:55.103786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.943402ms","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-03-23T05:22:55.103875Z","caller":"traceutil/trace.go:171","msg":"trace[657694552] range","detail":"{range_begin:/registry/masterleases/199.19.213.187; range_end:; response_count:1; response_revision:1549; }","duration":"263.753988ms","start":"2026-03-23T05:22:54.840096Z","end":"2026-03-23T05:22:55.10385Z","steps":["trace[657694552] 'range keys from in-memory index tree' (duration: 263.241746ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:55.10389Z","caller":"traceutil/trace.go:171","msg":"trace[60503162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1549; }","duration":"440.126827ms","start":"2026-03-23T05:22:54.663738Z","end":"2026-03-23T05:22:55.103865Z","steps":["trace[60503162] 'range keys from in-memory index tree' (duration: 439.727258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:55.103939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:22:54.663708Z","time spent":"440.220469ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-23T05:22:55.103956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.692216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-85babbd5-cbde-41a3-9083-f772df5c883c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-23T05:22:55.104063Z","caller":"traceutil/trace.go:171","msg":"trace[1120312804] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-85babbd5-cbde-41a3-9083-f772df5c883c; range_end:; response_count:1; response_revision:1549; }","duration":"109.86169ms","start":"2026-03-23T05:22:54.994176Z","end":"2026-03-23T05:22:55.104038Z","steps":["trace[1120312804] 'range keys from in-memory index tree' (duration: 109.499191ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:55.320095Z","caller":"traceutil/trace.go:171","msg":"trace[1950457051] linearizableReadLoop","detail":"{readStateIndex:1612; appliedIndex:1609; }","duration":"202.809535ms","start":"2026-03-23T05:22:55.11726Z","end":"2026-03-23T05:22:55.32007Z","steps":["trace[1950457051] 'read index received' (duration: 32.888544ms)","trace[1950457051] 'applied index is now lower than readState.Index' (duration: 169.919511ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:22:55.320203Z","caller":"traceutil/trace.go:171","msg":"trace[774061485] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"210.838303ms","start":"2026-03-23T05:22:55.109323Z","end":"2026-03-23T05:22:55.320161Z","steps":["trace[774061485] 'process raft request' (duration: 200.547491ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:55.320536Z","caller":"traceutil/trace.go:171","msg":"trace[1969559917] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"208.385972ms","start":"2026-03-23T05:22:55.112084Z","end":"2026-03-23T05:22:55.32047Z","steps":["trace[1969559917] 'process raft request' (duration: 207.90719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:55.320494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.203636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-85babbd5-cbde-41a3-9083-f772df5c883c\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-03-23T05:22:55.320659Z","caller":"traceutil/trace.go:171","msg":"trace[292017986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-85babbd5-cbde-41a3-9083-f772df5c883c; range_end:; response_count:1; response_revision:1551; }","duration":"203.41323ms","start":"2026-03-23T05:22:55.117226Z","end":"2026-03-23T05:22:55.32064Z","steps":["trace[292017986] 'agreement among raft nodes before linearized reading' (duration: 203.192645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:55.351633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.798654ms","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-03-23T05:22:55.351728Z","caller":"traceutil/trace.go:171","msg":"trace[2066721435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1552; }","duration":"137.944408ms","start":"2026-03-23T05:22:55.213762Z","end":"2026-03-23T05:22:55.351707Z","steps":["trace[2066721435] 'agreement among raft nodes before linearized reading' (duration: 137.682392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:55.351718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.824462ms","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-03-23T05:22:55.351792Z","caller":"traceutil/trace.go:171","msg":"trace[1455905583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1552; }","duration":"123.939075ms","start":"2026-03-23T05:22:55.227832Z","end":"2026-03-23T05:22:55.351771Z","steps":["trace[1455905583] 'agreement among raft nodes before linearized reading' (duration: 123.629757ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:22:55.352185Z","caller":"traceutil/trace.go:171","msg":"trace[742029705] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"200.807428ms","start":"2026-03-23T05:22:55.151307Z","end":"2026-03-23T05:22:55.352114Z","steps":["trace[742029705] 'process raft request' (duration: 199.973417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:22:55.692975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.411485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-03-23T05:22:55.693111Z","caller":"traceutil/trace.go:171","msg":"trace[1801104756] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1554; }","duration":"244.617789ms","start":"2026-03-23T05:22:55.448461Z","end":"2026-03-23T05:22:55.693078Z","steps":["trace[1801104756] 'range keys from in-memory index tree' (duration: 244.128217ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:09.510925Z","caller":"traceutil/trace.go:171","msg":"trace[184229969] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"115.073377ms","start":"2026-03-23T05:23:09.395813Z","end":"2026-03-23T05:23:09.510886Z","steps":["trace[184229969] 'process raft request' (duration: 114.866913ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:39.513224Z","caller":"traceutil/trace.go:171","msg":"trace[1010867796] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"110.494726ms","start":"2026-03-23T05:23:39.402699Z","end":"2026-03-23T05:23:39.513194Z","steps":["trace[1010867796] 'process raft request' (duration: 110.285941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:39.957215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.406012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:23:39.957619Z","caller":"traceutil/trace.go:171","msg":"trace[435843041] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"197.614178ms","start":"2026-03-23T05:23:39.759944Z","end":"2026-03-23T05:23:39.957559Z","steps":["trace[435843041] 'process raft request' (duration: 84.751085ms)","trace[435843041] 'compare' (duration: 112.090135ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:30.670882Z","caller":"traceutil/trace.go:171","msg":"trace[675228552] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"214.662916ms","start":"2026-03-23T05:24:30.456185Z","end":"2026-03-23T05:24:30.670848Z","steps":["trace[675228552] 'process raft request' (duration: 214.39621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:25:03.678097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.132258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:25:03.678269Z","caller":"traceutil/trace.go:171","msg":"trace[1226058978] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"410.909798ms","start":"2026-03-23T05:25:03.267337Z","end":"2026-03-23T05:25:03.678247Z","steps":["trace[1226058978] 'process raft request' (duration: 138.506904ms)","trace[1226058978] 'compare' (duration: 271.981394ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:25:03.678345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:25:03.267321Z","time spent":"410.9882ms","remote":"127.0.0.1:53596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:26:45.14633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.93851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:45.146685Z","caller":"traceutil/trace.go:171","msg":"trace[810036111] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"249.997311ms","start":"2026-03-23T05:26:44.896654Z","end":"2026-03-23T05:26:45.146652Z","steps":["trace[810036111] 'process raft request' (duration: 145.647841ms)","trace[810036111] 'compare' (duration: 103.806056ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:51.278347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.914137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:51.27848Z","caller":"traceutil/trace.go:171","msg":"trace[1272751813] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"212.874152ms","start":"2026-03-23T05:26:51.065582Z","end":"2026-03-23T05:26:51.278457Z","steps":["trace[1272751813] 'process raft request' (duration: 102.75597ms)","trace[1272751813] 'compare' (duration: 109.702521ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:10.301896Z","caller":"traceutil/trace.go:171","msg":"trace[2034945854] transaction","detail":"{read_only:false; response_revision:3363; number_of_response:1; }","duration":"195.48732ms","start":"2026-03-23T05:28:10.106379Z","end":"2026-03-23T05:28:10.301867Z","steps":["trace[2034945854] 'process raft request' (duration: 195.306825ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:10.399279Z","caller":"traceutil/trace.go:171","msg":"trace[719740850] linearizableReadLoop","detail":"{readStateIndex:3497; appliedIndex:3495; }","duration":"119.395888ms","start":"2026-03-23T05:28:10.279856Z","end":"2026-03-23T05:28:10.399252Z","steps":["trace[719740850] 'read index received' (duration: 21.810297ms)","trace[719740850] 'applied index is now lower than readState.Index' (duration: 97.584301ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:10.399445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.577302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:10.39947Z","caller":"traceutil/trace.go:171","msg":"trace[1638312943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3363; }","duration":"119.666025ms","start":"2026-03-23T05:28:10.279796Z","end":"2026-03-23T05:28:10.399462Z","steps":["trace[1638312943] 'agreement among raft nodes before linearized reading' (duration: 119.589283ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:21.876113Z","caller":"traceutil/trace.go:171","msg":"trace[1573155446] transaction","detail":"{read_only:false; response_revision:3422; number_of_response:1; }","duration":"114.922047ms","start":"2026-03-23T05:28:21.761162Z","end":"2026-03-23T05:28:21.876084Z","steps":["trace[1573155446] 'process raft request' (duration: 68.366191ms)","trace[1573155446] 'compare' (duration: 46.443123ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:22.072808Z","caller":"traceutil/trace.go:171","msg":"trace[1241438777] transaction","detail":"{read_only:false; response_revision:3423; number_of_response:1; }","duration":"127.392534ms","start":"2026-03-23T05:28:21.945387Z","end":"2026-03-23T05:28:22.072779Z","steps":["trace[1241438777] 'process raft request' (duration: 57.670178ms)","trace[1241438777] 'compare' (duration: 69.547912ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:22.250521Z","caller":"traceutil/trace.go:171","msg":"trace[27402541] linearizableReadLoop","detail":"{readStateIndex:3561; appliedIndex:3560; }","duration":"107.768742ms","start":"2026-03-23T05:28:22.142723Z","end":"2026-03-23T05:28:22.250492Z","steps":["trace[27402541] 'read index received' (duration: 78.146883ms)","trace[27402541] 'applied index is now lower than readState.Index' (duration: 29.620289ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:22.250599Z","caller":"traceutil/trace.go:171","msg":"trace[1611794275] transaction","detail":"{read_only:false; response_revision:3425; number_of_response:1; }","duration":"123.788087ms","start":"2026-03-23T05:28:22.12677Z","end":"2026-03-23T05:28:22.250558Z","steps":["trace[1611794275] 'process raft request' (duration: 94.161238ms)","trace[1611794275] 'compare' (duration: 29.444665ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:22.250685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.942926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:22.25074Z","caller":"traceutil/trace.go:171","msg":"trace[778641154] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:3425; }","duration":"108.049109ms","start":"2026-03-23T05:28:22.142673Z","end":"2026-03-23T05:28:22.250722Z","steps":["trace[778641154] 'agreement among raft nodes before linearized reading' (duration: 107.936976ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:24.017868Z","caller":"traceutil/trace.go:171","msg":"trace[831147287] transaction","detail":"{read_only:false; response_revision:3434; number_of_response:1; }","duration":"133.784621ms","start":"2026-03-23T05:28:23.88404Z","end":"2026-03-23T05:28:24.017825Z","steps":["trace[831147287] 'process raft request' (duration: 103.254341ms)","trace[831147287] 'compare' (duration: 30.361956ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:25.646072Z","caller":"traceutil/trace.go:171","msg":"trace[272724207] transaction","detail":"{read_only:false; response_revision:3440; number_of_response:1; }","duration":"154.477881ms","start":"2026-03-23T05:28:25.491568Z","end":"2026-03-23T05:28:25.646046Z","steps":["trace[272724207] 'process raft request' (duration: 154.319437ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:27.94882Z","caller":"traceutil/trace.go:171","msg":"trace[2078721037] transaction","detail":"{read_only:false; response_revision:3451; number_of_response:1; }","duration":"111.874153ms","start":"2026-03-23T05:28:27.836907Z","end":"2026-03-23T05:28:27.948781Z","steps":["trace[2078721037] 'process raft request' (duration: 111.589086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:28.141673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.903583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-03-23T05:28:28.141764Z","caller":"traceutil/trace.go:171","msg":"trace[695208382] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3452; }","duration":"101.038347ms","start":"2026-03-23T05:28:28.040704Z","end":"2026-03-23T05:28:28.141742Z","steps":["trace[695208382] 'range keys from in-memory index tree' (duration: 100.714268ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:30.944589Z","caller":"traceutil/trace.go:171","msg":"trace[1503082862] linearizableReadLoop","detail":"{readStateIndex:3607; appliedIndex:3606; }","duration":"111.806311ms","start":"2026-03-23T05:28:30.832752Z","end":"2026-03-23T05:28:30.944558Z","steps":["trace[1503082862] 'read index received' (duration: 111.567255ms)","trace[1503082862] 'applied index is now lower than readState.Index' (duration: 238.016µs)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:30.944938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.122299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ptgxq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-23T05:28:30.944998Z","caller":"traceutil/trace.go:171","msg":"trace[1323528075] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ptgxq; range_end:; response_count:1; response_revision:3469; }","duration":"112.262892ms","start":"2026-03-23T05:28:30.832721Z","end":"2026-03-23T05:28:30.944984Z","steps":["trace[1323528075] 'agreement among raft nodes before linearized reading' (duration: 111.961605ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:30.945107Z","caller":"traceutil/trace.go:171","msg":"trace[153101712] transaction","detail":"{read_only:false; response_revision:3469; number_of_response:1; }","duration":"139.667197ms","start":"2026-03-23T05:28:30.805398Z","end":"2026-03-23T05:28:30.945065Z","steps":["trace[153101712] 'process raft request' (duration: 138.97161ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:34.384899Z","caller":"traceutil/trace.go:171","msg":"trace[1245407775] linearizableReadLoop","detail":"{readStateIndex:3622; appliedIndex:3621; }","duration":"106.105951ms","start":"2026-03-23T05:28:34.278754Z","end":"2026-03-23T05:28:34.38486Z","steps":["trace[1245407775] 'read index received' (duration: 41.350418ms)","trace[1245407775] 'applied index is now lower than readState.Index' (duration: 64.754423ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:34.384954Z","caller":"traceutil/trace.go:171","msg":"trace[693380998] transaction","detail":"{read_only:false; response_revision:3484; number_of_response:1; }","duration":"136.739865ms","start":"2026-03-23T05:28:34.248181Z","end":"2026-03-23T05:28:34.384921Z","steps":["trace[693380998] 'process raft request' (duration: 71.958991ms)","trace[693380998] 'compare' (duration: 64.582929ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:34.385072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.305746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:34.385135Z","caller":"traceutil/trace.go:171","msg":"trace[1199517796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3484; }","duration":"106.400888ms","start":"2026-03-23T05:28:34.278721Z","end":"2026-03-23T05:28:34.385122Z","steps":["trace[1199517796] 'agreement among raft nodes before linearized reading' (duration: 106.282106ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:34.588599Z","caller":"traceutil/trace.go:171","msg":"trace[563174156] transaction","detail":"{read_only:false; response_revision:3485; number_of_response:1; }","duration":"199.279613ms","start":"2026-03-23T05:28:34.38929Z","end":"2026-03-23T05:28:34.58857Z","steps":["trace[563174156] 'process raft request' (duration: 158.836477ms)","trace[563174156] 'compare' (duration: 40.307323ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:34.603713Z","caller":"traceutil/trace.go:171","msg":"trace[1787419279] transaction","detail":"{read_only:false; response_revision:3486; number_of_response:1; }","duration":"214.279433ms","start":"2026-03-23T05:28:34.389408Z","end":"2026-03-23T05:28:34.603687Z","steps":["trace[1787419279] 'process raft request' (duration: 214.066708ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:34.60397Z","caller":"traceutil/trace.go:171","msg":"trace[1766520593] linearizableReadLoop","detail":"{readStateIndex:3624; appliedIndex:3622; }","duration":"213.577095ms","start":"2026-03-23T05:28:34.390377Z","end":"2026-03-23T05:28:34.603954Z","steps":["trace[1766520593] 'read index received' (duration: 157.763642ms)","trace[1766520593] 'applied index is now lower than readState.Index' (duration: 55.812433ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:34.604024Z","caller":"traceutil/trace.go:171","msg":"trace[955930990] transaction","detail":"{read_only:false; response_revision:3487; number_of_response:1; }","duration":"210.276234ms","start":"2026-03-23T05:28:34.393721Z","end":"2026-03-23T05:28:34.603997Z","steps":["trace[955930990] 'process raft request' (duration: 209.907805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:34.604085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.694378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/kube-prometheus-stack\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:34.604055Z","caller":"traceutil/trace.go:171","msg":"trace[180810350] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3487; }","duration":"209.440213ms","start":"2026-03-23T05:28:34.394577Z","end":"2026-03-23T05:28:34.604017Z","steps":["trace[180810350] 'process raft request' (duration: 209.31612ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:34.604129Z","caller":"traceutil/trace.go:171","msg":"trace[1249468205] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/kube-prometheus-stack; range_end:; response_count:0; response_revision:3487; }","duration":"213.79236ms","start":"2026-03-23T05:28:34.390325Z","end":"2026-03-23T05:28:34.604118Z","steps":["trace[1249468205] 'agreement among raft nodes before linearized reading' (duration: 213.693388ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:34.749276Z","caller":"traceutil/trace.go:171","msg":"trace[1360769957] transaction","detail":"{read_only:false; response_revision:3489; number_of_response:1; }","duration":"130.199203ms","start":"2026-03-23T05:28:34.619049Z","end":"2026-03-23T05:28:34.749248Z","steps":["trace[1360769957] 'process raft request' (duration: 70.370422ms)","trace[1360769957] 'compare' (duration: 59.655417ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:34.749912Z","caller":"traceutil/trace.go:171","msg":"trace[636662546] transaction","detail":"{read_only:false; response_revision:3490; number_of_response:1; }","duration":"100.737748ms","start":"2026-03-23T05:28:34.649138Z","end":"2026-03-23T05:28:34.749876Z","steps":["trace[636662546] 'process raft request' (duration: 100.474002ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:34.925556Z","caller":"traceutil/trace.go:171","msg":"trace[1487436735] transaction","detail":"{read_only:false; response_revision:3495; number_of_response:1; }","duration":"130.811589ms","start":"2026-03-23T05:28:34.79469Z","end":"2026-03-23T05:28:34.925501Z","steps":["trace[1487436735] 'process raft request' (duration: 111.804361ms)","trace[1487436735] 'compare' (duration: 18.458215ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:35.09063Z","caller":"traceutil/trace.go:171","msg":"trace[803582506] transaction","detail":"{read_only:false; response_revision:3501; number_of_response:1; }","duration":"146.588027ms","start":"2026-03-23T05:28:34.944006Z","end":"2026-03-23T05:28:35.090594Z","steps":["trace[803582506] 'process raft request' (duration: 93.226544ms)","trace[803582506] 'compare' (duration: 53.114807ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:35.09068Z","caller":"traceutil/trace.go:171","msg":"trace[1721294716] transaction","detail":"{read_only:false; response_revision:3502; number_of_response:1; }","duration":"145.329966ms","start":"2026-03-23T05:28:34.945321Z","end":"2026-03-23T05:28:35.090651Z","steps":["trace[1721294716] 'process raft request' (duration: 145.164062ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.090766Z","caller":"traceutil/trace.go:171","msg":"trace[481201251] transaction","detail":"{read_only:false; response_revision:3503; number_of_response:1; }","duration":"145.382987ms","start":"2026-03-23T05:28:34.945365Z","end":"2026-03-23T05:28:35.090748Z","steps":["trace[481201251] 'process raft request' (duration: 145.240474ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.090979Z","caller":"traceutil/trace.go:171","msg":"trace[484840614] transaction","detail":"{read_only:false; response_revision:3504; number_of_response:1; }","duration":"144.382533ms","start":"2026-03-23T05:28:34.946582Z","end":"2026-03-23T05:28:35.090964Z","steps":["trace[484840614] 'process raft request' (duration: 144.053295ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.091248Z","caller":"traceutil/trace.go:171","msg":"trace[1753843499] transaction","detail":"{read_only:false; response_revision:3505; number_of_response:1; }","duration":"144.554886ms","start":"2026-03-23T05:28:34.946628Z","end":"2026-03-23T05:28:35.091183Z","steps":["trace[1753843499] 'process raft request' (duration: 144.070714ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.091275Z","caller":"traceutil/trace.go:171","msg":"trace[1805516819] transaction","detail":"{read_only:false; response_revision:3506; number_of_response:1; }","duration":"144.573717ms","start":"2026-03-23T05:28:34.946689Z","end":"2026-03-23T05:28:35.091262Z","steps":["trace[1805516819] 'process raft request' (duration: 144.437813ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.09194Z","caller":"traceutil/trace.go:171","msg":"trace[1598702712] transaction","detail":"{read_only:false; response_revision:3507; number_of_response:1; }","duration":"142.881186ms","start":"2026-03-23T05:28:34.949004Z","end":"2026-03-23T05:28:35.091885Z","steps":["trace[1598702712] 'process raft request' (duration: 142.191738ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.092034Z","caller":"traceutil/trace.go:171","msg":"trace[431073179] linearizableReadLoop","detail":"{readStateIndex:3648; appliedIndex:3641; }","duration":"137.182475ms","start":"2026-03-23T05:28:34.954834Z","end":"2026-03-23T05:28:35.092016Z","steps":["trace[431073179] 'read index received' (duration: 82.336865ms)","trace[431073179] 'applied index is now lower than readState.Index' (duration: 54.620895ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:35.092289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.777581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/kube-prometheus-stack.189f6142142a4a69\" ","response":"range_response_count:1 size:771"} {"level":"warn","ts":"2026-03-23T05:28:35.093273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.192072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-23T05:28:35.093331Z","caller":"traceutil/trace.go:171","msg":"trace[885002662] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:3507; }","duration":"136.294934ms","start":"2026-03-23T05:28:34.957025Z","end":"2026-03-23T05:28:35.09332Z","steps":["trace[885002662] 'agreement among raft nodes before linearized reading' (duration: 135.135716ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.092364Z","caller":"traceutil/trace.go:171","msg":"trace[1654737237] range","detail":"{range_begin:/registry/events/default/kube-prometheus-stack.189f6142142a4a69; range_end:; response_count:1; response_revision:3507; }","duration":"148.890953ms","start":"2026-03-23T05:28:34.943456Z","end":"2026-03-23T05:28:35.092347Z","steps":["trace[1654737237] 'agreement among raft nodes before linearized reading' (duration: 148.518424ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.33666Z","caller":"traceutil/trace.go:171","msg":"trace[1222973685] linearizableReadLoop","detail":"{readStateIndex:3650; appliedIndex:3648; }","duration":"239.259057ms","start":"2026-03-23T05:28:35.097384Z","end":"2026-03-23T05:28:35.336643Z","steps":["trace[1222973685] 'read index received' (duration: 34.774515ms)","trace[1222973685] 'applied index is now lower than readState.Index' (duration: 204.483262ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:35.336694Z","caller":"traceutil/trace.go:171","msg":"trace[1034674376] transaction","detail":"{read_only:false; response_revision:3510; number_of_response:1; }","duration":"239.267707ms","start":"2026-03-23T05:28:35.097385Z","end":"2026-03-23T05:28:35.336653Z","steps":["trace[1034674376] 'process raft request' (duration: 239.126734ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.336813Z","caller":"traceutil/trace.go:171","msg":"trace[1833946409] transaction","detail":"{read_only:false; response_revision:3511; number_of_response:1; }","duration":"236.812988ms","start":"2026-03-23T05:28:35.099981Z","end":"2026-03-23T05:28:35.336794Z","steps":["trace[1833946409] 'process raft request' (duration: 236.572602ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.336873Z","caller":"traceutil/trace.go:171","msg":"trace[1867494639] transaction","detail":"{read_only:false; response_revision:3512; number_of_response:1; }","duration":"232.436579ms","start":"2026-03-23T05:28:35.104423Z","end":"2026-03-23T05:28:35.336859Z","steps":["trace[1867494639] 'process raft request' (duration: 232.164233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:35.337022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.209512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:6 size:46100"} {"level":"warn","ts":"2026-03-23T05:28:35.337042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.652987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-23T05:28:35.337067Z","caller":"traceutil/trace.go:171","msg":"trace[1996268367] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:6; response_revision:3512; }","duration":"238.309734ms","start":"2026-03-23T05:28:35.098747Z","end":"2026-03-23T05:28:35.337057Z","steps":["trace[1996268367] 'agreement among raft nodes before linearized reading' (duration: 238.117029ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.3371Z","caller":"traceutil/trace.go:171","msg":"trace[916813162] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:3512; }","duration":"239.74445ms","start":"2026-03-23T05:28:35.097341Z","end":"2026-03-23T05:28:35.337085Z","steps":["trace[916813162] 'agreement among raft nodes before linearized reading' (duration: 239.487664ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.336602Z","caller":"traceutil/trace.go:171","msg":"trace[160203900] transaction","detail":"{read_only:false; response_revision:3509; number_of_response:1; }","duration":"239.451393ms","start":"2026-03-23T05:28:35.097127Z","end":"2026-03-23T05:28:35.336578Z","steps":["trace[160203900] 'process raft request' (duration: 152.171825ms)","trace[160203900] 'compare' (duration: 87.087063ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:35.534311Z","caller":"traceutil/trace.go:171","msg":"trace[381119979] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3514; }","duration":"143.380468ms","start":"2026-03-23T05:28:35.390892Z","end":"2026-03-23T05:28:35.534272Z","steps":["trace[381119979] 'process raft request' (duration: 112.221951ms)","trace[381119979] 'compare' (duration: 30.931901ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:35.804113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.904197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:28:35.804405Z","caller":"traceutil/trace.go:171","msg":"trace[1575440592] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3517; }","duration":"227.923838ms","start":"2026-03-23T05:28:35.576452Z","end":"2026-03-23T05:28:35.804375Z","steps":["trace[1575440592] 'process raft request' (duration: 227.761574ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:35.804405Z","caller":"traceutil/trace.go:171","msg":"trace[1244984096] transaction","detail":"{read_only:false; response_revision:3517; number_of_response:1; }","duration":"255.731252ms","start":"2026-03-23T05:28:35.548644Z","end":"2026-03-23T05:28:35.804375Z","steps":["trace[1244984096] 'process raft request' (duration: 99.479907ms)","trace[1244984096] 'compare' (duration: 155.775784ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:37.544762Z","caller":"traceutil/trace.go:171","msg":"trace[1244697312] linearizableReadLoop","detail":"{readStateIndex:3674; appliedIndex:3673; }","duration":"123.165751ms","start":"2026-03-23T05:28:37.421567Z","end":"2026-03-23T05:28:37.544733Z","steps":["trace[1244697312] 'read index received' (duration: 93.0981ms)","trace[1244697312] 'applied index is now lower than readState.Index' (duration: 30.066051ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:37.544898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.313534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:37.54494Z","caller":"traceutil/trace.go:171","msg":"trace[1295466816] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3531; }","duration":"123.414766ms","start":"2026-03-23T05:28:37.421509Z","end":"2026-03-23T05:28:37.544924Z","steps":["trace[1295466816] 'agreement among raft nodes before linearized reading' (duration: 123.329424ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:37.544903Z","caller":"traceutil/trace.go:171","msg":"trace[1633044635] transaction","detail":"{read_only:false; response_revision:3531; number_of_response:1; }","duration":"134.829748ms","start":"2026-03-23T05:28:37.409987Z","end":"2026-03-23T05:28:37.544816Z","steps":["trace[1633044635] 'process raft request' (duration: 104.893241ms)","trace[1633044635] 'compare' (duration: 29.583408ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:37.756878Z","caller":"traceutil/trace.go:171","msg":"trace[1985439550] transaction","detail":"{read_only:false; response_revision:3532; number_of_response:1; }","duration":"129.136927ms","start":"2026-03-23T05:28:37.627708Z","end":"2026-03-23T05:28:37.756845Z","steps":["trace[1985439550] 'process raft request' (duration: 126.325158ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:37.833804Z","caller":"traceutil/trace.go:171","msg":"trace[846296211] transaction","detail":"{read_only:false; response_revision:3533; number_of_response:1; }","duration":"190.973489ms","start":"2026-03-23T05:28:37.642682Z","end":"2026-03-23T05:28:37.833655Z","steps":["trace[846296211] 'process raft request' (duration: 190.698772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:38.156434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.911899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:28:38.156636Z","caller":"traceutil/trace.go:171","msg":"trace[242490533] transaction","detail":"{read_only:false; response_revision:3534; number_of_response:1; }","duration":"293.544214ms","start":"2026-03-23T05:28:37.863047Z","end":"2026-03-23T05:28:38.156591Z","steps":["trace[242490533] 'process raft request' (duration: 56.384718ms)","trace[242490533] 'compare' (duration: 236.567851ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:38.156801Z","caller":"traceutil/trace.go:171","msg":"trace[809566498] linearizableReadLoop","detail":"{readStateIndex:3677; appliedIndex:3676; }","duration":"286.293955ms","start":"2026-03-23T05:28:37.870398Z","end":"2026-03-23T05:28:38.156692Z","steps":["trace[809566498] 'read index received' (duration: 49.117869ms)","trace[809566498] 'applied index is now lower than readState.Index' (duration: 237.172336ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:38.156944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.50687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:38.157083Z","caller":"traceutil/trace.go:171","msg":"trace[1585409167] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com; range_end:; response_count:0; response_revision:3534; }","duration":"286.720326ms","start":"2026-03-23T05:28:37.870331Z","end":"2026-03-23T05:28:38.157051Z","steps":["trace[1585409167] 'agreement among raft nodes before linearized reading' (duration: 286.445489ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:38.157011Z","caller":"traceutil/trace.go:171","msg":"trace[1002145373] transaction","detail":"{read_only:false; response_revision:3535; number_of_response:1; }","duration":"239.156065ms","start":"2026-03-23T05:28:37.917835Z","end":"2026-03-23T05:28:38.156991Z","steps":["trace[1002145373] 'process raft request' (duration: 238.801997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:38.157424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.274112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-23T05:28:38.15749Z","caller":"traceutil/trace.go:171","msg":"trace[1543909593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3535; }","duration":"253.406916ms","start":"2026-03-23T05:28:37.904063Z","end":"2026-03-23T05:28:38.15747Z","steps":["trace[1543909593] 'agreement among raft nodes before linearized reading' (duration: 253.029427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:38.471774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.159547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:28:38.472066Z","caller":"traceutil/trace.go:171","msg":"trace[793853267] transaction","detail":"{read_only:false; response_revision:3541; number_of_response:1; }","duration":"227.827576ms","start":"2026-03-23T05:28:38.244184Z","end":"2026-03-23T05:28:38.472011Z","steps":["trace[793853267] 'process raft request' (duration: 94.319ms)","trace[793853267] 'compare' (duration: 132.922951ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:38.472155Z","caller":"traceutil/trace.go:171","msg":"trace[1359943735] linearizableReadLoop","detail":"{readStateIndex:3684; appliedIndex:3683; }","duration":"224.262088ms","start":"2026-03-23T05:28:38.247864Z","end":"2026-03-23T05:28:38.472126Z","steps":["trace[1359943735] 'read index received' (duration: 90.754214ms)","trace[1359943735] 'applied index is now lower than readState.Index' (duration: 133.505724ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:38.472306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.416842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/probes.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:38.472375Z","caller":"traceutil/trace.go:171","msg":"trace[188155594] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/probes.monitoring.coreos.com; range_end:; response_count:0; response_revision:3541; }","duration":"224.532145ms","start":"2026-03-23T05:28:38.247827Z","end":"2026-03-23T05:28:38.472359Z","steps":["trace[188155594] 'agreement among raft nodes before linearized reading' (duration: 224.411842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:38.473322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.094397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:38.47343Z","caller":"traceutil/trace.go:171","msg":"trace[1546427876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3542; }","duration":"194.23285ms","start":"2026-03-23T05:28:38.279179Z","end":"2026-03-23T05:28:38.473412Z","steps":["trace[1546427876] 'agreement among raft nodes before linearized reading' (duration: 194.085956ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:38.473704Z","caller":"traceutil/trace.go:171","msg":"trace[1994480506] transaction","detail":"{read_only:false; response_revision:3542; number_of_response:1; }","duration":"217.481582ms","start":"2026-03-23T05:28:38.256198Z","end":"2026-03-23T05:28:38.47368Z","steps":["trace[1994480506] 'process raft request' (duration: 216.109978ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:38.696636Z","caller":"traceutil/trace.go:171","msg":"trace[1488439915] linearizableReadLoop","detail":"{readStateIndex:3687; appliedIndex:3686; }","duration":"124.852141ms","start":"2026-03-23T05:28:38.571762Z","end":"2026-03-23T05:28:38.696614Z","steps":["trace[1488439915] 'read index received' (duration: 77.737972ms)","trace[1488439915] 'applied index is now lower than readState.Index' (duration: 47.112909ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:38.69671Z","caller":"traceutil/trace.go:171","msg":"trace[509728390] transaction","detail":"{read_only:false; response_revision:3544; number_of_response:1; }","duration":"196.362192ms","start":"2026-03-23T05:28:38.500315Z","end":"2026-03-23T05:28:38.696677Z","steps":["trace[509728390] 'process raft request' (duration: 149.209232ms)","trace[509728390] 'compare' (duration: 46.995306ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:38.696828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.049567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:38.69689Z","caller":"traceutil/trace.go:171","msg":"trace[1756460039] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:3544; }","duration":"125.119768ms","start":"2026-03-23T05:28:38.571755Z","end":"2026-03-23T05:28:38.696875Z","steps":["trace[1756460039] 'agreement among raft nodes before linearized reading' (duration: 125.009206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:38.746552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.145857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-03-23T05:28:38.746634Z","caller":"traceutil/trace.go:171","msg":"trace[724694735] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3545; }","duration":"118.262059ms","start":"2026-03-23T05:28:38.62835Z","end":"2026-03-23T05:28:38.746612Z","steps":["trace[724694735] 'agreement among raft nodes before linearized reading' (duration: 118.069065ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:38.746688Z","caller":"traceutil/trace.go:171","msg":"trace[1919966902] transaction","detail":"{read_only:false; response_revision:3545; number_of_response:1; }","duration":"170.980077ms","start":"2026-03-23T05:28:38.575672Z","end":"2026-03-23T05:28:38.746652Z","steps":["trace[1919966902] 'process raft request' (duration: 170.497336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:38.746669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.744092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:38.746858Z","caller":"traceutil/trace.go:171","msg":"trace[1260008870] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:3545; }","duration":"155.995768ms","start":"2026-03-23T05:28:38.590838Z","end":"2026-03-23T05:28:38.746834Z","steps":["trace[1260008870] 'agreement among raft nodes before linearized reading' (duration: 155.729501ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:38.911722Z","caller":"traceutil/trace.go:171","msg":"trace[1605390067] transaction","detail":"{read_only:false; response_revision:3546; number_of_response:1; }","duration":"160.743975ms","start":"2026-03-23T05:28:38.750949Z","end":"2026-03-23T05:28:38.911693Z","steps":["trace[1605390067] 'process raft request' (duration: 68.193417ms)","trace[1605390067] 'compare' (duration: 92.393644ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:38.911951Z","caller":"traceutil/trace.go:171","msg":"trace[1351030455] linearizableReadLoop","detail":"{readStateIndex:3691; appliedIndex:3688; }","duration":"137.34819ms","start":"2026-03-23T05:28:38.774588Z","end":"2026-03-23T05:28:38.911936Z","steps":["trace[1351030455] 'read index received' (duration: 44.562746ms)","trace[1351030455] 'applied index is now lower than readState.Index' (duration: 92.784514ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:38.912035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.433912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:38.912028Z","caller":"traceutil/trace.go:171","msg":"trace[500645510] transaction","detail":"{read_only:false; response_revision:3548; number_of_response:1; }","duration":"149.863437ms","start":"2026-03-23T05:28:38.762145Z","end":"2026-03-23T05:28:38.912008Z","steps":["trace[500645510] 'process raft request' (duration: 149.703583ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:38.912076Z","caller":"traceutil/trace.go:171","msg":"trace[1672517734] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:3548; }","duration":"137.528705ms","start":"2026-03-23T05:28:38.774535Z","end":"2026-03-23T05:28:38.912064Z","steps":["trace[1672517734] 'agreement among raft nodes before linearized reading' (duration: 137.455743ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:38.912095Z","caller":"traceutil/trace.go:171","msg":"trace[1532054752] transaction","detail":"{read_only:false; response_revision:3547; number_of_response:1; }","duration":"160.940709ms","start":"2026-03-23T05:28:38.751148Z","end":"2026-03-23T05:28:38.912089Z","steps":["trace[1532054752] 'process raft request' (duration: 160.515279ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:39.210009Z","caller":"traceutil/trace.go:171","msg":"trace[1710663782] transaction","detail":"{read_only:false; response_revision:3550; number_of_response:1; }","duration":"192.871585ms","start":"2026-03-23T05:28:39.017101Z","end":"2026-03-23T05:28:39.209972Z","steps":["trace[1710663782] 'process raft request' (duration: 192.405433ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:39.488614Z","caller":"traceutil/trace.go:171","msg":"trace[1167641337] linearizableReadLoop","detail":"{readStateIndex:3695; appliedIndex:3694; }","duration":"170.717281ms","start":"2026-03-23T05:28:39.317872Z","end":"2026-03-23T05:28:39.48859Z","steps":["trace[1167641337] 'read index received' (duration: 169.535062ms)","trace[1167641337] 'applied index is now lower than readState.Index' (duration: 1.181119ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:39.488722Z","caller":"traceutil/trace.go:171","msg":"trace[654613614] transaction","detail":"{read_only:false; response_revision:3552; number_of_response:1; }","duration":"177.692562ms","start":"2026-03-23T05:28:39.310997Z","end":"2026-03-23T05:28:39.48869Z","steps":["trace[654613614] 'process raft request' (duration: 176.77056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:39.488827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.925206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:39.48894Z","caller":"traceutil/trace.go:171","msg":"trace[757637220] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com; range_end:; response_count:0; response_revision:3552; }","duration":"171.117451ms","start":"2026-03-23T05:28:39.317794Z","end":"2026-03-23T05:28:39.488911Z","steps":["trace[757637220] 'agreement among raft nodes before linearized reading' (duration: 170.937196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:39.488861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.840198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ptgxq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-23T05:28:39.489037Z","caller":"traceutil/trace.go:171","msg":"trace[375015898] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ptgxq; range_end:; response_count:1; response_revision:3552; }","duration":"137.031942ms","start":"2026-03-23T05:28:39.351993Z","end":"2026-03-23T05:28:39.489025Z","steps":["trace[375015898] 'agreement among raft nodes before linearized reading' (duration: 136.742315ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:39.692611Z","caller":"traceutil/trace.go:171","msg":"trace[525969550] transaction","detail":"{read_only:false; response_revision:3553; number_of_response:1; }","duration":"112.042366ms","start":"2026-03-23T05:28:39.580546Z","end":"2026-03-23T05:28:39.692588Z","steps":["trace[525969550] 'process raft request' (duration: 111.678768ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:39.740999Z","caller":"traceutil/trace.go:171","msg":"trace[949210597] transaction","detail":"{read_only:false; response_revision:3554; number_of_response:1; }","duration":"142.185059ms","start":"2026-03-23T05:28:39.598789Z","end":"2026-03-23T05:28:39.740974Z","steps":["trace[949210597] 'process raft request' (duration: 141.906892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:40.080006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.727609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:28:40.080124Z","caller":"traceutil/trace.go:171","msg":"trace[701676108] transaction","detail":"{read_only:false; response_revision:3555; number_of_response:1; }","duration":"323.93677ms","start":"2026-03-23T05:28:39.756166Z","end":"2026-03-23T05:28:40.080102Z","steps":["trace[701676108] 'process raft request' (duration: 209.74975ms)","trace[701676108] 'compare' (duration: 113.498934ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:40.080189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:39.756137Z","time spent":"324.024122ms","remote":"127.0.0.1:53596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-23T05:28:40.093461Z","caller":"traceutil/trace.go:171","msg":"trace[1884806114] transaction","detail":"{read_only:false; response_revision:3556; number_of_response:1; }","duration":"329.289403ms","start":"2026-03-23T05:28:39.764141Z","end":"2026-03-23T05:28:40.093431Z","steps":["trace[1884806114] 'process raft request' (duration: 329.036337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:40.093691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:39.764116Z","time spent":"329.477648ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1113,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:28:40.093772Z","caller":"traceutil/trace.go:171","msg":"trace[1014555231] linearizableReadLoop","detail":"{readStateIndex:3699; appliedIndex:3697; }","duration":"321.281415ms","start":"2026-03-23T05:28:39.772466Z","end":"2026-03-23T05:28:40.093748Z","steps":["trace[1014555231] 'read index received' (duration: 193.563632ms)","trace[1014555231] 'applied index is now lower than readState.Index' (duration: 127.716403ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:40.093889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.403869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusrules.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:40.093942Z","caller":"traceutil/trace.go:171","msg":"trace[913977421] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusrules.monitoring.coreos.com; range_end:; response_count:0; response_revision:3557; }","duration":"321.501551ms","start":"2026-03-23T05:28:39.772427Z","end":"2026-03-23T05:28:40.093928Z","steps":["trace[913977421] 'agreement among raft nodes before linearized reading' (duration: 321.410869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:40.093985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:39.772412Z","time spent":"321.563743ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusrules.monitoring.coreos.com\" "} {"level":"info","ts":"2026-03-23T05:28:40.094039Z","caller":"traceutil/trace.go:171","msg":"trace[1794651311] transaction","detail":"{read_only:false; response_revision:3557; number_of_response:1; }","duration":"262.725805ms","start":"2026-03-23T05:28:39.831204Z","end":"2026-03-23T05:28:40.09393Z","steps":["trace[1794651311] 'process raft request' (duration: 262.232843ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:40.351462Z","caller":"traceutil/trace.go:171","msg":"trace[237538104] linearizableReadLoop","detail":"{readStateIndex:3703; appliedIndex:3702; }","duration":"192.159999ms","start":"2026-03-23T05:28:40.159281Z","end":"2026-03-23T05:28:40.351441Z","steps":["trace[237538104] 'read index received' (duration: 102.504763ms)","trace[237538104] 'applied index is now lower than readState.Index' (duration: 89.654346ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:40.35158Z","caller":"traceutil/trace.go:171","msg":"trace[438977697] transaction","detail":"{read_only:false; response_revision:3560; number_of_response:1; }","duration":"194.531156ms","start":"2026-03-23T05:28:40.157032Z","end":"2026-03-23T05:28:40.351563Z","steps":["trace[438977697] 'process raft request' (duration: 104.824419ms)","trace[438977697] 'compare' (duration: 89.493212ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:40.352179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.873867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:40.352233Z","caller":"traceutil/trace.go:171","msg":"trace[156917784] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com; range_end:; response_count:0; response_revision:3560; }","duration":"192.945808ms","start":"2026-03-23T05:28:40.159275Z","end":"2026-03-23T05:28:40.352221Z","steps":["trace[156917784] 'agreement among raft nodes before linearized reading' (duration: 192.847846ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:40.356793Z","caller":"traceutil/trace.go:171","msg":"trace[619842387] transaction","detail":"{read_only:false; response_revision:3561; number_of_response:1; }","duration":"154.887691ms","start":"2026-03-23T05:28:40.201876Z","end":"2026-03-23T05:28:40.356763Z","steps":["trace[619842387] 'process raft request' (duration: 154.562394ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:40.452064Z","caller":"traceutil/trace.go:171","msg":"trace[515798463] linearizableReadLoop","detail":"{readStateIndex:3705; appliedIndex:3703; }","duration":"100.506443ms","start":"2026-03-23T05:28:40.351531Z","end":"2026-03-23T05:28:40.452038Z","steps":["trace[515798463] 'read index received' (duration: 4.845129ms)","trace[515798463] 'applied index is now lower than readState.Index' (duration: 95.660154ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:40.452331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.200922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-23T05:28:40.452375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.137239ms","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-03-23T05:28:40.452404Z","caller":"traceutil/trace.go:171","msg":"trace[2115880074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3561; }","duration":"173.307625ms","start":"2026-03-23T05:28:40.279079Z","end":"2026-03-23T05:28:40.452387Z","steps":["trace[2115880074] 'agreement among raft nodes before linearized reading' (duration: 173.176642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:40.452413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.451916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-23T05:28:40.452455Z","caller":"traceutil/trace.go:171","msg":"trace[24222110] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3561; }","duration":"279.239251ms","start":"2026-03-23T05:28:40.173197Z","end":"2026-03-23T05:28:40.452436Z","steps":["trace[24222110] 'agreement among raft nodes before linearized reading' (duration: 279.013516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:40.452491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.619855ms","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-03-23T05:28:40.452546Z","caller":"traceutil/trace.go:171","msg":"trace[1263903833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3561; }","duration":"280.762068ms","start":"2026-03-23T05:28:40.171773Z","end":"2026-03-23T05:28:40.452535Z","steps":["trace[1263903833] 'agreement among raft nodes before linearized reading' (duration: 280.46005ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:40.452485Z","caller":"traceutil/trace.go:171","msg":"trace[1155889584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3561; }","duration":"279.563229ms","start":"2026-03-23T05:28:40.172904Z","end":"2026-03-23T05:28:40.452467Z","steps":["trace[1155889584] 'agreement among raft nodes before linearized reading' (duration: 279.309313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:40.497555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.728387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:40.49764Z","caller":"traceutil/trace.go:171","msg":"trace[2100263249] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com; range_end:; response_count:0; response_revision:3562; }","duration":"126.845431ms","start":"2026-03-23T05:28:40.370774Z","end":"2026-03-23T05:28:40.49762Z","steps":["trace[2100263249] 'agreement among raft nodes before linearized reading' (duration: 126.700867ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:40.661521Z","caller":"traceutil/trace.go:171","msg":"trace[1537271907] transaction","detail":"{read_only:false; response_revision:3567; number_of_response:1; }","duration":"113.255438ms","start":"2026-03-23T05:28:40.548238Z","end":"2026-03-23T05:28:40.661493Z","steps":["trace[1537271907] 'process raft request' (duration: 113.108244ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:40.907673Z","caller":"traceutil/trace.go:171","msg":"trace[1227471506] transaction","detail":"{read_only:false; response_revision:3568; number_of_response:1; }","duration":"198.478834ms","start":"2026-03-23T05:28:40.709163Z","end":"2026-03-23T05:28:40.907642Z","steps":["trace[1227471506] 'process raft request' (duration: 198.3297ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.021595Z","caller":"traceutil/trace.go:171","msg":"trace[1901667946] linearizableReadLoop","detail":"{readStateIndex:3713; appliedIndex:3711; }","duration":"296.309512ms","start":"2026-03-23T05:28:40.725259Z","end":"2026-03-23T05:28:41.021569Z","steps":["trace[1901667946] 'read index received' (duration: 182.335698ms)","trace[1901667946] 'applied index is now lower than readState.Index' (duration: 113.972804ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:41.021761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.484326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:41.0218Z","caller":"traceutil/trace.go:171","msg":"trace[980336063] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:3569; }","duration":"296.565438ms","start":"2026-03-23T05:28:40.725222Z","end":"2026-03-23T05:28:41.021787Z","steps":["trace[980336063] 'agreement among raft nodes before linearized reading' (duration: 296.483736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:41.021878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.042203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-23T05:28:41.021925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.786813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-03-23T05:28:41.021925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.500031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/thanosrulers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:41.022041Z","caller":"traceutil/trace.go:171","msg":"trace[582551169] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3569; }","duration":"105.916065ms","start":"2026-03-23T05:28:40.916099Z","end":"2026-03-23T05:28:41.022015Z","steps":["trace[582551169] 'agreement among raft nodes before linearized reading' (duration: 105.629818ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.022048Z","caller":"traceutil/trace.go:171","msg":"trace[2034184495] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/thanosrulers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3569; }","duration":"106.681055ms","start":"2026-03-23T05:28:40.915356Z","end":"2026-03-23T05:28:41.022037Z","steps":["trace[2034184495] 'agreement among raft nodes before linearized reading' (duration: 106.47408ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.02197Z","caller":"traceutil/trace.go:171","msg":"trace[1303629934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3569; }","duration":"107.162957ms","start":"2026-03-23T05:28:40.914783Z","end":"2026-03-23T05:28:41.021946Z","steps":["trace[1303629934] 'agreement among raft nodes before linearized reading' (duration: 106.940271ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.022276Z","caller":"traceutil/trace.go:171","msg":"trace[91785124] transaction","detail":"{read_only:false; response_revision:3569; number_of_response:1; }","duration":"309.187927ms","start":"2026-03-23T05:28:40.712499Z","end":"2026-03-23T05:28:41.021687Z","steps":["trace[91785124] 'process raft request' (duration: 254.719738ms)","trace[91785124] 'compare' (duration: 54.171612ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:41.022408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:40.712475Z","time spent":"309.860815ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121675,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:28:41.221041Z","caller":"traceutil/trace.go:171","msg":"trace[953902607] transaction","detail":"{read_only:false; response_revision:3572; number_of_response:1; }","duration":"194.996847ms","start":"2026-03-23T05:28:41.026024Z","end":"2026-03-23T05:28:41.221021Z","steps":["trace[953902607] 'process raft request' (duration: 194.888244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:41.221198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.858274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/thanosrulers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:41.221045Z","caller":"traceutil/trace.go:171","msg":"trace[1031121201] linearizableReadLoop","detail":"{readStateIndex:3716; appliedIndex:3713; }","duration":"194.70114ms","start":"2026-03-23T05:28:41.026323Z","end":"2026-03-23T05:28:41.221024Z","steps":["trace[1031121201] 'read index received' (duration: 44.039804ms)","trace[1031121201] 'applied index is now lower than readState.Index' (duration: 150.660416ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:41.221197Z","caller":"traceutil/trace.go:171","msg":"trace[580950109] transaction","detail":"{read_only:false; response_revision:3573; number_of_response:1; }","duration":"175.200211ms","start":"2026-03-23T05:28:41.045968Z","end":"2026-03-23T05:28:41.221168Z","steps":["trace[580950109] 'process raft request' (duration: 174.997766ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.221253Z","caller":"traceutil/trace.go:171","msg":"trace[938849212] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/thanosrulers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3573; }","duration":"194.929666ms","start":"2026-03-23T05:28:41.026311Z","end":"2026-03-23T05:28:41.221241Z","steps":["trace[938849212] 'agreement among raft nodes before linearized reading' (duration: 194.813682ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.22102Z","caller":"traceutil/trace.go:171","msg":"trace[172158816] transaction","detail":"{read_only:false; response_revision:3571; number_of_response:1; }","duration":"195.001908ms","start":"2026-03-23T05:28:41.025978Z","end":"2026-03-23T05:28:41.22098Z","steps":["trace[172158816] 'process raft request' (duration: 96.31893ms)","trace[172158816] 'compare' (duration: 98.470873ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:41.259691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.217697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-23T05:28:41.259699Z","caller":"traceutil/trace.go:171","msg":"trace[1481350769] transaction","detail":"{read_only:false; response_revision:3574; number_of_response:1; }","duration":"186.076078ms","start":"2026-03-23T05:28:41.073599Z","end":"2026-03-23T05:28:41.259675Z","steps":["trace[1481350769] 'process raft request' (duration: 185.782781ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.25975Z","caller":"traceutil/trace.go:171","msg":"trace[562684417] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3574; }","duration":"222.30016ms","start":"2026-03-23T05:28:41.037434Z","end":"2026-03-23T05:28:41.259734Z","steps":["trace[562684417] 'agreement among raft nodes before linearized reading' (duration: 222.205718ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.619073Z","caller":"traceutil/trace.go:171","msg":"trace[1321068819] linearizableReadLoop","detail":"{readStateIndex:3719; appliedIndex:3718; }","duration":"332.372129ms","start":"2026-03-23T05:28:41.286676Z","end":"2026-03-23T05:28:41.619049Z","steps":["trace[1321068819] 'read index received' (duration: 296.64613ms)","trace[1321068819] 'applied index is now lower than readState.Index' (duration: 35.724909ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:41.619097Z","caller":"traceutil/trace.go:171","msg":"trace[1132494150] transaction","detail":"{read_only:false; response_revision:3575; number_of_response:1; }","duration":"335.008433ms","start":"2026-03-23T05:28:41.284064Z","end":"2026-03-23T05:28:41.619072Z","steps":["trace[1132494150] 'process raft request' (duration: 299.287914ms)","trace[1132494150] 'compare' (duration: 35.462762ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:41.619223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:41.284039Z","time spent":"335.106236ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:28:41.61933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.627195ms","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-03-23T05:28:41.619436Z","caller":"traceutil/trace.go:171","msg":"trace[804135150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3575; }","duration":"332.785639ms","start":"2026-03-23T05:28:41.286626Z","end":"2026-03-23T05:28:41.619412Z","steps":["trace[804135150] 'agreement among raft nodes before linearized reading' (duration: 332.503232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:41.6195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:41.286605Z","time spent":"332.88081ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-23T05:28:41.702367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.888033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-23T05:28:41.702379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.034732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-23T05:28:41.702474Z","caller":"traceutil/trace.go:171","msg":"trace[866417254] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3576; }","duration":"415.166835ms","start":"2026-03-23T05:28:41.287284Z","end":"2026-03-23T05:28:41.702451Z","steps":["trace[866417254] 'agreement among raft nodes before linearized reading' (duration: 414.875908ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:41.702457Z","caller":"traceutil/trace.go:171","msg":"trace[1651532511] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:3576; }","duration":"338.036587ms","start":"2026-03-23T05:28:41.364399Z","end":"2026-03-23T05:28:41.702436Z","steps":["trace[1651532511] 'agreement among raft nodes before linearized reading' (duration: 337.869823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:41.702373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.535798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ptgxq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-23T05:28:41.702607Z","caller":"traceutil/trace.go:171","msg":"trace[369420420] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ptgxq; range_end:; response_count:1; response_revision:3576; }","duration":"203.822255ms","start":"2026-03-23T05:28:41.498768Z","end":"2026-03-23T05:28:41.70259Z","steps":["trace[369420420] 'agreement among raft nodes before linearized reading' (duration: 203.429855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:41.702647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:41.364378Z","time spent":"338.213142ms","remote":"127.0.0.1:55644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-03-23T05:28:41.702396Z","caller":"traceutil/trace.go:171","msg":"trace[539543522] transaction","detail":"{read_only:false; response_revision:3576; number_of_response:1; }","duration":"358.800338ms","start":"2026-03-23T05:28:41.343563Z","end":"2026-03-23T05:28:41.702363Z","steps":["trace[539543522] 'process raft request' (duration: 358.401288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:41.702535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:41.287272Z","time spent":"415.251027ms","remote":"127.0.0.1:53686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-23T05:28:41.702805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:28:41.34354Z","time spent":"359.181658ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":266750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:28:41.852447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.772313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:28:41.852912Z","caller":"traceutil/trace.go:171","msg":"trace[73363861] transaction","detail":"{read_only:false; response_revision:3577; number_of_response:1; }","duration":"229.154239ms","start":"2026-03-23T05:28:41.623648Z","end":"2026-03-23T05:28:41.852802Z","steps":["trace[73363861] 'process raft request' (duration: 122.950736ms)","trace[73363861] 'compare' (duration: 105.67151ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:41.853086Z","caller":"traceutil/trace.go:171","msg":"trace[1360066690] linearizableReadLoop","detail":"{readStateIndex:3721; appliedIndex:3720; }","duration":"147.017937ms","start":"2026-03-23T05:28:41.706048Z","end":"2026-03-23T05:28:41.853066Z","steps":["trace[1360066690] 'read index received' (duration: 40.491816ms)","trace[1360066690] 'applied index is now lower than readState.Index' (duration: 106.523891ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:41.853266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.213762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-23T05:28:41.853313Z","caller":"traceutil/trace.go:171","msg":"trace[853754360] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3577; }","duration":"147.292393ms","start":"2026-03-23T05:28:41.706011Z","end":"2026-03-23T05:28:41.853304Z","steps":["trace[853754360] 'agreement among raft nodes before linearized reading' (duration: 147.15169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:41.853553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.624408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:6 size:46100"} {"level":"info","ts":"2026-03-23T05:28:41.853589Z","caller":"traceutil/trace.go:171","msg":"trace[1034773797] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:6; response_revision:3578; }","duration":"146.680809ms","start":"2026-03-23T05:28:41.706901Z","end":"2026-03-23T05:28:41.853582Z","steps":["trace[1034773797] 'agreement among raft nodes before linearized reading' (duration: 146.553086ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:42.204087Z","caller":"traceutil/trace.go:171","msg":"trace[1020370541] linearizableReadLoop","detail":"{readStateIndex:3723; appliedIndex:3722; }","duration":"202.466292ms","start":"2026-03-23T05:28:42.001599Z","end":"2026-03-23T05:28:42.204065Z","steps":["trace[1020370541] 'read index received' (duration: 111.666228ms)","trace[1020370541] 'applied index is now lower than readState.Index' (duration: 90.798814ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:42.204291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.670827ms","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-03-23T05:28:42.204335Z","caller":"traceutil/trace.go:171","msg":"trace[693175801] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3579; }","duration":"202.764929ms","start":"2026-03-23T05:28:42.001559Z","end":"2026-03-23T05:28:42.204324Z","steps":["trace[693175801] 'agreement among raft nodes before linearized reading' (duration: 202.626226ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:42.204194Z","caller":"traceutil/trace.go:171","msg":"trace[1340508774] transaction","detail":"{read_only:false; response_revision:3579; number_of_response:1; }","duration":"267.375759ms","start":"2026-03-23T05:28:41.936781Z","end":"2026-03-23T05:28:42.204157Z","steps":["trace[1340508774] 'process raft request' (duration: 176.573115ms)","trace[1340508774] 'compare' (duration: 90.552678ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:42.435166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.979223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:42.435229Z","caller":"traceutil/trace.go:171","msg":"trace[653266292] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:3580; }","duration":"118.079115ms","start":"2026-03-23T05:28:42.317138Z","end":"2026-03-23T05:28:42.435217Z","steps":["trace[653266292] 'range keys from in-memory index tree' (duration: 117.933672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:42.435226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.423069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:42.435326Z","caller":"traceutil/trace.go:171","msg":"trace[1454687811] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:3580; }","duration":"117.559922ms","start":"2026-03-23T05:28:42.317746Z","end":"2026-03-23T05:28:42.435306Z","steps":["trace[1454687811] 'range keys from in-memory index tree' (duration: 117.375849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:28:42.435561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.250169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:42.435632Z","caller":"traceutil/trace.go:171","msg":"trace[662628268] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:3580; }","duration":"118.331041ms","start":"2026-03-23T05:28:42.317266Z","end":"2026-03-23T05:28:42.435597Z","steps":["trace[662628268] 'count revisions from in-memory index tree' (duration: 118.213859ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:28:43.391119Z","caller":"traceutil/trace.go:171","msg":"trace[1656057556] linearizableReadLoop","detail":"{readStateIndex:3732; appliedIndex:3731; }","duration":"112.500228ms","start":"2026-03-23T05:28:43.278598Z","end":"2026-03-23T05:28:43.391098Z","steps":["trace[1656057556] 'read index received' (duration: 26.231165ms)","trace[1656057556] 'applied index is now lower than readState.Index' (duration: 86.267863ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:28:43.391161Z","caller":"traceutil/trace.go:171","msg":"trace[1013900446] transaction","detail":"{read_only:false; response_revision:3588; number_of_response:1; }","duration":"161.880873ms","start":"2026-03-23T05:28:43.229247Z","end":"2026-03-23T05:28:43.391128Z","steps":["trace[1013900446] 'process raft request' (duration: 75.628181ms)","trace[1013900446] 'compare' (duration: 86.079208ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:28:43.39124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.627561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:28:43.391297Z","caller":"traceutil/trace.go:171","msg":"trace[244837949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3588; }","duration":"112.743484ms","start":"2026-03-23T05:28:43.278543Z","end":"2026-03-23T05:28:43.391286Z","steps":["trace[244837949] 'agreement among raft nodes before linearized reading' (duration: 112.646272ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:24.338697Z","caller":"traceutil/trace.go:171","msg":"trace[1118204538] transaction","detail":"{read_only:false; response_revision:4258; number_of_response:1; }","duration":"101.566941ms","start":"2026-03-23T05:29:24.237108Z","end":"2026-03-23T05:29:24.338675Z","steps":["trace[1118204538] 'process raft request' (duration: 101.175781ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:24.594764Z","caller":"traceutil/trace.go:171","msg":"trace[8986929] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4258; }","duration":"255.034281ms","start":"2026-03-23T05:29:24.339677Z","end":"2026-03-23T05:29:24.594712Z","steps":["trace[8986929] 'process raft request' (duration: 254.906258ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:24.594709Z","caller":"traceutil/trace.go:171","msg":"trace[766561811] linearizableReadLoop","detail":"{readStateIndex:4427; appliedIndex:4426; }","duration":"254.356993ms","start":"2026-03-23T05:29:24.340325Z","end":"2026-03-23T05:29:24.594682Z","steps":["trace[766561811] 'read index received' (duration: 254.23526ms)","trace[766561811] 'applied index is now lower than readState.Index' (duration: 120.583µs)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:29:24.594923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.519167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-23T05:29:24.595075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.665848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-361127b8-86ec-4aa2-a8f9-4833fe3bfe13\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-03-23T05:29:24.595105Z","caller":"traceutil/trace.go:171","msg":"trace[51092199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4258; }","duration":"254.756164ms","start":"2026-03-23T05:29:24.340296Z","end":"2026-03-23T05:29:24.595052Z","steps":["trace[51092199] 'agreement among raft nodes before linearized reading' (duration: 254.494937ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:24.595155Z","caller":"traceutil/trace.go:171","msg":"trace[2078015956] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-361127b8-86ec-4aa2-a8f9-4833fe3bfe13; range_end:; response_count:1; response_revision:4258; }","duration":"199.77056ms","start":"2026-03-23T05:29:24.395341Z","end":"2026-03-23T05:29:24.595111Z","steps":["trace[2078015956] 'agreement among raft nodes before linearized reading' (duration: 199.576765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:24.596094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.743102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2351364"} {"level":"info","ts":"2026-03-23T05:29:24.596168Z","caller":"traceutil/trace.go:171","msg":"trace[1336168675] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4258; }","duration":"176.860016ms","start":"2026-03-23T05:29:24.419293Z","end":"2026-03-23T05:29:24.596153Z","steps":["trace[1336168675] 'agreement among raft nodes before linearized reading' (duration: 175.79449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:29.125502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.272453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:29:29.125845Z","caller":"traceutil/trace.go:171","msg":"trace[412725073] transaction","detail":"{read_only:false; response_revision:4359; number_of_response:1; }","duration":"195.160337ms","start":"2026-03-23T05:29:28.930652Z","end":"2026-03-23T05:29:29.125813Z","steps":["trace[412725073] 'process raft request' (duration: 195.032284ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.125954Z","caller":"traceutil/trace.go:171","msg":"trace[562502001] transaction","detail":"{read_only:false; response_revision:4362; number_of_response:1; }","duration":"195.138146ms","start":"2026-03-23T05:29:28.930797Z","end":"2026-03-23T05:29:29.125935Z","steps":["trace[562502001] 'process raft request' (duration: 195.052934ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.125987Z","caller":"traceutil/trace.go:171","msg":"trace[2013537170] linearizableReadLoop","detail":"{readStateIndex:4534; appliedIndex:4528; }","duration":"194.350026ms","start":"2026-03-23T05:29:28.931621Z","end":"2026-03-23T05:29:29.125971Z","steps":["trace[2013537170] 'read index received' (duration: 23.669223ms)","trace[2013537170] 'applied index is now lower than readState.Index' (duration: 170.679973ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:29:29.126007Z","caller":"traceutil/trace.go:171","msg":"trace[598875503] transaction","detail":"{read_only:false; response_revision:4361; number_of_response:1; }","duration":"195.217758ms","start":"2026-03-23T05:29:28.930726Z","end":"2026-03-23T05:29:29.125944Z","steps":["trace[598875503] 'process raft request' (duration: 195.076105ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.126241Z","caller":"traceutil/trace.go:171","msg":"trace[712825420] transaction","detail":"{read_only:false; response_revision:4364; number_of_response:1; }","duration":"184.705428ms","start":"2026-03-23T05:29:28.941529Z","end":"2026-03-23T05:29:29.126234Z","steps":["trace[712825420] 'process raft request' (duration: 184.389171ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.126277Z","caller":"traceutil/trace.go:171","msg":"trace[1862268267] transaction","detail":"{read_only:false; response_revision:4360; number_of_response:1; }","duration":"195.582857ms","start":"2026-03-23T05:29:28.930681Z","end":"2026-03-23T05:29:29.126264Z","steps":["trace[1862268267] 'process raft request' (duration: 195.084525ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.125841Z","caller":"traceutil/trace.go:171","msg":"trace[1949867394] transaction","detail":"{read_only:false; response_revision:4358; number_of_response:1; }","duration":"196.632412ms","start":"2026-03-23T05:29:28.929159Z","end":"2026-03-23T05:29:29.125792Z","steps":["trace[1949867394] 'process raft request' (duration: 25.97638ms)","trace[1949867394] 'compare' (duration: 169.912314ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:29:29.126508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.875988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-03-23T05:29:29.126534Z","caller":"traceutil/trace.go:171","msg":"trace[800974962] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4364; }","duration":"194.95689ms","start":"2026-03-23T05:29:28.93157Z","end":"2026-03-23T05:29:29.126527Z","steps":["trace[800974962] 'agreement among raft nodes before linearized reading' (duration: 194.836247ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.126079Z","caller":"traceutil/trace.go:171","msg":"trace[23335973] transaction","detail":"{read_only:false; response_revision:4363; number_of_response:1; }","duration":"195.268429ms","start":"2026-03-23T05:29:28.930802Z","end":"2026-03-23T05:29:29.12607Z","steps":["trace[23335973] 'process raft request' (duration: 195.077745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:29.126697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.218768ms","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":"warn","ts":"2026-03-23T05:29:29.126783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.235221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-23T05:29:29.12681Z","caller":"traceutil/trace.go:171","msg":"trace[62663416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4364; }","duration":"144.326014ms","start":"2026-03-23T05:29:28.982477Z","end":"2026-03-23T05:29:29.126803Z","steps":["trace[62663416] 'agreement among raft nodes before linearized reading' (duration: 144.198631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:29.12687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.551029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-23T05:29:29.126903Z","caller":"traceutil/trace.go:171","msg":"trace[571638626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4364; }","duration":"144.58641ms","start":"2026-03-23T05:29:28.982308Z","end":"2026-03-23T05:29:29.126894Z","steps":["trace[571638626] 'agreement among raft nodes before linearized reading' (duration: 144.490468ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.126777Z","caller":"traceutil/trace.go:171","msg":"trace[9758423] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4364; }","duration":"191.30487ms","start":"2026-03-23T05:29:28.935438Z","end":"2026-03-23T05:29:29.126743Z","steps":["trace[9758423] 'agreement among raft nodes before linearized reading' (duration: 191.062565ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:29.493418Z","caller":"traceutil/trace.go:171","msg":"trace[198042210] linearizableReadLoop","detail":"{readStateIndex:4549; appliedIndex:4548; }","duration":"129.616521ms","start":"2026-03-23T05:29:29.363754Z","end":"2026-03-23T05:29:29.493371Z","steps":["trace[198042210] 'read index received' (duration: 124.025483ms)","trace[198042210] 'applied index is now lower than readState.Index' (duration: 5.590048ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:29:29.49353Z","caller":"traceutil/trace.go:171","msg":"trace[1210297550] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4376; }","duration":"130.491703ms","start":"2026-03-23T05:29:29.362988Z","end":"2026-03-23T05:29:29.49348Z","steps":["trace[1210297550] 'process raft request' (duration: 124.729691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:29.493671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.890819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-23T05:29:29.493827Z","caller":"traceutil/trace.go:171","msg":"trace[82443503] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4376; }","duration":"130.048822ms","start":"2026-03-23T05:29:29.363736Z","end":"2026-03-23T05:29:29.493785Z","steps":["trace[82443503] 'agreement among raft nodes before linearized reading' (duration: 129.771995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:29.493706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.979986ms","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":"info","ts":"2026-03-23T05:29:29.494044Z","caller":"traceutil/trace.go:171","msg":"trace[2117502271] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4376; }","duration":"129.366125ms","start":"2026-03-23T05:29:29.364661Z","end":"2026-03-23T05:29:29.494027Z","steps":["trace[2117502271] 'agreement among raft nodes before linearized reading' (duration: 128.887093ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:32.927885Z","caller":"traceutil/trace.go:171","msg":"trace[1517931434] linearizableReadLoop","detail":"{readStateIndex:4674; appliedIndex:4673; }","duration":"116.473608ms","start":"2026-03-23T05:29:32.811387Z","end":"2026-03-23T05:29:32.92786Z","steps":["trace[1517931434] 'read index received' (duration: 116.351285ms)","trace[1517931434] 'applied index is now lower than readState.Index' (duration: 121.433µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:29:32.928152Z","caller":"traceutil/trace.go:171","msg":"trace[1360245454] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"228.734642ms","start":"2026-03-23T05:29:32.699405Z","end":"2026-03-23T05:29:32.92814Z","steps":["trace[1360245454] 'process raft request' (duration: 228.333232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:32.928473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.076282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af523b22-2909-414f-bd64-63610920ea27\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-23T05:29:32.928516Z","caller":"traceutil/trace.go:171","msg":"trace[206276249] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-af523b22-2909-414f-bd64-63610920ea27; range_end:; response_count:1; response_revision:4497; }","duration":"117.129854ms","start":"2026-03-23T05:29:32.811374Z","end":"2026-03-23T05:29:32.928503Z","steps":["trace[206276249] 'agreement among raft nodes before linearized reading' (duration: 116.944639ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:33.099609Z","caller":"traceutil/trace.go:171","msg":"trace[1371970860] linearizableReadLoop","detail":"{readStateIndex:4675; appliedIndex:4674; }","duration":"171.629916ms","start":"2026-03-23T05:29:32.927955Z","end":"2026-03-23T05:29:33.099585Z","steps":["trace[1371970860] 'read index received' (duration: 106.671467ms)","trace[1371970860] 'applied index is now lower than readState.Index' (duration: 64.957389ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:29:33.099693Z","caller":"traceutil/trace.go:171","msg":"trace[203375343] transaction","detail":"{read_only:false; response_revision:4498; number_of_response:1; }","duration":"259.702244ms","start":"2026-03-23T05:29:32.839952Z","end":"2026-03-23T05:29:33.099654Z","steps":["trace[203375343] 'process raft request' (duration: 194.575691ms)","trace[203375343] 'compare' (duration: 64.950559ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:29:33.099817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.400803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af523b22-2909-414f-bd64-63610920ea27\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-23T05:29:33.099867Z","caller":"traceutil/trace.go:171","msg":"trace[1810984905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-af523b22-2909-414f-bd64-63610920ea27; range_end:; response_count:1; response_revision:4498; }","duration":"282.503086ms","start":"2026-03-23T05:29:32.817349Z","end":"2026-03-23T05:29:33.099852Z","steps":["trace[1810984905] 'agreement among raft nodes before linearized reading' (duration: 282.347232ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:33.133814Z","caller":"traceutil/trace.go:171","msg":"trace[628596226] transaction","detail":"{read_only:false; response_revision:4499; number_of_response:1; }","duration":"197.169467ms","start":"2026-03-23T05:29:32.936608Z","end":"2026-03-23T05:29:33.133778Z","steps":["trace[628596226] 'process raft request' (duration: 196.886109ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:33.133957Z","caller":"traceutil/trace.go:171","msg":"trace[1382064066] transaction","detail":"{read_only:false; response_revision:4500; number_of_response:1; }","duration":"197.067993ms","start":"2026-03-23T05:29:32.936866Z","end":"2026-03-23T05:29:33.133934Z","steps":["trace[1382064066] 'process raft request' (duration: 196.776106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:33.134065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.389922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-23T05:29:33.134142Z","caller":"traceutil/trace.go:171","msg":"trace[375882597] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4500; }","duration":"169.504825ms","start":"2026-03-23T05:29:32.964621Z","end":"2026-03-23T05:29:33.134126Z","steps":["trace[375882597] 'agreement among raft nodes before linearized reading' (duration: 169.28933ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:36.606772Z","caller":"traceutil/trace.go:171","msg":"trace[2006688584] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"220.147971ms","start":"2026-03-23T05:29:36.386597Z","end":"2026-03-23T05:29:36.606745Z","steps":["trace[2006688584] 'process raft request' (duration: 219.771012ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:43.397394Z","caller":"traceutil/trace.go:171","msg":"trace[544329133] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"129.036017ms","start":"2026-03-23T05:29:43.268331Z","end":"2026-03-23T05:29:43.397367Z","steps":["trace[544329133] 'read index received' (duration: 66.976029ms)","trace[544329133] 'applied index is now lower than readState.Index' (duration: 62.059078ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:29:43.397459Z","caller":"traceutil/trace.go:171","msg":"trace[131261821] transaction","detail":"{read_only:false; response_revision:4696; number_of_response:1; }","duration":"131.97843ms","start":"2026-03-23T05:29:43.265443Z","end":"2026-03-23T05:29:43.397422Z","steps":["trace[131261821] 'process raft request' (duration: 69.735167ms)","trace[131261821] 'compare' (duration: 61.799992ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:29:43.397604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.255352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:29:43.397652Z","caller":"traceutil/trace.go:171","msg":"trace[1122394889] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:4696; }","duration":"129.354155ms","start":"2026-03-23T05:29:43.268285Z","end":"2026-03-23T05:29:43.397639Z","steps":["trace[1122394889] 'agreement among raft nodes before linearized reading' (duration: 129.209131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:43.420491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.364921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:29:43.420546Z","caller":"traceutil/trace.go:171","msg":"trace[735563557] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"151.886621ms","start":"2026-03-23T05:29:43.268633Z","end":"2026-03-23T05:29:43.42052Z","steps":["trace[735563557] 'process raft request' (duration: 151.617485ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:43.420574Z","caller":"traceutil/trace.go:171","msg":"trace[383081427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4697; }","duration":"143.486974ms","start":"2026-03-23T05:29:43.27707Z","end":"2026-03-23T05:29:43.420557Z","steps":["trace[383081427] 'agreement among raft nodes before linearized reading' (duration: 143.373131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:43.420678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.524524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:7 size:224038"} {"level":"info","ts":"2026-03-23T05:29:43.420788Z","caller":"traceutil/trace.go:171","msg":"trace[291304422] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:7; response_revision:4697; }","duration":"104.692188ms","start":"2026-03-23T05:29:43.316079Z","end":"2026-03-23T05:29:43.420772Z","steps":["trace[291304422] 'agreement among raft nodes before linearized reading' (duration: 104.35149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:45.581259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.081202ms","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-03-23T05:29:45.581356Z","caller":"traceutil/trace.go:171","msg":"trace[1773120970] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4735; }","duration":"132.230236ms","start":"2026-03-23T05:29:45.449105Z","end":"2026-03-23T05:29:45.581335Z","steps":["trace[1773120970] 'range keys from in-memory index tree' (duration: 131.841247ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:51.485914Z","caller":"traceutil/trace.go:171","msg":"trace[1903426087] linearizableReadLoop","detail":"{readStateIndex:5023; appliedIndex:5022; }","duration":"173.726188ms","start":"2026-03-23T05:29:51.312158Z","end":"2026-03-23T05:29:51.485884Z","steps":["trace[1903426087] 'read index received' (duration: 79.998569ms)","trace[1903426087] 'applied index is now lower than readState.Index' (duration: 93.726559ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:29:51.486038Z","caller":"traceutil/trace.go:171","msg":"trace[522138113] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"180.429584ms","start":"2026-03-23T05:29:51.305573Z","end":"2026-03-23T05:29:51.486003Z","steps":["trace[522138113] 'process raft request' (duration: 86.643754ms)","trace[522138113] 'compare' (duration: 93.527694ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:29:51.486309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.366296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-03-23T05:29:51.486365Z","caller":"traceutil/trace.go:171","msg":"trace[464171002] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4837; }","duration":"172.480169ms","start":"2026-03-23T05:29:51.313871Z","end":"2026-03-23T05:29:51.486351Z","steps":["trace[464171002] 'agreement among raft nodes before linearized reading' (duration: 172.292564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:51.486342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.455051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.189f6153e66a0fbd\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-03-23T05:29:51.486447Z","caller":"traceutil/trace.go:171","msg":"trace[1782035948] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.189f6153e66a0fbd; range_end:; response_count:1; response_revision:4837; }","duration":"173.575124ms","start":"2026-03-23T05:29:51.312843Z","end":"2026-03-23T05:29:51.486418Z","steps":["trace[1782035948] 'agreement among raft nodes before linearized reading' (duration: 173.270097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:51.487873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.702856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2366191"} {"level":"info","ts":"2026-03-23T05:29:51.48803Z","caller":"traceutil/trace.go:171","msg":"trace[2041710562] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:4837; }","duration":"175.891911ms","start":"2026-03-23T05:29:51.312118Z","end":"2026-03-23T05:29:51.48801Z","steps":["trace[2041710562] 'agreement among raft nodes before linearized reading' (duration: 174.454366ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:53.392406Z","caller":"traceutil/trace.go:171","msg":"trace[1908781491] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"396.78542ms","start":"2026-03-23T05:29:52.995582Z","end":"2026-03-23T05:29:53.392367Z","steps":["trace[1908781491] 'process raft request' (duration: 396.498633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:53.392585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.19087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1740177e-602c-4f2e-ba46-e44725b8a2fc\" ","response":"range_response_count:1 size:3766"} {"level":"warn","ts":"2026-03-23T05:29:53.392658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:29:52.995568Z","time spent":"396.930064ms","remote":"127.0.0.1:53686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:29:53.392675Z","caller":"traceutil/trace.go:171","msg":"trace[323456743] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1740177e-602c-4f2e-ba46-e44725b8a2fc; range_end:; response_count:1; response_revision:4858; }","duration":"211.315933ms","start":"2026-03-23T05:29:53.181338Z","end":"2026-03-23T05:29:53.392654Z","steps":["trace[323456743] 'agreement among raft nodes before linearized reading' (duration: 211.17985ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:53.392796Z","caller":"traceutil/trace.go:171","msg":"trace[1589196383] linearizableReadLoop","detail":"{readStateIndex:5044; appliedIndex:5043; }","duration":"210.880003ms","start":"2026-03-23T05:29:53.18138Z","end":"2026-03-23T05:29:53.39226Z","steps":["trace[1589196383] 'read index received' (duration: 210.667637ms)","trace[1589196383] 'applied index is now lower than readState.Index' (duration: 211.216µs)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:29:53.393036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.189007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:29:53.393103Z","caller":"traceutil/trace.go:171","msg":"trace[1187081252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4858; }","duration":"113.31044ms","start":"2026-03-23T05:29:53.279776Z","end":"2026-03-23T05:29:53.393086Z","steps":["trace[1187081252] 'agreement among raft nodes before linearized reading' (duration: 113.197527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:29:53.39304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.564571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-23T05:29:53.393293Z","caller":"traceutil/trace.go:171","msg":"trace[47888464] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4858; }","duration":"168.856519ms","start":"2026-03-23T05:29:53.224421Z","end":"2026-03-23T05:29:53.393277Z","steps":["trace[47888464] 'agreement among raft nodes before linearized reading' (duration: 168.547321ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:55.276339Z","caller":"traceutil/trace.go:171","msg":"trace[305787967] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"132.492934ms","start":"2026-03-23T05:29:55.143806Z","end":"2026-03-23T05:29:55.276299Z","steps":["trace[305787967] 'process raft request' (duration: 132.202037ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:30:07.920868Z","caller":"traceutil/trace.go:171","msg":"trace[937174636] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"114.90329ms","start":"2026-03-23T05:30:07.805932Z","end":"2026-03-23T05:30:07.920836Z","steps":["trace[937174636] 'process raft request' (duration: 113.836653ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:30:07.921847Z","caller":"traceutil/trace.go:171","msg":"trace[477345109] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"115.013873ms","start":"2026-03-23T05:30:07.806063Z","end":"2026-03-23T05:30:07.921076Z","steps":["trace[477345109] 'process raft request' (duration: 114.014027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:30:08.099859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.326491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:30:08.100074Z","caller":"traceutil/trace.go:171","msg":"trace[2090691580] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"172.478561ms","start":"2026-03-23T05:30:07.927516Z","end":"2026-03-23T05:30:08.099995Z","steps":["trace[2090691580] 'process raft request' (duration: 50.850413ms)","trace[2090691580] 'compare' (duration: 121.111176ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:30:08.288133Z","caller":"traceutil/trace.go:171","msg":"trace[811179698] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"111.501814ms","start":"2026-03-23T05:30:08.176592Z","end":"2026-03-23T05:30:08.288094Z","steps":["trace[811179698] 'process raft request' (duration: 73.254108ms)","trace[811179698] 'compare' (duration: 38.025269ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:30:50.308579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.706027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:30:50.308701Z","caller":"traceutil/trace.go:171","msg":"trace[1967070387] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"180.402172ms","start":"2026-03-23T05:30:50.128286Z","end":"2026-03-23T05:30:50.308688Z","steps":["trace[1967070387] 'process raft request' (duration: 72.52489ms)","trace[1967070387] 'compare' (duration: 107.616925ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:30:50.308897Z","caller":"traceutil/trace.go:171","msg":"trace[1226201593] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"180.174287ms","start":"2026-03-23T05:30:50.128689Z","end":"2026-03-23T05:30:50.308863Z","steps":["trace[1226201593] 'process raft request' (duration: 179.971861ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:30:50.641026Z","caller":"traceutil/trace.go:171","msg":"trace[2022343057] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"250.320606ms","start":"2026-03-23T05:30:50.39067Z","end":"2026-03-23T05:30:50.64099Z","steps":["trace[2022343057] 'process raft request' (duration: 155.193026ms)","trace[2022343057] 'compare' (duration: 94.759911ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:31:01.556637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2567} {"level":"info","ts":"2026-03-23T05:31:01.62002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2567,"took":"62.051446ms","hash":313078564,"current-db-size-bytes":40333312,"current-db-size":"40 MB","current-db-size-in-use-bytes":29544448,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-23T05:31:01.620089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":313078564,"revision":2567,"compact-revision":-1} {"level":"info","ts":"2026-03-23T05:35:50.552087Z","caller":"traceutil/trace.go:171","msg":"trace[1606828514] transaction","detail":"{read_only:false; response_revision:7509; number_of_response:1; }","duration":"109.219697ms","start":"2026-03-23T05:35:50.442834Z","end":"2026-03-23T05:35:50.552054Z","steps":["trace[1606828514] 'process raft request' (duration: 108.989251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:35:52.48417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.422272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:35:52.484309Z","caller":"traceutil/trace.go:171","msg":"trace[213654178] linearizableReadLoop","detail":"{readStateIndex:7792; appliedIndex:7791; }","duration":"321.984935ms","start":"2026-03-23T05:35:52.162305Z","end":"2026-03-23T05:35:52.484289Z","steps":["trace[213654178] 'read index received' (duration: 155.297576ms)","trace[213654178] 'applied index is now lower than readState.Index' (duration: 166.685449ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:35:52.484411Z","caller":"traceutil/trace.go:171","msg":"trace[1489743090] transaction","detail":"{read_only:false; response_revision:7517; number_of_response:1; }","duration":"434.207154ms","start":"2026-03-23T05:35:52.05016Z","end":"2026-03-23T05:35:52.484367Z","steps":["trace[1489743090] 'process raft request' (duration: 267.469114ms)","trace[1489743090] 'compare' (duration: 166.165086ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:35:52.484577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.279202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-23T05:35:52.484634Z","caller":"traceutil/trace.go:171","msg":"trace[363477861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7517; }","duration":"322.381554ms","start":"2026-03-23T05:35:52.162241Z","end":"2026-03-23T05:35:52.484622Z","steps":["trace[363477861] 'agreement among raft nodes before linearized reading' (duration: 322.161019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:35:52.484689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:35:52.162221Z","time spent":"322.455496ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-23T05:35:52.484729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.844507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:35:52.484826Z","caller":"traceutil/trace.go:171","msg":"trace[943492650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7517; }","duration":"204.996471ms","start":"2026-03-23T05:35:52.279809Z","end":"2026-03-23T05:35:52.484805Z","steps":["trace[943492650] 'agreement among raft nodes before linearized reading' (duration: 204.835397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:35:52.484625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:35:52.050135Z","time spent":"434.373808ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:36:01.563658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5487} {"level":"info","ts":"2026-03-23T05:36:01.664769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5487,"took":"99.301848ms","hash":2247621731,"current-db-size-bytes":42110976,"current-db-size":"42 MB","current-db-size-in-use-bytes":21876736,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-23T05:36:01.664842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2247621731,"revision":5487,"compact-revision":2567} {"level":"info","ts":"2026-03-23T05:36:04.632899Z","caller":"traceutil/trace.go:171","msg":"trace[925756252] transaction","detail":"{read_only:false; response_revision:7574; number_of_response:1; }","duration":"139.748541ms","start":"2026-03-23T05:36:04.492523Z","end":"2026-03-23T05:36:04.632272Z","steps":["trace[925756252] 'process raft request' (duration: 139.523286ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:04.885054Z","caller":"traceutil/trace.go:171","msg":"trace[763122432] linearizableReadLoop","detail":"{readStateIndex:7855; appliedIndex:7854; }","duration":"224.008608ms","start":"2026-03-23T05:36:04.661003Z","end":"2026-03-23T05:36:04.885012Z","steps":["trace[763122432] 'read index received' (duration: 139.038745ms)","trace[763122432] 'applied index is now lower than readState.Index' (duration: 84.968303ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:36:04.885134Z","caller":"traceutil/trace.go:171","msg":"trace[1524990196] transaction","detail":"{read_only:false; response_revision:7576; number_of_response:1; }","duration":"248.476536ms","start":"2026-03-23T05:36:04.636621Z","end":"2026-03-23T05:36:04.885098Z","steps":["trace[1524990196] 'process raft request' (duration: 163.473112ms)","trace[1524990196] 'compare' (duration: 84.779159ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:36:04.885645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.623332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ptgxq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-23T05:36:04.885726Z","caller":"traceutil/trace.go:171","msg":"trace[1420859036] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ptgxq; range_end:; response_count:1; response_revision:7576; }","duration":"224.716964ms","start":"2026-03-23T05:36:04.660987Z","end":"2026-03-23T05:36:04.885704Z","steps":["trace[1420859036] 'agreement among raft nodes before linearized reading' (duration: 224.229793ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:44.572544Z","caller":"traceutil/trace.go:171","msg":"trace[1088060031] transaction","detail":"{read_only:false; response_revision:7865; number_of_response:1; }","duration":"125.100773ms","start":"2026-03-23T05:36:44.447419Z","end":"2026-03-23T05:36:44.57252Z","steps":["trace[1088060031] 'process raft request' (duration: 124.661843ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:45.45614Z","caller":"traceutil/trace.go:171","msg":"trace[1678163878] linearizableReadLoop","detail":"{readStateIndex:8161; appliedIndex:8160; }","duration":"288.401802ms","start":"2026-03-23T05:36:45.167698Z","end":"2026-03-23T05:36:45.4561Z","steps":["trace[1678163878] 'read index received' (duration: 239.337355ms)","trace[1678163878] 'applied index is now lower than readState.Index' (duration: 49.062467ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:36:45.456181Z","caller":"traceutil/trace.go:171","msg":"trace[1351940925] transaction","detail":"{read_only:false; response_revision:7869; number_of_response:1; }","duration":"350.641025ms","start":"2026-03-23T05:36:45.105486Z","end":"2026-03-23T05:36:45.456127Z","steps":["trace[1351940925] 'process raft request' (duration: 301.477886ms)","trace[1351940925] 'compare' (duration: 48.74559ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:36:45.456473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:36:45.105459Z","time spent":"350.864241ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:36:45.45646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.72425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ptgxq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-23T05:36:45.45658Z","caller":"traceutil/trace.go:171","msg":"trace[2068718501] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ptgxq; range_end:; response_count:1; response_revision:7869; }","duration":"288.884914ms","start":"2026-03-23T05:36:45.167672Z","end":"2026-03-23T05:36:45.456556Z","steps":["trace[2068718501] 'agreement among raft nodes before linearized reading' (duration: 288.538344ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:45.486227Z","caller":"traceutil/trace.go:171","msg":"trace[1147183532] transaction","detail":"{read_only:false; response_revision:7870; number_of_response:1; }","duration":"263.046173ms","start":"2026-03-23T05:36:45.223135Z","end":"2026-03-23T05:36:45.486181Z","steps":["trace[1147183532] 'process raft request' (duration: 262.850158ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:45.486402Z","caller":"traceutil/trace.go:171","msg":"trace[505899695] transaction","detail":"{read_only:false; response_revision:7872; number_of_response:1; }","duration":"125.549144ms","start":"2026-03-23T05:36:45.360833Z","end":"2026-03-23T05:36:45.486382Z","steps":["trace[505899695] 'process raft request' (duration: 125.295288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:36:45.486523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.564215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:36:45.487051Z","caller":"traceutil/trace.go:171","msg":"trace[535074677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7872; }","duration":"209.135348ms","start":"2026-03-23T05:36:45.277897Z","end":"2026-03-23T05:36:45.487032Z","steps":["trace[535074677] 'agreement among raft nodes before linearized reading' (duration: 208.530784ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:45.486331Z","caller":"traceutil/trace.go:171","msg":"trace[1449282518] transaction","detail":"{read_only:false; response_revision:7871; number_of_response:1; }","duration":"229.622512ms","start":"2026-03-23T05:36:45.256661Z","end":"2026-03-23T05:36:45.486283Z","steps":["trace[1449282518] 'process raft request' (duration: 229.431187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:36:45.4866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.812531ms","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-03-23T05:36:45.487339Z","caller":"traceutil/trace.go:171","msg":"trace[253439047] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7872; }","duration":"139.59721ms","start":"2026-03-23T05:36:45.347693Z","end":"2026-03-23T05:36:45.487291Z","steps":["trace[253439047] 'agreement among raft nodes before linearized reading' (duration: 138.801151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:36:45.486673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.607483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-sdb95\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-03-23T05:36:45.487522Z","caller":"traceutil/trace.go:171","msg":"trace[1357140560] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-sdb95; range_end:; response_count:1; response_revision:7872; }","duration":"128.533775ms","start":"2026-03-23T05:36:45.358965Z","end":"2026-03-23T05:36:45.487499Z","steps":["trace[1357140560] 'agreement among raft nodes before linearized reading' (duration: 127.446139ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:46.492172Z","caller":"traceutil/trace.go:171","msg":"trace[1748121070] transaction","detail":"{read_only:false; response_revision:7875; number_of_response:1; }","duration":"321.079146ms","start":"2026-03-23T05:36:46.17106Z","end":"2026-03-23T05:36:46.492139Z","steps":["trace[1748121070] 'process raft request' (duration: 320.904192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:36:46.492403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:36:46.171042Z","time spent":"321.222209ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:36:46.530814Z","caller":"traceutil/trace.go:171","msg":"trace[32971966] transaction","detail":"{read_only:false; response_revision:7876; number_of_response:1; }","duration":"359.292652ms","start":"2026-03-23T05:36:46.171493Z","end":"2026-03-23T05:36:46.530786Z","steps":["trace[32971966] 'process raft request' (duration: 359.056977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:36:46.531055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:36:46.171472Z","time spent":"359.428416ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:36:46.531236Z","caller":"traceutil/trace.go:171","msg":"trace[1294064795] linearizableReadLoop","detail":"{readStateIndex:8171; appliedIndex:8167; }","duration":"298.38131ms","start":"2026-03-23T05:36:46.232823Z","end":"2026-03-23T05:36:46.531205Z","steps":["trace[1294064795] 'read index received' (duration: 259.303272ms)","trace[1294064795] 'applied index is now lower than readState.Index' (duration: 39.076598ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:36:46.531268Z","caller":"traceutil/trace.go:171","msg":"trace[1636930498] transaction","detail":"{read_only:false; response_revision:7878; number_of_response:1; }","duration":"359.223551ms","start":"2026-03-23T05:36:46.171998Z","end":"2026-03-23T05:36:46.531221Z","steps":["trace[1636930498] 'process raft request' (duration: 358.995865ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:46.531312Z","caller":"traceutil/trace.go:171","msg":"trace[981167320] transaction","detail":"{read_only:false; response_revision:7879; number_of_response:1; }","duration":"357.819107ms","start":"2026-03-23T05:36:46.173472Z","end":"2026-03-23T05:36:46.531291Z","steps":["trace[981167320] 'process raft request' (duration: 357.673684ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:46.531319Z","caller":"traceutil/trace.go:171","msg":"trace[3072059] transaction","detail":"{read_only:false; response_revision:7877; number_of_response:1; }","duration":"359.762144ms","start":"2026-03-23T05:36:46.171513Z","end":"2026-03-23T05:36:46.531275Z","steps":["trace[3072059] 'process raft request' (duration: 359.217961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:36:46.531411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:36:46.173463Z","time spent":"357.91543ms","remote":"127.0.0.1:53602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":583,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-23T05:36:46.531476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.737732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-23T05:36:46.531482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:36:46.171982Z","time spent":"359.372395ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:36:46.531514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.690828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-03-23T05:36:46.531546Z","caller":"traceutil/trace.go:171","msg":"trace[414847291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7879; }","duration":"251.846284ms","start":"2026-03-23T05:36:46.279681Z","end":"2026-03-23T05:36:46.531527Z","steps":["trace[414847291] 'agreement among raft nodes before linearized reading' (duration: 251.731511ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:46.531552Z","caller":"traceutil/trace.go:171","msg":"trace[1497928267] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7879; }","duration":"298.755999ms","start":"2026-03-23T05:36:46.232783Z","end":"2026-03-23T05:36:46.531539Z","steps":["trace[1497928267] 'agreement among raft nodes before linearized reading' (duration: 298.594015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:36:46.531542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.960104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-23T05:36:46.531597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:36:46.171491Z","time spent":"359.924277ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:36:46.531619Z","caller":"traceutil/trace.go:171","msg":"trace[639034247] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:7879; }","duration":"144.097898ms","start":"2026-03-23T05:36:46.3875Z","end":"2026-03-23T05:36:46.531598Z","steps":["trace[639034247] 'agreement among raft nodes before linearized reading' (duration: 143.931874ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:36:47.263334Z","caller":"traceutil/trace.go:171","msg":"trace[305230397] transaction","detail":"{read_only:false; response_revision:7883; number_of_response:1; }","duration":"110.664646ms","start":"2026-03-23T05:36:47.152634Z","end":"2026-03-23T05:36:47.263298Z","steps":["trace[305230397] 'process raft request' (duration: 110.364458ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:40:24.662581Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bf16572acb5c7f39","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-23T05:40:24.668374Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-23T05:40:24.668531Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-23T05:41:01.575144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7559} {"level":"info","ts":"2026-03-23T05:41:01.641435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7559,"took":"63.925517ms","hash":1178218859,"current-db-size-bytes":42110976,"current-db-size":"42 MB","current-db-size-in-use-bytes":25731072,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-23T05:41:01.641497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1178218859,"revision":7559,"compact-revision":5487} {"level":"info","ts":"2026-03-23T05:46:01.582149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9842} {"level":"info","ts":"2026-03-23T05:46:01.665643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9842,"took":"79.794121ms","hash":1947676316,"current-db-size-bytes":42110976,"current-db-size":"42 MB","current-db-size-in-use-bytes":16748544,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-23T05:46:01.665702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1947676316,"revision":9842,"compact-revision":7559}