{"level":"warn","ts":"2026-05-05T13:45:33.022905Z","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-05T13:45:33.022997Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-05T13:45:33.023066Z","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-05T13:45:33.023081Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-05T13:45:33.023101Z","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-05T13:45:33.023749Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-05-05T13:45:33.023825Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-05T13:45:33.028885Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.850422ms"} {"level":"info","ts":"2026-05-05T13:45:33.036078Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-05-05T13:45:33.036187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T13:45:33.036218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-05-05T13:45:33.03624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T13:45:33.036255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-05-05T13:45:33.036311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-05-05T13:45:33.04721Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T13:45:33.048861Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T13:45:33.050229Z","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-05T13:45:33.053069Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T13:45:33.054431Z","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-05T13:45:33.054515Z","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-05T13:45:33.054527Z","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-05T13:45:33.054464Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T13:45:33.05598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-05-05T13:45:33.056239Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-05T13:45:33.059472Z","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-05T13:45:33.059578Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-05T13:45:33.05961Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-05T13:45:33.059815Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T13:45:33.059867Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T13:45:33.136512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T13:45:33.136552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T13:45:33.13659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-05-05T13:45:33.136608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-05-05T13:45:33.13662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-05T13:45:33.136642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-05-05T13:45:33.136655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-05T13:45:33.137825Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:45:33.13782Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:33.137893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:45:33.138354Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T13:45:33.138515Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T13:45:33.139188Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:33.139398Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:33.139495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:33.140784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T13:45:33.141492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-05-05T13:45:33.137778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T13:45:59.014904Z","caller":"traceutil/trace.go:171","msg":"trace[62225616] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"141.423853ms","start":"2026-05-05T13:45:58.873435Z","end":"2026-05-05T13:45:59.014859Z","steps":["trace[62225616] 'process raft request' (duration: 141.258039ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:08.43452Z","caller":"traceutil/trace.go:171","msg":"trace[1945039369] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"164.863076ms","start":"2026-05-05T13:46:08.269634Z","end":"2026-05-05T13:46:08.434497Z","steps":["trace[1945039369] 'process raft request' (duration: 164.817855ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:08.43457Z","caller":"traceutil/trace.go:171","msg":"trace[950389553] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"183.382355ms","start":"2026-05-05T13:46:08.251144Z","end":"2026-05-05T13:46:08.434526Z","steps":["trace[950389553] 'process raft request' (duration: 171.213353ms)","trace[950389553] 'compare' (duration: 11.959917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:46:09.065223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.203917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T13:46:09.06535Z","caller":"traceutil/trace.go:171","msg":"trace[1366979308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"503.380842ms","start":"2026-05-05T13:46:08.561947Z","end":"2026-05-05T13:46:09.065328Z","steps":["trace[1366979308] 'range keys from in-memory index tree' (duration: 503.115835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:09.065263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.752004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T13:46:09.065393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:08.561928Z","time spent":"503.454123ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T13:46:09.065494Z","caller":"traceutil/trace.go:171","msg":"trace[1260022887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"498.962679ms","start":"2026-05-05T13:46:08.566476Z","end":"2026-05-05T13:46:09.065439Z","steps":["trace[1260022887] 'range keys from in-memory index tree' (duration: 498.631021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:09.065628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:08.566446Z","time spent":"499.140564ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T13:46:09.065832Z","caller":"traceutil/trace.go:171","msg":"trace[1515159887] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"406.387216ms","start":"2026-05-05T13:46:08.659424Z","end":"2026-05-05T13:46:09.065811Z","steps":["trace[1515159887] 'process raft request' (duration: 397.986491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:09.066345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:08.659408Z","time spent":"406.480958ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:46:09.079213Z","caller":"traceutil/trace.go:171","msg":"trace[2034764727] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"319.047394ms","start":"2026-05-05T13:46:08.760135Z","end":"2026-05-05T13:46:09.079183Z","steps":["trace[2034764727] 'process raft request' (duration: 318.780798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:09.079344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:08.760115Z","time spent":"319.156577ms","remote":"127.0.0.1:37468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:46:09.079728Z","caller":"traceutil/trace.go:171","msg":"trace[1454416347] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"239.599986ms","start":"2026-05-05T13:46:08.840109Z","end":"2026-05-05T13:46:09.079709Z","steps":["trace[1454416347] 'process raft request' (duration: 239.058483ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:36.757279Z","caller":"traceutil/trace.go:171","msg":"trace[2099790108] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"115.217343ms","start":"2026-05-05T13:47:36.642036Z","end":"2026-05-05T13:47:36.757254Z","steps":["trace[2099790108] 'process raft request' (duration: 115.07014ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:36.832538Z","caller":"traceutil/trace.go:171","msg":"trace[1097792320] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1865; }","duration":"130.714978ms","start":"2026-05-05T13:47:36.701806Z","end":"2026-05-05T13:47:36.832521Z","steps":["trace[1097792320] 'read index received' (duration: 55.399369ms)","trace[1097792320] 'applied index is now lower than readState.Index' (duration: 75.313739ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:36.832883Z","caller":"traceutil/trace.go:171","msg":"trace[1908020302] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"147.656744ms","start":"2026-05-05T13:47:36.685182Z","end":"2026-05-05T13:47:36.832839Z","steps":["trace[1908020302] 'process raft request' (duration: 127.823056ms)","trace[1908020302] 'compare' (duration: 19.4519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:36.832975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.133457ms","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-05T13:47:36.833068Z","caller":"traceutil/trace.go:171","msg":"trace[1933200638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1796; }","duration":"131.297631ms","start":"2026-05-05T13:47:36.701752Z","end":"2026-05-05T13:47:36.83305Z","steps":["trace[1933200638] 'agreement among raft nodes before linearized reading' (duration: 130.961904ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:21.80598Z","caller":"traceutil/trace.go:171","msg":"trace[1485638671] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"194.58632ms","start":"2026-05-05T13:48:21.61137Z","end":"2026-05-05T13:48:21.805956Z","steps":["trace[1485638671] 'process raft request' (duration: 194.397786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:21.957797Z","caller":"traceutil/trace.go:171","msg":"trace[1240280212] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"151.802795ms","start":"2026-05-05T13:48:21.805928Z","end":"2026-05-05T13:48:21.957731Z","steps":["trace[1240280212] 'read index received' (duration: 60.775125ms)","trace[1240280212] 'applied index is now lower than readState.Index' (duration: 91.02394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:48:21.958136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.792568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T13:48:21.958189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.388113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T13:48:21.958223Z","caller":"traceutil/trace.go:171","msg":"trace[2004774571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2089; }","duration":"165.942431ms","start":"2026-05-05T13:48:21.792259Z","end":"2026-05-05T13:48:21.958202Z","steps":["trace[2004774571] 'agreement among raft nodes before linearized reading' (duration: 165.733486ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:21.958255Z","caller":"traceutil/trace.go:171","msg":"trace[1166483319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2089; }","duration":"149.505115ms","start":"2026-05-05T13:48:21.808733Z","end":"2026-05-05T13:48:21.958238Z","steps":["trace[1166483319] 'agreement among raft nodes before linearized reading' (duration: 149.30303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:21.958242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.879464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T13:48:21.95836Z","caller":"traceutil/trace.go:171","msg":"trace[532489303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2089; }","duration":"150.047267ms","start":"2026-05-05T13:48:21.808283Z","end":"2026-05-05T13:48:21.95833Z","steps":["trace[532489303] 'agreement among raft nodes before linearized reading' (duration: 149.741671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:21.95818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.881429ms","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-05T13:48:21.958442Z","caller":"traceutil/trace.go:171","msg":"trace[387328645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2089; }","duration":"165.182254ms","start":"2026-05-05T13:48:21.793248Z","end":"2026-05-05T13:48:21.95843Z","steps":["trace[387328645] 'agreement among raft nodes before linearized reading' (duration: 164.730385ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:44.557038Z","caller":"traceutil/trace.go:171","msg":"trace[1027845048] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"104.164701ms","start":"2026-05-05T13:49:44.452844Z","end":"2026-05-05T13:49:44.557008Z","steps":["trace[1027845048] 'process raft request' (duration: 104.010768ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:44.643608Z","caller":"traceutil/trace.go:171","msg":"trace[1320346040] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2682; }","duration":"189.884213ms","start":"2026-05-05T13:49:44.453699Z","end":"2026-05-05T13:49:44.643583Z","steps":["trace[1320346040] 'read index received' (duration: 103.158572ms)","trace[1320346040] 'applied index is now lower than readState.Index' (duration: 86.724201ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:49:44.64376Z","caller":"traceutil/trace.go:171","msg":"trace[872942940] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"190.657738ms","start":"2026-05-05T13:49:44.453077Z","end":"2026-05-05T13:49:44.643734Z","steps":["trace[872942940] 'process raft request' (duration: 190.419343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:49:44.643865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.149668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T13:49:44.643876Z","caller":"traceutil/trace.go:171","msg":"trace[530044552] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"190.887982ms","start":"2026-05-05T13:49:44.452963Z","end":"2026-05-05T13:49:44.643851Z","steps":["trace[530044552] 'process raft request' (duration: 149.295279ms)","trace[530044552] 'compare' (duration: 41.009482ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:49:44.643907Z","caller":"traceutil/trace.go:171","msg":"trace[1247112679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2587; }","duration":"190.23729ms","start":"2026-05-05T13:49:44.453658Z","end":"2026-05-05T13:49:44.643896Z","steps":["trace[1247112679] 'agreement among raft nodes before linearized reading' (duration: 190.067117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:53:56.394113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.372663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3adec47b-a0d4-45a0-b408-d67724bf1934\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T13:53:56.394226Z","caller":"traceutil/trace.go:171","msg":"trace[1099650529] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3adec47b-a0d4-45a0-b408-d67724bf1934; range_end:; response_count:1; response_revision:4811; }","duration":"268.598159ms","start":"2026-05-05T13:53:56.125608Z","end":"2026-05-05T13:53:56.394206Z","steps":["trace[1099650529] 'range keys from in-memory index tree' (duration: 268.128407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:53:56.394817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.472466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T13:53:56.39491Z","caller":"traceutil/trace.go:171","msg":"trace[488483148] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4811; }","duration":"274.796854ms","start":"2026-05-05T13:53:56.120091Z","end":"2026-05-05T13:53:56.394888Z","steps":["trace[488483148] 'range keys from in-memory index tree' (duration: 273.645394ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:08.283925Z","caller":"traceutil/trace.go:171","msg":"trace[713036408] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"107.693942ms","start":"2026-05-05T13:54:08.17621Z","end":"2026-05-05T13:54:08.283904Z","steps":["trace[713036408] 'process raft request' (duration: 107.643391ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:08.283972Z","caller":"traceutil/trace.go:171","msg":"trace[1915049709] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"108.935703ms","start":"2026-05-05T13:54:08.174997Z","end":"2026-05-05T13:54:08.283932Z","steps":["trace[1915049709] 'process raft request' (duration: 67.45801ms)","trace[1915049709] 'compare' (duration: 41.266338ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:09.013554Z","caller":"traceutil/trace.go:171","msg":"trace[1205674517] linearizableReadLoop","detail":"{readStateIndex:5244; appliedIndex:5243; }","duration":"107.219999ms","start":"2026-05-05T13:54:08.906302Z","end":"2026-05-05T13:54:09.013522Z","steps":["trace[1205674517] 'read index received' (duration: 106.918812ms)","trace[1205674517] 'applied index is now lower than readState.Index' (duration: 299.747µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:09.013681Z","caller":"traceutil/trace.go:171","msg":"trace[399696590] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"176.360201ms","start":"2026-05-05T13:54:08.837269Z","end":"2026-05-05T13:54:09.013629Z","steps":["trace[399696590] 'process raft request' (duration: 175.973852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:09.013802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.287601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:09.013848Z","caller":"traceutil/trace.go:171","msg":"trace[1274390008] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5055; }","duration":"105.404565ms","start":"2026-05-05T13:54:08.90843Z","end":"2026-05-05T13:54:09.013835Z","steps":["trace[1274390008] 'agreement among raft nodes before linearized reading' (duration: 105.275521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:09.013821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.486367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:09.013928Z","caller":"traceutil/trace.go:171","msg":"trace[626324704] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5055; }","duration":"107.674591ms","start":"2026-05-05T13:54:08.90623Z","end":"2026-05-05T13:54:09.013904Z","steps":["trace[626324704] 'agreement among raft nodes before linearized reading' (duration: 107.414584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.062595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.075431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:54:16.062795Z","caller":"traceutil/trace.go:171","msg":"trace[538366554] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"222.341807ms","start":"2026-05-05T13:54:15.840409Z","end":"2026-05-05T13:54:16.062751Z","steps":["trace[538366554] 'process raft request' (duration: 107.833265ms)","trace[538366554] 'compare' (duration: 113.939318ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:16.062921Z","caller":"traceutil/trace.go:171","msg":"trace[1464408252] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"188.192877ms","start":"2026-05-05T13:54:15.874711Z","end":"2026-05-05T13:54:16.062903Z","steps":["trace[1464408252] 'process raft request' (duration: 188.141566ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:16.062981Z","caller":"traceutil/trace.go:171","msg":"trace[1485719785] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"207.792245ms","start":"2026-05-05T13:54:15.855145Z","end":"2026-05-05T13:54:16.062937Z","steps":["trace[1485719785] 'process raft request' (duration: 207.639771ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:16.063092Z","caller":"traceutil/trace.go:171","msg":"trace[480509937] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"215.796434ms","start":"2026-05-05T13:54:15.847282Z","end":"2026-05-05T13:54:16.063078Z","steps":["trace[480509937] 'process raft request' (duration: 215.445946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:16.062829Z","caller":"traceutil/trace.go:171","msg":"trace[645684420] linearizableReadLoop","detail":"{readStateIndex:5388; appliedIndex:5387; }","duration":"216.600484ms","start":"2026-05-05T13:54:15.846188Z","end":"2026-05-05T13:54:16.062788Z","steps":["trace[645684420] 'read index received' (duration: 102.077612ms)","trace[645684420] 'applied index is now lower than readState.Index' (duration: 114.517952ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:16.063391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.171868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T13:54:16.063443Z","caller":"traceutil/trace.go:171","msg":"trace[2082085142] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5200; }","duration":"217.2509ms","start":"2026-05-05T13:54:15.846179Z","end":"2026-05-05T13:54:16.06343Z","steps":["trace[2082085142] 'agreement among raft nodes before linearized reading' (duration: 217.034845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.064037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.779413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T13:54:16.064086Z","caller":"traceutil/trace.go:171","msg":"trace[1247083627] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5200; }","duration":"217.881145ms","start":"2026-05-05T13:54:15.846195Z","end":"2026-05-05T13:54:16.064076Z","steps":["trace[1247083627] 'agreement among raft nodes before linearized reading' (duration: 217.748842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.064587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.4289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-626a85e8-aafa-4d2c-9177-edcc3c3b5ae6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T13:54:16.064646Z","caller":"traceutil/trace.go:171","msg":"trace[1738604474] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-626a85e8-aafa-4d2c-9177-edcc3c3b5ae6; range_end:; response_count:1; response_revision:5200; }","duration":"163.537322ms","start":"2026-05-05T13:54:15.901091Z","end":"2026-05-05T13:54:16.064628Z","steps":["trace[1738604474] 'agreement among raft nodes before linearized reading' (duration: 163.379159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.349663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.055983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:54:16.349793Z","caller":"traceutil/trace.go:171","msg":"trace[1650253212] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"279.118702ms","start":"2026-05-05T13:54:16.070652Z","end":"2026-05-05T13:54:16.349771Z","steps":["trace[1650253212] 'process raft request' (duration: 96.853792ms)","trace[1650253212] 'compare' (duration: 181.89559ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:16.51438Z","caller":"traceutil/trace.go:171","msg":"trace[1823807561] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"443.391362ms","start":"2026-05-05T13:54:16.070959Z","end":"2026-05-05T13:54:16.514351Z","steps":["trace[1823807561] 'process raft request' (duration: 408.840912ms)","trace[1823807561] 'compare' (duration: 34.280903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:16.514558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:16.070945Z","time spent":"443.503445ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:16.515018Z","caller":"traceutil/trace.go:171","msg":"trace[1143198817] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"441.914145ms","start":"2026-05-05T13:54:16.073082Z","end":"2026-05-05T13:54:16.514997Z","steps":["trace[1143198817] 'process raft request' (duration: 441.217878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.515102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:16.073067Z","time spent":"441.993658ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:16.515143Z","caller":"traceutil/trace.go:171","msg":"trace[1040830328] linearizableReadLoop","detail":"{readStateIndex:5394; appliedIndex:5391; }","duration":"440.223793ms","start":"2026-05-05T13:54:16.074889Z","end":"2026-05-05T13:54:16.515112Z","steps":["trace[1040830328] 'read index received' (duration: 93.247592ms)","trace[1040830328] 'applied index is now lower than readState.Index' (duration: 346.974441ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:16.515208Z","caller":"traceutil/trace.go:171","msg":"trace[718328502] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"435.952856ms","start":"2026-05-05T13:54:16.079241Z","end":"2026-05-05T13:54:16.515194Z","steps":["trace[718328502] 'process raft request' (duration: 435.628048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.515269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:16.07921Z","time spent":"436.035068ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:54:16.515613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.696994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"warn","ts":"2026-05-05T13:54:16.515634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.501306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:54:16.515745Z","caller":"traceutil/trace.go:171","msg":"trace[1532508156] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:5204; }","duration":"419.64713ms","start":"2026-05-05T13:54:16.096069Z","end":"2026-05-05T13:54:16.515716Z","steps":["trace[1532508156] 'agreement among raft nodes before linearized reading' (duration: 419.478725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.515811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:16.096058Z","time spent":"419.729333ms","remote":"127.0.0.1:41406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"info","ts":"2026-05-05T13:54:16.515674Z","caller":"traceutil/trace.go:171","msg":"trace[380323243] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5204; }","duration":"440.823378ms","start":"2026-05-05T13:54:16.074831Z","end":"2026-05-05T13:54:16.515654Z","steps":["trace[380323243] 'agreement among raft nodes before linearized reading' (duration: 440.476948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.515797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.783572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T13:54:16.516093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:16.074809Z","time spent":"441.213317ms","remote":"127.0.0.1:37384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1153,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"info","ts":"2026-05-05T13:54:16.516156Z","caller":"traceutil/trace.go:171","msg":"trace[1106741686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5204; }","duration":"289.14745ms","start":"2026-05-05T13:54:16.226992Z","end":"2026-05-05T13:54:16.51614Z","steps":["trace[1106741686] 'agreement among raft nodes before linearized reading' (duration: 288.72403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.516255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.547254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T13:54:16.51631Z","caller":"traceutil/trace.go:171","msg":"trace[943812045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5204; }","duration":"292.654337ms","start":"2026-05-05T13:54:16.223643Z","end":"2026-05-05T13:54:16.516297Z","steps":["trace[943812045] 'agreement among raft nodes before linearized reading' (duration: 292.115914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:16.517444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.647101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2368093"} {"level":"info","ts":"2026-05-05T13:54:16.517551Z","caller":"traceutil/trace.go:171","msg":"trace[1233212037] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5204; }","duration":"207.769414ms","start":"2026-05-05T13:54:16.309761Z","end":"2026-05-05T13:54:16.517531Z","steps":["trace[1233212037] 'agreement among raft nodes before linearized reading' (duration: 205.842445ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:33.319834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2844} {"level":"info","ts":"2026-05-05T13:55:33.385476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2844,"took":"63.83623ms","hash":4202826443,"current-db-size-bytes":56541184,"current-db-size":"56 MB","current-db-size-in-use-bytes":33087488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T13:55:33.385559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4202826443,"revision":2844,"compact-revision":-1} {"level":"info","ts":"2026-05-05T13:56:59.704062Z","caller":"traceutil/trace.go:171","msg":"trace[548835384] transaction","detail":"{read_only:false; response_revision:6433; number_of_response:1; }","duration":"476.178382ms","start":"2026-05-05T13:56:59.227857Z","end":"2026-05-05T13:56:59.704035Z","steps":["trace[548835384] 'process raft request' (duration: 476.031138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:59.704214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.227834Z","time spent":"476.299145ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:56:59.705263Z","caller":"traceutil/trace.go:171","msg":"trace[931524827] transaction","detail":"{read_only:false; response_revision:6434; number_of_response:1; }","duration":"474.785949ms","start":"2026-05-05T13:56:59.23047Z","end":"2026-05-05T13:56:59.705256Z","steps":["trace[931524827] 'process raft request' (duration: 474.709487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:59.705304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.230443Z","time spent":"474.840559ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:56:59.705374Z","caller":"traceutil/trace.go:171","msg":"trace[1625415496] linearizableReadLoop","detail":"{readStateIndex:6665; appliedIndex:6662; }","duration":"345.371275ms","start":"2026-05-05T13:56:59.359995Z","end":"2026-05-05T13:56:59.705367Z","steps":["trace[1625415496] 'read index received' (duration: 343.9475ms)","trace[1625415496] 'applied index is now lower than readState.Index' (duration: 1.422915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:56:59.705659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.637671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-05T13:56:59.70572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.219218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T13:56:59.705795Z","caller":"traceutil/trace.go:171","msg":"trace[1909536539] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6435; }","duration":"340.301211ms","start":"2026-05-05T13:56:59.365471Z","end":"2026-05-05T13:56:59.705772Z","steps":["trace[1909536539] 'agreement among raft nodes before linearized reading' (duration: 340.138497ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:59.7058Z","caller":"traceutil/trace.go:171","msg":"trace[1181414300] transaction","detail":"{read_only:false; response_revision:6435; number_of_response:1; }","duration":"381.237788ms","start":"2026-05-05T13:56:59.324529Z","end":"2026-05-05T13:56:59.705766Z","steps":["trace[1181414300] 'process raft request' (duration: 380.699915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:59.705668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.661382ms","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":"warn","ts":"2026-05-05T13:56:59.705855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.365466Z","time spent":"340.379152ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T13:56:59.705878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.052132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:56:59.705904Z","caller":"traceutil/trace.go:171","msg":"trace[32177926] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6435; }","duration":"343.910788ms","start":"2026-05-05T13:56:59.36195Z","end":"2026-05-05T13:56:59.705861Z","steps":["trace[32177926] 'agreement among raft nodes before linearized reading' (duration: 343.615521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:59.705909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.324511Z","time spent":"381.34136ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:56:59.705934Z","caller":"traceutil/trace.go:171","msg":"trace[195367255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6435; }","duration":"342.149865ms","start":"2026-05-05T13:56:59.363766Z","end":"2026-05-05T13:56:59.705916Z","steps":["trace[195367255] 'agreement among raft nodes before linearized reading' (duration: 341.979631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:59.705971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.363755Z","time spent":"342.208676ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T13:56:59.705973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.361946Z","time spent":"344.002681ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:56:59.706156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.945877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T13:56:59.706213Z","caller":"traceutil/trace.go:171","msg":"trace[217088117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6435; }","duration":"341.006098ms","start":"2026-05-05T13:56:59.365187Z","end":"2026-05-05T13:56:59.706193Z","steps":["trace[217088117] 'agreement among raft nodes before linearized reading' (duration: 340.545866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:59.706263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.365182Z","time spent":"341.071749ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T13:56:59.705803Z","caller":"traceutil/trace.go:171","msg":"trace[1435538192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6435; }","duration":"345.820525ms","start":"2026-05-05T13:56:59.359967Z","end":"2026-05-05T13:56:59.705788Z","steps":["trace[1435538192] 'agreement among raft nodes before linearized reading' (duration: 345.526808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:56:59.706542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:56:59.359959Z","time spent":"346.566344ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T14:00:15.845367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.315323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.845428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.833779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.845443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.460098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.845473Z","caller":"traceutil/trace.go:171","msg":"trace[697742468] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8017; }","duration":"329.450135ms","start":"2026-05-05T14:00:15.515998Z","end":"2026-05-05T14:00:15.845448Z","steps":["trace[697742468] 'range keys from in-memory index tree' (duration: 329.254191ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.845512Z","caller":"traceutil/trace.go:171","msg":"trace[616118333] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8017; }","duration":"310.942451ms","start":"2026-05-05T14:00:15.534548Z","end":"2026-05-05T14:00:15.84549Z","steps":["trace[616118333] 'range keys from in-memory index tree' (duration: 310.770637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.845526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.515991Z","time spent":"329.522647ms","remote":"127.0.0.1:46834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 "} {"level":"warn","ts":"2026-05-05T14:00:15.845562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.534538Z","time spent":"311.008663ms","remote":"127.0.0.1:46836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 "} {"level":"warn","ts":"2026-05-05T14:00:15.845586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.096032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.845658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.585445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.845702Z","caller":"traceutil/trace.go:171","msg":"trace[1312032038] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8017; }","duration":"330.286446ms","start":"2026-05-05T14:00:15.515385Z","end":"2026-05-05T14:00:15.845672Z","steps":["trace[1312032038] 'range keys from in-memory index tree' (duration: 330.01862ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.845725Z","caller":"traceutil/trace.go:171","msg":"trace[662570800] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8017; }","duration":"348.695008ms","start":"2026-05-05T14:00:15.497005Z","end":"2026-05-05T14:00:15.8457Z","steps":["trace[662570800] 'range keys from in-memory index tree' (duration: 348.509104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.845761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.515374Z","time spent":"330.375289ms","remote":"127.0.0.1:46834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T14:00:15.8458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.496998Z","time spent":"348.77236ms","remote":"127.0.0.1:46826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 "} {"level":"warn","ts":"2026-05-05T14:00:15.845819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.726391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.84576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.69806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.845857Z","caller":"traceutil/trace.go:171","msg":"trace[472323959] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8017; }","duration":"311.806974ms","start":"2026-05-05T14:00:15.534039Z","end":"2026-05-05T14:00:15.845846Z","steps":["trace[472323959] 'count revisions from in-memory index tree' (duration: 311.634919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.845892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.534021Z","time spent":"311.861915ms","remote":"127.0.0.1:46836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:00:15.845905Z","caller":"traceutil/trace.go:171","msg":"trace[819968863] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8017; }","duration":"311.889305ms","start":"2026-05-05T14:00:15.533981Z","end":"2026-05-05T14:00:15.84587Z","steps":["trace[819968863] 'range keys from in-memory index tree' (duration: 311.626028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.845947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.556424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.845963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.533974Z","time spent":"311.975267ms","remote":"127.0.0.1:46836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 "} {"level":"info","ts":"2026-05-05T14:00:15.845983Z","caller":"traceutil/trace.go:171","msg":"trace[949046344] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8017; }","duration":"330.606485ms","start":"2026-05-05T14:00:15.515366Z","end":"2026-05-05T14:00:15.845972Z","steps":["trace[949046344] 'count revisions from in-memory index tree' (duration: 330.489822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.846018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.515357Z","time spent":"330.651496ms","remote":"127.0.0.1:46834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-05-05T14:00:15.846128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.195316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.846189Z","caller":"traceutil/trace.go:171","msg":"trace[1654482105] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8017; }","duration":"350.256708ms","start":"2026-05-05T14:00:15.495917Z","end":"2026-05-05T14:00:15.846174Z","steps":["trace[1654482105] 'count revisions from in-memory index tree' (duration: 349.391895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.846253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.495896Z","time spent":"350.34311ms","remote":"127.0.0.1:46826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:00:15.846256Z","caller":"traceutil/trace.go:171","msg":"trace[2102408966] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8017; }","duration":"350.381351ms","start":"2026-05-05T14:00:15.495844Z","end":"2026-05-05T14:00:15.846226Z","steps":["trace[2102408966] 'range keys from in-memory index tree' (duration: 350.110314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.846312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.495828Z","time spent":"350.471204ms","remote":"127.0.0.1:46826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 "} {"level":"info","ts":"2026-05-05T14:00:15.959506Z","caller":"traceutil/trace.go:171","msg":"trace[2116016117] linearizableReadLoop","detail":"{readStateIndex:8299; appliedIndex:8298; }","duration":"405.39033ms","start":"2026-05-05T14:00:15.554096Z","end":"2026-05-05T14:00:15.959486Z","steps":["trace[2116016117] 'read index received' (duration: 405.172895ms)","trace[2116016117] 'applied index is now lower than readState.Index' (duration: 194.875µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:00:15.959553Z","caller":"traceutil/trace.go:171","msg":"trace[1195351305] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"411.414172ms","start":"2026-05-05T14:00:15.548118Z","end":"2026-05-05T14:00:15.959532Z","steps":["trace[1195351305] 'process raft request' (duration: 411.164666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.959696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.548098Z","time spent":"411.499073ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:00:15.959729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.624771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.959742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.850426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.959771Z","caller":"traceutil/trace.go:171","msg":"trace[1162111142] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8018; }","duration":"239.681784ms","start":"2026-05-05T14:00:15.720075Z","end":"2026-05-05T14:00:15.959757Z","steps":["trace[1162111142] 'agreement among raft nodes before linearized reading' (duration: 239.605971ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.959797Z","caller":"traceutil/trace.go:171","msg":"trace[1099631360] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8018; }","duration":"404.96286ms","start":"2026-05-05T14:00:15.55482Z","end":"2026-05-05T14:00:15.959783Z","steps":["trace[1099631360] 'agreement among raft nodes before linearized reading' (duration: 404.826106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.95984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.55481Z","time spent":"405.015031ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T14:00:15.959853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.063914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.95993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.670533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.742092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.214754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960056Z","caller":"traceutil/trace.go:171","msg":"trace[439427377] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8018; }","duration":"257.286446ms","start":"2026-05-05T14:00:15.702764Z","end":"2026-05-05T14:00:15.960051Z","steps":["trace[439427377] 'agreement among raft nodes before linearized reading' (duration: 257.250225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.96005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.951477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960069Z","caller":"traceutil/trace.go:171","msg":"trace[698422824] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8018; }","duration":"199.977108ms","start":"2026-05-05T14:00:15.76008Z","end":"2026-05-05T14:00:15.960057Z","steps":["trace[698422824] 'agreement among raft nodes before linearized reading' (duration: 199.899996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960085Z","caller":"traceutil/trace.go:171","msg":"trace[1145705721] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8018; }","duration":"367.002407ms","start":"2026-05-05T14:00:15.593073Z","end":"2026-05-05T14:00:15.960075Z","steps":["trace[1145705721] 'agreement among raft nodes before linearized reading' (duration: 366.947466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.03122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.593066Z","time spent":"367.045918ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 "} {"level":"info","ts":"2026-05-05T14:00:15.95998Z","caller":"traceutil/trace.go:171","msg":"trace[1741330685] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8018; }","duration":"301.222807ms","start":"2026-05-05T14:00:15.658719Z","end":"2026-05-05T14:00:15.959942Z","steps":["trace[1741330685] 'agreement among raft nodes before linearized reading' (duration: 300.95093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960136Z","caller":"traceutil/trace.go:171","msg":"trace[939253966] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8018; }","duration":"277.087352ms","start":"2026-05-05T14:00:15.683035Z","end":"2026-05-05T14:00:15.960123Z","steps":["trace[939253966] 'agreement among raft nodes before linearized reading' (duration: 277.015761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.513338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.96018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.658713Z","time spent":"301.448272ms","remote":"127.0.0.1:46898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:00:15.960207Z","caller":"traceutil/trace.go:171","msg":"trace[1118629038] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8018; }","duration":"333.63223ms","start":"2026-05-05T14:00:15.626557Z","end":"2026-05-05T14:00:15.960189Z","steps":["trace[1118629038] 'agreement among raft nodes before linearized reading' (duration: 333.486257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.005157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.626548Z","time spent":"333.694701ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 "} {"level":"info","ts":"2026-05-05T14:00:15.960288Z","caller":"traceutil/trace.go:171","msg":"trace[1566704462] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8018; }","duration":"200.051319ms","start":"2026-05-05T14:00:15.760228Z","end":"2026-05-05T14:00:15.960279Z","steps":["trace[1566704462] 'agreement among raft nodes before linearized reading' (duration: 199.999028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.773877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960355Z","caller":"traceutil/trace.go:171","msg":"trace[226744850] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8018; }","duration":"367.839849ms","start":"2026-05-05T14:00:15.592506Z","end":"2026-05-05T14:00:15.960346Z","steps":["trace[226744850] 'agreement among raft nodes before linearized reading' (duration: 367.774817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.697595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.409292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.59249Z","time spent":"367.88959ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T14:00:15.960428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.188942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960459Z","caller":"traceutil/trace.go:171","msg":"trace[397679768] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8018; }","duration":"301.523716ms","start":"2026-05-05T14:00:15.658906Z","end":"2026-05-05T14:00:15.96043Z","steps":["trace[397679768] 'agreement among raft nodes before linearized reading' (duration: 301.358821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.906941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.350745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.977843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.997068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.627811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.344609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.925029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.96056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.551735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960573Z","caller":"traceutil/trace.go:171","msg":"trace[2140047344] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8018; }","duration":"217.664882ms","start":"2026-05-05T14:00:15.742898Z","end":"2026-05-05T14:00:15.960563Z","steps":["trace[2140047344] 'agreement among raft nodes before linearized reading' (duration: 217.611451ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960586Z","caller":"traceutil/trace.go:171","msg":"trace[845100877] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8018; }","duration":"334.39514ms","start":"2026-05-05T14:00:15.626179Z","end":"2026-05-05T14:00:15.960574Z","steps":["trace[845100877] 'agreement among raft nodes before linearized reading' (duration: 334.333869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960108Z","caller":"traceutil/trace.go:171","msg":"trace[1967903969] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8018; }","duration":"354.020991ms","start":"2026-05-05T14:00:15.606012Z","end":"2026-05-05T14:00:15.960033Z","steps":["trace[1967903969] 'agreement among raft nodes before linearized reading' (duration: 353.769905ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960601Z","caller":"traceutil/trace.go:171","msg":"trace[972172584] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8018; }","duration":"277.603316ms","start":"2026-05-05T14:00:15.682988Z","end":"2026-05-05T14:00:15.960591Z","steps":["trace[972172584] 'agreement among raft nodes before linearized reading' (duration: 277.544804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.003521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.787402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.209263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.606Z","time spent":"354.627377ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 "} {"level":"info","ts":"2026-05-05T14:00:15.960644Z","caller":"traceutil/trace.go:171","msg":"trace[1191835902] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8018; }","duration":"277.048772ms","start":"2026-05-05T14:00:15.683587Z","end":"2026-05-05T14:00:15.960635Z","steps":["trace[1191835902] 'agreement among raft nodes before linearized reading' (duration: 276.99417ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960655Z","caller":"traceutil/trace.go:171","msg":"trace[67465537] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8018; }","duration":"316.264725ms","start":"2026-05-05T14:00:15.644385Z","end":"2026-05-05T14:00:15.96065Z","steps":["trace[67465537] 'agreement among raft nodes before linearized reading' (duration: 316.214564ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.96066Z","caller":"traceutil/trace.go:171","msg":"trace[1692238898] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8018; }","duration":"301.852133ms","start":"2026-05-05T14:00:15.658792Z","end":"2026-05-05T14:00:15.960644Z","steps":["trace[1692238898] 'agreement among raft nodes before linearized reading' (duration: 301.783521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.230667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.290777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960555Z","caller":"traceutil/trace.go:171","msg":"trace[472734063] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8018; }","duration":"257.956612ms","start":"2026-05-05T14:00:15.702539Z","end":"2026-05-05T14:00:15.960495Z","steps":["trace[472734063] 'agreement among raft nodes before linearized reading' (duration: 257.898471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.658781Z","time spent":"301.912675ms","remote":"127.0.0.1:46898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 "} {"level":"warn","ts":"2026-05-05T14:00:15.960481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.188196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960746Z","caller":"traceutil/trace.go:171","msg":"trace[1446125574] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8018; }","duration":"389.378369ms","start":"2026-05-05T14:00:15.571355Z","end":"2026-05-05T14:00:15.960733Z","steps":["trace[1446125574] 'agreement among raft nodes before linearized reading' (duration: 389.311237ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960767Z","caller":"traceutil/trace.go:171","msg":"trace[1921860680] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8018; }","duration":"334.497592ms","start":"2026-05-05T14:00:15.626256Z","end":"2026-05-05T14:00:15.960754Z","steps":["trace[1921860680] 'agreement among raft nodes before linearized reading' (duration: 334.184175ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960409Z","caller":"traceutil/trace.go:171","msg":"trace[2094048719] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8018; }","duration":"257.734897ms","start":"2026-05-05T14:00:15.702659Z","end":"2026-05-05T14:00:15.960394Z","steps":["trace[2094048719] 'agreement among raft nodes before linearized reading' (duration: 257.677795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.571334Z","time spent":"389.443261ms","remote":"127.0.0.1:46856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-05-05T14:00:15.960802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.626239Z","time spent":"334.551854ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-05-05T14:00:15.960471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.61634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.960872Z","caller":"traceutil/trace.go:171","msg":"trace[1374469293] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8018; }","duration":"389.04335ms","start":"2026-05-05T14:00:15.571815Z","end":"2026-05-05T14:00:15.960859Z","steps":["trace[1374469293] 'agreement among raft nodes before linearized reading' (duration: 388.61396ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960631Z","caller":"traceutil/trace.go:171","msg":"trace[572451636] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8018; }","duration":"405.052362ms","start":"2026-05-05T14:00:15.555533Z","end":"2026-05-05T14:00:15.960585Z","steps":["trace[572451636] 'agreement among raft nodes before linearized reading' (duration: 404.914939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.571801Z","time spent":"389.100532ms","remote":"127.0.0.1:46856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 "} {"level":"warn","ts":"2026-05-05T14:00:15.960923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.555523Z","time spent":"405.38986ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 "} {"level":"warn","ts":"2026-05-05T14:00:15.960927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.45452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.64437Z","time spent":"316.303975ms","remote":"127.0.0.1:46884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 "} {"level":"info","ts":"2026-05-05T14:00:15.960972Z","caller":"traceutil/trace.go:171","msg":"trace[734169967] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8018; }","duration":"218.518772ms","start":"2026-05-05T14:00:15.742442Z","end":"2026-05-05T14:00:15.96096Z","steps":["trace[734169967] 'agreement among raft nodes before linearized reading' (duration: 218.44611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.833823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.96054Z","caller":"traceutil/trace.go:171","msg":"trace[1276573262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8018; }","duration":"406.443818ms","start":"2026-05-05T14:00:15.554072Z","end":"2026-05-05T14:00:15.960516Z","steps":["trace[1276573262] 'agreement among raft nodes before linearized reading' (duration: 406.312264ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.961085Z","caller":"traceutil/trace.go:171","msg":"trace[1114112185] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8018; }","duration":"354.882414ms","start":"2026-05-05T14:00:15.606191Z","end":"2026-05-05T14:00:15.961073Z","steps":["trace[1114112185] 'agreement among raft nodes before linearized reading' (duration: 354.825812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.751959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.961093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.554061Z","time spent":"407.023102ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T14:00:15.961119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.606179Z","time spent":"354.930585ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:00:15.961123Z","caller":"traceutil/trace.go:171","msg":"trace[1032766047] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8018; }","duration":"389.822781ms","start":"2026-05-05T14:00:15.571289Z","end":"2026-05-05T14:00:15.961111Z","steps":["trace[1032766047] 'agreement among raft nodes before linearized reading' (duration: 389.758309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.571275Z","time spent":"389.872072ms","remote":"127.0.0.1:46856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T14:00:15.96116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.694127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.961192Z","caller":"traceutil/trace.go:171","msg":"trace[926706221] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8018; }","duration":"218.736407ms","start":"2026-05-05T14:00:15.742445Z","end":"2026-05-05T14:00:15.961181Z","steps":["trace[926706221] 'agreement among raft nodes before linearized reading' (duration: 218.685076ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.960718Z","caller":"traceutil/trace.go:171","msg":"trace[928071643] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8018; }","duration":"354.287099ms","start":"2026-05-05T14:00:15.606419Z","end":"2026-05-05T14:00:15.960706Z","steps":["trace[928071643] 'agreement among raft nodes before linearized reading' (duration: 354.227868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.606409Z","time spent":"354.816922ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 "} {"level":"warn","ts":"2026-05-05T14:00:15.960621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.626171Z","time spent":"334.441771ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T14:00:15.961337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.217987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:00:15.960559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.642638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.961371Z","caller":"traceutil/trace.go:171","msg":"trace[1934985272] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8018; }","duration":"240.250148ms","start":"2026-05-05T14:00:15.721108Z","end":"2026-05-05T14:00:15.961358Z","steps":["trace[1934985272] 'agreement among raft nodes before linearized reading' (duration: 240.197426ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:15.961379Z","caller":"traceutil/trace.go:171","msg":"trace[1813839305] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8018; }","duration":"406.516819ms","start":"2026-05-05T14:00:15.554849Z","end":"2026-05-05T14:00:15.961366Z","steps":["trace[1813839305] 'agreement among raft nodes before linearized reading' (duration: 405.674338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.658892Z","time spent":"301.602817ms","remote":"127.0.0.1:46898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T14:00:15.961411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.554813Z","time spent":"406.588731ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:00:15.960544Z","caller":"traceutil/trace.go:171","msg":"trace[804624872] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8018; }","duration":"368.033994ms","start":"2026-05-05T14:00:15.592498Z","end":"2026-05-05T14:00:15.960532Z","steps":["trace[804624872] 'agreement among raft nodes before linearized reading' (duration: 367.996773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.592483Z","time spent":"368.963477ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:00:15.960556Z","caller":"traceutil/trace.go:171","msg":"trace[2044560292] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8018; }","duration":"316.07958ms","start":"2026-05-05T14:00:15.644465Z","end":"2026-05-05T14:00:15.960544Z","steps":["trace[2044560292] 'agreement among raft nodes before linearized reading' (duration: 316.005859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.644452Z","time spent":"317.065084ms","remote":"127.0.0.1:46884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 "} {"level":"info","ts":"2026-05-05T14:00:15.960466Z","caller":"traceutil/trace.go:171","msg":"trace[902833971] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8018; }","duration":"200.248545ms","start":"2026-05-05T14:00:15.760204Z","end":"2026-05-05T14:00:15.960453Z","steps":["trace[902833971] 'agreement among raft nodes before linearized reading' (duration: 200.196104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.459768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.961624Z","caller":"traceutil/trace.go:171","msg":"trace[575261053] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8018; }","duration":"241.507829ms","start":"2026-05-05T14:00:15.720107Z","end":"2026-05-05T14:00:15.961615Z","steps":["trace[575261053] 'agreement among raft nodes before linearized reading' (duration: 241.451687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.960584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.221743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:15.961682Z","caller":"traceutil/trace.go:171","msg":"trace[431634883] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8018; }","duration":"317.319761ms","start":"2026-05-05T14:00:15.644348Z","end":"2026-05-05T14:00:15.961668Z","steps":["trace[431634883] 'agreement among raft nodes before linearized reading' (duration: 316.211644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:15.961725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:15.644342Z","time spent":"317.368003ms","remote":"127.0.0.1:46884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:00:24.182638Z","caller":"traceutil/trace.go:171","msg":"trace[169248502] transaction","detail":"{read_only:false; response_revision:8059; number_of_response:1; }","duration":"207.840104ms","start":"2026-05-05T14:00:23.97475Z","end":"2026-05-05T14:00:24.18259Z","steps":["trace[169248502] 'process raft request' (duration: 207.597109ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:25.114098Z","caller":"traceutil/trace.go:171","msg":"trace[562207147] transaction","detail":"{read_only:false; response_revision:8066; number_of_response:1; }","duration":"251.998742ms","start":"2026-05-05T14:00:24.862069Z","end":"2026-05-05T14:00:25.114068Z","steps":["trace[562207147] 'process raft request' (duration: 251.846519ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:25.11415Z","caller":"traceutil/trace.go:171","msg":"trace[908225601] linearizableReadLoop","detail":"{readStateIndex:8351; appliedIndex:8348; }","duration":"164.015595ms","start":"2026-05-05T14:00:24.950107Z","end":"2026-05-05T14:00:25.114123Z","steps":["trace[908225601] 'read index received' (duration: 162.410205ms)","trace[908225601] 'applied index is now lower than readState.Index' (duration: 1.60392ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:00:25.11416Z","caller":"traceutil/trace.go:171","msg":"trace[1682078343] transaction","detail":"{read_only:false; response_revision:8065; number_of_response:1; }","duration":"298.15233ms","start":"2026-05-05T14:00:24.815955Z","end":"2026-05-05T14:00:25.114107Z","steps":["trace[1682078343] 'process raft request' (duration: 296.582671ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:25.114322Z","caller":"traceutil/trace.go:171","msg":"trace[371002511] transaction","detail":"{read_only:false; response_revision:8067; number_of_response:1; }","duration":"250.851202ms","start":"2026-05-05T14:00:24.86343Z","end":"2026-05-05T14:00:25.114281Z","steps":["trace[371002511] 'process raft request' (duration: 250.590896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:25.114475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.346964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-05T14:00:25.114527Z","caller":"traceutil/trace.go:171","msg":"trace[1532156450] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8067; }","duration":"164.449687ms","start":"2026-05-05T14:00:24.950058Z","end":"2026-05-05T14:00:25.114508Z","steps":["trace[1532156450] 'agreement among raft nodes before linearized reading' (duration: 164.236991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:33.327321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5940} {"level":"info","ts":"2026-05-05T14:00:33.41929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5940,"took":"88.47272ms","hash":4137386563,"current-db-size-bytes":56541184,"current-db-size":"56 MB","current-db-size-in-use-bytes":25788416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T14:00:33.419346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4137386563,"revision":5940,"compact-revision":2844} {"level":"info","ts":"2026-05-05T14:00:40.780154Z","caller":"traceutil/trace.go:171","msg":"trace[2034876956] transaction","detail":"{read_only:false; response_revision:8150; number_of_response:1; }","duration":"133.955931ms","start":"2026-05-05T14:00:40.646161Z","end":"2026-05-05T14:00:40.780117Z","steps":["trace[2034876956] 'process raft request' (duration: 133.737545ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:40.797451Z","caller":"traceutil/trace.go:171","msg":"trace[2125480878] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"140.065105ms","start":"2026-05-05T14:00:40.657358Z","end":"2026-05-05T14:00:40.797423Z","steps":["trace[2125480878] 'process raft request' (duration: 139.788708ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:06.1218Z","caller":"traceutil/trace.go:171","msg":"trace[1617113481] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8378; }","duration":"115.706893ms","start":"2026-05-05T14:01:06.006052Z","end":"2026-05-05T14:01:06.121759Z","steps":["trace[1617113481] 'process raft request' (duration: 94.800308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:06.916465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.430113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T14:01:06.916575Z","caller":"traceutil/trace.go:171","msg":"trace[472376511] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8381; }","duration":"250.590556ms","start":"2026-05-05T14:01:06.665962Z","end":"2026-05-05T14:01:06.916553Z","steps":["trace[472376511] 'count revisions from in-memory index tree' (duration: 250.29951ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:07.035252Z","caller":"traceutil/trace.go:171","msg":"trace[1307949832] linearizableReadLoop","detail":"{readStateIndex:8680; appliedIndex:8679; }","duration":"117.18405ms","start":"2026-05-05T14:01:06.918041Z","end":"2026-05-05T14:01:07.035225Z","steps":["trace[1307949832] 'read index received' (duration: 117.029486ms)","trace[1307949832] 'applied index is now lower than readState.Index' (duration: 153.084µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:07.035688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.612311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:01:07.03575Z","caller":"traceutil/trace.go:171","msg":"trace[759155993] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8382; }","duration":"117.752435ms","start":"2026-05-05T14:01:06.917981Z","end":"2026-05-05T14:01:07.035734Z","steps":["trace[759155993] 'agreement among raft nodes before linearized reading' (duration: 117.429187ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:07.036307Z","caller":"traceutil/trace.go:171","msg":"trace[446277919] transaction","detail":"{read_only:false; response_revision:8382; number_of_response:1; }","duration":"133.962861ms","start":"2026-05-05T14:01:06.902327Z","end":"2026-05-05T14:01:07.03629Z","steps":["trace[446277919] 'process raft request' (duration: 132.707159ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:07.100655Z","caller":"traceutil/trace.go:171","msg":"trace[737538116] transaction","detail":"{read_only:false; response_revision:8385; number_of_response:1; }","duration":"119.862737ms","start":"2026-05-05T14:01:06.980769Z","end":"2026-05-05T14:01:07.100632Z","steps":["trace[737538116] 'process raft request' (duration: 119.826697ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:07.101005Z","caller":"traceutil/trace.go:171","msg":"trace[610419641] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"180.639382ms","start":"2026-05-05T14:01:06.920358Z","end":"2026-05-05T14:01:07.100997Z","steps":["trace[610419641] 'process raft request' (duration: 180.063567ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:07.101278Z","caller":"traceutil/trace.go:171","msg":"trace[1075166324] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"122.966264ms","start":"2026-05-05T14:01:06.978303Z","end":"2026-05-05T14:01:07.101269Z","steps":["trace[1075166324] 'process raft request' (duration: 122.257827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:07.91023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.354271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g6bxk\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T14:01:07.910314Z","caller":"traceutil/trace.go:171","msg":"trace[603132411] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g6bxk; range_end:; response_count:1; response_revision:8392; }","duration":"187.467764ms","start":"2026-05-05T14:01:07.722825Z","end":"2026-05-05T14:01:07.910293Z","steps":["trace[603132411] 'range keys from in-memory index tree' (duration: 187.266748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:08.555314Z","caller":"traceutil/trace.go:171","msg":"trace[960981541] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"182.155561ms","start":"2026-05-05T14:01:08.37311Z","end":"2026-05-05T14:01:08.555266Z","steps":["trace[960981541] 'process raft request' (duration: 181.893824ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:00.068441Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242b.wal"} {"level":"info","ts":"2026-05-05T14:02:09.928597Z","caller":"traceutil/trace.go:171","msg":"trace[1842799232] transaction","detail":"{read_only:false; response_revision:9001; number_of_response:1; }","duration":"207.080778ms","start":"2026-05-05T14:02:09.721454Z","end":"2026-05-05T14:02:09.928534Z","steps":["trace[1842799232] 'process raft request' (duration: 192.089623ms)","trace[1842799232] 'compare' (duration: 14.642585ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:02:09.929129Z","caller":"traceutil/trace.go:171","msg":"trace[979700737] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"207.482046ms","start":"2026-05-05T14:02:09.721634Z","end":"2026-05-05T14:02:09.929116Z","steps":["trace[979700737] 'process raft request' (duration: 206.745908ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:31.868024Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T14:03:31.87335Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T14:03:31.873447Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T14:05:33.333985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8117} {"level":"info","ts":"2026-05-05T14:05:33.399134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8117,"took":"62.712785ms","hash":2300317199,"current-db-size-bytes":56541184,"current-db-size":"56 MB","current-db-size-in-use-bytes":30183424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T14:05:33.399202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2300317199,"revision":8117,"compact-revision":5940} {"level":"warn","ts":"2026-05-05T14:06:24.744922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.607951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:06:24.745039Z","caller":"traceutil/trace.go:171","msg":"trace[36468165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11104; }","duration":"189.782336ms","start":"2026-05-05T14:06:24.55523Z","end":"2026-05-05T14:06:24.745012Z","steps":["trace[36468165] 'range keys from in-memory index tree' (duration: 189.505679ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:24.745043Z","caller":"traceutil/trace.go:171","msg":"trace[525014035] transaction","detail":"{read_only:false; response_revision:11105; number_of_response:1; }","duration":"173.719684ms","start":"2026-05-05T14:06:24.571272Z","end":"2026-05-05T14:06:24.744992Z","steps":["trace[525014035] 'process raft request' (duration: 158.82268ms)","trace[525014035] 'compare' (duration: 14.400542ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:08:15.882074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843197931447592,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T14:08:16.085158Z","caller":"traceutil/trace.go:171","msg":"trace[154861511] transaction","detail":"{read_only:false; response_revision:11873; number_of_response:1; }","duration":"802.701545ms","start":"2026-05-05T14:08:15.282431Z","end":"2026-05-05T14:08:16.085132Z","steps":["trace[154861511] 'process raft request' (duration: 802.539372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.085301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.282416Z","time spent":"802.821819ms","remote":"127.0.0.1:37368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T14:08:16.089633Z","caller":"traceutil/trace.go:171","msg":"trace[1079920087] linearizableReadLoop","detail":"{readStateIndex:12280; appliedIndex:12277; }","duration":"708.100967ms","start":"2026-05-05T14:08:15.381506Z","end":"2026-05-05T14:08:16.089607Z","steps":["trace[1079920087] 'read index received' (duration: 703.508239ms)","trace[1079920087] 'applied index is now lower than readState.Index' (duration: 4.591678ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:08:16.089666Z","caller":"traceutil/trace.go:171","msg":"trace[1197443085] transaction","detail":"{read_only:false; response_revision:11874; number_of_response:1; }","duration":"801.078499ms","start":"2026-05-05T14:08:15.288564Z","end":"2026-05-05T14:08:16.089642Z","steps":["trace[1197443085] 'process raft request' (duration: 800.839083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.089796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.282951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-05-05T14:08:16.0898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.288534Z","time spent":"801.187191ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9320,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:08:16.089846Z","caller":"traceutil/trace.go:171","msg":"trace[565422905] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:11875; }","duration":"708.382503ms","start":"2026-05-05T14:08:15.381446Z","end":"2026-05-05T14:08:16.089829Z","steps":["trace[565422905] 'agreement among raft nodes before linearized reading' (duration: 708.286411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.089889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.381416Z","time spent":"708.461545ms","remote":"127.0.0.1:37492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2008,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-05T14:08:16.089889Z","caller":"traceutil/trace.go:171","msg":"trace[280375051] transaction","detail":"{read_only:false; response_revision:11875; number_of_response:1; }","duration":"438.479849ms","start":"2026-05-05T14:08:15.651266Z","end":"2026-05-05T14:08:16.089745Z","steps":["trace[280375051] 'process raft request' (duration: 438.285384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.089926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.958228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T14:08:16.089939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.65301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:08:16.089969Z","caller":"traceutil/trace.go:171","msg":"trace[149103701] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:11875; }","duration":"568.009109ms","start":"2026-05-05T14:08:15.521945Z","end":"2026-05-05T14:08:16.089954Z","steps":["trace[149103701] 'agreement among raft nodes before linearized reading' (duration: 567.932107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.089965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.212772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T14:08:16.090001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.521921Z","time spent":"568.07131ms","remote":"127.0.0.1:41406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:08:16.090009Z","caller":"traceutil/trace.go:171","msg":"trace[1669854814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11875; }","duration":"535.736072ms","start":"2026-05-05T14:08:15.554248Z","end":"2026-05-05T14:08:16.089984Z","steps":["trace[1669854814] 'agreement among raft nodes before linearized reading' (duration: 535.634599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.952289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:1 size:7048"} {"level":"warn","ts":"2026-05-05T14:08:16.090019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.651239Z","time spent":"438.705435ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:08:16.090041Z","caller":"traceutil/trace.go:171","msg":"trace[667147959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11875; }","duration":"207.326855ms","start":"2026-05-05T14:08:15.882692Z","end":"2026-05-05T14:08:16.090019Z","steps":["trace[667147959] 'agreement among raft nodes before linearized reading' (duration: 207.170511ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:16.090083Z","caller":"traceutil/trace.go:171","msg":"trace[194202767] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:1; response_revision:11875; }","duration":"372.056111ms","start":"2026-05-05T14:08:15.718007Z","end":"2026-05-05T14:08:16.090063Z","steps":["trace[194202767] 'agreement among raft nodes before linearized reading' (duration: 371.829625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.09013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.717997Z","time spent":"372.120732ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":7070,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-05-05T14:08:16.090175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.718174ms","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-05T14:08:16.090185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.547026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-05-05T14:08:16.090199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.175537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-05T14:08:16.090209Z","caller":"traceutil/trace.go:171","msg":"trace[337136075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11875; }","duration":"576.772055ms","start":"2026-05-05T14:08:15.513426Z","end":"2026-05-05T14:08:16.090198Z","steps":["trace[337136075] 'agreement among raft nodes before linearized reading' (duration: 576.645832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.142102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:1 size:7048"} {"level":"warn","ts":"2026-05-05T14:08:16.089867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.184949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:08:16.090235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.986997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-05T14:08:16.090178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.554355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T14:08:16.090248Z","caller":"traceutil/trace.go:171","msg":"trace[638089188] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11875; }","duration":"327.278211ms","start":"2026-05-05T14:08:15.762957Z","end":"2026-05-05T14:08:16.090235Z","steps":["trace[638089188] 'agreement among raft nodes before linearized reading' (duration: 327.152528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.073934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-05T14:08:16.090281Z","caller":"traceutil/trace.go:171","msg":"trace[1827358081] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11875; }","duration":"158.054038ms","start":"2026-05-05T14:08:15.932214Z","end":"2026-05-05T14:08:16.090268Z","steps":["trace[1827358081] 'agreement among raft nodes before linearized reading' (duration: 157.944866ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:16.090289Z","caller":"traceutil/trace.go:171","msg":"trace[1789721957] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:1; response_revision:11875; }","duration":"372.272106ms","start":"2026-05-05T14:08:15.71798Z","end":"2026-05-05T14:08:16.090252Z","steps":["trace[1789721957] 'agreement among raft nodes before linearized reading' (duration: 371.961999ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:16.090299Z","caller":"traceutil/trace.go:171","msg":"trace[1196221774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11875; }","duration":"338.708579ms","start":"2026-05-05T14:08:15.751569Z","end":"2026-05-05T14:08:16.090278Z","steps":["trace[1196221774] 'agreement among raft nodes before linearized reading' (duration: 338.392701ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:16.090289Z","caller":"traceutil/trace.go:171","msg":"trace[1019348740] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:11875; }","duration":"639.64176ms","start":"2026-05-05T14:08:15.450623Z","end":"2026-05-05T14:08:16.090265Z","steps":["trace[1019348740] 'agreement among raft nodes before linearized reading' (duration: 639.164859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.363168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-05T14:08:16.090247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.513416Z","time spent":"576.824627ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T14:08:16.090331Z","caller":"traceutil/trace.go:171","msg":"trace[1393471350] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:11875; }","duration":"367.162735ms","start":"2026-05-05T14:08:15.723143Z","end":"2026-05-05T14:08:16.090305Z","steps":["trace[1393471350] 'agreement among raft nodes before linearized reading' (duration: 366.916879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.751559Z","time spent":"338.77153ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T14:08:16.090345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.019369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T14:08:16.090337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.71797Z","time spent":"372.357958ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":7070,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-05-05T14:08:16.090298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.762929Z","time spent":"327.357912ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T14:08:16.090361Z","caller":"traceutil/trace.go:171","msg":"trace[1798317205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11875; }","duration":"551.45397ms","start":"2026-05-05T14:08:15.53889Z","end":"2026-05-05T14:08:16.090344Z","steps":["trace[1798317205] 'agreement among raft nodes before linearized reading' (duration: 551.246775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.450608Z","time spent":"639.733332ms","remote":"127.0.0.1:50506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:08:16.090379Z","caller":"traceutil/trace.go:171","msg":"trace[219002382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11875; }","duration":"207.08173ms","start":"2026-05-05T14:08:15.883288Z","end":"2026-05-05T14:08:16.09037Z","steps":["trace[219002382] 'agreement among raft nodes before linearized reading' (duration: 206.989718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.723131Z","time spent":"367.237557ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-05T14:08:16.090228Z","caller":"traceutil/trace.go:171","msg":"trace[241388818] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11875; }","duration":"373.613167ms","start":"2026-05-05T14:08:15.716603Z","end":"2026-05-05T14:08:16.090217Z","steps":["trace[241388818] 'agreement among raft nodes before linearized reading' (duration: 373.477324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:16.090409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.53888Z","time spent":"551.515031ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T14:08:16.090451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.716592Z","time spent":"373.847773ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"warn","ts":"2026-05-05T14:08:16.090054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:15.554222Z","time spent":"535.821044ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T14:10:33.341045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10834} {"level":"info","ts":"2026-05-05T14:10:33.426388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10834,"took":"81.828091ms","hash":2697301273,"current-db-size-bytes":56541184,"current-db-size":"56 MB","current-db-size-in-use-bytes":27627520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T14:10:33.426464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2697301273,"revision":10834,"compact-revision":8117} {"level":"info","ts":"2026-05-05T14:15:33.347936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12870} {"level":"info","ts":"2026-05-05T14:15:33.425516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12870,"took":"75.422993ms","hash":4235069136,"current-db-size-bytes":56541184,"current-db-size":"56 MB","current-db-size-in-use-bytes":23588864,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T14:15:33.425644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4235069136,"revision":12870,"compact-revision":10834}