{"level":"warn","ts":"2026-05-19T13:27:48.232672Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:27:48.232755Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T13:27:48.232817Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:27:48.232832Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-19T13:27:48.23285Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T13:27:48.233471Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-05-19T13:27:48.233545Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T13:27:48.238128Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.383346ms"} {"level":"info","ts":"2026-05-19T13:27:48.245101Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-05-19T13:27:48.245226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T13:27:48.245255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-05-19T13:27:48.245276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T13:27:48.245294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-05-19T13:27:48.245345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-05-19T13:27:48.255552Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T13:27:48.257141Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T13:27:48.258607Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T13:27:48.260954Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T13:27:48.261616Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T13:27:48.261699Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T13:27:48.261713Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T13:27:48.261695Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T13:27:48.263683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-05-19T13:27:48.264157Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-19T13:27:48.267165Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T13:27:48.267342Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-19T13:27:48.267397Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-19T13:27:48.268791Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T13:27:48.268855Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T13:27:48.34559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T13:27:48.345635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T13:27:48.345654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-05-19T13:27:48.345667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-05-19T13:27:48.345672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-19T13:27:48.345681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-05-19T13:27:48.345688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-19T13:27:48.34664Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:27:48.346755Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T13:27:48.346777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:27:48.346816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:27:48.347022Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T13:27:48.347163Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T13:27:48.347678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:27:48.347768Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:27:48.347813Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:27:48.348724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T13:27:48.351482Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-05-19T13:28:06.450096Z","caller":"traceutil/trace.go:171","msg":"trace[966291725] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"134.043348ms","start":"2026-05-19T13:28:06.316024Z","end":"2026-05-19T13:28:06.450067Z","steps":["trace[966291725] 'process raft request' (duration: 133.885875ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:28:25.443293Z","caller":"traceutil/trace.go:171","msg":"trace[557853778] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"144.8978ms","start":"2026-05-19T13:28:25.298366Z","end":"2026-05-19T13:28:25.443264Z","steps":["trace[557853778] 'process raft request' (duration: 144.747887ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:28:25.443302Z","caller":"traceutil/trace.go:171","msg":"trace[2079801947] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:641; }","duration":"132.68476ms","start":"2026-05-19T13:28:25.310585Z","end":"2026-05-19T13:28:25.44327Z","steps":["trace[2079801947] 'read index received' (duration: 132.67292ms)","trace[2079801947] 'applied index is now lower than readState.Index' (duration: 9.74µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:28:25.443502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.888515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T13:28:25.4436Z","caller":"traceutil/trace.go:171","msg":"trace[444318616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"133.015728ms","start":"2026-05-19T13:28:25.310569Z","end":"2026-05-19T13:28:25.443584Z","steps":["trace[444318616] 'agreement among raft nodes before linearized reading' (duration: 132.800043ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:28:25.650106Z","caller":"traceutil/trace.go:171","msg":"trace[1942193870] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"263.731685ms","start":"2026-05-19T13:28:25.386341Z","end":"2026-05-19T13:28:25.650073Z","steps":["trace[1942193870] 'process raft request' (duration: 263.143743ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:28:25.713449Z","caller":"traceutil/trace.go:171","msg":"trace[416095042] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"179.721176ms","start":"2026-05-19T13:28:25.533686Z","end":"2026-05-19T13:28:25.713407Z","steps":["trace[416095042] 'read index received' (duration: 115.710967ms)","trace[416095042] 'applied index is now lower than readState.Index' (duration: 64.008929ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:28:25.713583Z","caller":"traceutil/trace.go:171","msg":"trace[1414390515] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"265.799311ms","start":"2026-05-19T13:28:25.447705Z","end":"2026-05-19T13:28:25.713504Z","steps":["trace[1414390515] 'process raft request' (duration: 265.528285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:28:25.713713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.972451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zhj8k\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T13:28:25.713786Z","caller":"traceutil/trace.go:171","msg":"trace[104900387] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zhj8k; range_end:; response_count:1; response_revision:623; }","duration":"180.129985ms","start":"2026-05-19T13:28:25.533644Z","end":"2026-05-19T13:28:25.713774Z","steps":["trace[104900387] 'agreement among raft nodes before linearized reading' (duration: 179.957871ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:28:25.816939Z","caller":"traceutil/trace.go:171","msg":"trace[1746128211] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"101.099106ms","start":"2026-05-19T13:28:25.715814Z","end":"2026-05-19T13:28:25.816913Z","steps":["trace[1746128211] 'process raft request' (duration: 67.785692ms)","trace[1746128211] 'compare' (duration: 33.14266ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:29:07.979696Z","caller":"traceutil/trace.go:171","msg":"trace[579347516] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"259.942633ms","start":"2026-05-19T13:29:07.719727Z","end":"2026-05-19T13:29:07.97967Z","steps":["trace[579347516] 'process raft request' (duration: 259.753169ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:07.981787Z","caller":"traceutil/trace.go:171","msg":"trace[359539001] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1206; }","duration":"232.191451ms","start":"2026-05-19T13:29:07.749575Z","end":"2026-05-19T13:29:07.981766Z","steps":["trace[359539001] 'read index received' (duration: 229.915231ms)","trace[359539001] 'applied index is now lower than readState.Index' (duration: 2.27521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:29:07.981922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.69205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:29:07.98195Z","caller":"traceutil/trace.go:171","msg":"trace[1874262677] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1177; }","duration":"201.784002ms","start":"2026-05-19T13:29:07.78016Z","end":"2026-05-19T13:29:07.981944Z","steps":["trace[1874262677] 'agreement among raft nodes before linearized reading' (duration: 201.71075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:29:07.981957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.366215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:29:07.982051Z","caller":"traceutil/trace.go:171","msg":"trace[1191667949] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1177; }","duration":"232.522489ms","start":"2026-05-19T13:29:07.749509Z","end":"2026-05-19T13:29:07.982031Z","steps":["trace[1191667949] 'agreement among raft nodes before linearized reading' (duration: 232.373926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:07.982007Z","caller":"traceutil/trace.go:171","msg":"trace[914506291] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"262.229493ms","start":"2026-05-19T13:29:07.719754Z","end":"2026-05-19T13:29:07.981984Z","steps":["trace[914506291] 'process raft request' (duration: 261.896806ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:28.352619Z","caller":"traceutil/trace.go:171","msg":"trace[1127752361] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"107.564498ms","start":"2026-05-19T13:29:28.245006Z","end":"2026-05-19T13:29:28.35257Z","steps":["trace[1127752361] 'process raft request' (duration: 69.76658ms)","trace[1127752361] 'compare' (duration: 37.551102ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:29:28.353616Z","caller":"traceutil/trace.go:171","msg":"trace[1953318219] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"104.495201ms","start":"2026-05-19T13:29:28.249088Z","end":"2026-05-19T13:29:28.353584Z","steps":["trace[1953318219] 'process raft request' (duration: 104.427489ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:28.35369Z","caller":"traceutil/trace.go:171","msg":"trace[783059933] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"105.462812ms","start":"2026-05-19T13:29:28.248191Z","end":"2026-05-19T13:29:28.353654Z","steps":["trace[783059933] 'process raft request' (duration: 105.181566ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:32.268073Z","caller":"traceutil/trace.go:171","msg":"trace[529514664] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"100.484204ms","start":"2026-05-19T13:29:32.167545Z","end":"2026-05-19T13:29:32.268029Z","steps":["trace[529514664] 'process raft request' (duration: 100.231759ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:58.4661Z","caller":"traceutil/trace.go:171","msg":"trace[1528403661] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"123.326569ms","start":"2026-05-19T13:29:58.342755Z","end":"2026-05-19T13:29:58.466081Z","steps":["trace[1528403661] 'process raft request' (duration: 123.205767ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:58.576961Z","caller":"traceutil/trace.go:171","msg":"trace[164127839] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"106.952476ms","start":"2026-05-19T13:29:58.469983Z","end":"2026-05-19T13:29:58.576935Z","steps":["trace[164127839] 'process raft request' (duration: 106.794032ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:58.578833Z","caller":"traceutil/trace.go:171","msg":"trace[531678892] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"108.007836ms","start":"2026-05-19T13:29:58.470793Z","end":"2026-05-19T13:29:58.578801Z","steps":["trace[531678892] 'process raft request' (duration: 107.326972ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:30:49.541078Z","caller":"traceutil/trace.go:171","msg":"trace[1844664640] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"128.954907ms","start":"2026-05-19T13:30:49.412099Z","end":"2026-05-19T13:30:49.541054Z","steps":["trace[1844664640] 'process raft request' (duration: 128.752443ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:31:22.9853Z","caller":"traceutil/trace.go:171","msg":"trace[387692948] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"121.269209ms","start":"2026-05-19T13:31:22.864001Z","end":"2026-05-19T13:31:22.98527Z","steps":["trace[387692948] 'process raft request' (duration: 73.620737ms)","trace[387692948] 'compare' (duration: 47.496559ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:31:22.985295Z","caller":"traceutil/trace.go:171","msg":"trace[1177933467] linearizableReadLoop","detail":"{readStateIndex:2452; appliedIndex:2450; }","duration":"101.87929ms","start":"2026-05-19T13:31:22.883383Z","end":"2026-05-19T13:31:22.985262Z","steps":["trace[1177933467] 'read index received' (duration: 8.107999ms)","trace[1177933467] 'applied index is now lower than readState.Index' (duration: 93.770191ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:31:22.985484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.988053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:31:22.98564Z","caller":"traceutil/trace.go:171","msg":"trace[1876229474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2366; }","duration":"102.29225ms","start":"2026-05-19T13:31:22.883329Z","end":"2026-05-19T13:31:22.985621Z","steps":["trace[1876229474] 'agreement among raft nodes before linearized reading' (duration: 101.994393ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:31:29.218497Z","caller":"traceutil/trace.go:171","msg":"trace[677771016] linearizableReadLoop","detail":"{readStateIndex:2485; appliedIndex:2484; }","duration":"203.181717ms","start":"2026-05-19T13:31:29.01528Z","end":"2026-05-19T13:31:29.218462Z","steps":["trace[677771016] 'read index received' (duration: 117.2959ms)","trace[677771016] 'applied index is now lower than readState.Index' (duration: 85.884167ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:31:29.218568Z","caller":"traceutil/trace.go:171","msg":"trace[2002540679] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"212.030772ms","start":"2026-05-19T13:31:29.006485Z","end":"2026-05-19T13:31:29.218516Z","steps":["trace[2002540679] 'process raft request' (duration: 126.192006ms)","trace[2002540679] 'compare' (duration: 85.658472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:31:29.218789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.482643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T13:31:29.218836Z","caller":"traceutil/trace.go:171","msg":"trace[1065109175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2398; }","duration":"203.587566ms","start":"2026-05-19T13:31:29.015233Z","end":"2026-05-19T13:31:29.218821Z","steps":["trace[1065109175] 'agreement among raft nodes before linearized reading' (duration: 203.391362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:29.218927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.980608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T13:31:29.21906Z","caller":"traceutil/trace.go:171","msg":"trace[62683954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2398; }","duration":"163.191063ms","start":"2026-05-19T13:31:29.055838Z","end":"2026-05-19T13:31:29.219029Z","steps":["trace[62683954] 'agreement among raft nodes before linearized reading' (duration: 162.878347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:29.218927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.765477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T13:31:29.219335Z","caller":"traceutil/trace.go:171","msg":"trace[2070912858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2398; }","duration":"174.095824ms","start":"2026-05-19T13:31:29.045093Z","end":"2026-05-19T13:31:29.219189Z","steps":["trace[2070912858] 'agreement among raft nodes before linearized reading' (duration: 173.577193ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:32:49.84842Z","caller":"traceutil/trace.go:171","msg":"trace[580997001] transaction","detail":"{read_only:false; response_revision:3035; number_of_response:1; }","duration":"129.857578ms","start":"2026-05-19T13:32:49.718531Z","end":"2026-05-19T13:32:49.848388Z","steps":["trace[580997001] 'process raft request' (duration: 103.3496ms)","trace[580997001] 'compare' (duration: 26.377224ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:36:36.969534Z","caller":"traceutil/trace.go:171","msg":"trace[1135721635] linearizableReadLoop","detail":"{readStateIndex:5346; appliedIndex:5345; }","duration":"100.590442ms","start":"2026-05-19T13:36:36.868918Z","end":"2026-05-19T13:36:36.969509Z","steps":["trace[1135721635] 'read index received' (duration: 67.040364ms)","trace[1135721635] 'applied index is now lower than readState.Index' (duration: 33.546598ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:36:36.969498Z","caller":"traceutil/trace.go:171","msg":"trace[1929344920] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"137.766049ms","start":"2026-05-19T13:36:36.831695Z","end":"2026-05-19T13:36:36.969461Z","steps":["trace[1929344920] 'process raft request' (duration: 104.253242ms)","trace[1929344920] 'compare' (duration: 33.278192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:36:36.971076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.130227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b2c4d6b8-b5ac-4837-8363-56c69536c4df\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-19T13:36:36.971169Z","caller":"traceutil/trace.go:171","msg":"trace[1416048557] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b2c4d6b8-b5ac-4837-8363-56c69536c4df; range_end:; response_count:1; response_revision:5159; }","duration":"102.268889ms","start":"2026-05-19T13:36:36.868876Z","end":"2026-05-19T13:36:36.971144Z","steps":["trace[1416048557] 'agreement among raft nodes before linearized reading' (duration: 100.747505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.380985Z","caller":"traceutil/trace.go:171","msg":"trace[776610465] linearizableReadLoop","detail":"{readStateIndex:5735; appliedIndex:5734; }","duration":"141.939168ms","start":"2026-05-19T13:36:56.239022Z","end":"2026-05-19T13:36:56.380961Z","steps":["trace[776610465] 'read index received' (duration: 127.883233ms)","trace[776610465] 'applied index is now lower than readState.Index' (duration: 14.055085ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:36:56.381026Z","caller":"traceutil/trace.go:171","msg":"trace[1976205355] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"169.985057ms","start":"2026-05-19T13:36:56.211006Z","end":"2026-05-19T13:36:56.380991Z","steps":["trace[1976205355] 'process raft request' (duration: 155.985573ms)","trace[1976205355] 'compare' (duration: 13.626136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:36:56.381227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.182474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d5a71f0-ccfe-464c-b34a-9878fdfec631\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-19T13:36:56.38127Z","caller":"traceutil/trace.go:171","msg":"trace[252034419] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3d5a71f0-ccfe-464c-b34a-9878fdfec631; range_end:; response_count:1; response_revision:5538; }","duration":"142.270136ms","start":"2026-05-19T13:36:56.238988Z","end":"2026-05-19T13:36:56.381258Z","steps":["trace[252034419] 'agreement among raft nodes before linearized reading' (duration: 142.115122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:56.381316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.124152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-19T13:36:56.381537Z","caller":"traceutil/trace.go:171","msg":"trace[1558988710] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5538; }","duration":"136.397209ms","start":"2026-05-19T13:36:56.245127Z","end":"2026-05-19T13:36:56.381524Z","steps":["trace[1558988710] 'agreement among raft nodes before linearized reading' (duration: 136.070381ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.515501Z","caller":"traceutil/trace.go:171","msg":"trace[1891381978] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"129.127362ms","start":"2026-05-19T13:36:56.386341Z","end":"2026-05-19T13:36:56.515469Z","steps":["trace[1891381978] 'process raft request' (duration: 128.912197ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.515838Z","caller":"traceutil/trace.go:171","msg":"trace[1571890261] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"129.210303ms","start":"2026-05-19T13:36:56.3866Z","end":"2026-05-19T13:36:56.51581Z","steps":["trace[1571890261] 'process raft request' (duration: 128.787124ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.515855Z","caller":"traceutil/trace.go:171","msg":"trace[2122724652] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"129.255264ms","start":"2026-05-19T13:36:56.386053Z","end":"2026-05-19T13:36:56.515308Z","steps":["trace[2122724652] 'process raft request' (duration: 125.879901ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.516228Z","caller":"traceutil/trace.go:171","msg":"trace[1785828207] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"128.14346ms","start":"2026-05-19T13:36:56.388057Z","end":"2026-05-19T13:36:56.516201Z","steps":["trace[1785828207] 'process raft request' (duration: 127.489956ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.516449Z","caller":"traceutil/trace.go:171","msg":"trace[714981911] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"125.960873ms","start":"2026-05-19T13:36:56.390464Z","end":"2026-05-19T13:36:56.516425Z","steps":["trace[714981911] 'process raft request' (duration: 125.653217ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.516623Z","caller":"traceutil/trace.go:171","msg":"trace[209497015] transaction","detail":"{read_only:false; response_revision:5545; number_of_response:1; }","duration":"119.627916ms","start":"2026-05-19T13:36:56.39698Z","end":"2026-05-19T13:36:56.516608Z","steps":["trace[209497015] 'process raft request' (duration: 119.33777ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.51664Z","caller":"traceutil/trace.go:171","msg":"trace[289407843] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"111.456588ms","start":"2026-05-19T13:36:56.405158Z","end":"2026-05-19T13:36:56.516614Z","steps":["trace[289407843] 'process raft request' (duration: 111.351976ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.701724Z","caller":"traceutil/trace.go:171","msg":"trace[1130027360] linearizableReadLoop","detail":"{readStateIndex:5745; appliedIndex:5744; }","duration":"130.323928ms","start":"2026-05-19T13:36:56.571372Z","end":"2026-05-19T13:36:56.701696Z","steps":["trace[1130027360] 'read index received' (duration: 116.395245ms)","trace[1130027360] 'applied index is now lower than readState.Index' (duration: 13.927763ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:36:56.701815Z","caller":"traceutil/trace.go:171","msg":"trace[7045371] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"146.458877ms","start":"2026-05-19T13:36:56.55531Z","end":"2026-05-19T13:36:56.701769Z","steps":["trace[7045371] 'process raft request' (duration: 132.472294ms)","trace[7045371] 'compare' (duration: 13.797369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:36:56.701859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.472741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:36:56.701894Z","caller":"traceutil/trace.go:171","msg":"trace[33115988] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5548; }","duration":"130.578834ms","start":"2026-05-19T13:36:56.571305Z","end":"2026-05-19T13:36:56.701884Z","steps":["trace[33115988] 'agreement among raft nodes before linearized reading' (duration: 130.491352ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.855813Z","caller":"traceutil/trace.go:171","msg":"trace[1803915522] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"148.050982ms","start":"2026-05-19T13:36:56.707709Z","end":"2026-05-19T13:36:56.85576Z","steps":["trace[1803915522] 'process raft request' (duration: 134.437407ms)","trace[1803915522] 'compare' (duration: 13.447502ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:36:56.856074Z","caller":"traceutil/trace.go:171","msg":"trace[1591411280] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"147.90387ms","start":"2026-05-19T13:36:56.708146Z","end":"2026-05-19T13:36:56.85605Z","steps":["trace[1591411280] 'process raft request' (duration: 147.560532ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.856316Z","caller":"traceutil/trace.go:171","msg":"trace[514809485] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"147.620283ms","start":"2026-05-19T13:36:56.708683Z","end":"2026-05-19T13:36:56.856303Z","steps":["trace[514809485] 'process raft request' (duration: 147.278326ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.856529Z","caller":"traceutil/trace.go:171","msg":"trace[355729324] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"147.03142ms","start":"2026-05-19T13:36:56.709485Z","end":"2026-05-19T13:36:56.856516Z","steps":["trace[355729324] 'process raft request' (duration: 146.749724ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:56.856569Z","caller":"traceutil/trace.go:171","msg":"trace[1294518336] linearizableReadLoop","detail":"{readStateIndex:5751; appliedIndex:5746; }","duration":"127.285301ms","start":"2026-05-19T13:36:56.729262Z","end":"2026-05-19T13:36:56.856547Z","steps":["trace[1294518336] 'read index received' (duration: 112.831288ms)","trace[1294518336] 'applied index is now lower than readState.Index' (duration: 14.452593ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:36:56.85666Z","caller":"traceutil/trace.go:171","msg":"trace[1786299370] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"146.009698ms","start":"2026-05-19T13:36:56.710635Z","end":"2026-05-19T13:36:56.856645Z","steps":["trace[1786299370] 'process raft request' (duration: 145.792524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:56.856759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.481685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b0fafc81247334\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-05-19T13:36:56.856809Z","caller":"traceutil/trace.go:171","msg":"trace[1620001256] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b0fafc81247334; range_end:; response_count:1; response_revision:5554; }","duration":"127.573438ms","start":"2026-05-19T13:36:56.729224Z","end":"2026-05-19T13:36:56.856797Z","steps":["trace[1620001256] 'agreement among raft nodes before linearized reading' (duration: 127.419644ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:07.50117Z","caller":"traceutil/trace.go:171","msg":"trace[666746452] linearizableReadLoop","detail":"{readStateIndex:5921; appliedIndex:5920; }","duration":"117.115846ms","start":"2026-05-19T13:37:07.384019Z","end":"2026-05-19T13:37:07.501135Z","steps":["trace[666746452] 'read index received' (duration: 116.808099ms)","trace[666746452] 'applied index is now lower than readState.Index' (duration: 306.187µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:37:07.501206Z","caller":"traceutil/trace.go:171","msg":"trace[447722825] transaction","detail":"{read_only:false; response_revision:5718; number_of_response:1; }","duration":"153.616892ms","start":"2026-05-19T13:37:07.347556Z","end":"2026-05-19T13:37:07.501173Z","steps":["trace[447722825] 'process raft request' (duration: 153.386407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:37:07.501512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.465613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T13:37:07.50161Z","caller":"traceutil/trace.go:171","msg":"trace[1620772813] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5718; }","duration":"117.618377ms","start":"2026-05-19T13:37:07.383965Z","end":"2026-05-19T13:37:07.501583Z","steps":["trace[1620772813] 'agreement among raft nodes before linearized reading' (duration: 117.339591ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:48.83161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3028} {"level":"info","ts":"2026-05-19T13:37:48.913203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3028,"took":"80.073448ms","hash":1683414090,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":30916608,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T13:37:48.913288Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1683414090,"revision":3028,"compact-revision":-1} {"level":"info","ts":"2026-05-19T13:42:48.837402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6064} {"level":"info","ts":"2026-05-19T13:42:48.932552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6064,"took":"92.706716ms","hash":1544778804,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":20762624,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-19T13:42:48.932618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1544778804,"revision":6064,"compact-revision":3028} {"level":"info","ts":"2026-05-19T13:44:49.95805Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002424.wal"} {"level":"info","ts":"2026-05-19T13:45:00.005692Z","caller":"traceutil/trace.go:171","msg":"trace[1318115708] transaction","detail":"{read_only:false; response_revision:8990; number_of_response:1; }","duration":"100.940615ms","start":"2026-05-19T13:44:59.904732Z","end":"2026-05-19T13:45:00.005673Z","steps":["trace[1318115708] 'process raft request' (duration: 100.419764ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:00.397382Z","caller":"traceutil/trace.go:171","msg":"trace[572150062] transaction","detail":"{read_only:false; response_revision:8993; number_of_response:1; }","duration":"130.852096ms","start":"2026-05-19T13:45:00.266498Z","end":"2026-05-19T13:45:00.39735Z","steps":["trace[572150062] 'process raft request' (duration: 130.57387ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:00.510747Z","caller":"traceutil/trace.go:171","msg":"trace[1038228006] linearizableReadLoop","detail":"{readStateIndex:9307; appliedIndex:9306; }","duration":"111.762981ms","start":"2026-05-19T13:45:00.398953Z","end":"2026-05-19T13:45:00.510716Z","steps":["trace[1038228006] 'read index received' (duration: 14.914225ms)","trace[1038228006] 'applied index is now lower than readState.Index' (duration: 96.847676ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:45:00.510788Z","caller":"traceutil/trace.go:171","msg":"trace[2025761538] transaction","detail":"{read_only:false; response_revision:8994; number_of_response:1; }","duration":"237.555566ms","start":"2026-05-19T13:45:00.273193Z","end":"2026-05-19T13:45:00.510749Z","steps":["trace[2025761538] 'process raft request' (duration: 140.916366ms)","trace[2025761538] 'compare' (duration: 96.483137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:45:00.510903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.924454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:45:00.510968Z","caller":"traceutil/trace.go:171","msg":"trace[1422828595] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8994; }","duration":"112.011746ms","start":"2026-05-19T13:45:00.39894Z","end":"2026-05-19T13:45:00.510951Z","steps":["trace[1422828595] 'agreement among raft nodes before linearized reading' (duration: 111.879983ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:01.756547Z","caller":"traceutil/trace.go:171","msg":"trace[1240347076] transaction","detail":"{read_only:false; response_revision:9000; number_of_response:1; }","duration":"107.947697ms","start":"2026-05-19T13:45:01.648566Z","end":"2026-05-19T13:45:01.756513Z","steps":["trace[1240347076] 'process raft request' (duration: 107.718812ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:02.419928Z","caller":"traceutil/trace.go:171","msg":"trace[2121407930] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"111.165808ms","start":"2026-05-19T13:45:02.308736Z","end":"2026-05-19T13:45:02.419902Z","steps":["trace[2121407930] 'process raft request' (duration: 110.856061ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:02.761566Z","caller":"traceutil/trace.go:171","msg":"trace[195139117] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"121.545753ms","start":"2026-05-19T13:45:02.639994Z","end":"2026-05-19T13:45:02.76154Z","steps":["trace[195139117] 'process raft request' (duration: 112.999148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:42.296545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.814235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:499"} {"level":"info","ts":"2026-05-19T13:45:42.296651Z","caller":"traceutil/trace.go:171","msg":"trace[1328916184] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9304; }","duration":"148.98043ms","start":"2026-05-19T13:45:42.147649Z","end":"2026-05-19T13:45:42.296629Z","steps":["trace[1328916184] 'range keys from in-memory index tree' (duration: 148.699993ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:42.300189Z","caller":"traceutil/trace.go:171","msg":"trace[1197592361] linearizableReadLoop","detail":"{readStateIndex:9633; appliedIndex:9632; }","duration":"144.337029ms","start":"2026-05-19T13:45:42.155827Z","end":"2026-05-19T13:45:42.300164Z","steps":["trace[1197592361] 'read index received' (duration: 144.170285ms)","trace[1197592361] 'applied index is now lower than readState.Index' (duration: 165.373µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:45:42.300348Z","caller":"traceutil/trace.go:171","msg":"trace[393034705] transaction","detail":"{read_only:false; response_revision:9305; number_of_response:1; }","duration":"147.369385ms","start":"2026-05-19T13:45:42.15294Z","end":"2026-05-19T13:45:42.300309Z","steps":["trace[393034705] 'process raft request' (duration: 147.061418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:42.300411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.564763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-05-19T13:45:42.300453Z","caller":"traceutil/trace.go:171","msg":"trace[1825572572] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9305; }","duration":"144.622125ms","start":"2026-05-19T13:45:42.155821Z","end":"2026-05-19T13:45:42.300443Z","steps":["trace[1825572572] 'agreement among raft nodes before linearized reading' (duration: 144.460691ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:42.311967Z","caller":"traceutil/trace.go:171","msg":"trace[519364665] transaction","detail":"{read_only:false; response_revision:9306; number_of_response:1; }","duration":"113.419067ms","start":"2026-05-19T13:45:42.198534Z","end":"2026-05-19T13:45:42.311953Z","steps":["trace[519364665] 'process raft request' (duration: 113.310215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:42.3122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.148256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-8xgk5\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-05-19T13:45:42.312288Z","caller":"traceutil/trace.go:171","msg":"trace[2099351402] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-8xgk5; range_end:; response_count:1; response_revision:9307; }","duration":"156.272718ms","start":"2026-05-19T13:45:42.155997Z","end":"2026-05-19T13:45:42.312269Z","steps":["trace[2099351402] 'agreement among raft nodes before linearized reading' (duration: 156.130375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:42.851428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.08985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:45:42.851538Z","caller":"traceutil/trace.go:171","msg":"trace[678353778] transaction","detail":"{read_only:false; response_revision:9315; number_of_response:1; }","duration":"241.538682ms","start":"2026-05-19T13:45:42.609981Z","end":"2026-05-19T13:45:42.85152Z","steps":["trace[678353778] 'process raft request' (duration: 86.739456ms)","trace[678353778] 'compare' (duration: 153.974068ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:45:42.933492Z","caller":"traceutil/trace.go:171","msg":"trace[1424858926] linearizableReadLoop","detail":"{readStateIndex:9644; appliedIndex:9643; }","duration":"106.847023ms","start":"2026-05-19T13:45:42.82662Z","end":"2026-05-19T13:45:42.933467Z","steps":["trace[1424858926] 'read index received' (duration: 106.803372ms)","trace[1424858926] 'applied index is now lower than readState.Index' (duration: 42.531µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:45:42.933571Z","caller":"traceutil/trace.go:171","msg":"trace[1991389333] transaction","detail":"{read_only:false; response_revision:9316; number_of_response:1; }","duration":"189.973381ms","start":"2026-05-19T13:45:42.743567Z","end":"2026-05-19T13:45:42.933541Z","steps":["trace[1991389333] 'process raft request' (duration: 189.773937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:42.933769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.135339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-19T13:45:42.933841Z","caller":"traceutil/trace.go:171","msg":"trace[1525866777] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9316; }","duration":"107.260862ms","start":"2026-05-19T13:45:42.826564Z","end":"2026-05-19T13:45:42.933825Z","steps":["trace[1525866777] 'agreement among raft nodes before linearized reading' (duration: 107.009377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:43.000239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.458053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:45:43.000333Z","caller":"traceutil/trace.go:171","msg":"trace[1196185792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9317; }","duration":"116.622796ms","start":"2026-05-19T13:45:42.883686Z","end":"2026-05-19T13:45:43.000309Z","steps":["trace[1196185792] 'agreement among raft nodes before linearized reading' (duration: 116.487333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:43.794551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.460081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2214716"} {"level":"info","ts":"2026-05-19T13:45:43.794659Z","caller":"traceutil/trace.go:171","msg":"trace[967365922] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:9321; }","duration":"121.631684ms","start":"2026-05-19T13:45:43.673002Z","end":"2026-05-19T13:45:43.794634Z","steps":["trace[967365922] 'range keys from in-memory index tree' (duration: 120.054451ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:43.937561Z","caller":"traceutil/trace.go:171","msg":"trace[887923416] transaction","detail":"{read_only:false; response_revision:9323; number_of_response:1; }","duration":"104.448291ms","start":"2026-05-19T13:45:43.833049Z","end":"2026-05-19T13:45:43.937497Z","steps":["trace[887923416] 'process raft request' (duration: 73.659221ms)","trace[887923416] 'compare' (duration: 30.413192ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:46:19.069986Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T13:46:19.076935Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T13:46:19.077048Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T13:46:47.333042Z","caller":"traceutil/trace.go:171","msg":"trace[29745406] transaction","detail":"{read_only:false; response_revision:9997; number_of_response:1; }","duration":"104.745904ms","start":"2026-05-19T13:46:47.228262Z","end":"2026-05-19T13:46:47.333008Z","steps":["trace[29745406] 'process raft request' (duration: 104.506519ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:47:48.844186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8020} {"level":"info","ts":"2026-05-19T13:47:48.918515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8020,"took":"61.397493ms","hash":1606739436,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":26959872,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-19T13:47:48.918592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1606739436,"revision":8020,"compact-revision":6064} {"level":"info","ts":"2026-05-19T13:50:36.140236Z","caller":"traceutil/trace.go:171","msg":"trace[1869954713] transaction","detail":"{read_only:false; response_revision:11868; number_of_response:1; }","duration":"139.529269ms","start":"2026-05-19T13:50:36.000689Z","end":"2026-05-19T13:50:36.140218Z","steps":["trace[1869954713] 'process raft request' (duration: 139.402836ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:50:36.223741Z","caller":"traceutil/trace.go:171","msg":"trace[1260453644] transaction","detail":"{read_only:false; response_revision:11869; number_of_response:1; }","duration":"100.285958ms","start":"2026-05-19T13:50:36.123427Z","end":"2026-05-19T13:50:36.223713Z","steps":["trace[1260453644] 'process raft request' (duration: 99.255146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:50:53.964148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.711892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:50:53.964512Z","caller":"traceutil/trace.go:171","msg":"trace[742672749] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11968; }","duration":"133.032389ms","start":"2026-05-19T13:50:53.831348Z","end":"2026-05-19T13:50:53.96438Z","steps":["trace[742672749] 'range keys from in-memory index tree' (duration: 132.547147ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:50:54.44397Z","caller":"traceutil/trace.go:171","msg":"trace[21878048] transaction","detail":"{read_only:false; response_revision:11969; number_of_response:1; }","duration":"156.62538ms","start":"2026-05-19T13:50:54.287318Z","end":"2026-05-19T13:50:54.443944Z","steps":["trace[21878048] 'process raft request' (duration: 156.488948ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:50:54.460843Z","caller":"traceutil/trace.go:171","msg":"trace[1385005240] transaction","detail":"{read_only:false; response_revision:11970; number_of_response:1; }","duration":"167.078128ms","start":"2026-05-19T13:50:54.293735Z","end":"2026-05-19T13:50:54.460813Z","steps":["trace[1385005240] 'process raft request' (duration: 166.837393ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:50:54.461162Z","caller":"traceutil/trace.go:171","msg":"trace[328790905] transaction","detail":"{read_only:false; response_revision:11971; number_of_response:1; }","duration":"166.886003ms","start":"2026-05-19T13:50:54.29423Z","end":"2026-05-19T13:50:54.461116Z","steps":["trace[328790905] 'process raft request' (duration: 166.504705ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:52:48.860229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10415} {"level":"info","ts":"2026-05-19T13:52:48.946245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10415,"took":"82.256586ms","hash":1141788069,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":27566080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T13:52:48.946402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1141788069,"revision":10415,"compact-revision":8020} {"level":"info","ts":"2026-05-19T13:56:43.63677Z","caller":"traceutil/trace.go:171","msg":"trace[604448136] transaction","detail":"{read_only:false; response_revision:14363; number_of_response:1; }","duration":"115.971082ms","start":"2026-05-19T13:56:43.520774Z","end":"2026-05-19T13:56:43.636745Z","steps":["trace[604448136] 'process raft request' (duration: 115.719337ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:03.020196Z","caller":"traceutil/trace.go:171","msg":"trace[1725557489] transaction","detail":"{read_only:false; response_revision:14462; number_of_response:1; }","duration":"106.455246ms","start":"2026-05-19T13:57:02.913705Z","end":"2026-05-19T13:57:03.02016Z","steps":["trace[1725557489] 'process raft request' (duration: 106.259942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:57:03.222839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.096723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T13:57:03.222888Z","caller":"traceutil/trace.go:171","msg":"trace[1604071738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14462; }","duration":"109.210046ms","start":"2026-05-19T13:57:03.113668Z","end":"2026-05-19T13:57:03.222878Z","steps":["trace[1604071738] 'range keys from in-memory index tree' (duration: 108.961549ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:10.842334Z","caller":"traceutil/trace.go:171","msg":"trace[2119057056] transaction","detail":"{read_only:false; response_revision:14502; number_of_response:1; }","duration":"127.54943ms","start":"2026-05-19T13:57:10.714763Z","end":"2026-05-19T13:57:10.842312Z","steps":["trace[2119057056] 'process raft request' (duration: 127.400437ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:19.452924Z","caller":"traceutil/trace.go:171","msg":"trace[169659872] linearizableReadLoop","detail":"{readStateIndex:15054; appliedIndex:15053; }","duration":"159.284376ms","start":"2026-05-19T13:57:19.29362Z","end":"2026-05-19T13:57:19.452904Z","steps":["trace[169659872] 'read index received' (duration: 159.117172ms)","trace[169659872] 'applied index is now lower than readState.Index' (duration: 166.483µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:57:19.452952Z","caller":"traceutil/trace.go:171","msg":"trace[68545517] transaction","detail":"{read_only:false; response_revision:14559; number_of_response:1; }","duration":"159.833787ms","start":"2026-05-19T13:57:19.293103Z","end":"2026-05-19T13:57:19.452937Z","steps":["trace[68545517] 'process raft request' (duration: 159.690894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:57:19.45306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.429218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T13:57:19.453085Z","caller":"traceutil/trace.go:171","msg":"trace[295332376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14559; }","duration":"159.481499ms","start":"2026-05-19T13:57:19.293597Z","end":"2026-05-19T13:57:19.453079Z","steps":["trace[295332376] 'agreement among raft nodes before linearized reading' (duration: 159.379797ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:28.742388Z","caller":"traceutil/trace.go:171","msg":"trace[731278534] transaction","detail":"{read_only:false; response_revision:14727; number_of_response:1; }","duration":"296.509135ms","start":"2026-05-19T13:57:28.445839Z","end":"2026-05-19T13:57:28.742348Z","steps":["trace[731278534] 'process raft request' (duration: 296.24962ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:28.742665Z","caller":"traceutil/trace.go:171","msg":"trace[1167693742] linearizableReadLoop","detail":"{readStateIndex:15225; appliedIndex:15225; }","duration":"176.962266ms","start":"2026-05-19T13:57:28.565669Z","end":"2026-05-19T13:57:28.742631Z","steps":["trace[1167693742] 'read index received' (duration: 176.950346ms)","trace[1167693742] 'applied index is now lower than readState.Index' (duration: 9.2µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:57:28.743025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.323574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-05-19T13:57:28.743139Z","caller":"traceutil/trace.go:171","msg":"trace[302923308] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14727; }","duration":"177.479478ms","start":"2026-05-19T13:57:28.565626Z","end":"2026-05-19T13:57:28.743106Z","steps":["trace[302923308] 'agreement among raft nodes before linearized reading' (duration: 177.10598ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:28.952932Z","caller":"traceutil/trace.go:171","msg":"trace[1869580808] transaction","detail":"{read_only:false; response_revision:14728; number_of_response:1; }","duration":"195.447594ms","start":"2026-05-19T13:57:28.757444Z","end":"2026-05-19T13:57:28.952892Z","steps":["trace[1869580808] 'process raft request' (duration: 195.068286ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:48.86703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12738} {"level":"info","ts":"2026-05-19T13:57:48.942473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12738,"took":"72.78516ms","hash":1646718399,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":29528064,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T13:57:48.942551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1646718399,"revision":12738,"compact-revision":10415} {"level":"info","ts":"2026-05-19T13:59:10.324243Z","caller":"traceutil/trace.go:171","msg":"trace[840294330] transaction","detail":"{read_only:false; response_revision:15826; number_of_response:1; }","duration":"139.762314ms","start":"2026-05-19T13:59:10.184456Z","end":"2026-05-19T13:59:10.324219Z","steps":["trace[840294330] 'process raft request' (duration: 116.730256ms)","trace[840294330] 'compare' (duration: 22.924376ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:59:10.32686Z","caller":"traceutil/trace.go:171","msg":"trace[1705822698] transaction","detail":"{read_only:false; response_revision:15827; number_of_response:1; }","duration":"141.811669ms","start":"2026-05-19T13:59:10.18503Z","end":"2026-05-19T13:59:10.326842Z","steps":["trace[1705822698] 'process raft request' (duration: 141.505131ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:02:48.872802Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15063} {"level":"info","ts":"2026-05-19T14:02:48.967051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15063,"took":"90.764876ms","hash":3091337083,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":30715904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T14:02:48.96715Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3091337083,"revision":15063,"compact-revision":12738} {"level":"warn","ts":"2026-05-19T14:04:50.394367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.838096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:04:50.394468Z","caller":"traceutil/trace.go:171","msg":"trace[334808960] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18368; }","duration":"176.987439ms","start":"2026-05-19T14:04:50.21746Z","end":"2026-05-19T14:04:50.394447Z","steps":["trace[334808960] 'range keys from in-memory index tree' (duration: 176.743114ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:04:50.744276Z","caller":"traceutil/trace.go:171","msg":"trace[653504391] linearizableReadLoop","detail":"{readStateIndex:18984; appliedIndex:18983; }","duration":"138.384995ms","start":"2026-05-19T14:04:50.605868Z","end":"2026-05-19T14:04:50.744253Z","steps":["trace[653504391] 'read index received' (duration: 138.207191ms)","trace[653504391] 'applied index is now lower than readState.Index' (duration: 176.644µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:04:50.744391Z","caller":"traceutil/trace.go:171","msg":"trace[507715460] transaction","detail":"{read_only:false; response_revision:18369; number_of_response:1; }","duration":"200.450637ms","start":"2026-05-19T14:04:50.543912Z","end":"2026-05-19T14:04:50.744362Z","steps":["trace[507715460] 'process raft request' (duration: 200.143321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:04:50.744468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.578889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-server-66b745cd9b-b75dm\" ","response":"range_response_count:1 size:13183"} {"level":"info","ts":"2026-05-19T14:04:50.744498Z","caller":"traceutil/trace.go:171","msg":"trace[402178790] range","detail":"{range_begin:/registry/pods/openstack/neutron-server-66b745cd9b-b75dm; range_end:; response_count:1; response_revision:18369; }","duration":"138.653951ms","start":"2026-05-19T14:04:50.605837Z","end":"2026-05-19T14:04:50.74449Z","steps":["trace[402178790] 'agreement among raft nodes before linearized reading' (duration: 138.512238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:04:51.096553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.582684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18823"} {"level":"warn","ts":"2026-05-19T14:04:51.096601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.432906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ls9sx\" ","response":"range_response_count:1 size:20846"} {"level":"info","ts":"2026-05-19T14:04:51.096653Z","caller":"traceutil/trace.go:171","msg":"trace[663512569] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18370; }","duration":"165.721256ms","start":"2026-05-19T14:04:50.93091Z","end":"2026-05-19T14:04:51.096631Z","steps":["trace[663512569] 'range keys from in-memory index tree' (duration: 165.393059ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:04:51.096673Z","caller":"traceutil/trace.go:171","msg":"trace[1742590867] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-ls9sx; range_end:; response_count:1; response_revision:18370; }","duration":"138.543988ms","start":"2026-05-19T14:04:50.95811Z","end":"2026-05-19T14:04:51.096654Z","steps":["trace[1742590867] 'range keys from in-memory index tree' (duration: 138.308893ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:04:51.343357Z","caller":"traceutil/trace.go:171","msg":"trace[845088390] linearizableReadLoop","detail":"{readStateIndex:18986; appliedIndex:18985; }","duration":"149.657969ms","start":"2026-05-19T14:04:51.193684Z","end":"2026-05-19T14:04:51.343342Z","steps":["trace[845088390] 'read index received' (duration: 149.448024ms)","trace[845088390] 'applied index is now lower than readState.Index' (duration: 209.235µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:04:51.343635Z","caller":"traceutil/trace.go:171","msg":"trace[1832071507] transaction","detail":"{read_only:false; response_revision:18371; number_of_response:1; }","duration":"232.273535ms","start":"2026-05-19T14:04:51.111354Z","end":"2026-05-19T14:04:51.343628Z","steps":["trace[1832071507] 'process raft request' (duration: 231.835136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:04:51.343855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.17214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-19T14:04:51.343886Z","caller":"traceutil/trace.go:171","msg":"trace[680265165] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18371; }","duration":"150.238451ms","start":"2026-05-19T14:04:51.19364Z","end":"2026-05-19T14:04:51.343879Z","steps":["trace[680265165] 'agreement among raft nodes before linearized reading' (duration: 150.16069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:04:51.343983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.983071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-19T14:04:51.344035Z","caller":"traceutil/trace.go:171","msg":"trace[1106628125] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18371; }","duration":"147.201965ms","start":"2026-05-19T14:04:51.19681Z","end":"2026-05-19T14:04:51.344012Z","steps":["trace[1106628125] 'agreement among raft nodes before linearized reading' (duration: 146.896559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:04:51.344206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.656108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-19T14:04:51.344304Z","caller":"traceutil/trace.go:171","msg":"trace[1265389974] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18371; }","duration":"125.799892ms","start":"2026-05-19T14:04:51.218481Z","end":"2026-05-19T14:04:51.344281Z","steps":["trace[1265389974] 'agreement among raft nodes before linearized reading' (duration: 125.518586ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:06:28.043483Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:06:28.05029Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T14:06:28.05057Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T14:07:48.879572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17532} {"level":"info","ts":"2026-05-19T14:07:48.972225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17532,"took":"89.097337ms","hash":1732429778,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":33845248,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T14:07:48.972335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1732429778,"revision":17532,"compact-revision":15063} {"level":"info","ts":"2026-05-19T14:10:40.901046Z","caller":"traceutil/trace.go:171","msg":"trace[1735083989] transaction","detail":"{read_only:false; response_revision:21147; number_of_response:1; }","duration":"107.452196ms","start":"2026-05-19T14:10:40.793538Z","end":"2026-05-19T14:10:40.90099Z","steps":["trace[1735083989] 'process raft request' (duration: 107.109847ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:40.90107Z","caller":"traceutil/trace.go:171","msg":"trace[115539731] transaction","detail":"{read_only:false; response_revision:21148; number_of_response:1; }","duration":"107.449435ms","start":"2026-05-19T14:10:40.793575Z","end":"2026-05-19T14:10:40.901025Z","steps":["trace[115539731] 'process raft request' (duration: 107.315562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:41.183417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.481468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:41.183584Z","caller":"traceutil/trace.go:171","msg":"trace[45219192] transaction","detail":"{read_only:false; response_revision:21149; number_of_response:1; }","duration":"228.295599ms","start":"2026-05-19T14:10:40.95526Z","end":"2026-05-19T14:10:41.183555Z","steps":["trace[45219192] 'process raft request' (duration: 100.585416ms)","trace[45219192] 'compare' (duration: 127.272374ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:41.18618Z","caller":"traceutil/trace.go:171","msg":"trace[245450294] transaction","detail":"{read_only:false; response_revision:21150; number_of_response:1; }","duration":"223.247979ms","start":"2026-05-19T14:10:40.962899Z","end":"2026-05-19T14:10:41.186147Z","steps":["trace[245450294] 'process raft request' (duration: 223.069086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:41.18647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.432749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:10:41.186553Z","caller":"traceutil/trace.go:171","msg":"trace[105780006] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:21150; }","duration":"184.623355ms","start":"2026-05-19T14:10:41.001913Z","end":"2026-05-19T14:10:41.186537Z","steps":["trace[105780006] 'agreement among raft nodes before linearized reading' (duration: 184.309737ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:41.186165Z","caller":"traceutil/trace.go:171","msg":"trace[1020312283] linearizableReadLoop","detail":"{readStateIndex:21855; appliedIndex:21853; }","duration":"184.140134ms","start":"2026-05-19T14:10:41.001967Z","end":"2026-05-19T14:10:41.186107Z","steps":["trace[1020312283] 'read index received' (duration: 54.006048ms)","trace[1020312283] 'applied index is now lower than readState.Index' (duration: 130.133065ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:41.627093Z","caller":"traceutil/trace.go:171","msg":"trace[797413998] linearizableReadLoop","detail":"{readStateIndex:21856; appliedIndex:21855; }","duration":"111.650416ms","start":"2026-05-19T14:10:41.515413Z","end":"2026-05-19T14:10:41.627063Z","steps":["trace[797413998] 'read index received' (duration: 111.429821ms)","trace[797413998] 'applied index is now lower than readState.Index' (duration: 219.435µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:41.627349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.902611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:10:41.627404Z","caller":"traceutil/trace.go:171","msg":"trace[1816576100] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:21151; }","duration":"112.014644ms","start":"2026-05-19T14:10:41.515373Z","end":"2026-05-19T14:10:41.627388Z","steps":["trace[1816576100] 'agreement among raft nodes before linearized reading' (duration: 111.815319ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:41.62784Z","caller":"traceutil/trace.go:171","msg":"trace[824505021] transaction","detail":"{read_only:false; response_revision:21151; number_of_response:1; }","duration":"133.947839ms","start":"2026-05-19T14:10:41.493874Z","end":"2026-05-19T14:10:41.627822Z","steps":["trace[824505021] 'process raft request' (duration: 133.040809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:41.930363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.121741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:10:41.930453Z","caller":"traceutil/trace.go:171","msg":"trace[866861629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21151; }","duration":"201.269625ms","start":"2026-05-19T14:10:41.729162Z","end":"2026-05-19T14:10:41.930431Z","steps":["trace[866861629] 'range keys from in-memory index tree' (duration: 200.931357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:41.930444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.665313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T14:10:41.930566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.547278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-19T14:10:41.930358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.765001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T14:10:41.930776Z","caller":"traceutil/trace.go:171","msg":"trace[463724134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21151; }","duration":"194.242342ms","start":"2026-05-19T14:10:41.736512Z","end":"2026-05-19T14:10:41.930754Z","steps":["trace[463724134] 'range keys from in-memory index tree' (duration: 193.551057ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:41.930612Z","caller":"traceutil/trace.go:171","msg":"trace[1307188068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21151; }","duration":"103.845327ms","start":"2026-05-19T14:10:41.826711Z","end":"2026-05-19T14:10:41.930557Z","steps":["trace[1307188068] 'range keys from in-memory index tree' (duration: 103.392707ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:41.930742Z","caller":"traceutil/trace.go:171","msg":"trace[560453416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21151; }","duration":"102.730662ms","start":"2026-05-19T14:10:41.827985Z","end":"2026-05-19T14:10:41.930715Z","steps":["trace[560453416] 'range keys from in-memory index tree' (duration: 102.268882ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:48.886976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19934} {"level":"info","ts":"2026-05-19T14:12:49.003274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19934,"took":"112.718146ms","hash":3981805636,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T14:12:49.003351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3981805636,"revision":19934,"compact-revision":17532} {"level":"warn","ts":"2026-05-19T14:14:34.905979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.301211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:14:34.906322Z","caller":"traceutil/trace.go:171","msg":"trace[1943496316] transaction","detail":"{read_only:false; response_revision:23555; number_of_response:1; }","duration":"294.703828ms","start":"2026-05-19T14:14:34.611586Z","end":"2026-05-19T14:14:34.90629Z","steps":["trace[1943496316] 'process raft request' (duration: 61.008478ms)","trace[1943496316] 'compare' (duration: 233.143038ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:14:34.906371Z","caller":"traceutil/trace.go:171","msg":"trace[513354116] transaction","detail":"{read_only:false; response_revision:23556; number_of_response:1; }","duration":"261.795546ms","start":"2026-05-19T14:14:34.644519Z","end":"2026-05-19T14:14:34.906314Z","steps":["trace[513354116] 'process raft request' (duration: 261.594372ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:17:48.892156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22192} {"level":"info","ts":"2026-05-19T14:17:48.998104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22192,"took":"102.938835ms","hash":829856592,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":43036672,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-19T14:17:48.998178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":829856592,"revision":22192,"compact-revision":19934} {"level":"info","ts":"2026-05-19T14:18:28.047142Z","caller":"traceutil/trace.go:171","msg":"trace[288202359] linearizableReadLoop","detail":"{readStateIndex:26676; appliedIndex:26675; }","duration":"165.032596ms","start":"2026-05-19T14:18:27.882087Z","end":"2026-05-19T14:18:28.04712Z","steps":["trace[288202359] 'read index received' (duration: 164.902573ms)","trace[288202359] 'applied index is now lower than readState.Index' (duration: 128.953µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:28.047241Z","caller":"traceutil/trace.go:171","msg":"trace[1479306332] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"191.351615ms","start":"2026-05-19T14:18:27.85586Z","end":"2026-05-19T14:18:28.047211Z","steps":["trace[1479306332] 'process raft request' (duration: 191.097859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.047698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.169859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:28.047739Z","caller":"traceutil/trace.go:171","msg":"trace[1331663010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25836; }","duration":"165.699311ms","start":"2026-05-19T14:18:27.88203Z","end":"2026-05-19T14:18:28.04773Z","steps":["trace[1331663010] 'agreement among raft nodes before linearized reading' (duration: 165.17601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.361647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.339956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:28.362013Z","caller":"traceutil/trace.go:171","msg":"trace[643207779] linearizableReadLoop","detail":"{readStateIndex:26677; appliedIndex:26676; }","duration":"306.082104ms","start":"2026-05-19T14:18:28.055903Z","end":"2026-05-19T14:18:28.361985Z","steps":["trace[643207779] 'read index received' (duration: 157.33337ms)","trace[643207779] 'applied index is now lower than readState.Index' (duration: 148.746994ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:28.36193Z","caller":"traceutil/trace.go:171","msg":"trace[253218875] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"223.630183ms","start":"2026-05-19T14:18:28.138283Z","end":"2026-05-19T14:18:28.361913Z","steps":["trace[253218875] 'process raft request' (duration: 223.462159ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:28.362061Z","caller":"traceutil/trace.go:171","msg":"trace[365265885] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"365.068819ms","start":"2026-05-19T14:18:27.996948Z","end":"2026-05-19T14:18:28.362017Z","steps":["trace[365265885] 'process raft request' (duration: 216.273973ms)","trace[365265885] 'compare' (duration: 147.940828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:28.362259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.34252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-19T14:18:28.362236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:27.996924Z","time spent":"365.222832ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:28.362357Z","caller":"traceutil/trace.go:171","msg":"trace[749203901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25838; }","duration":"306.462062ms","start":"2026-05-19T14:18:28.055869Z","end":"2026-05-19T14:18:28.362331Z","steps":["trace[749203901] 'agreement among raft nodes before linearized reading' (duration: 306.190917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.362397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:28.05585Z","time spent":"306.537654ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T14:18:28.362405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.343138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18620"} {"level":"warn","ts":"2026-05-19T14:18:28.362427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.475126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T14:18:28.362536Z","caller":"traceutil/trace.go:171","msg":"trace[1804911414] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25838; }","duration":"305.494583ms","start":"2026-05-19T14:18:28.056984Z","end":"2026-05-19T14:18:28.362479Z","steps":["trace[1804911414] 'agreement among raft nodes before linearized reading' (duration: 305.187425ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:28.362536Z","caller":"traceutil/trace.go:171","msg":"trace[756205033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25838; }","duration":"109.616359ms","start":"2026-05-19T14:18:28.252889Z","end":"2026-05-19T14:18:28.362505Z","steps":["trace[756205033] 'agreement among raft nodes before linearized reading' (duration: 109.354113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.362534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.361802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T14:18:28.36264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.733008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:28.362794Z","caller":"traceutil/trace.go:171","msg":"trace[526508246] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:25838; }","duration":"206.950173ms","start":"2026-05-19T14:18:28.155813Z","end":"2026-05-19T14:18:28.362763Z","steps":["trace[526508246] 'agreement among raft nodes before linearized reading' (duration: 206.507712ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:28.362691Z","caller":"traceutil/trace.go:171","msg":"trace[1766155538] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25838; }","duration":"102.595928ms","start":"2026-05-19T14:18:28.26007Z","end":"2026-05-19T14:18:28.362666Z","steps":["trace[1766155538] 'agreement among raft nodes before linearized reading' (duration: 102.140527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.362686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:28.056972Z","time spent":"305.702117ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18644,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-05-19T14:18:28.750134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.252468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:28.750239Z","caller":"traceutil/trace.go:171","msg":"trace[1105848799] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"301.904084ms","start":"2026-05-19T14:18:28.448316Z","end":"2026-05-19T14:18:28.75022Z","steps":["trace[1105848799] 'process raft request' (duration: 150.451001ms)","trace[1105848799] 'compare' (duration: 151.091205ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:28.750296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:28.448295Z","time spent":"301.974636ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:28.820882Z","caller":"traceutil/trace.go:171","msg":"trace[2113964492] linearizableReadLoop","detail":"{readStateIndex:26683; appliedIndex:26681; }","duration":"335.351787ms","start":"2026-05-19T14:18:28.485504Z","end":"2026-05-19T14:18:28.820856Z","steps":["trace[2113964492] 'read index received' (duration: 113.483573ms)","trace[2113964492] 'applied index is now lower than readState.Index' (duration: 221.867034ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:28.820972Z","caller":"traceutil/trace.go:171","msg":"trace[1766575881] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"236.001439ms","start":"2026-05-19T14:18:28.584925Z","end":"2026-05-19T14:18:28.820926Z","steps":["trace[1766575881] 'process raft request' (duration: 235.853056ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:28.821001Z","caller":"traceutil/trace.go:171","msg":"trace[45530838] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"371.611749ms","start":"2026-05-19T14:18:28.449361Z","end":"2026-05-19T14:18:28.820973Z","steps":["trace[45530838] 'process raft request' (duration: 371.270232ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:28.821039Z","caller":"traceutil/trace.go:171","msg":"trace[418456112] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"233.596007ms","start":"2026-05-19T14:18:28.587427Z","end":"2026-05-19T14:18:28.821023Z","steps":["trace[418456112] 'process raft request' (duration: 233.390393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.821234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.937612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"info","ts":"2026-05-19T14:18:28.821916Z","caller":"traceutil/trace.go:171","msg":"trace[145264778] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:25845; }","duration":"276.621737ms","start":"2026-05-19T14:18:28.545248Z","end":"2026-05-19T14:18:28.821869Z","steps":["trace[145264778] 'agreement among raft nodes before linearized reading' (duration: 275.821429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.821754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:28.449345Z","time spent":"372.219372ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:28.821251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.737695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-19T14:18:28.822073Z","caller":"traceutil/trace.go:171","msg":"trace[34825967] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25845; }","duration":"336.609304ms","start":"2026-05-19T14:18:28.485447Z","end":"2026-05-19T14:18:28.822056Z","steps":["trace[34825967] 'agreement among raft nodes before linearized reading' (duration: 335.633033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:28.822117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:28.485426Z","time spent":"336.680126ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"info","ts":"2026-05-19T14:18:29.210577Z","caller":"traceutil/trace.go:171","msg":"trace[1152033462] transaction","detail":"{read_only:false; response_revision:25847; number_of_response:1; }","duration":"130.848007ms","start":"2026-05-19T14:18:29.079701Z","end":"2026-05-19T14:18:29.210549Z","steps":["trace[1152033462] 'process raft request' (duration: 43.240324ms)","trace[1152033462] 'compare' (duration: 87.50121ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:30.249379Z","caller":"traceutil/trace.go:171","msg":"trace[1723015327] linearizableReadLoop","detail":"{readStateIndex:26688; appliedIndex:26687; }","duration":"493.360621ms","start":"2026-05-19T14:18:29.755987Z","end":"2026-05-19T14:18:30.249348Z","steps":["trace[1723015327] 'read index received' (duration: 493.109425ms)","trace[1723015327] 'applied index is now lower than readState.Index' (duration: 249.836µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:30.2495Z","caller":"traceutil/trace.go:171","msg":"trace[1933573795] transaction","detail":"{read_only:false; response_revision:25848; number_of_response:1; }","duration":"496.698942ms","start":"2026-05-19T14:18:29.752775Z","end":"2026-05-19T14:18:30.249474Z","steps":["trace[1933573795] 'process raft request' (duration: 496.335105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:30.249814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:29.752742Z","time spent":"496.812795ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:30.249848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.82427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-19T14:18:30.249886Z","caller":"traceutil/trace.go:171","msg":"trace[1298193356] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25848; }","duration":"493.936583ms","start":"2026-05-19T14:18:29.755939Z","end":"2026-05-19T14:18:30.249876Z","steps":["trace[1298193356] 'agreement among raft nodes before linearized reading' (duration: 493.540794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:30.249922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:29.755914Z","time spent":"494.000974ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18644,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-05-19T14:18:30.26026Z","caller":"traceutil/trace.go:171","msg":"trace[1774263169] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"356.120795ms","start":"2026-05-19T14:18:29.904121Z","end":"2026-05-19T14:18:30.260242Z","steps":["trace[1774263169] 'process raft request' (duration: 356.064914ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:30.260325Z","caller":"traceutil/trace.go:171","msg":"trace[1476099437] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"477.775154ms","start":"2026-05-19T14:18:29.782522Z","end":"2026-05-19T14:18:30.260297Z","steps":["trace[1476099437] 'process raft request' (duration: 477.628511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:30.260397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:29.904095Z","time spent":"356.210836ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:30.260465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:29.782495Z","time spent":"477.897227ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:30.260481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.749081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:30.26052Z","caller":"traceutil/trace.go:171","msg":"trace[283452999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25852; }","duration":"376.830343ms","start":"2026-05-19T14:18:29.883679Z","end":"2026-05-19T14:18:30.260509Z","steps":["trace[283452999] 'agreement among raft nodes before linearized reading' (duration: 376.725151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:30.260612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:29.883655Z","time spent":"376.947635ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T14:18:30.260319Z","caller":"traceutil/trace.go:171","msg":"trace[710667240] transaction","detail":"{read_only:false; response_revision:25849; number_of_response:1; }","duration":"486.484672ms","start":"2026-05-19T14:18:29.773799Z","end":"2026-05-19T14:18:30.260283Z","steps":["trace[710667240] 'process raft request' (duration: 486.241557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:30.260797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:29.773766Z","time spent":"486.935962ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:36.536265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.507064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:36.536411Z","caller":"traceutil/trace.go:171","msg":"trace[868706974] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"267.95238ms","start":"2026-05-19T14:18:36.268432Z","end":"2026-05-19T14:18:36.536384Z","steps":["trace[868706974] 'process raft request' (duration: 122.229241ms)","trace[868706974] 'compare' (duration: 145.32182ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:36.59984Z","caller":"traceutil/trace.go:171","msg":"trace[1828154416] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"321.668781ms","start":"2026-05-19T14:18:36.278144Z","end":"2026-05-19T14:18:36.599813Z","steps":["trace[1828154416] 'process raft request' (duration: 321.470486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:36.600008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.278125Z","time spent":"321.789734ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:36.600038Z","caller":"traceutil/trace.go:171","msg":"trace[1952865074] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"320.73307ms","start":"2026-05-19T14:18:36.279285Z","end":"2026-05-19T14:18:36.600018Z","steps":["trace[1952865074] 'process raft request' (duration: 320.454534ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:36.600054Z","caller":"traceutil/trace.go:171","msg":"trace[1098658860] linearizableReadLoop","detail":"{readStateIndex:26754; appliedIndex:26751; }","duration":"260.08171ms","start":"2026-05-19T14:18:36.339882Z","end":"2026-05-19T14:18:36.599964Z","steps":["trace[1098658860] 'read index received' (duration: 50.879839ms)","trace[1098658860] 'applied index is now lower than readState.Index' (duration: 209.198751ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:36.600132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.279274Z","time spent":"320.808661ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:36.600268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.387167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:36.600339Z","caller":"traceutil/trace.go:171","msg":"trace[62245875] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25912; }","duration":"260.53606ms","start":"2026-05-19T14:18:36.339782Z","end":"2026-05-19T14:18:36.600319Z","steps":["trace[62245875] 'agreement among raft nodes before linearized reading' (duration: 260.387277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:36.879326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.922458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:36.879687Z","caller":"traceutil/trace.go:171","msg":"trace[751064592] transaction","detail":"{read_only:false; response_revision:25914; number_of_response:1; }","duration":"479.674415ms","start":"2026-05-19T14:18:36.399994Z","end":"2026-05-19T14:18:36.879668Z","steps":["trace[751064592] 'process raft request' (duration: 479.43333ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:36.879685Z","caller":"traceutil/trace.go:171","msg":"trace[679485072] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"482.964677ms","start":"2026-05-19T14:18:36.396692Z","end":"2026-05-19T14:18:36.879657Z","steps":["trace[679485072] 'process raft request' (duration: 308.62369ms)","trace[679485072] 'compare' (duration: 173.624181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:36.879767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.399981Z","time spent":"479.751286ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:36.87984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.39667Z","time spent":"483.074349ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:36.879975Z","caller":"traceutil/trace.go:171","msg":"trace[877031735] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"186.664344ms","start":"2026-05-19T14:18:36.693301Z","end":"2026-05-19T14:18:36.879966Z","steps":["trace[877031735] 'process raft request' (duration: 186.221265ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:36.880132Z","caller":"traceutil/trace.go:171","msg":"trace[2104194618] linearizableReadLoop","detail":"{readStateIndex:26756; appliedIndex:26754; }","duration":"279.98358ms","start":"2026-05-19T14:18:36.600124Z","end":"2026-05-19T14:18:36.880108Z","steps":["trace[2104194618] 'read index received' (duration: 105.206794ms)","trace[2104194618] 'applied index is now lower than readState.Index' (duration: 174.735805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:36.88022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.992849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T14:18:36.880277Z","caller":"traceutil/trace.go:171","msg":"trace[337016362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25915; }","duration":"404.05667ms","start":"2026-05-19T14:18:36.476204Z","end":"2026-05-19T14:18:36.88026Z","steps":["trace[337016362] 'agreement among raft nodes before linearized reading' (duration: 403.877037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:36.880306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.712844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-19T14:18:36.880312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.476182Z","time spent":"404.123232ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T14:18:36.880327Z","caller":"traceutil/trace.go:171","msg":"trace[1342928106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25915; }","duration":"404.777686ms","start":"2026-05-19T14:18:36.475544Z","end":"2026-05-19T14:18:36.880321Z","steps":["trace[1342928106] 'agreement among raft nodes before linearized reading' (duration: 404.655483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:36.880345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.475524Z","time spent":"404.817377ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T14:18:36.880346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.939093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:36.880392Z","caller":"traceutil/trace.go:171","msg":"trace[339023639] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25915; }","duration":"469.036345ms","start":"2026-05-19T14:18:36.411346Z","end":"2026-05-19T14:18:36.880383Z","steps":["trace[339023639] 'agreement among raft nodes before linearized reading' (duration: 468.956993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:36.880425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.411311Z","time spent":"469.105956ms","remote":"127.0.0.1:59268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:18:36.88044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.123694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-05-19T14:18:36.880454Z","caller":"traceutil/trace.go:171","msg":"trace[1796169405] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25915; }","duration":"474.136684ms","start":"2026-05-19T14:18:36.406313Z","end":"2026-05-19T14:18:36.88045Z","steps":["trace[1796169405] 'agreement among raft nodes before linearized reading' (duration: 474.086143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:36.880471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.406262Z","time spent":"474.205467ms","remote":"127.0.0.1:38642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11813,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-19T14:18:36.880622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.659488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:18:36.880676Z","caller":"traceutil/trace.go:171","msg":"trace[1897329509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25915; }","duration":"413.74351ms","start":"2026-05-19T14:18:36.466921Z","end":"2026-05-19T14:18:36.880665Z","steps":["trace[1897329509] 'agreement among raft nodes before linearized reading' (duration: 413.616897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:36.880701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.4669Z","time spent":"413.795271ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T14:18:37.253986Z","caller":"traceutil/trace.go:171","msg":"trace[1606292301] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"403.246413ms","start":"2026-05-19T14:18:36.850712Z","end":"2026-05-19T14:18:37.253958Z","steps":["trace[1606292301] 'process raft request' (duration: 370.325853ms)","trace[1606292301] 'compare' (duration: 32.708246ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:37.25413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.850681Z","time spent":"403.374356ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:37.254159Z","caller":"traceutil/trace.go:171","msg":"trace[2095800635] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"400.003484ms","start":"2026-05-19T14:18:36.854139Z","end":"2026-05-19T14:18:37.254142Z","steps":["trace[2095800635] 'process raft request' (duration: 399.777508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.254245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.854105Z","time spent":"400.092425ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:37.254896Z","caller":"traceutil/trace.go:171","msg":"trace[392545156] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"370.314542ms","start":"2026-05-19T14:18:36.884568Z","end":"2026-05-19T14:18:37.254883Z","steps":["trace[392545156] 'process raft request' (duration: 369.522555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.254976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.884529Z","time spent":"370.409844ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:37.254978Z","caller":"traceutil/trace.go:171","msg":"trace[395283530] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"303.282273ms","start":"2026-05-19T14:18:36.951673Z","end":"2026-05-19T14:18:37.254955Z","steps":["trace[395283530] 'process raft request' (duration: 303.226212ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:37.255031Z","caller":"traceutil/trace.go:171","msg":"trace[1131481821] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"368.83364ms","start":"2026-05-19T14:18:36.886187Z","end":"2026-05-19T14:18:37.25502Z","steps":["trace[1131481821] 'process raft request' (duration: 368.679997ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:37.255015Z","caller":"traceutil/trace.go:171","msg":"trace[548515710] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"369.080316ms","start":"2026-05-19T14:18:36.885882Z","end":"2026-05-19T14:18:37.254962Z","steps":["trace[548515710] 'process raft request' (duration: 368.881341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.255111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.886166Z","time spent":"368.894972ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:37.255107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.951639Z","time spent":"303.399026ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:37.255211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.885865Z","time spent":"369.233268ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:37.329376Z","caller":"traceutil/trace.go:171","msg":"trace[732668157] linearizableReadLoop","detail":"{readStateIndex:26759; appliedIndex:26757; }","duration":"449.163425ms","start":"2026-05-19T14:18:36.880175Z","end":"2026-05-19T14:18:37.329339Z","steps":["trace[732668157] 'read index received' (duration: 340.850576ms)","trace[732668157] 'applied index is now lower than readState.Index' (duration: 108.310959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:37.329954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.487987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:18:37.330011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.664087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:37.330054Z","caller":"traceutil/trace.go:171","msg":"trace[1102912550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25921; }","duration":"443.724439ms","start":"2026-05-19T14:18:36.886316Z","end":"2026-05-19T14:18:37.330041Z","steps":["trace[1102912550] 'agreement among raft nodes before linearized reading' (duration: 443.652727ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:37.330051Z","caller":"traceutil/trace.go:171","msg":"trace[1160645735] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25921; }","duration":"632.6115ms","start":"2026-05-19T14:18:36.697414Z","end":"2026-05-19T14:18:37.330026Z","steps":["trace[1160645735] 'agreement among raft nodes before linearized reading' (duration: 632.408375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.330089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.886307Z","time spent":"443.77452ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T14:18:37.330109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.697401Z","time spent":"632.695581ms","remote":"127.0.0.1:38050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:18:37.330136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.161213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-19T14:18:37.330125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.567833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T14:18:37.330182Z","caller":"traceutil/trace.go:171","msg":"trace[1712374701] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25921; }","duration":"286.238525ms","start":"2026-05-19T14:18:37.043928Z","end":"2026-05-19T14:18:37.330167Z","steps":["trace[1712374701] 'agreement among raft nodes before linearized reading' (duration: 286.090452ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:37.330213Z","caller":"traceutil/trace.go:171","msg":"trace[568785265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25921; }","duration":"473.705606ms","start":"2026-05-19T14:18:36.856488Z","end":"2026-05-19T14:18:37.330194Z","steps":["trace[568785265] 'agreement among raft nodes before linearized reading' (duration: 473.435171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.330259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.856473Z","time spent":"473.773217ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T14:18:37.330336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.294236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-19T14:18:37.330343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.822626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-05-19T14:18:37.330371Z","caller":"traceutil/trace.go:171","msg":"trace[2010823937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25921; }","duration":"541.348518ms","start":"2026-05-19T14:18:36.789009Z","end":"2026-05-19T14:18:37.330358Z","steps":["trace[2010823937] 'agreement among raft nodes before linearized reading' (duration: 541.221335ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:37.330379Z","caller":"traceutil/trace.go:171","msg":"trace[705786127] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25921; }","duration":"440.910368ms","start":"2026-05-19T14:18:36.889458Z","end":"2026-05-19T14:18:37.330369Z","steps":["trace[705786127] 'agreement among raft nodes before linearized reading' (duration: 440.800456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.330403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.788993Z","time spent":"541.400949ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T14:18:37.330407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.88943Z","time spent":"440.97145ms","remote":"127.0.0.1:38642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11813,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-19T14:18:37.329966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.283345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:18:37.33054Z","caller":"traceutil/trace.go:171","msg":"trace[765828236] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25921; }","duration":"720.914388ms","start":"2026-05-19T14:18:36.609613Z","end":"2026-05-19T14:18:37.330528Z","steps":["trace[765828236] 'agreement among raft nodes before linearized reading' (duration: 720.277475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.330577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:36.609593Z","time spent":"720.973139ms","remote":"127.0.0.1:35432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-05-19T14:18:37.641045Z","caller":"traceutil/trace.go:171","msg":"trace[908749086] transaction","detail":"{read_only:false; response_revision:25922; number_of_response:1; }","duration":"306.021923ms","start":"2026-05-19T14:18:37.334995Z","end":"2026-05-19T14:18:37.641017Z","steps":["trace[908749086] 'process raft request' (duration: 305.843979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.641232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:37.33497Z","time spent":"306.149326ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:37.658533Z","caller":"traceutil/trace.go:171","msg":"trace[822896999] linearizableReadLoop","detail":"{readStateIndex:26766; appliedIndex:26764; }","duration":"322.233833ms","start":"2026-05-19T14:18:37.336272Z","end":"2026-05-19T14:18:37.658506Z","steps":["trace[822896999] 'read index received' (duration: 304.588962ms)","trace[822896999] 'applied index is now lower than readState.Index' (duration: 17.643631ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:37.65868Z","caller":"traceutil/trace.go:171","msg":"trace[1823046741] transaction","detail":"{read_only:false; response_revision:25923; number_of_response:1; }","duration":"323.378227ms","start":"2026-05-19T14:18:37.335287Z","end":"2026-05-19T14:18:37.658666Z","steps":["trace[1823046741] 'process raft request' (duration: 321.465485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.658801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:37.33523Z","time spent":"323.488789ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:37.658914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.560885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:37.658991Z","caller":"traceutil/trace.go:171","msg":"trace[2418580] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25923; }","duration":"157.678447ms","start":"2026-05-19T14:18:37.501295Z","end":"2026-05-19T14:18:37.658973Z","steps":["trace[2418580] 'agreement among raft nodes before linearized reading' (duration: 157.557854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.659328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.05252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-19T14:18:37.659387Z","caller":"traceutil/trace.go:171","msg":"trace[722298841] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25923; }","duration":"323.129462ms","start":"2026-05-19T14:18:37.336241Z","end":"2026-05-19T14:18:37.65937Z","steps":["trace[722298841] 'agreement among raft nodes before linearized reading' (duration: 322.947308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:37.659435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:37.336229Z","time spent":"323.193423ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18644,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-19T14:18:38.047018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.688246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:38.047423Z","caller":"traceutil/trace.go:171","msg":"trace[7686933] linearizableReadLoop","detail":"{readStateIndex:26767; appliedIndex:26766; }","duration":"163.452512ms","start":"2026-05-19T14:18:37.883947Z","end":"2026-05-19T14:18:38.0474Z","steps":["trace[7686933] 'read index received' (duration: 12.347837ms)","trace[7686933] 'applied index is now lower than readState.Index' (duration: 151.102755ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:38.047499Z","caller":"traceutil/trace.go:171","msg":"trace[1789453122] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"279.182403ms","start":"2026-05-19T14:18:37.768277Z","end":"2026-05-19T14:18:38.04746Z","steps":["trace[1789453122] 'process raft request' (duration: 127.964405ms)","trace[1789453122] 'compare' (duration: 150.114533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:38.047559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.609785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:38.047619Z","caller":"traceutil/trace.go:171","msg":"trace[1109316623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25924; }","duration":"163.701608ms","start":"2026-05-19T14:18:37.883901Z","end":"2026-05-19T14:18:38.047603Z","steps":["trace[1109316623] 'agreement among raft nodes before linearized reading' (duration: 163.603996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:38.04763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.135781ms","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:6"} {"level":"info","ts":"2026-05-19T14:18:38.047682Z","caller":"traceutil/trace.go:171","msg":"trace[2102844836] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25924; }","duration":"117.229353ms","start":"2026-05-19T14:18:37.930438Z","end":"2026-05-19T14:18:38.047667Z","steps":["trace[2102844836] 'agreement among raft nodes before linearized reading' (duration: 117.134051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:38.485415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.944331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:38.485506Z","caller":"traceutil/trace.go:171","msg":"trace[81393846] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25924; }","duration":"107.088403ms","start":"2026-05-19T14:18:38.378394Z","end":"2026-05-19T14:18:38.485483Z","steps":["trace[81393846] 'count revisions from in-memory index tree' (duration: 106.832839ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:38.672877Z","caller":"traceutil/trace.go:171","msg":"trace[1566013611] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"130.222383ms","start":"2026-05-19T14:18:38.542624Z","end":"2026-05-19T14:18:38.672846Z","steps":["trace[1566013611] 'process raft request' (duration: 130.019699ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:39.223497Z","caller":"traceutil/trace.go:171","msg":"trace[122074733] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"619.107828ms","start":"2026-05-19T14:18:38.604366Z","end":"2026-05-19T14:18:39.223474Z","steps":["trace[122074733] 'process raft request' (duration: 618.937553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:39.223614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:38.604342Z","time spent":"619.216851ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:39.223709Z","caller":"traceutil/trace.go:171","msg":"trace[1700658993] linearizableReadLoop","detail":"{readStateIndex:26770; appliedIndex:26768; }","duration":"339.974558ms","start":"2026-05-19T14:18:38.88372Z","end":"2026-05-19T14:18:39.223694Z","steps":["trace[1700658993] 'read index received' (duration: 339.61754ms)","trace[1700658993] 'applied index is now lower than readState.Index' (duration: 356.358µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:39.223825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.10882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:39.223838Z","caller":"traceutil/trace.go:171","msg":"trace[24448681] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"617.897762ms","start":"2026-05-19T14:18:38.605932Z","end":"2026-05-19T14:18:39.22383Z","steps":["trace[24448681] 'process raft request' (duration: 617.508324ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:39.223861Z","caller":"traceutil/trace.go:171","msg":"trace[1144451930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25927; }","duration":"340.180692ms","start":"2026-05-19T14:18:38.883671Z","end":"2026-05-19T14:18:39.223852Z","steps":["trace[1144451930] 'agreement among raft nodes before linearized reading' (duration: 340.08528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:39.22389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:38.883647Z","time spent":"340.236303ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T14:18:39.223903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:38.605893Z","time spent":"617.983184ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:39.231656Z","caller":"traceutil/trace.go:171","msg":"trace[1568539128] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"344.985934ms","start":"2026-05-19T14:18:38.886652Z","end":"2026-05-19T14:18:39.231638Z","steps":["trace[1568539128] 'process raft request' (duration: 344.864542ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:39.231839Z","caller":"traceutil/trace.go:171","msg":"trace[1346698208] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"342.631524ms","start":"2026-05-19T14:18:38.889153Z","end":"2026-05-19T14:18:39.231784Z","steps":["trace[1346698208] 'process raft request' (duration: 342.45872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:39.231873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:38.886616Z","time spent":"345.093327ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:39.231944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:38.889134Z","time spent":"342.755036ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:39.373136Z","caller":"traceutil/trace.go:171","msg":"trace[1943689992] linearizableReadLoop","detail":"{readStateIndex:26773; appliedIndex:26772; }","duration":"115.192389ms","start":"2026-05-19T14:18:39.257921Z","end":"2026-05-19T14:18:39.373113Z","steps":["trace[1943689992] 'read index received' (duration: 46.072795ms)","trace[1943689992] 'applied index is now lower than readState.Index' (duration: 69.118864ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:39.373233Z","caller":"traceutil/trace.go:171","msg":"trace[182196226] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"149.554702ms","start":"2026-05-19T14:18:39.223638Z","end":"2026-05-19T14:18:39.373193Z","steps":["trace[182196226] 'process raft request' (duration: 80.381776ms)","trace[182196226] 'compare' (duration: 68.996522ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:39.37334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.399813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:18:39.373379Z","caller":"traceutil/trace.go:171","msg":"trace[1851189961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25930; }","duration":"115.481925ms","start":"2026-05-19T14:18:39.257887Z","end":"2026-05-19T14:18:39.373369Z","steps":["trace[1851189961] 'agreement among raft nodes before linearized reading' (duration: 115.333442ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:39.374695Z","caller":"traceutil/trace.go:171","msg":"trace[1085722742] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"114.922865ms","start":"2026-05-19T14:18:39.259739Z","end":"2026-05-19T14:18:39.374662Z","steps":["trace[1085722742] 'process raft request' (duration: 114.833772ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:39.374728Z","caller":"traceutil/trace.go:171","msg":"trace[983742516] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"114.010044ms","start":"2026-05-19T14:18:39.260696Z","end":"2026-05-19T14:18:39.374706Z","steps":["trace[983742516] 'process raft request' (duration: 113.90551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:39.374765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.777783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T14:18:39.374832Z","caller":"traceutil/trace.go:171","msg":"trace[193001818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25933; }","duration":"116.865346ms","start":"2026-05-19T14:18:39.257952Z","end":"2026-05-19T14:18:39.374817Z","steps":["trace[193001818] 'agreement among raft nodes before linearized reading' (duration: 116.676822ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:39.374835Z","caller":"traceutil/trace.go:171","msg":"trace[736132656] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"116.027537ms","start":"2026-05-19T14:18:39.258757Z","end":"2026-05-19T14:18:39.374785Z","steps":["trace[736132656] 'process raft request' (duration: 115.7054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:39.37494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.309434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:18:39.375016Z","caller":"traceutil/trace.go:171","msg":"trace[1413082453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25933; }","duration":"116.405156ms","start":"2026-05-19T14:18:39.258591Z","end":"2026-05-19T14:18:39.374996Z","steps":["trace[1413082453] 'agreement among raft nodes before linearized reading' (duration: 116.211192ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:48.888844Z","caller":"traceutil/trace.go:171","msg":"trace[1389958063] transaction","detail":"{read_only:false; response_revision:26002; number_of_response:1; }","duration":"184.269733ms","start":"2026-05-19T14:18:48.704553Z","end":"2026-05-19T14:18:48.888823Z","steps":["trace[1389958063] 'process raft request' (duration: 183.911295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:49.591321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.660691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:49.591474Z","caller":"traceutil/trace.go:171","msg":"trace[1240535747] linearizableReadLoop","detail":"{readStateIndex:26848; appliedIndex:26847; }","duration":"389.536078ms","start":"2026-05-19T14:18:49.201923Z","end":"2026-05-19T14:18:49.591459Z","steps":["trace[1240535747] 'read index received' (duration: 39.761µs)","trace[1240535747] 'applied index is now lower than readState.Index' (duration: 389.495477ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:49.59146Z","caller":"traceutil/trace.go:171","msg":"trace[2118013863] transaction","detail":"{read_only:false; response_revision:26003; number_of_response:1; }","duration":"683.447278ms","start":"2026-05-19T14:18:48.907977Z","end":"2026-05-19T14:18:49.591424Z","steps":["trace[2118013863] 'process raft request' (duration: 286.622283ms)","trace[2118013863] 'compare' (duration: 396.369954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:49.591611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:48.907965Z","time spent":"683.568721ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:49.591654Z","caller":"traceutil/trace.go:171","msg":"trace[1152327546] transaction","detail":"{read_only:false; response_revision:26004; number_of_response:1; }","duration":"332.416963ms","start":"2026-05-19T14:18:49.259219Z","end":"2026-05-19T14:18:49.591636Z","steps":["trace[1152327546] 'process raft request' (duration: 332.190949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:49.591911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.259195Z","time spent":"332.567207ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:49.591914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.957336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:49.593414Z","caller":"traceutil/trace.go:171","msg":"trace[2034244116] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26004; }","duration":"391.499549ms","start":"2026-05-19T14:18:49.201885Z","end":"2026-05-19T14:18:49.593384Z","steps":["trace[2034244116] 'agreement among raft nodes before linearized reading' (duration: 389.64468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:49.593821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.201862Z","time spent":"391.931248ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:18:50.239617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.690593ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:50.239748Z","caller":"traceutil/trace.go:171","msg":"trace[1125232111] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26004; }","duration":"353.828436ms","start":"2026-05-19T14:18:49.885898Z","end":"2026-05-19T14:18:50.239726Z","steps":["trace[1125232111] 'range keys from in-memory index tree' (duration: 353.626691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.240396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.439099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:50.240885Z","caller":"traceutil/trace.go:171","msg":"trace[391622903] linearizableReadLoop","detail":"{readStateIndex:26856; appliedIndex:26849; }","duration":"649.351411ms","start":"2026-05-19T14:18:49.591509Z","end":"2026-05-19T14:18:50.24086Z","steps":["trace[391622903] 'read index received' (duration: 114.34533ms)","trace[391622903] 'applied index is now lower than readState.Index' (duration: 535.004811ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:50.240932Z","caller":"traceutil/trace.go:171","msg":"trace[1098043478] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"826.614252ms","start":"2026-05-19T14:18:49.414296Z","end":"2026-05-19T14:18:50.24091Z","steps":["trace[1098043478] 'process raft request' (duration: 826.404667ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:50.241021Z","caller":"traceutil/trace.go:171","msg":"trace[1313989157] transaction","detail":"{read_only:false; response_revision:26010; number_of_response:1; }","duration":"826.046599ms","start":"2026-05-19T14:18:49.414959Z","end":"2026-05-19T14:18:50.241005Z","steps":["trace[1313989157] 'process raft request' (duration: 825.792643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.414263Z","time spent":"826.717654ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:50.241103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.414943Z","time spent":"826.112511ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:50.241139Z","caller":"traceutil/trace.go:171","msg":"trace[164019711] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"976.737075ms","start":"2026-05-19T14:18:49.264373Z","end":"2026-05-19T14:18:50.241111Z","steps":["trace[164019711] 'process raft request' (duration: 976.128482ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:50.24116Z","caller":"traceutil/trace.go:171","msg":"trace[49023315] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"812.19562ms","start":"2026-05-19T14:18:49.428938Z","end":"2026-05-19T14:18:50.241133Z","steps":["trace[49023315] 'process raft request' (duration: 811.862892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.488252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:18:50.24125Z","caller":"traceutil/trace.go:171","msg":"trace[1548429845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26011; }","duration":"778.567684ms","start":"2026-05-19T14:18:49.462672Z","end":"2026-05-19T14:18:50.241239Z","steps":["trace[1548429845] 'agreement among raft nodes before linearized reading' (duration: 778.42541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.512524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T14:18:50.241242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.544553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-19T14:18:50.241261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.528691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-19T14:18:50.241279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.462655Z","time spent":"778.618165ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T14:18:50.241276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.26434Z","time spent":"976.858809ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:50.241277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.428917Z","time spent":"812.295793ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:50.241296Z","caller":"traceutil/trace.go:171","msg":"trace[1919515829] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:26011; }","duration":"257.596946ms","start":"2026-05-19T14:18:49.983683Z","end":"2026-05-19T14:18:50.24128Z","steps":["trace[1919515829] 'agreement among raft nodes before linearized reading' (duration: 257.430732ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:50.241302Z","caller":"traceutil/trace.go:171","msg":"trace[1293048096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26011; }","duration":"778.633106ms","start":"2026-05-19T14:18:49.462653Z","end":"2026-05-19T14:18:50.241286Z","steps":["trace[1293048096] 'agreement among raft nodes before linearized reading' (duration: 778.42239ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:50.241311Z","caller":"traceutil/trace.go:171","msg":"trace[2133524687] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26011; }","duration":"646.592413ms","start":"2026-05-19T14:18:49.594702Z","end":"2026-05-19T14:18:50.241294Z","steps":["trace[2133524687] 'agreement among raft nodes before linearized reading' (duration: 646.393088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.462642Z","time spent":"778.692547ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T14:18:50.241347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.594693Z","time spent":"646.646204ms","remote":"127.0.0.1:49916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-05-19T14:18:50.241363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.2227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:18:50.241375Z","caller":"traceutil/trace.go:171","msg":"trace[2021795289] transaction","detail":"{read_only:false; response_revision:26007; number_of_response:1; }","duration":"975.665323ms","start":"2026-05-19T14:18:49.265693Z","end":"2026-05-19T14:18:50.241358Z","steps":["trace[2021795289] 'process raft request' (duration: 974.902696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.604279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-05-19T14:18:50.241411Z","caller":"traceutil/trace.go:171","msg":"trace[1122254656] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"978.423443ms","start":"2026-05-19T14:18:49.262973Z","end":"2026-05-19T14:18:50.241397Z","steps":["trace[1122254656] 'process raft request' (duration: 442.89041ms)","trace[1122254656] 'compare' (duration: 533.860706ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:50.241416Z","caller":"traceutil/trace.go:171","msg":"trace[248858362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26011; }","duration":"780.352473ms","start":"2026-05-19T14:18:49.461049Z","end":"2026-05-19T14:18:50.241401Z","steps":["trace[248858362] 'agreement among raft nodes before linearized reading' (duration: 780.153399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:50.24144Z","caller":"traceutil/trace.go:171","msg":"trace[135753497] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26011; }","duration":"656.69367ms","start":"2026-05-19T14:18:49.584724Z","end":"2026-05-19T14:18:50.241418Z","steps":["trace[135753497] 'agreement among raft nodes before linearized reading' (duration: 656.379693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.461029Z","time spent":"780.419624ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T14:18:50.241467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.262945Z","time spent":"978.490525ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:50.241465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.265675Z","time spent":"975.739715ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:50.24148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.58471Z","time spent":"656.761613ms","remote":"127.0.0.1:43850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418328,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-05-19T14:18:50.241484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.937285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:18:50.241482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.091078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:50.241513Z","caller":"traceutil/trace.go:171","msg":"trace[41794983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26011; }","duration":"358.013116ms","start":"2026-05-19T14:18:49.883493Z","end":"2026-05-19T14:18:50.241506Z","steps":["trace[41794983] 'agreement among raft nodes before linearized reading' (duration: 357.970075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.24153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.883468Z","time spent":"358.058218ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T14:18:50.241534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.055428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T14:18:50.241577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.998769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T14:18:50.241586Z","caller":"traceutil/trace.go:171","msg":"trace[137908867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26011; }","duration":"526.126059ms","start":"2026-05-19T14:18:49.715444Z","end":"2026-05-19T14:18:50.24157Z","steps":["trace[137908867] 'agreement among raft nodes before linearized reading' (duration: 525.951355ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:50.241523Z","caller":"traceutil/trace.go:171","msg":"trace[665063811] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:26011; }","duration":"134.20517ms","start":"2026-05-19T14:18:50.107305Z","end":"2026-05-19T14:18:50.24151Z","steps":["trace[665063811] 'agreement among raft nodes before linearized reading' (duration: 134.146729ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:50.241613Z","caller":"traceutil/trace.go:171","msg":"trace[1795217464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26011; }","duration":"540.06207ms","start":"2026-05-19T14:18:49.701541Z","end":"2026-05-19T14:18:50.241603Z","steps":["trace[1795217464] 'agreement among raft nodes before linearized reading' (duration: 539.965558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.715431Z","time spent":"526.191171ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T14:18:50.241642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.701523Z","time spent":"540.113271ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T14:18:50.241581Z","caller":"traceutil/trace.go:171","msg":"trace[1335602697] transaction","detail":"{read_only:false; response_revision:26008; number_of_response:1; }","duration":"854.223889ms","start":"2026-05-19T14:18:49.387342Z","end":"2026-05-19T14:18:50.241566Z","steps":["trace[1335602697] 'process raft request' (duration: 853.301828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.241828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:49.387312Z","time spent":"854.451174ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:50.556165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.007828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:50.556245Z","caller":"traceutil/trace.go:171","msg":"trace[1473787097] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26016; }","duration":"185.181682ms","start":"2026-05-19T14:18:50.371044Z","end":"2026-05-19T14:18:50.556225Z","steps":["trace[1473787097] 'count revisions from in-memory index tree' (duration: 184.842395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:50.556501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.009783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-19T14:18:50.556582Z","caller":"traceutil/trace.go:171","msg":"trace[633231394] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26016; }","duration":"101.132096ms","start":"2026-05-19T14:18:50.455422Z","end":"2026-05-19T14:18:50.556554Z","steps":["trace[633231394] 'range keys from in-memory index tree' (duration: 100.816438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.236145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710538640123375553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T14:18:51.237185Z","caller":"traceutil/trace.go:171","msg":"trace[848247027] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"628.93779ms","start":"2026-05-19T14:18:50.608219Z","end":"2026-05-19T14:18:51.237157Z","steps":["trace[848247027] 'process raft request' (duration: 628.629774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.237334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:50.608192Z","time spent":"629.068193ms","remote":"127.0.0.1:43850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:51.75843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.284558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:51.758606Z","caller":"traceutil/trace.go:171","msg":"trace[1331458009] linearizableReadLoop","detail":"{readStateIndex:26864; appliedIndex:26861; }","duration":"1.023017836s","start":"2026-05-19T14:18:50.735571Z","end":"2026-05-19T14:18:51.758589Z","steps":["trace[1331458009] 'read index received' (duration: 501.262781ms)","trace[1331458009] 'applied index is now lower than readState.Index' (duration: 521.754125ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:51.758675Z","caller":"traceutil/trace.go:171","msg":"trace[69754815] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"865.37457ms","start":"2026-05-19T14:18:50.893292Z","end":"2026-05-19T14:18:51.758667Z","steps":["trace[69754815] 'process raft request' (duration: 865.212856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.758728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:50.893266Z","time spent":"865.433311ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:18:51.758823Z","caller":"traceutil/trace.go:171","msg":"trace[840820755] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"1.083433321s","start":"2026-05-19T14:18:50.675335Z","end":"2026-05-19T14:18:51.758768Z","steps":["trace[840820755] 'process raft request' (duration: 807.719213ms)","trace[840820755] 'compare' (duration: 274.723387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:51.75892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023342653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:51.758959Z","caller":"traceutil/trace.go:171","msg":"trace[1927728001] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:26019; }","duration":"1.023424354s","start":"2026-05-19T14:18:50.735522Z","end":"2026-05-19T14:18:51.758946Z","steps":["trace[1927728001] 'agreement among raft nodes before linearized reading' (duration: 1.023359163s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.75899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:50.735503Z","time spent":"1.023479125s","remote":"127.0.0.1:44684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:18:51.759121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:50.67531Z","time spent":"1.083580185s","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:51.781426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.521093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:18:51.781471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.902296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:51.781512Z","caller":"traceutil/trace.go:171","msg":"trace[1189322446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26020; }","duration":"897.653526ms","start":"2026-05-19T14:18:50.883839Z","end":"2026-05-19T14:18:51.781492Z","steps":["trace[1189322446] 'agreement among raft nodes before linearized reading' (duration: 897.505373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.781555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:50.883816Z","time spent":"897.730209ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T14:18:51.781555Z","caller":"traceutil/trace.go:171","msg":"trace[1299341927] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26020; }","duration":"130.052839ms","start":"2026-05-19T14:18:51.651484Z","end":"2026-05-19T14:18:51.781537Z","steps":["trace[1299341927] 'agreement among raft nodes before linearized reading' (duration: 129.903406ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:51.781563Z","caller":"traceutil/trace.go:171","msg":"trace[715753525] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"184.558678ms","start":"2026-05-19T14:18:51.596969Z","end":"2026-05-19T14:18:51.781528Z","steps":["trace[715753525] 'process raft request' (duration: 184.233811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.781595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.646255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:18:51.781683Z","caller":"traceutil/trace.go:171","msg":"trace[912117060] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26020; }","duration":"144.811798ms","start":"2026-05-19T14:18:51.636851Z","end":"2026-05-19T14:18:51.781663Z","steps":["trace[912117060] 'agreement among raft nodes before linearized reading' (duration: 144.611814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.781831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.679181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-19T14:18:51.782069Z","caller":"traceutil/trace.go:171","msg":"trace[1272074345] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26020; }","duration":"284.948308ms","start":"2026-05-19T14:18:51.497092Z","end":"2026-05-19T14:18:51.78204Z","steps":["trace[1272074345] 'agreement among raft nodes before linearized reading' (duration: 284.273973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.781781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.509817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-conductor-0.18b0fd3fda95c1b6\" ","response":"range_response_count:1 size:869"} {"level":"info","ts":"2026-05-19T14:18:51.782511Z","caller":"traceutil/trace.go:171","msg":"trace[4185044] range","detail":"{range_begin:/registry/events/openstack/magnum-conductor-0.18b0fd3fda95c1b6; range_end:; response_count:1; response_revision:26020; }","duration":"366.278995ms","start":"2026-05-19T14:18:51.416197Z","end":"2026-05-19T14:18:51.782476Z","steps":["trace[4185044] 'agreement among raft nodes before linearized reading' (duration: 365.408656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.78257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:51.416189Z","time spent":"366.364157ms","remote":"127.0.0.1:49888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":893,"request content":"key:\"/registry/events/openstack/magnum-conductor-0.18b0fd3fda95c1b6\" "} {"level":"warn","ts":"2026-05-19T14:18:51.781879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.698812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-conductor-0\" ","response":"range_response_count:1 size:10440"} {"level":"info","ts":"2026-05-19T14:18:51.782695Z","caller":"traceutil/trace.go:171","msg":"trace[848132081] range","detail":"{range_begin:/registry/pods/openstack/magnum-conductor-0; range_end:; response_count:1; response_revision:26020; }","duration":"367.540852ms","start":"2026-05-19T14:18:51.415135Z","end":"2026-05-19T14:18:51.782676Z","steps":["trace[848132081] 'agreement among raft nodes before linearized reading' (duration: 366.637941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.782768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:51.415113Z","time spent":"367.632814ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":10464,"request content":"key:\"/registry/pods/openstack/magnum-conductor-0\" "} {"level":"warn","ts":"2026-05-19T14:18:51.781951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.227413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:18:51.782908Z","caller":"traceutil/trace.go:171","msg":"trace[119247579] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26020; }","duration":"882.207813ms","start":"2026-05-19T14:18:50.900682Z","end":"2026-05-19T14:18:51.78289Z","steps":["trace[119247579] 'agreement among raft nodes before linearized reading' (duration: 881.219852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.782959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:50.900667Z","time spent":"882.277405ms","remote":"127.0.0.1:38120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":32,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:18:51.781964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.378509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:51.783193Z","caller":"traceutil/trace.go:171","msg":"trace[695765672] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26020; }","duration":"356.648946ms","start":"2026-05-19T14:18:51.426524Z","end":"2026-05-19T14:18:51.783173Z","steps":["trace[695765672] 'agreement among raft nodes before linearized reading' (duration: 355.361539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.783251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:51.426514Z","time spent":"356.721507ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-05-19T14:18:51.990975Z","caller":"traceutil/trace.go:171","msg":"trace[1450356912] linearizableReadLoop","detail":"{readStateIndex:26866; appliedIndex:26865; }","duration":"205.173784ms","start":"2026-05-19T14:18:51.785782Z","end":"2026-05-19T14:18:51.990956Z","steps":["trace[1450356912] 'read index received' (duration: 204.51665ms)","trace[1450356912] 'applied index is now lower than readState.Index' (duration: 630.783µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:51.991029Z","caller":"traceutil/trace.go:171","msg":"trace[1897143490] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"205.345907ms","start":"2026-05-19T14:18:51.78566Z","end":"2026-05-19T14:18:51.991006Z","steps":["trace[1897143490] 'process raft request' (duration: 204.611591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.991162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.368538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bc936b69-3eff-4f05-8cab-e7dc08026d60\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-05-19T14:18:51.991192Z","caller":"traceutil/trace.go:171","msg":"trace[130042385] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bc936b69-3eff-4f05-8cab-e7dc08026d60; range_end:; response_count:1; response_revision:26021; }","duration":"205.425229ms","start":"2026-05-19T14:18:51.785758Z","end":"2026-05-19T14:18:51.991184Z","steps":["trace[130042385] 'agreement among raft nodes before linearized reading' (duration: 205.290556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:51.996238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.616618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:51.996255Z","caller":"traceutil/trace.go:171","msg":"trace[1651390395] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"201.76851ms","start":"2026-05-19T14:18:51.794456Z","end":"2026-05-19T14:18:51.996225Z","steps":["trace[1651390395] 'process raft request' (duration: 201.550115ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:51.996298Z","caller":"traceutil/trace.go:171","msg":"trace[620640860] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26022; }","duration":"208.699449ms","start":"2026-05-19T14:18:51.787584Z","end":"2026-05-19T14:18:51.996283Z","steps":["trace[620640860] 'agreement among raft nodes before linearized reading' (duration: 208.572127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:56.393746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.900352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-19T14:18:56.393808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.76156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:18:56.39381Z","caller":"traceutil/trace.go:171","msg":"trace[353575899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26121; }","duration":"107.990005ms","start":"2026-05-19T14:18:56.285805Z","end":"2026-05-19T14:18:56.393795Z","steps":["trace[353575899] 'range keys from in-memory index tree' (duration: 107.77958ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:56.393834Z","caller":"traceutil/trace.go:171","msg":"trace[261884811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26121; }","duration":"107.80023ms","start":"2026-05-19T14:18:56.286026Z","end":"2026-05-19T14:18:56.393826Z","steps":["trace[261884811] 'range keys from in-memory index tree' (duration: 107.148685ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:56.39396Z","caller":"traceutil/trace.go:171","msg":"trace[1257832649] linearizableReadLoop","detail":"{readStateIndex:26968; appliedIndex:26967; }","duration":"107.207267ms","start":"2026-05-19T14:18:56.286747Z","end":"2026-05-19T14:18:56.393955Z","steps":["trace[1257832649] 'read index received' (duration: 50.141633ms)","trace[1257832649] 'applied index is now lower than readState.Index' (duration: 57.065084ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:56.394221Z","caller":"traceutil/trace.go:171","msg":"trace[576111433] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"108.060875ms","start":"2026-05-19T14:18:56.286144Z","end":"2026-05-19T14:18:56.394205Z","steps":["trace[576111433] 'process raft request' (duration: 50.733046ms)","trace[576111433] 'compare' (duration: 56.779338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:56.394288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.515704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T14:18:56.394345Z","caller":"traceutil/trace.go:171","msg":"trace[606421182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26122; }","duration":"107.598095ms","start":"2026-05-19T14:18:56.286733Z","end":"2026-05-19T14:18:56.394331Z","steps":["trace[606421182] 'agreement among raft nodes before linearized reading' (duration: 107.390991ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:56.637477Z","caller":"traceutil/trace.go:171","msg":"trace[797369232] linearizableReadLoop","detail":"{readStateIndex:26969; appliedIndex:26968; }","duration":"243.484752ms","start":"2026-05-19T14:18:56.393971Z","end":"2026-05-19T14:18:56.637456Z","steps":["trace[797369232] 'read index received' (duration: 149.261425ms)","trace[797369232] 'applied index is now lower than readState.Index' (duration: 94.222227ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:56.637614Z","caller":"traceutil/trace.go:171","msg":"trace[729076105] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"308.295902ms","start":"2026-05-19T14:18:56.329279Z","end":"2026-05-19T14:18:56.637575Z","steps":["trace[729076105] 'process raft request' (duration: 213.942143ms)","trace[729076105] 'compare' (duration: 94.012022ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:56.637704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.577147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T14:18:56.637742Z","caller":"traceutil/trace.go:171","msg":"trace[1922296733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26123; }","duration":"345.640289ms","start":"2026-05-19T14:18:56.292092Z","end":"2026-05-19T14:18:56.637732Z","steps":["trace[1922296733] 'agreement among raft nodes before linearized reading' (duration: 345.485085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:56.637796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:56.292083Z","time spent":"345.70497ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T14:18:56.637745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:56.32926Z","time spent":"308.423194ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T14:18:56.654902Z","caller":"traceutil/trace.go:171","msg":"trace[708772935] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"254.703434ms","start":"2026-05-19T14:18:56.400178Z","end":"2026-05-19T14:18:56.654882Z","steps":["trace[708772935] 'process raft request' (duration: 254.646773ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:56.654912Z","caller":"traceutil/trace.go:171","msg":"trace[1075843506] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"256.132374ms","start":"2026-05-19T14:18:56.398751Z","end":"2026-05-19T14:18:56.654883Z","steps":["trace[1075843506] 'process raft request' (duration: 255.95749ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:56.65521Z","caller":"traceutil/trace.go:171","msg":"trace[1634112249] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"196.21067ms","start":"2026-05-19T14:18:56.45898Z","end":"2026-05-19T14:18:56.655191Z","steps":["trace[1634112249] 'process raft request' (duration: 196.027066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:56.655327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.429406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-19T14:18:56.655352Z","caller":"traceutil/trace.go:171","msg":"trace[1910848387] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"249.120013ms","start":"2026-05-19T14:18:56.406218Z","end":"2026-05-19T14:18:56.655338Z","steps":["trace[1910848387] 'process raft request' (duration: 248.633523ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:56.655382Z","caller":"traceutil/trace.go:171","msg":"trace[1224749072] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26127; }","duration":"160.556779ms","start":"2026-05-19T14:18:56.494809Z","end":"2026-05-19T14:18:56.655366Z","steps":["trace[1224749072] 'agreement among raft nodes before linearized reading' (duration: 160.383646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:56.655565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.403908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-19T14:18:56.655596Z","caller":"traceutil/trace.go:171","msg":"trace[1108123812] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26127; }","duration":"223.474799ms","start":"2026-05-19T14:18:56.432111Z","end":"2026-05-19T14:18:56.655585Z","steps":["trace[1108123812] 'agreement among raft nodes before linearized reading' (duration: 223.307526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:56.655864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.952908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2214717"} {"level":"info","ts":"2026-05-19T14:18:56.655929Z","caller":"traceutil/trace.go:171","msg":"trace[822700481] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26127; }","duration":"248.055429ms","start":"2026-05-19T14:18:56.407861Z","end":"2026-05-19T14:18:56.655917Z","steps":["trace[822700481] 'agreement among raft nodes before linearized reading' (duration: 247.266532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.005823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.089997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:57.005918Z","caller":"traceutil/trace.go:171","msg":"trace[1484241444] linearizableReadLoop","detail":"{readStateIndex:26974; appliedIndex:26973; }","duration":"347.425638ms","start":"2026-05-19T14:18:56.658478Z","end":"2026-05-19T14:18:57.005903Z","steps":["trace[1484241444] 'read index received' (duration: 95.133716ms)","trace[1484241444] 'applied index is now lower than readState.Index' (duration: 252.290802ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:57.00619Z","caller":"traceutil/trace.go:171","msg":"trace[1568087938] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"362.703357ms","start":"2026-05-19T14:18:56.64347Z","end":"2026-05-19T14:18:57.006173Z","steps":["trace[1568087938] 'process raft request' (duration: 110.18571ms)","trace[1568087938] 'compare' (duration: 251.501605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:57.006283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:56.643454Z","time spent":"362.792799ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:18:57.006534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.052972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-97667bd7-c02b-45cc-82a9-4562cd13b043\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-05-19T14:18:57.006574Z","caller":"traceutil/trace.go:171","msg":"trace[1150010108] range","detail":"{range_begin:/registry/persistentvolumes/pvc-97667bd7-c02b-45cc-82a9-4562cd13b043; range_end:; response_count:1; response_revision:26128; }","duration":"348.117903ms","start":"2026-05-19T14:18:56.658443Z","end":"2026-05-19T14:18:57.006561Z","steps":["trace[1150010108] 'agreement among raft nodes before linearized reading' (duration: 348.00565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.006602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:56.658429Z","time spent":"348.166485ms","remote":"127.0.0.1:49954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1347,"request content":"key:\"/registry/persistentvolumes/pvc-97667bd7-c02b-45cc-82a9-4562cd13b043\" "} {"level":"warn","ts":"2026-05-19T14:18:57.006806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.659973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-19T14:18:57.006834Z","caller":"traceutil/trace.go:171","msg":"trace[1344435300] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26128; }","duration":"341.712534ms","start":"2026-05-19T14:18:56.665113Z","end":"2026-05-19T14:18:57.006825Z","steps":["trace[1344435300] 'agreement among raft nodes before linearized reading' (duration: 341.629743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.006859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:56.665095Z","time spent":"341.758305ms","remote":"127.0.0.1:49916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-05-19T14:18:57.00838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.533475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:57.008432Z","caller":"traceutil/trace.go:171","msg":"trace[1230004453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26128; }","duration":"121.605667ms","start":"2026-05-19T14:18:56.886812Z","end":"2026-05-19T14:18:57.008418Z","steps":["trace[1230004453] 'agreement among raft nodes before linearized reading' (duration: 121.527416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.008702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.086184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9db08706-78dc-4181-88f9-230a7e8a0f18\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-19T14:18:57.008742Z","caller":"traceutil/trace.go:171","msg":"trace[1777482275] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9db08706-78dc-4181-88f9-230a7e8a0f18; range_end:; response_count:1; response_revision:26128; }","duration":"132.145805ms","start":"2026-05-19T14:18:56.876583Z","end":"2026-05-19T14:18:57.008729Z","steps":["trace[1777482275] 'agreement among raft nodes before linearized reading' (duration: 132.032682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.008951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.880634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b0fd4761c9fe9d\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-19T14:18:57.008979Z","caller":"traceutil/trace.go:171","msg":"trace[1391099695] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b0fd4761c9fe9d; range_end:; response_count:1; response_revision:26128; }","duration":"147.946566ms","start":"2026-05-19T14:18:56.861023Z","end":"2026-05-19T14:18:57.00897Z","steps":["trace[1391099695] 'agreement among raft nodes before linearized reading' (duration: 147.858684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.00915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.691033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:57.009177Z","caller":"traceutil/trace.go:171","msg":"trace[1839974711] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26128; }","duration":"279.743474ms","start":"2026-05-19T14:18:56.729425Z","end":"2026-05-19T14:18:57.009169Z","steps":["trace[1839974711] 'agreement among raft nodes before linearized reading' (duration: 279.694413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.009345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.394782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-05-19T14:18:57.009371Z","caller":"traceutil/trace.go:171","msg":"trace[1663374221] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:26128; }","duration":"319.457483ms","start":"2026-05-19T14:18:56.689905Z","end":"2026-05-19T14:18:57.009363Z","steps":["trace[1663374221] 'agreement among raft nodes before linearized reading' (duration: 319.375962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.009399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:56.689887Z","time spent":"319.506344ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1846,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-19T14:18:57.306174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.75581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:18:57.306386Z","caller":"traceutil/trace.go:171","msg":"trace[2062272777] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"294.697749ms","start":"2026-05-19T14:18:57.011657Z","end":"2026-05-19T14:18:57.306355Z","steps":["trace[2062272777] 'process raft request' (duration: 141.685732ms)","trace[2062272777] 'compare' (duration: 152.27759ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:18:57.306417Z","caller":"traceutil/trace.go:171","msg":"trace[1402544518] linearizableReadLoop","detail":"{readStateIndex:26975; appliedIndex:26974; }","duration":"293.803989ms","start":"2026-05-19T14:18:57.012595Z","end":"2026-05-19T14:18:57.306399Z","steps":["trace[1402544518] 'read index received' (duration: 140.760421ms)","trace[1402544518] 'applied index is now lower than readState.Index' (duration: 153.041808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:57.432297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.032083ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-19T14:18:57.432397Z","caller":"traceutil/trace.go:171","msg":"trace[344604357] linearizableReadLoop","detail":"{readStateIndex:26976; appliedIndex:26975; }","duration":"125.769658ms","start":"2026-05-19T14:18:57.306608Z","end":"2026-05-19T14:18:57.432378Z","steps":["trace[344604357] 'read index received' (duration: 72.562µs)","trace[344604357] 'applied index is now lower than readState.Index' (duration: 125.695796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:57.43243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.816902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:18:57.432484Z","caller":"traceutil/trace.go:171","msg":"trace[1322642144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26129; }","duration":"419.899314ms","start":"2026-05-19T14:18:57.012569Z","end":"2026-05-19T14:18:57.432468Z","steps":["trace[1322642144] 'agreement among raft nodes before linearized reading' (duration: 293.871251ms)","trace[1322642144] 'range keys from in-memory index tree' (duration: 125.946911ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:18:57.432518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:57.01255Z","time spent":"419.961825ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T14:18:57.432571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.241099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-05-19T14:18:57.432628Z","caller":"traceutil/trace.go:171","msg":"trace[46063996] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:26129; }","duration":"400.331301ms","start":"2026-05-19T14:18:57.032278Z","end":"2026-05-19T14:18:57.432609Z","steps":["trace[46063996] 'agreement among raft nodes before linearized reading' (duration: 400.155847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:18:57.432668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:18:57.032261Z","time spent":"400.397812ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3590,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-05-19T14:19:04.530999Z","caller":"traceutil/trace.go:171","msg":"trace[651213439] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"232.339441ms","start":"2026-05-19T14:19:04.298629Z","end":"2026-05-19T14:19:04.530968Z","steps":["trace[651213439] 'process raft request' (duration: 232.162166ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:04.735335Z","caller":"traceutil/trace.go:171","msg":"trace[1284054914] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"435.772277ms","start":"2026-05-19T14:19:04.299538Z","end":"2026-05-19T14:19:04.73531Z","steps":["trace[1284054914] 'process raft request' (duration: 435.690945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:04.735491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.29951Z","time spent":"435.90191ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:19:04.735562Z","caller":"traceutil/trace.go:171","msg":"trace[914696641] linearizableReadLoop","detail":"{readStateIndex:27061; appliedIndex:27055; }","duration":"311.111134ms","start":"2026-05-19T14:19:04.424443Z","end":"2026-05-19T14:19:04.735554Z","steps":["trace[914696641] 'read index received' (duration: 106.483412ms)","trace[914696641] 'applied index is now lower than readState.Index' (duration: 204.626672ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:04.73535Z","caller":"traceutil/trace.go:171","msg":"trace[1436638364] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"435.835708ms","start":"2026-05-19T14:19:04.299485Z","end":"2026-05-19T14:19:04.735321Z","steps":["trace[1436638364] 'process raft request' (duration: 435.587103ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:04.735561Z","caller":"traceutil/trace.go:171","msg":"trace[795165996] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"433.452066ms","start":"2026-05-19T14:19:04.30208Z","end":"2026-05-19T14:19:04.735533Z","steps":["trace[795165996] 'process raft request' (duration: 433.19113ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:04.735623Z","caller":"traceutil/trace.go:171","msg":"trace[1863458487] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"425.781961ms","start":"2026-05-19T14:19:04.309836Z","end":"2026-05-19T14:19:04.735618Z","steps":["trace[1863458487] 'process raft request' (duration: 425.685519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:04.735647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.299453Z","time spent":"436.164225ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:19:04.735637Z","caller":"traceutil/trace.go:171","msg":"trace[27019803] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"427.764794ms","start":"2026-05-19T14:19:04.307835Z","end":"2026-05-19T14:19:04.7356Z","steps":["trace[27019803] 'process raft request' (duration: 427.60148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:04.735698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.309821Z","time spent":"425.819432ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:19:04.735701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.301986Z","time spent":"433.642731ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:19:04.735754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.937678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T14:19:04.735813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.307765Z","time spent":"427.942858ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:19:04.735833Z","caller":"traceutil/trace.go:171","msg":"trace[225070302] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26213; }","duration":"310.042471ms","start":"2026-05-19T14:19:04.425777Z","end":"2026-05-19T14:19:04.73582Z","steps":["trace[225070302] 'agreement among raft nodes before linearized reading' (duration: 309.925628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:04.73586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.425771Z","time spent":"310.084101ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:19:04.735871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.40156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T14:19:04.735946Z","caller":"traceutil/trace.go:171","msg":"trace[722425950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26213; }","duration":"311.490492ms","start":"2026-05-19T14:19:04.424435Z","end":"2026-05-19T14:19:04.735926Z","steps":["trace[722425950] 'agreement among raft nodes before linearized reading' (duration: 311.341069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:04.735986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.424385Z","time spent":"311.592114ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T14:19:04.73608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.860052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-19T14:19:04.736145Z","caller":"traceutil/trace.go:171","msg":"trace[2008323561] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26213; }","duration":"138.962333ms","start":"2026-05-19T14:19:04.597165Z","end":"2026-05-19T14:19:04.736127Z","steps":["trace[2008323561] 'agreement among raft nodes before linearized reading' (duration: 138.720208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:05.162834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.52547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:19:05.162917Z","caller":"traceutil/trace.go:171","msg":"trace[1961867534] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"423.42201ms","start":"2026-05-19T14:19:04.73948Z","end":"2026-05-19T14:19:05.162902Z","steps":["trace[1961867534] 'process raft request' (duration: 280.747716ms)","trace[1961867534] 'compare' (duration: 142.161953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:19:05.16296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.739456Z","time spent":"423.48335ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:19:05.176723Z","caller":"traceutil/trace.go:171","msg":"trace[10114053] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"436.671626ms","start":"2026-05-19T14:19:04.740033Z","end":"2026-05-19T14:19:05.176705Z","steps":["trace[10114053] 'process raft request' (duration: 435.711646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:05.176933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.740018Z","time spent":"436.763628ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:19:05.176995Z","caller":"traceutil/trace.go:171","msg":"trace[1524571468] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"436.754917ms","start":"2026-05-19T14:19:04.740205Z","end":"2026-05-19T14:19:05.17696Z","steps":["trace[1524571468] 'process raft request' (duration: 436.452261ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:05.177053Z","caller":"traceutil/trace.go:171","msg":"trace[1099201680] linearizableReadLoop","detail":"{readStateIndex:27066; appliedIndex:27062; }","duration":"136.365537ms","start":"2026-05-19T14:19:05.040664Z","end":"2026-05-19T14:19:05.177029Z","steps":["trace[1099201680] 'read index received' (duration: 135.071349ms)","trace[1099201680] 'applied index is now lower than readState.Index' (duration: 1.292938ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:05.177102Z","caller":"traceutil/trace.go:171","msg":"trace[212505752] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"436.843549ms","start":"2026-05-19T14:19:04.740249Z","end":"2026-05-19T14:19:05.177092Z","steps":["trace[212505752] 'process raft request' (duration: 436.611214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:05.17721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.740183Z","time spent":"436.86894ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:19:05.17722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.740238Z","time spent":"436.888991ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:19:05.177219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.54271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T14:19:05.177237Z","caller":"traceutil/trace.go:171","msg":"trace[922205576] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"436.820499ms","start":"2026-05-19T14:19:04.740332Z","end":"2026-05-19T14:19:05.177153Z","steps":["trace[922205576] 'process raft request' (duration: 436.636386ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:05.177277Z","caller":"traceutil/trace.go:171","msg":"trace[1441396687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26218; }","duration":"136.629053ms","start":"2026-05-19T14:19:05.04063Z","end":"2026-05-19T14:19:05.177259Z","steps":["trace[1441396687] 'agreement among raft nodes before linearized reading' (duration: 136.531081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:05.177306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:04.740306Z","time spent":"436.964952ms","remote":"127.0.0.1:50082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:19:05.177365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.635226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:19:05.177408Z","caller":"traceutil/trace.go:171","msg":"trace[474151609] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26218; }","duration":"101.720058ms","start":"2026-05-19T14:19:05.075673Z","end":"2026-05-19T14:19:05.177393Z","steps":["trace[474151609] 'agreement among raft nodes before linearized reading' (duration: 101.649766ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:23.759155Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a60.wal"} {"level":"info","ts":"2026-05-19T14:19:24.016839Z","caller":"traceutil/trace.go:171","msg":"trace[754131907] linearizableReadLoop","detail":"{readStateIndex:27232; appliedIndex:27231; }","duration":"132.502693ms","start":"2026-05-19T14:19:23.884298Z","end":"2026-05-19T14:19:24.016801Z","steps":["trace[754131907] 'read index received' (duration: 132.35431ms)","trace[754131907] 'applied index is now lower than readState.Index' (duration: 147.463µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:24.016865Z","caller":"traceutil/trace.go:171","msg":"trace[1981866280] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"150.735827ms","start":"2026-05-19T14:19:23.866098Z","end":"2026-05-19T14:19:24.016834Z","steps":["trace[1981866280] 'process raft request' (duration: 150.559993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:24.016963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.647727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:19:24.016998Z","caller":"traceutil/trace.go:171","msg":"trace[1422728916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26380; }","duration":"132.726138ms","start":"2026-05-19T14:19:23.884259Z","end":"2026-05-19T14:19:24.016985Z","steps":["trace[1422728916] 'agreement among raft nodes before linearized reading' (duration: 132.652616ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:24.089965Z","caller":"traceutil/trace.go:171","msg":"trace[860414815] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"183.620167ms","start":"2026-05-19T14:19:23.906319Z","end":"2026-05-19T14:19:24.089939Z","steps":["trace[860414815] 'process raft request' (duration: 183.062885ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:25.055331Z","caller":"traceutil/trace.go:171","msg":"trace[1276702680] transaction","detail":"{read_only:false; response_revision:26384; number_of_response:1; }","duration":"249.532282ms","start":"2026-05-19T14:19:24.805781Z","end":"2026-05-19T14:19:25.055313Z","steps":["trace[1276702680] 'process raft request' (duration: 249.107073ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:25.055452Z","caller":"traceutil/trace.go:171","msg":"trace[1875109817] transaction","detail":"{read_only:false; response_revision:26385; number_of_response:1; }","duration":"247.035497ms","start":"2026-05-19T14:19:24.80841Z","end":"2026-05-19T14:19:25.055445Z","steps":["trace[1875109817] 'process raft request' (duration: 246.586358ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:25.055539Z","caller":"traceutil/trace.go:171","msg":"trace[911593190] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"245.612847ms","start":"2026-05-19T14:19:24.80992Z","end":"2026-05-19T14:19:25.055533Z","steps":["trace[911593190] 'process raft request' (duration: 245.105527ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:25.055641Z","caller":"traceutil/trace.go:171","msg":"trace[206360859] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"240.436585ms","start":"2026-05-19T14:19:24.815198Z","end":"2026-05-19T14:19:25.055634Z","steps":["trace[206360859] 'process raft request' (duration: 239.851623ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:26.790393Z","caller":"traceutil/trace.go:171","msg":"trace[712445976] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"139.960645ms","start":"2026-05-19T14:19:26.650413Z","end":"2026-05-19T14:19:26.790373Z","steps":["trace[712445976] 'process raft request' (duration: 139.817802ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.228138Z","caller":"traceutil/trace.go:171","msg":"trace[1787648910] transaction","detail":"{read_only:false; response_revision:26401; number_of_response:1; }","duration":"166.840685ms","start":"2026-05-19T14:19:27.061271Z","end":"2026-05-19T14:19:27.228112Z","steps":["trace[1787648910] 'process raft request' (duration: 166.762524ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.228312Z","caller":"traceutil/trace.go:171","msg":"trace[142023497] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"167.139762ms","start":"2026-05-19T14:19:27.061163Z","end":"2026-05-19T14:19:27.228303Z","steps":["trace[142023497] 'process raft request' (duration: 166.756894ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.228607Z","caller":"traceutil/trace.go:171","msg":"trace[1799187895] transaction","detail":"{read_only:false; response_revision:26402; number_of_response:1; }","duration":"167.248504ms","start":"2026-05-19T14:19:27.061336Z","end":"2026-05-19T14:19:27.228584Z","steps":["trace[1799187895] 'process raft request' (duration: 166.736564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:27.33039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.970803ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-19T14:19:27.331394Z","caller":"traceutil/trace.go:171","msg":"trace[29536419] transaction","detail":"{read_only:false; response_revision:26403; number_of_response:1; }","duration":"190.404914ms","start":"2026-05-19T14:19:27.14095Z","end":"2026-05-19T14:19:27.331355Z","steps":["trace[29536419] 'process raft request' (duration: 189.543385ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.515508Z","caller":"traceutil/trace.go:171","msg":"trace[1419036103] linearizableReadLoop","detail":"{readStateIndex:27261; appliedIndex:27256; }","duration":"165.931726ms","start":"2026-05-19T14:19:27.349553Z","end":"2026-05-19T14:19:27.515484Z","steps":["trace[1419036103] 'read index received' (duration: 61.620402ms)","trace[1419036103] 'applied index is now lower than readState.Index' (duration: 104.310284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:19:27.515735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.15612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-7775b87f79-c74t2.18b0fd3fa02872f6\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-05-19T14:19:27.515779Z","caller":"traceutil/trace.go:171","msg":"trace[630210405] range","detail":"{range_begin:/registry/events/openstack/magnum-api-7775b87f79-c74t2.18b0fd3fa02872f6; range_end:; response_count:1; response_revision:26408; }","duration":"166.236462ms","start":"2026-05-19T14:19:27.34953Z","end":"2026-05-19T14:19:27.515767Z","steps":["trace[630210405] 'agreement among raft nodes before linearized reading' (duration: 166.056838ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.516164Z","caller":"traceutil/trace.go:171","msg":"trace[828475062] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"179.94878ms","start":"2026-05-19T14:19:27.336199Z","end":"2026-05-19T14:19:27.516148Z","steps":["trace[828475062] 'process raft request' (duration: 178.992888ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.516302Z","caller":"traceutil/trace.go:171","msg":"trace[992845827] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"179.332686ms","start":"2026-05-19T14:19:27.33696Z","end":"2026-05-19T14:19:27.516293Z","steps":["trace[992845827] 'process raft request' (duration: 178.402665ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.51642Z","caller":"traceutil/trace.go:171","msg":"trace[1050832526] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"178.754763ms","start":"2026-05-19T14:19:27.337655Z","end":"2026-05-19T14:19:27.516409Z","steps":["trace[1050832526] 'process raft request' (duration: 177.747521ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.516555Z","caller":"traceutil/trace.go:171","msg":"trace[1428618460] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"177.918715ms","start":"2026-05-19T14:19:27.338628Z","end":"2026-05-19T14:19:27.516546Z","steps":["trace[1428618460] 'process raft request' (duration: 176.820281ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:27.97679Z","caller":"traceutil/trace.go:171","msg":"trace[1328546277] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"160.708192ms","start":"2026-05-19T14:19:27.816053Z","end":"2026-05-19T14:19:27.976761Z","steps":["trace[1328546277] 'process raft request' (duration: 128.417154ms)","trace[1328546277] 'compare' (duration: 32.177706ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:29.298883Z","caller":"traceutil/trace.go:171","msg":"trace[736512228] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"160.477587ms","start":"2026-05-19T14:19:29.138381Z","end":"2026-05-19T14:19:29.298859Z","steps":["trace[736512228] 'process raft request' (duration: 160.333144ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.36433Z","caller":"traceutil/trace.go:171","msg":"trace[1646143718] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"130.768035ms","start":"2026-05-19T14:19:29.23354Z","end":"2026-05-19T14:19:29.364308Z","steps":["trace[1646143718] 'process raft request' (duration: 126.80745ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.364371Z","caller":"traceutil/trace.go:171","msg":"trace[1649711064] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"130.797736ms","start":"2026-05-19T14:19:29.233554Z","end":"2026-05-19T14:19:29.364352Z","steps":["trace[1649711064] 'process raft request' (duration: 130.559481ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.364639Z","caller":"traceutil/trace.go:171","msg":"trace[355638760] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"130.920438ms","start":"2026-05-19T14:19:29.233713Z","end":"2026-05-19T14:19:29.364633Z","steps":["trace[355638760] 'process raft request' (duration: 130.433298ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.46073Z","caller":"traceutil/trace.go:171","msg":"trace[1307461563] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"101.421943ms","start":"2026-05-19T14:19:29.359287Z","end":"2026-05-19T14:19:29.460709Z","steps":["trace[1307461563] 'process raft request' (duration: 100.747088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.590467Z","caller":"traceutil/trace.go:171","msg":"trace[11263694] linearizableReadLoop","detail":"{readStateIndex:27274; appliedIndex:27273; }","duration":"120.30748ms","start":"2026-05-19T14:19:29.470134Z","end":"2026-05-19T14:19:29.590442Z","steps":["trace[11263694] 'read index received' (duration: 50.974671ms)","trace[11263694] 'applied index is now lower than readState.Index' (duration: 69.331659ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:29.590825Z","caller":"traceutil/trace.go:171","msg":"trace[187249804] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"125.744108ms","start":"2026-05-19T14:19:29.465061Z","end":"2026-05-19T14:19:29.590805Z","steps":["trace[187249804] 'process raft request' (duration: 56.101002ms)","trace[187249804] 'compare' (duration: 69.154905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:19:29.591156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.004114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-19T14:19:29.591214Z","caller":"traceutil/trace.go:171","msg":"trace[12864600] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26421; }","duration":"121.108417ms","start":"2026-05-19T14:19:29.47009Z","end":"2026-05-19T14:19:29.591199Z","steps":["trace[12864600] 'agreement among raft nodes before linearized reading' (duration: 120.905513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:29.837427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.522208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:19:29.837537Z","caller":"traceutil/trace.go:171","msg":"trace[2058802402] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"243.163645ms","start":"2026-05-19T14:19:29.59435Z","end":"2026-05-19T14:19:29.837514Z","steps":["trace[2058802402] 'process raft request' (duration: 101.472603ms)","trace[2058802402] 'compare' (duration: 141.364595ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:29.868924Z","caller":"traceutil/trace.go:171","msg":"trace[42407189] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"273.742835ms","start":"2026-05-19T14:19:29.595165Z","end":"2026-05-19T14:19:29.868908Z","steps":["trace[42407189] 'process raft request' (duration: 273.634252ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.869073Z","caller":"traceutil/trace.go:171","msg":"trace[475520958] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"273.885118ms","start":"2026-05-19T14:19:29.595181Z","end":"2026-05-19T14:19:29.869066Z","steps":["trace[475520958] 'process raft request' (duration: 273.696164ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.869164Z","caller":"traceutil/trace.go:171","msg":"trace[311118189] linearizableReadLoop","detail":"{readStateIndex:27278; appliedIndex:27274; }","duration":"183.75061ms","start":"2026-05-19T14:19:29.685407Z","end":"2026-05-19T14:19:29.869157Z","steps":["trace[311118189] 'read index received' (duration: 10.429696ms)","trace[311118189] 'applied index is now lower than readState.Index' (duration: 173.320324ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:19:29.869225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.808001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:19:29.869251Z","caller":"traceutil/trace.go:171","msg":"trace[620855113] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26425; }","duration":"183.877433ms","start":"2026-05-19T14:19:29.685365Z","end":"2026-05-19T14:19:29.869242Z","steps":["trace[620855113] 'agreement among raft nodes before linearized reading' (duration: 183.819182ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:29.869371Z","caller":"traceutil/trace.go:171","msg":"trace[755635975] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"274.061412ms","start":"2026-05-19T14:19:29.595301Z","end":"2026-05-19T14:19:29.869363Z","steps":["trace[755635975] 'process raft request' (duration: 273.740805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:33.361481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.721548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:19:33.361554Z","caller":"traceutil/trace.go:171","msg":"trace[1283953477] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26450; }","duration":"132.84318ms","start":"2026-05-19T14:19:33.228695Z","end":"2026-05-19T14:19:33.361538Z","steps":["trace[1283953477] 'count revisions from in-memory index tree' (duration: 132.600485ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:34.118993Z","caller":"traceutil/trace.go:171","msg":"trace[671843755] linearizableReadLoop","detail":"{readStateIndex:27315; appliedIndex:27314; }","duration":"181.784387ms","start":"2026-05-19T14:19:33.937181Z","end":"2026-05-19T14:19:34.118965Z","steps":["trace[671843755] 'read index received' (duration: 143.759496ms)","trace[671843755] 'applied index is now lower than readState.Index' (duration: 38.023561ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:34.119013Z","caller":"traceutil/trace.go:171","msg":"trace[545863929] transaction","detail":"{read_only:false; response_revision:26460; number_of_response:1; }","duration":"184.298333ms","start":"2026-05-19T14:19:33.934682Z","end":"2026-05-19T14:19:34.118981Z","steps":["trace[545863929] 'process raft request' (duration: 146.288001ms)","trace[545863929] 'compare' (duration: 37.836827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:19:34.119134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.926571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:19:34.119178Z","caller":"traceutil/trace.go:171","msg":"trace[267863160] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26460; }","duration":"181.994463ms","start":"2026-05-19T14:19:33.93717Z","end":"2026-05-19T14:19:34.119164Z","steps":["trace[267863160] 'agreement among raft nodes before linearized reading' (duration: 181.88962ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:34.161326Z","caller":"traceutil/trace.go:171","msg":"trace[1365245787] transaction","detail":"{read_only:false; response_revision:26461; number_of_response:1; }","duration":"221.584588ms","start":"2026-05-19T14:19:33.939716Z","end":"2026-05-19T14:19:34.161301Z","steps":["trace[1365245787] 'process raft request' (duration: 221.381534ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:34.16187Z","caller":"traceutil/trace.go:171","msg":"trace[107178082] transaction","detail":"{read_only:false; response_revision:26462; number_of_response:1; }","duration":"104.921148ms","start":"2026-05-19T14:19:34.056885Z","end":"2026-05-19T14:19:34.161806Z","steps":["trace[107178082] 'process raft request' (duration: 104.356006ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:35.288881Z","caller":"traceutil/trace.go:171","msg":"trace[2106311820] linearizableReadLoop","detail":"{readStateIndex:27320; appliedIndex:27319; }","duration":"184.484066ms","start":"2026-05-19T14:19:35.104373Z","end":"2026-05-19T14:19:35.288857Z","steps":["trace[2106311820] 'read index received' (duration: 184.334923ms)","trace[2106311820] 'applied index is now lower than readState.Index' (duration: 148.063µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:35.289125Z","caller":"traceutil/trace.go:171","msg":"trace[1350993126] transaction","detail":"{read_only:false; response_revision:26465; number_of_response:1; }","duration":"192.660063ms","start":"2026-05-19T14:19:35.096434Z","end":"2026-05-19T14:19:35.289094Z","steps":["trace[1350993126] 'process raft request' (duration: 192.273865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:35.289286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.892925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:19:35.289359Z","caller":"traceutil/trace.go:171","msg":"trace[1822628723] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26465; }","duration":"185.016178ms","start":"2026-05-19T14:19:35.104331Z","end":"2026-05-19T14:19:35.289347Z","steps":["trace[1822628723] 'agreement among raft nodes before linearized reading' (duration: 184.790663ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:46.06861Z","caller":"traceutil/trace.go:171","msg":"trace[398613612] linearizableReadLoop","detail":"{readStateIndex:27417; appliedIndex:27416; }","duration":"116.466746ms","start":"2026-05-19T14:19:45.952124Z","end":"2026-05-19T14:19:46.068591Z","steps":["trace[398613612] 'read index received' (duration: 116.295452ms)","trace[398613612] 'applied index is now lower than readState.Index' (duration: 170.564µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:46.068699Z","caller":"traceutil/trace.go:171","msg":"trace[651372057] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"147.078628ms","start":"2026-05-19T14:19:45.921613Z","end":"2026-05-19T14:19:46.068692Z","steps":["trace[651372057] 'process raft request' (duration: 146.865253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:46.068875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.733861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T14:19:46.068903Z","caller":"traceutil/trace.go:171","msg":"trace[1594625505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26560; }","duration":"116.794363ms","start":"2026-05-19T14:19:45.9521Z","end":"2026-05-19T14:19:46.068895Z","steps":["trace[1594625505] 'agreement among raft nodes before linearized reading' (duration: 116.694611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:46.069098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.838544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-19T14:19:46.069177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.889595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:19:46.069171Z","caller":"traceutil/trace.go:171","msg":"trace[456350063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26560; }","duration":"116.989377ms","start":"2026-05-19T14:19:45.952164Z","end":"2026-05-19T14:19:46.069153Z","steps":["trace[456350063] 'agreement among raft nodes before linearized reading' (duration: 116.816543ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:46.069205Z","caller":"traceutil/trace.go:171","msg":"trace[1337656132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26560; }","duration":"116.961217ms","start":"2026-05-19T14:19:45.952238Z","end":"2026-05-19T14:19:46.069199Z","steps":["trace[1337656132] 'agreement among raft nodes before linearized reading' (duration: 116.896235ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:46.351404Z","caller":"traceutil/trace.go:171","msg":"trace[2140405652] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"153.129019ms","start":"2026-05-19T14:19:46.198249Z","end":"2026-05-19T14:19:46.351378Z","steps":["trace[2140405652] 'process raft request' (duration: 72.071698ms)","trace[2140405652] 'compare' (duration: 80.601372ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:46.777904Z","caller":"traceutil/trace.go:171","msg":"trace[223687416] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"190.010526ms","start":"2026-05-19T14:19:46.587863Z","end":"2026-05-19T14:19:46.777873Z","steps":["trace[223687416] 'process raft request' (duration: 189.601088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:47.34398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.086447ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-19T14:22:48.898281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25408} {"level":"info","ts":"2026-05-19T14:22:49.047009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25408,"took":"144.100002ms","hash":1636065995,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":38567936,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-05-19T14:22:49.047066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1636065995,"revision":25408,"compact-revision":22192} {"level":"warn","ts":"2026-05-19T14:23:33.076275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.912918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:23:33.076438Z","caller":"traceutil/trace.go:171","msg":"trace[1321705817] transaction","detail":"{read_only:false; response_revision:28858; number_of_response:1; }","duration":"187.067753ms","start":"2026-05-19T14:23:32.889348Z","end":"2026-05-19T14:23:33.076416Z","steps":["trace[1321705817] 'process raft request' (duration: 54.91017ms)","trace[1321705817] 'compare' (duration: 131.692603ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:33.084025Z","caller":"traceutil/trace.go:171","msg":"trace[430767830] transaction","detail":"{read_only:false; response_revision:28859; number_of_response:1; }","duration":"194.466793ms","start":"2026-05-19T14:23:32.889533Z","end":"2026-05-19T14:23:33.084Z","steps":["trace[430767830] 'process raft request' (duration: 194.222108ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:33.084136Z","caller":"traceutil/trace.go:171","msg":"trace[857978219] linearizableReadLoop","detail":"{readStateIndex:29775; appliedIndex:29773; }","duration":"137.449988ms","start":"2026-05-19T14:23:32.946664Z","end":"2026-05-19T14:23:33.084114Z","steps":["trace[857978219] 'read index received' (duration: 137.017259ms)","trace[857978219] 'applied index is now lower than readState.Index' (duration: 430.939µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:33.084292Z","caller":"traceutil/trace.go:171","msg":"trace[1397051594] transaction","detail":"{read_only:false; response_revision:28860; number_of_response:1; }","duration":"188.865032ms","start":"2026-05-19T14:23:32.895398Z","end":"2026-05-19T14:23:33.084263Z","steps":["trace[1397051594] 'process raft request' (duration: 188.533534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:33.084406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.747275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/glance-db-user\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-05-19T14:23:33.084451Z","caller":"traceutil/trace.go:171","msg":"trace[215275674] range","detail":"{range_begin:/registry/secrets/openstack/glance-db-user; range_end:; response_count:1; response_revision:28860; }","duration":"137.834807ms","start":"2026-05-19T14:23:32.946603Z","end":"2026-05-19T14:23:33.084437Z","steps":["trace[215275674] 'agreement among raft nodes before linearized reading' (duration: 137.590001ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.499035Z","caller":"traceutil/trace.go:171","msg":"trace[481177415] transaction","detail":"{read_only:false; response_revision:28959; number_of_response:1; }","duration":"160.816743ms","start":"2026-05-19T14:23:43.338202Z","end":"2026-05-19T14:23:43.499019Z","steps":["trace[481177415] 'process raft request' (duration: 160.68271ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.504641Z","caller":"traceutil/trace.go:171","msg":"trace[1514922671] linearizableReadLoop","detail":"{readStateIndex:29879; appliedIndex:29875; }","duration":"154.983768ms","start":"2026-05-19T14:23:43.34964Z","end":"2026-05-19T14:23:43.504623Z","steps":["trace[1514922671] 'read index received' (duration: 149.242783ms)","trace[1514922671] 'applied index is now lower than readState.Index' (duration: 5.740195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:43.504752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.09841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:43.504807Z","caller":"traceutil/trace.go:171","msg":"trace[565285925] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:28962; }","duration":"155.199221ms","start":"2026-05-19T14:23:43.349597Z","end":"2026-05-19T14:23:43.504796Z","steps":["trace[565285925] 'agreement among raft nodes before linearized reading' (duration: 155.105199ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.5048Z","caller":"traceutil/trace.go:171","msg":"trace[1486749075] transaction","detail":"{read_only:false; response_revision:28960; number_of_response:1; }","duration":"165.994866ms","start":"2026-05-19T14:23:43.338764Z","end":"2026-05-19T14:23:43.504759Z","steps":["trace[1486749075] 'process raft request' (duration: 165.657308ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.504847Z","caller":"traceutil/trace.go:171","msg":"trace[1221390689] transaction","detail":"{read_only:false; response_revision:28962; number_of_response:1; }","duration":"164.754708ms","start":"2026-05-19T14:23:43.340081Z","end":"2026-05-19T14:23:43.504835Z","steps":["trace[1221390689] 'process raft request' (duration: 164.507243ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.504723Z","caller":"traceutil/trace.go:171","msg":"trace[207121933] transaction","detail":"{read_only:false; response_revision:28961; number_of_response:1; }","duration":"164.950343ms","start":"2026-05-19T14:23:43.339734Z","end":"2026-05-19T14:23:43.504684Z","steps":["trace[207121933] 'process raft request' (duration: 164.82019ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:59.902578Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:23:59.907508Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T14:23:59.907597Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T14:27:48.903912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28458} {"level":"info","ts":"2026-05-19T14:27:49.037207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28458,"took":"128.714729ms","hash":2244540777,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":33079296,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-19T14:27:49.037276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2244540777,"revision":28458,"compact-revision":25408} {"level":"info","ts":"2026-05-19T14:32:48.909435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30837} {"level":"info","ts":"2026-05-19T14:32:49.039639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30837,"took":"127.123501ms","hash":1971390776,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":30531584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T14:32:49.03971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1971390776,"revision":30837,"compact-revision":28458} {"level":"info","ts":"2026-05-19T14:37:48.917199Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33104} {"level":"info","ts":"2026-05-19T14:37:49.067139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33104,"took":"146.351817ms","hash":2165546644,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":31068160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T14:37:49.067211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165546644,"revision":33104,"compact-revision":30837} {"level":"info","ts":"2026-05-19T14:42:48.924508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35410} {"level":"info","ts":"2026-05-19T14:42:49.064672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35410,"took":"135.798959ms","hash":3546896956,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:42:49.064755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3546896956,"revision":35410,"compact-revision":33104} {"level":"info","ts":"2026-05-19T14:45:17.076445Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:45:17.082159Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-19T14:45:17.082291Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-19T14:47:48.929191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37649} {"level":"info","ts":"2026-05-19T14:47:49.047764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37649,"took":"114.194132ms","hash":9093042,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":32067584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:47:49.047875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":9093042,"revision":37649,"compact-revision":35410}