{"level":"warn","ts":"2026-04-08T00:35:17.785695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:35:17.78579Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-08T00:35:17.785866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:35:17.785881Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-08T00:35:17.785902Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T00:35:17.787283Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-04-08T00:35:17.787385Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-08T00:35:17.791807Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.435376ms"} {"level":"info","ts":"2026-04-08T00:35:17.80122Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-04-08T00:35:17.801333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:35:17.801359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-04-08T00:35:17.801379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:35:17.801388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-04-08T00:35:17.801427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-04-08T00:35:17.808243Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:35:17.810675Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:35:17.811987Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-08T00:35:17.814425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:35:17.81459Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T00:35:17.81469Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T00:35:17.814719Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T00:35:17.814711Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:35:17.815351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-04-08T00:35:17.81583Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-08T00:35:17.818487Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T00:35:17.818597Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-08T00:35:17.818631Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-08T00:35:17.818814Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:35:17.818866Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:35:18.201726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:35:18.201785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:35:18.201828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-04-08T00:35:18.201854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:35:18.201866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-08T00:35:18.201883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-04-08T00:35:18.201904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-08T00:35:18.212587Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:35:18.212767Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:35:18.212823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:35:18.212872Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:18.215852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-04-08T00:35:18.216359Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:35:18.217684Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:35:18.217763Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:35:18.222232Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:18.222321Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:18.222464Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:54.576824Z","caller":"traceutil/trace.go:171","msg":"trace[63695376] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"185.285598ms","start":"2026-04-08T00:35:54.391501Z","end":"2026-04-08T00:35:54.576786Z","steps":["trace[63695376] 'process raft request' (duration: 179.355962ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:54.854901Z","caller":"traceutil/trace.go:171","msg":"trace[1418298342] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"256.706862ms","start":"2026-04-08T00:35:54.598169Z","end":"2026-04-08T00:35:54.854875Z","steps":["trace[1418298342] 'process raft request' (duration: 256.57286ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:55.116548Z","caller":"traceutil/trace.go:171","msg":"trace[1953980710] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"229.368529ms","start":"2026-04-08T00:35:54.887154Z","end":"2026-04-08T00:35:55.116523Z","steps":["trace[1953980710] 'read index received' (duration: 213.774584ms)","trace[1953980710] 'applied index is now lower than readState.Index' (duration: 15.592935ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:55.116727Z","caller":"traceutil/trace.go:171","msg":"trace[220257422] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"477.390702ms","start":"2026-04-08T00:35:54.639304Z","end":"2026-04-08T00:35:55.116695Z","steps":["trace[220257422] 'process raft request' (duration: 461.680775ms)","trace[220257422] 'compare' (duration: 15.197358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:35:55.116738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.573713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:35:55.117135Z","caller":"traceutil/trace.go:171","msg":"trace[1879102971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"229.986121ms","start":"2026-04-08T00:35:54.887105Z","end":"2026-04-08T00:35:55.117091Z","steps":["trace[1879102971] 'agreement among raft nodes before linearized reading' (duration: 229.500232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:55.117346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:54.639278Z","time spent":"477.532756ms","remote":"127.0.0.1:57758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:36:09.960442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.096207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T00:36:09.960735Z","caller":"traceutil/trace.go:171","msg":"trace[526292890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:736; }","duration":"225.428923ms","start":"2026-04-08T00:36:09.735277Z","end":"2026-04-08T00:36:09.960706Z","steps":["trace[526292890] 'range keys from in-memory index tree' (duration: 224.933343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:09.961004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.715035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sqkdv\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-04-08T00:36:09.961059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.609711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T00:36:09.961115Z","caller":"traceutil/trace.go:171","msg":"trace[2045406455] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sqkdv; range_end:; response_count:1; response_revision:736; }","duration":"379.891119ms","start":"2026-04-08T00:36:09.581203Z","end":"2026-04-08T00:36:09.961094Z","steps":["trace[2045406455] 'range keys from in-memory index tree' (duration: 379.368299ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:09.961147Z","caller":"traceutil/trace.go:171","msg":"trace[780863775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"321.741443ms","start":"2026-04-08T00:36:09.639386Z","end":"2026-04-08T00:36:09.961127Z","steps":["trace[780863775] 'range keys from in-memory index tree' (duration: 321.394066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:09.961166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:09.58118Z","time spent":"379.97286ms","remote":"127.0.0.1:57398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sqkdv\" "} {"level":"warn","ts":"2026-04-08T00:36:09.961195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:09.639365Z","time spent":"321.820775ms","remote":"127.0.0.1:57496","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":"warn","ts":"2026-04-08T00:37:28.413474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.090548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-08T00:37:28.413501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.085785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:28.413614Z","caller":"traceutil/trace.go:171","msg":"trace[78073955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1808; }","duration":"122.252409ms","start":"2026-04-08T00:37:28.291337Z","end":"2026-04-08T00:37:28.41359Z","steps":["trace[78073955] 'range keys from in-memory index tree' (duration: 121.994323ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:28.41372Z","caller":"traceutil/trace.go:171","msg":"trace[814304939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1808; }","duration":"237.266091ms","start":"2026-04-08T00:37:28.176291Z","end":"2026-04-08T00:37:28.413557Z","steps":["trace[814304939] 'range keys from in-memory index tree' (duration: 236.889873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:28.413808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.085294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe1e12ed-8845-4443-b3ba-3a99e4190967\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-08T00:37:28.413945Z","caller":"traceutil/trace.go:171","msg":"trace[482019798] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe1e12ed-8845-4443-b3ba-3a99e4190967; range_end:; response_count:1; response_revision:1808; }","duration":"208.255907ms","start":"2026-04-08T00:37:28.205663Z","end":"2026-04-08T00:37:28.413918Z","steps":["trace[482019798] 'range keys from in-memory index tree' (duration: 207.761968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:28.85912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.477104ms","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":"warn","ts":"2026-04-08T00:37:28.859148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.236456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:37:28.859215Z","caller":"traceutil/trace.go:171","msg":"trace[446596569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1810; }","duration":"136.588966ms","start":"2026-04-08T00:37:28.722607Z","end":"2026-04-08T00:37:28.859196Z","steps":["trace[446596569] 'range keys from in-memory index tree' (duration: 136.28743ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:28.859252Z","caller":"traceutil/trace.go:171","msg":"trace[1012717163] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1810; }","duration":"115.40554ms","start":"2026-04-08T00:37:28.743824Z","end":"2026-04-08T00:37:28.85923Z","steps":["trace[1012717163] 'count revisions from in-memory index tree' (duration: 115.155725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:28.859464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.278488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:37:28.859509Z","caller":"traceutil/trace.go:171","msg":"trace[414830939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1810; }","duration":"217.38165ms","start":"2026-04-08T00:37:28.642117Z","end":"2026-04-08T00:37:28.859499Z","steps":["trace[414830939] 'range keys from in-memory index tree' (duration: 217.132856ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:07.257412Z","caller":"traceutil/trace.go:171","msg":"trace[1626233377] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2149; }","duration":"104.230471ms","start":"2026-04-08T00:38:07.153154Z","end":"2026-04-08T00:38:07.257385Z","steps":["trace[1626233377] 'read index received' (duration: 104.001986ms)","trace[1626233377] 'applied index is now lower than readState.Index' (duration: 227.225µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:07.25781Z","caller":"traceutil/trace.go:171","msg":"trace[910316356] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"134.316542ms","start":"2026-04-08T00:38:07.123481Z","end":"2026-04-08T00:38:07.257797Z","steps":["trace[910316356] 'process raft request' (duration: 133.751621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:07.258093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.929094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T00:38:07.258137Z","caller":"traceutil/trace.go:171","msg":"trace[263439280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2069; }","duration":"105.034026ms","start":"2026-04-08T00:38:07.153094Z","end":"2026-04-08T00:38:07.258128Z","steps":["trace[263439280] 'agreement among raft nodes before linearized reading' (duration: 104.889413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:19.110201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.162738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:38:19.110544Z","caller":"traceutil/trace.go:171","msg":"trace[132022612] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"233.017581ms","start":"2026-04-08T00:38:18.877451Z","end":"2026-04-08T00:38:19.110469Z","steps":["trace[132022612] 'process raft request' (duration: 92.821492ms)","trace[132022612] 'compare' (duration: 138.987265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:39:14.433184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.583046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:39:14.433291Z","caller":"traceutil/trace.go:171","msg":"trace[1446569857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2601; }","duration":"137.743379ms","start":"2026-04-08T00:39:14.295523Z","end":"2026-04-08T00:39:14.433266Z","steps":["trace[1446569857] 'range keys from in-memory index tree' (duration: 137.476415ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:39:43.617668Z","caller":"traceutil/trace.go:171","msg":"trace[1582880797] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"107.577074ms","start":"2026-04-08T00:39:43.510064Z","end":"2026-04-08T00:39:43.617641Z","steps":["trace[1582880797] 'process raft request' (duration: 106.904069ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:39:49.390767Z","caller":"traceutil/trace.go:171","msg":"trace[407515280] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"114.143954ms","start":"2026-04-08T00:39:49.276586Z","end":"2026-04-08T00:39:49.39073Z","steps":["trace[407515280] 'process raft request' (duration: 113.829128ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:43.190501Z","caller":"traceutil/trace.go:171","msg":"trace[749755155] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"116.75536ms","start":"2026-04-08T00:43:43.073717Z","end":"2026-04-08T00:43:43.190472Z","steps":["trace[749755155] 'process raft request' (duration: 116.513415ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:43.190775Z","caller":"traceutil/trace.go:171","msg":"trace[915858410] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4866; }","duration":"115.435763ms","start":"2026-04-08T00:43:43.075304Z","end":"2026-04-08T00:43:43.190739Z","steps":["trace[915858410] 'process raft request' (duration: 115.119097ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:48.248549Z","caller":"traceutil/trace.go:171","msg":"trace[439824647] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5010; }","duration":"145.112738ms","start":"2026-04-08T00:43:48.103411Z","end":"2026-04-08T00:43:48.248524Z","steps":["trace[439824647] 'process raft request' (duration: 139.971047ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:48.417094Z","caller":"traceutil/trace.go:171","msg":"trace[1297607003] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"148.636708ms","start":"2026-04-08T00:43:48.268425Z","end":"2026-04-08T00:43:48.417062Z","steps":["trace[1297607003] 'process raft request' (duration: 148.426004ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:48.665742Z","caller":"traceutil/trace.go:171","msg":"trace[1428647236] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"108.208191ms","start":"2026-04-08T00:43:48.557475Z","end":"2026-04-08T00:43:48.665683Z","steps":["trace[1428647236] 'process raft request' (duration: 107.600949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:59.287522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.616709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-mqds4\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-04-08T00:43:59.287649Z","caller":"traceutil/trace.go:171","msg":"trace[482130798] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-mqds4; range_end:; response_count:1; response_revision:5243; }","duration":"209.783482ms","start":"2026-04-08T00:43:59.077846Z","end":"2026-04-08T00:43:59.287629Z","steps":["trace[482130798] 'range keys from in-memory index tree' (duration: 209.442456ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:02.699725Z","caller":"traceutil/trace.go:171","msg":"trace[889822719] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"102.937248ms","start":"2026-04-08T00:44:02.596765Z","end":"2026-04-08T00:44:02.699703Z","steps":["trace[889822719] 'process raft request' (duration: 102.814196ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:07.641524Z","caller":"traceutil/trace.go:171","msg":"trace[391113990] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"126.197336ms","start":"2026-04-08T00:44:07.515292Z","end":"2026-04-08T00:44:07.641489Z","steps":["trace[391113990] 'process raft request' (duration: 118.520804ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:07.650219Z","caller":"traceutil/trace.go:171","msg":"trace[744846349] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"134.762974ms","start":"2026-04-08T00:44:07.515397Z","end":"2026-04-08T00:44:07.65016Z","steps":["trace[744846349] 'process raft request' (duration: 134.482239ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:07.908786Z","caller":"traceutil/trace.go:171","msg":"trace[1362637397] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"112.321623ms","start":"2026-04-08T00:44:07.796434Z","end":"2026-04-08T00:44:07.908755Z","steps":["trace[1362637397] 'process raft request' (duration: 112.101659ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:20.491184Z","caller":"traceutil/trace.go:171","msg":"trace[1933011459] transaction","detail":"{read_only:false; response_revision:5576; number_of_response:1; }","duration":"128.604465ms","start":"2026-04-08T00:44:20.362541Z","end":"2026-04-08T00:44:20.491145Z","steps":["trace[1933011459] 'process raft request' (duration: 128.311289ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:20.613728Z","caller":"traceutil/trace.go:171","msg":"trace[964231453] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"113.745423ms","start":"2026-04-08T00:44:20.499941Z","end":"2026-04-08T00:44:20.613687Z","steps":["trace[964231453] 'process raft request' (duration: 82.620879ms)","trace[964231453] 'compare' (duration: 30.93151ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:20.640021Z","caller":"traceutil/trace.go:171","msg":"trace[1426806965] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"139.344197ms","start":"2026-04-08T00:44:20.500645Z","end":"2026-04-08T00:44:20.639989Z","steps":["trace[1426806965] 'process raft request' (duration: 139.290056ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:20.640039Z","caller":"traceutil/trace.go:171","msg":"trace[856308147] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"139.889288ms","start":"2026-04-08T00:44:20.500096Z","end":"2026-04-08T00:44:20.639985Z","steps":["trace[856308147] 'process raft request' (duration: 139.581452ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:20.640162Z","caller":"traceutil/trace.go:171","msg":"trace[34464569] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"139.698364ms","start":"2026-04-08T00:44:20.500407Z","end":"2026-04-08T00:44:20.640106Z","steps":["trace[34464569] 'process raft request' (duration: 139.46253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:21.937887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.11584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:44:21.938144Z","caller":"traceutil/trace.go:171","msg":"trace[1647050183] linearizableReadLoop","detail":"{readStateIndex:5795; appliedIndex:5793; }","duration":"209.764095ms","start":"2026-04-08T00:44:21.728359Z","end":"2026-04-08T00:44:21.938123Z","steps":["trace[1647050183] 'read index received' (duration: 100.849978ms)","trace[1647050183] 'applied index is now lower than readState.Index' (duration: 108.912797ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:21.938444Z","caller":"traceutil/trace.go:171","msg":"trace[2025096689] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"381.120813ms","start":"2026-04-08T00:44:21.557284Z","end":"2026-04-08T00:44:21.938404Z","steps":["trace[2025096689] 'process raft request' (duration: 380.730436ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:21.9385Z","caller":"traceutil/trace.go:171","msg":"trace[1888250883] transaction","detail":"{read_only:false; response_revision:5587; number_of_response:1; }","duration":"384.455399ms","start":"2026-04-08T00:44:21.553978Z","end":"2026-04-08T00:44:21.938434Z","steps":["trace[1888250883] 'process raft request' (duration: 171.698266ms)","trace[1888250883] 'compare' (duration: 211.954057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:21.938606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.230195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-08T00:44:21.938659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:21.557271Z","time spent":"381.277087ms","remote":"127.0.0.1:57496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:44:21.93872Z","caller":"traceutil/trace.go:171","msg":"trace[1879551415] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5588; }","duration":"210.405109ms","start":"2026-04-08T00:44:21.728287Z","end":"2026-04-08T00:44:21.938692Z","steps":["trace[1879551415] 'agreement among raft nodes before linearized reading' (duration: 210.148703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:21.938813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:21.553948Z","time spent":"384.704804ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:44:21.938935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.993848ms","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-04-08T00:44:21.93897Z","caller":"traceutil/trace.go:171","msg":"trace[1726988550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5588; }","duration":"147.059779ms","start":"2026-04-08T00:44:21.791901Z","end":"2026-04-08T00:44:21.938961Z","steps":["trace[1726988550] 'agreement among raft nodes before linearized reading' (duration: 146.968837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:21.939159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.087262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T00:44:21.939269Z","caller":"traceutil/trace.go:171","msg":"trace[778256158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5588; }","duration":"210.265564ms","start":"2026-04-08T00:44:21.728977Z","end":"2026-04-08T00:44:21.939243Z","steps":["trace[778256158] 'agreement among raft nodes before linearized reading' (duration: 209.891287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:22.240496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.827886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:44:22.240662Z","caller":"traceutil/trace.go:171","msg":"trace[719683149] transaction","detail":"{read_only:false; response_revision:5589; number_of_response:1; }","duration":"296.831931ms","start":"2026-04-08T00:44:21.943797Z","end":"2026-04-08T00:44:22.240629Z","steps":["trace[719683149] 'process raft request' (duration: 119.75106ms)","trace[719683149] 'compare' (duration: 176.661563ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:22.26911Z","caller":"traceutil/trace.go:171","msg":"trace[1394247578] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"324.836293ms","start":"2026-04-08T00:44:21.944244Z","end":"2026-04-08T00:44:22.26908Z","steps":["trace[1394247578] 'process raft request' (duration: 324.613668ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:22.269287Z","caller":"traceutil/trace.go:171","msg":"trace[696035172] linearizableReadLoop","detail":"{readStateIndex:5798; appliedIndex:5795; }","duration":"324.118769ms","start":"2026-04-08T00:44:21.945142Z","end":"2026-04-08T00:44:22.269261Z","steps":["trace[696035172] 'read index received' (duration: 118.421684ms)","trace[696035172] 'applied index is now lower than readState.Index' (duration: 205.695065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:22.269303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:21.94422Z","time spent":"324.970646ms","remote":"127.0.0.1:57496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:44:22.269385Z","caller":"traceutil/trace.go:171","msg":"trace[1383919200] transaction","detail":"{read_only:false; response_revision:5591; number_of_response:1; }","duration":"325.069118ms","start":"2026-04-08T00:44:21.944278Z","end":"2026-04-08T00:44:22.269348Z","steps":["trace[1383919200] 'process raft request' (duration: 324.755701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:22.269625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.178141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-04-08T00:44:22.269599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.381814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T00:44:22.269699Z","caller":"traceutil/trace.go:171","msg":"trace[2043066125] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5591; }","duration":"115.298003ms","start":"2026-04-08T00:44:22.15438Z","end":"2026-04-08T00:44:22.269678Z","steps":["trace[2043066125] 'agreement among raft nodes before linearized reading' (duration: 115.057208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:22.269512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.283902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jtmll\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-08T00:44:22.269722Z","caller":"traceutil/trace.go:171","msg":"trace[1088231920] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5591; }","duration":"324.570769ms","start":"2026-04-08T00:44:21.945126Z","end":"2026-04-08T00:44:22.269697Z","steps":["trace[1088231920] 'agreement among raft nodes before linearized reading' (duration: 324.253522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:22.269781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:21.94512Z","time spent":"324.65125ms","remote":"127.0.0.1:57508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-08T00:44:22.269768Z","caller":"traceutil/trace.go:171","msg":"trace[1806916808] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jtmll; range_end:; response_count:1; response_revision:5591; }","duration":"191.596378ms","start":"2026-04-08T00:44:22.078158Z","end":"2026-04-08T00:44:22.269755Z","steps":["trace[1806916808] 'agreement among raft nodes before linearized reading' (duration: 191.2257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:22.269889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:44:21.94425Z","time spent":"325.423065ms","remote":"127.0.0.1:57496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:44:22.533267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.26389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:44:22.533379Z","caller":"traceutil/trace.go:171","msg":"trace[1569530449] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5591; }","duration":"161.447433ms","start":"2026-04-08T00:44:22.371905Z","end":"2026-04-08T00:44:22.533352Z","steps":["trace[1569530449] 'range keys from in-memory index tree' (duration: 161.117966ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:45:18.26774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2972} {"level":"info","ts":"2026-04-08T00:45:18.34124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2972,"took":"71.764655ms","hash":3061453180,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":33394688,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-08T00:45:18.341312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3061453180,"revision":2972,"compact-revision":-1} {"level":"info","ts":"2026-04-08T00:50:17.984885Z","caller":"traceutil/trace.go:171","msg":"trace[41909241] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"126.273596ms","start":"2026-04-08T00:50:17.858586Z","end":"2026-04-08T00:50:17.98486Z","steps":["trace[41909241] 'process raft request' (duration: 126.130984ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:18.274297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6046} {"level":"info","ts":"2026-04-08T00:50:18.367197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6046,"took":"90.927917ms","hash":269984945,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":23851008,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-08T00:50:18.36727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":269984945,"revision":6046,"compact-revision":2972} {"level":"info","ts":"2026-04-08T00:50:28.572183Z","caller":"traceutil/trace.go:171","msg":"trace[283700164] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8622; }","duration":"105.339102ms","start":"2026-04-08T00:50:28.466824Z","end":"2026-04-08T00:50:28.572163Z","steps":["trace[283700164] 'read index received' (duration: 105.132418ms)","trace[283700164] 'applied index is now lower than readState.Index' (duration: 205.874µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:28.572251Z","caller":"traceutil/trace.go:171","msg":"trace[2028647236] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"126.250786ms","start":"2026-04-08T00:50:28.445965Z","end":"2026-04-08T00:50:28.572216Z","steps":["trace[2028647236] 'process raft request' (duration: 126.071623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:28.572334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.492365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-667588cf4c-v97w2.18a43b59735f45a3\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-04-08T00:50:28.572381Z","caller":"traceutil/trace.go:171","msg":"trace[754987216] range","detail":"{range_begin:/registry/events/openstack/barbican-api-667588cf4c-v97w2.18a43b59735f45a3; range_end:; response_count:1; response_revision:8328; }","duration":"105.569146ms","start":"2026-04-08T00:50:28.466785Z","end":"2026-04-08T00:50:28.572354Z","steps":["trace[754987216] 'agreement among raft nodes before linearized reading' (duration: 105.446274ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:28.771447Z","caller":"traceutil/trace.go:171","msg":"trace[1916697014] transaction","detail":"{read_only:false; response_revision:8329; number_of_response:1; }","duration":"127.091643ms","start":"2026-04-08T00:50:28.644326Z","end":"2026-04-08T00:50:28.771418Z","steps":["trace[1916697014] 'process raft request' (duration: 126.94526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.371286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.811751ms","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-04-08T00:50:30.371336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.169694ms","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":"warn","ts":"2026-04-08T00:50:30.371323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.616227ms","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":"info","ts":"2026-04-08T00:50:30.371407Z","caller":"traceutil/trace.go:171","msg":"trace[1608839202] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8334; }","duration":"177.268865ms","start":"2026-04-08T00:50:30.19412Z","end":"2026-04-08T00:50:30.371389Z","steps":["trace[1608839202] 'count revisions from in-memory index tree' (duration: 177.117742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.37143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.66547ms","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-04-08T00:50:30.371446Z","caller":"traceutil/trace.go:171","msg":"trace[1650955026] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8334; }","duration":"157.796921ms","start":"2026-04-08T00:50:30.213622Z","end":"2026-04-08T00:50:30.371419Z","steps":["trace[1650955026] 'range keys from in-memory index tree' (duration: 157.518735ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:30.371501Z","caller":"traceutil/trace.go:171","msg":"trace[422293501] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8334; }","duration":"194.720291ms","start":"2026-04-08T00:50:30.176743Z","end":"2026-04-08T00:50:30.371463Z","steps":["trace[422293501] 'count revisions from in-memory index tree' (duration: 194.629029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.371576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.876274ms","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-04-08T00:50:30.371587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.678304ms","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":"info","ts":"2026-04-08T00:50:30.371613Z","caller":"traceutil/trace.go:171","msg":"trace[477945457] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8334; }","duration":"194.929295ms","start":"2026-04-08T00:50:30.176674Z","end":"2026-04-08T00:50:30.371603Z","steps":["trace[477945457] 'range keys from in-memory index tree' (duration: 194.839613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.371558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.329974ms","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":"info","ts":"2026-04-08T00:50:30.371649Z","caller":"traceutil/trace.go:171","msg":"trace[1399068140] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8334; }","duration":"176.750005ms","start":"2026-04-08T00:50:30.194881Z","end":"2026-04-08T00:50:30.371631Z","steps":["trace[1399068140] 'range keys from in-memory index tree' (duration: 176.615252ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:30.371681Z","caller":"traceutil/trace.go:171","msg":"trace[1649823115] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8334; }","duration":"194.491976ms","start":"2026-04-08T00:50:30.177161Z","end":"2026-04-08T00:50:30.371653Z","steps":["trace[1649823115] 'range keys from in-memory index tree' (duration: 194.269862ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:30.371369Z","caller":"traceutil/trace.go:171","msg":"trace[1462233491] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8334; }","duration":"158.932233ms","start":"2026-04-08T00:50:30.21242Z","end":"2026-04-08T00:50:30.371352Z","steps":["trace[1462233491] 'count revisions from in-memory index tree' (duration: 158.718148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.371344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.868882ms","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-04-08T00:50:30.371801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.557622ms","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-04-08T00:50:30.371885Z","caller":"traceutil/trace.go:171","msg":"trace[1095585709] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8334; }","duration":"177.688424ms","start":"2026-04-08T00:50:30.194174Z","end":"2026-04-08T00:50:30.371863Z","steps":["trace[1095585709] 'range keys from in-memory index tree' (duration: 177.459499ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:30.371859Z","caller":"traceutil/trace.go:171","msg":"trace[1689714208] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8334; }","duration":"159.384831ms","start":"2026-04-08T00:50:30.212412Z","end":"2026-04-08T00:50:30.371796Z","steps":["trace[1689714208] 'range keys from in-memory index tree' (duration: 158.77083ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:30.37218Z","caller":"traceutil/trace.go:171","msg":"trace[678012934] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"148.838862ms","start":"2026-04-08T00:50:30.223317Z","end":"2026-04-08T00:50:30.372156Z","steps":["trace[678012934] 'process raft request' (duration: 100.424455ms)","trace[678012934] 'compare' (duration: 47.636412ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:30.372263Z","caller":"traceutil/trace.go:171","msg":"trace[166860749] linearizableReadLoop","detail":"{readStateIndex:8632; appliedIndex:8630; }","duration":"143.089809ms","start":"2026-04-08T00:50:30.229158Z","end":"2026-04-08T00:50:30.372248Z","steps":["trace[166860749] 'read index received' (duration: 94.59817ms)","trace[166860749] 'applied index is now lower than readState.Index' (duration: 48.488569ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:30.37234Z","caller":"traceutil/trace.go:171","msg":"trace[1909902946] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"143.298053ms","start":"2026-04-08T00:50:30.229019Z","end":"2026-04-08T00:50:30.372317Z","steps":["trace[1909902946] 'process raft request' (duration: 143.037788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.372355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.186011ms","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":"info","ts":"2026-04-08T00:50:30.372404Z","caller":"traceutil/trace.go:171","msg":"trace[1953635719] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8336; }","duration":"143.247293ms","start":"2026-04-08T00:50:30.229142Z","end":"2026-04-08T00:50:30.37239Z","steps":["trace[1953635719] 'agreement among raft nodes before linearized reading' (duration: 143.174491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.372429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.221562ms","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-04-08T00:50:30.372467Z","caller":"traceutil/trace.go:171","msg":"trace[500020339] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8336; }","duration":"143.300483ms","start":"2026-04-08T00:50:30.229156Z","end":"2026-04-08T00:50:30.372457Z","steps":["trace[500020339] 'agreement among raft nodes before linearized reading' (duration: 143.209951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.372493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.72006ms","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-04-08T00:50:30.372536Z","caller":"traceutil/trace.go:171","msg":"trace[230454724] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8336; }","duration":"109.791861ms","start":"2026-04-08T00:50:30.262729Z","end":"2026-04-08T00:50:30.372521Z","steps":["trace[230454724] 'agreement among raft nodes before linearized reading' (duration: 109.72429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.372578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.934073ms","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":"info","ts":"2026-04-08T00:50:30.372606Z","caller":"traceutil/trace.go:171","msg":"trace[291099296] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8336; }","duration":"109.991235ms","start":"2026-04-08T00:50:30.262607Z","end":"2026-04-08T00:50:30.372598Z","steps":["trace[291099296] 'agreement among raft nodes before linearized reading' (duration: 109.944004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.372658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.015602ms","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":"warn","ts":"2026-04-08T00:50:30.372684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.855438ms","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":"info","ts":"2026-04-08T00:50:30.372729Z","caller":"traceutil/trace.go:171","msg":"trace[1899013165] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8336; }","duration":"126.091413ms","start":"2026-04-08T00:50:30.246621Z","end":"2026-04-08T00:50:30.372712Z","steps":["trace[1899013165] 'agreement among raft nodes before linearized reading' (duration: 125.971681ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:30.372731Z","caller":"traceutil/trace.go:171","msg":"trace[2139067489] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8336; }","duration":"125.909759ms","start":"2026-04-08T00:50:30.246809Z","end":"2026-04-08T00:50:30.372718Z","steps":["trace[2139067489] 'agreement among raft nodes before linearized reading' (duration: 125.827318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.37277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.932139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:50:30.3728Z","caller":"traceutil/trace.go:171","msg":"trace[271731499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8336; }","duration":"126.970861ms","start":"2026-04-08T00:50:30.245821Z","end":"2026-04-08T00:50:30.372792Z","steps":["trace[271731499] 'agreement among raft nodes before linearized reading' (duration: 126.854278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.37289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.711242ms","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-04-08T00:50:30.372931Z","caller":"traceutil/trace.go:171","msg":"trace[1116930305] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8336; }","duration":"142.754363ms","start":"2026-04-08T00:50:30.230169Z","end":"2026-04-08T00:50:30.372923Z","steps":["trace[1116930305] 'agreement among raft nodes before linearized reading' (duration: 142.698722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.372927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.008035ms","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":"info","ts":"2026-04-08T00:50:30.372967Z","caller":"traceutil/trace.go:171","msg":"trace[322885533] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8336; }","duration":"110.062896ms","start":"2026-04-08T00:50:30.262892Z","end":"2026-04-08T00:50:30.372955Z","steps":["trace[322885533] 'agreement among raft nodes before linearized reading' (duration: 110.006755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:30.372985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.329528ms","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":"info","ts":"2026-04-08T00:50:30.373041Z","caller":"traceutil/trace.go:171","msg":"trace[1419483252] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8336; }","duration":"126.409269ms","start":"2026-04-08T00:50:30.246617Z","end":"2026-04-08T00:50:30.373026Z","steps":["trace[1419483252] 'agreement among raft nodes before linearized reading' (duration: 126.319537ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:58.334784Z","caller":"traceutil/trace.go:171","msg":"trace[17178074] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"127.738486ms","start":"2026-04-08T00:50:58.206989Z","end":"2026-04-08T00:50:58.334728Z","steps":["trace[17178074] 'process raft request' (duration: 127.487961ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:51:00.805164Z","caller":"traceutil/trace.go:171","msg":"trace[583262218] linearizableReadLoop","detail":"{readStateIndex:8859; appliedIndex:8858; }","duration":"191.943944ms","start":"2026-04-08T00:51:00.613197Z","end":"2026-04-08T00:51:00.805141Z","steps":["trace[583262218] 'read index received' (duration: 103.022026ms)","trace[583262218] 'applied index is now lower than readState.Index' (duration: 88.920888ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:51:00.805183Z","caller":"traceutil/trace.go:171","msg":"trace[1047011993] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"192.195449ms","start":"2026-04-08T00:51:00.612946Z","end":"2026-04-08T00:51:00.805142Z","steps":["trace[1047011993] 'process raft request' (duration: 103.285212ms)","trace[1047011993] 'compare' (duration: 88.750984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:51:00.805441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.230041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jtmll\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-04-08T00:51:00.805449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.271982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T00:51:00.805475Z","caller":"traceutil/trace.go:171","msg":"trace[2112134926] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jtmll; range_end:; response_count:1; response_revision:8556; }","duration":"192.290742ms","start":"2026-04-08T00:51:00.613177Z","end":"2026-04-08T00:51:00.805468Z","steps":["trace[2112134926] 'agreement among raft nodes before linearized reading' (duration: 192.069778ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:51:00.80549Z","caller":"traceutil/trace.go:171","msg":"trace[345823064] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8556; }","duration":"189.348364ms","start":"2026-04-08T00:51:00.616127Z","end":"2026-04-08T00:51:00.805475Z","steps":["trace[345823064] 'agreement among raft nodes before linearized reading' (duration: 189.161261ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:51:10.868886Z","caller":"traceutil/trace.go:171","msg":"trace[475807350] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8651; }","duration":"176.04298ms","start":"2026-04-08T00:51:10.692814Z","end":"2026-04-08T00:51:10.868857Z","steps":["trace[475807350] 'process raft request' (duration: 127.361248ms)","trace[475807350] 'compare' (duration: 48.438368ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:51:10.868899Z","caller":"traceutil/trace.go:171","msg":"trace[819943892] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8959; }","duration":"123.492982ms","start":"2026-04-08T00:51:10.745378Z","end":"2026-04-08T00:51:10.868871Z","steps":["trace[819943892] 'read index received' (duration: 74.933262ms)","trace[819943892] 'applied index is now lower than readState.Index' (duration: 48.55763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:51:10.869148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.760907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s5ct6\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-04-08T00:51:10.869192Z","caller":"traceutil/trace.go:171","msg":"trace[1975840704] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s5ct6; range_end:; response_count:1; response_revision:8652; }","duration":"123.848979ms","start":"2026-04-08T00:51:10.745331Z","end":"2026-04-08T00:51:10.86918Z","steps":["trace[1975840704] 'agreement among raft nodes before linearized reading' (duration: 123.675305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:51:10.869426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.339832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s5ct6\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-04-08T00:51:10.870451Z","caller":"traceutil/trace.go:171","msg":"trace[1919131097] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s5ct6; range_end:; response_count:1; response_revision:8652; }","duration":"110.386342ms","start":"2026-04-08T00:51:10.760033Z","end":"2026-04-08T00:51:10.870419Z","steps":["trace[1919131097] 'agreement among raft nodes before linearized reading' (duration: 109.072086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:51:11.407615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.737288ms","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-04-08T00:51:11.407633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.898582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:51:11.407692Z","caller":"traceutil/trace.go:171","msg":"trace[1817684558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8656; }","duration":"115.8622ms","start":"2026-04-08T00:51:11.291807Z","end":"2026-04-08T00:51:11.40767Z","steps":["trace[1817684558] 'range keys from in-memory index tree' (duration: 115.526524ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:51:11.407734Z","caller":"traceutil/trace.go:171","msg":"trace[922448758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8656; }","duration":"117.048464ms","start":"2026-04-08T00:51:11.290658Z","end":"2026-04-08T00:51:11.407707Z","steps":["trace[922448758] 'range keys from in-memory index tree' (duration: 116.803908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:51:11.748643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.720945ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:51:11.748795Z","caller":"traceutil/trace.go:171","msg":"trace[849804418] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8657; }","duration":"173.898478ms","start":"2026-04-08T00:51:11.574875Z","end":"2026-04-08T00:51:11.748774Z","steps":["trace[849804418] 'range keys from in-memory index tree' (duration: 173.698774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:51:11.749099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.389665ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-08T00:51:11.801158Z","caller":"traceutil/trace.go:171","msg":"trace[1550593343] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"125.127244ms","start":"2026-04-08T00:51:11.676007Z","end":"2026-04-08T00:51:11.801134Z","steps":["trace[1550593343] 'process raft request' (duration: 124.978392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:51:12.710017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.427101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:51:12.710243Z","caller":"traceutil/trace.go:171","msg":"trace[1267930890] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"146.123489ms","start":"2026-04-08T00:51:12.56409Z","end":"2026-04-08T00:51:12.710214Z","steps":["trace[1267930890] 'process raft request' (duration: 22.411713ms)","trace[1267930890] 'compare' (duration: 123.328319ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:51:50.816834Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247d.wal"} {"level":"info","ts":"2026-04-08T00:52:09.409057Z","caller":"traceutil/trace.go:171","msg":"trace[452596428] transaction","detail":"{read_only:false; response_revision:9280; number_of_response:1; }","duration":"107.548498ms","start":"2026-04-08T00:52:09.301458Z","end":"2026-04-08T00:52:09.409006Z","steps":["trace[452596428] 'process raft request' (duration: 107.284303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:52:21.569706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.205017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-08T00:52:21.569819Z","caller":"traceutil/trace.go:171","msg":"trace[1606144531] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:9375; }","duration":"120.34798ms","start":"2026-04-08T00:52:21.449426Z","end":"2026-04-08T00:52:21.569774Z","steps":["trace[1606144531] 'range keys from in-memory index tree' (duration: 120.002284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:52:21.569868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.984596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:52:21.570109Z","caller":"traceutil/trace.go:171","msg":"trace[522719591] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:9375; }","duration":"112.287041ms","start":"2026-04-08T00:52:21.457797Z","end":"2026-04-08T00:52:21.570085Z","steps":["trace[522719591] 'count revisions from in-memory index tree' (duration: 111.845113ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:52:59.269614Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T00:52:59.28327Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T00:52:59.283361Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T00:55:18.281387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8277} {"level":"info","ts":"2026-04-08T00:55:18.359531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8277,"took":"74.885277ms","hash":844752267,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":26013696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-08T00:55:18.359595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":844752267,"revision":8277,"compact-revision":6046} {"level":"info","ts":"2026-04-08T01:00:18.287901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10677} {"level":"info","ts":"2026-04-08T01:00:18.364738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10677,"took":"74.588324ms","hash":6598155,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":20615168,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-08T01:00:18.364802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":6598155,"revision":10677,"compact-revision":8277}