{"level":"warn","ts":"2026-04-17T05:51:21.944393Z","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-17T05:51:21.94449Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-17T05:51:21.944556Z","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-17T05:51:21.944571Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-17T05:51:21.94461Z","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-17T05:51:21.945234Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-04-17T05:51:21.94532Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-17T05:51:21.948974Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.40403ms"} {"level":"info","ts":"2026-04-17T05:51:21.954495Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-04-17T05:51:21.954562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T05:51:21.954611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-04-17T05:51:21.954632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T05:51:21.954642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-04-17T05:51:21.954697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-04-17T05:51:21.960313Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T05:51:21.961728Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T05:51:21.962751Z","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-17T05:51:21.965194Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T05:51:21.96533Z","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-17T05:51:21.965412Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T05:51:21.965475Z","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-17T05:51:21.965492Z","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-17T05:51:21.969986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-04-17T05:51:21.970877Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-17T05:51:21.973119Z","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-17T05:51:21.973264Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-17T05:51:21.973295Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-17T05:51:21.97408Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T05:51:21.97413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T05:51:22.85547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T05:51:22.855531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T05:51:22.855555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-04-17T05:51:22.855574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-04-17T05:51:22.855586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-17T05:51:22.855602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-04-17T05:51:22.855616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-17T05:51:22.965522Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:51:22.966694Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T05:51:22.966757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:51:22.96715Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:51:22.967337Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T05:51:22.967402Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T05:51:22.966877Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:51:22.967882Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:51:22.967966Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:51:22.970078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-04-17T05:51:22.972072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T05:51:51.208131Z","caller":"traceutil/trace.go:171","msg":"trace[762138738] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"118.127001ms","start":"2026-04-17T05:51:51.089985Z","end":"2026-04-17T05:51:51.208112Z","steps":["trace[762138738] 'process raft request' (duration: 117.920368ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:51:51.208971Z","caller":"traceutil/trace.go:171","msg":"trace[1927320473] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:584; }","duration":"103.016848ms","start":"2026-04-17T05:51:51.105947Z","end":"2026-04-17T05:51:51.208963Z","steps":["trace[1927320473] 'read index received' (duration: 103.014488ms)","trace[1927320473] 'applied index is now lower than readState.Index' (duration: 1.94µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:51:51.209119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.142239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-17T05:51:51.209146Z","caller":"traceutil/trace.go:171","msg":"trace[1733165913] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:562; }","duration":"103.20505ms","start":"2026-04-17T05:51:51.105936Z","end":"2026-04-17T05:51:51.209141Z","steps":["trace[1733165913] 'agreement among raft nodes before linearized reading' (duration: 103.069018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:51:51.227032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.60908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:51:51.227103Z","caller":"traceutil/trace.go:171","msg":"trace[733521043] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:563; }","duration":"111.717211ms","start":"2026-04-17T05:51:51.115373Z","end":"2026-04-17T05:51:51.22709Z","steps":["trace[733521043] 'agreement among raft nodes before linearized reading' (duration: 111.569709ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:51:54.685431Z","caller":"traceutil/trace.go:171","msg":"trace[520196923] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"107.079061ms","start":"2026-04-17T05:51:54.578297Z","end":"2026-04-17T05:51:54.685376Z","steps":["trace[520196923] 'process raft request' (duration: 106.866158ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:51:54.803777Z","caller":"traceutil/trace.go:171","msg":"trace[1693520739] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"212.000061ms","start":"2026-04-17T05:51:54.591753Z","end":"2026-04-17T05:51:54.803753Z","steps":["trace[1693520739] 'process raft request' (duration: 157.778901ms)","trace[1693520739] 'compare' (duration: 53.880084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:51:57.862666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.255838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:51:57.862762Z","caller":"traceutil/trace.go:171","msg":"trace[468376045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"324.39785ms","start":"2026-04-17T05:51:57.538339Z","end":"2026-04-17T05:51:57.862737Z","steps":["trace[468376045] 'range keys from in-memory index tree' (duration: 324.162296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:51:57.862807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:51:57.538321Z","time spent":"324.476692ms","remote":"127.0.0.1:47750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T05:52:13.36968Z","caller":"traceutil/trace.go:171","msg":"trace[70600142] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"246.09578ms","start":"2026-04-17T05:52:13.123553Z","end":"2026-04-17T05:52:13.369649Z","steps":["trace[70600142] 'process raft request' (duration: 237.791723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:53:29.969633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.724927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-17T05:53:29.969695Z","caller":"traceutil/trace.go:171","msg":"trace[616320087] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1786; }","duration":"278.81407ms","start":"2026-04-17T05:53:29.690868Z","end":"2026-04-17T05:53:29.969682Z","steps":["trace[616320087] 'range keys from in-memory index tree' (duration: 278.609194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:53:29.969832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.499762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-17T05:53:29.969933Z","caller":"traceutil/trace.go:171","msg":"trace[1388099482] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1786; }","duration":"290.646947ms","start":"2026-04-17T05:53:29.679264Z","end":"2026-04-17T05:53:29.969911Z","steps":["trace[1388099482] 'range keys from in-memory index tree' (duration: 290.296506ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:53:29.969908Z","caller":"traceutil/trace.go:171","msg":"trace[1705951384] linearizableReadLoop","detail":"{readStateIndex:1841; appliedIndex:1840; }","duration":"209.084009ms","start":"2026-04-17T05:53:29.760795Z","end":"2026-04-17T05:53:29.969879Z","steps":["trace[1705951384] 'read index received' (duration: 204.374224ms)","trace[1705951384] 'applied index is now lower than readState.Index' (duration: 4.708465ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:53:29.969974Z","caller":"traceutil/trace.go:171","msg":"trace[517092826] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"216.46757ms","start":"2026-04-17T05:53:29.753489Z","end":"2026-04-17T05:53:29.969957Z","steps":["trace[517092826] 'process raft request' (duration: 211.614039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:53:29.970101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.269616ms","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-04-17T05:53:29.970156Z","caller":"traceutil/trace.go:171","msg":"trace[1650924426] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1787; }","duration":"209.386169ms","start":"2026-04-17T05:53:29.760755Z","end":"2026-04-17T05:53:29.970141Z","steps":["trace[1650924426] 'agreement among raft nodes before linearized reading' (duration: 209.261555ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:53:30.003233Z","caller":"traceutil/trace.go:171","msg":"trace[1970698866] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"201.356269ms","start":"2026-04-17T05:53:29.801857Z","end":"2026-04-17T05:53:30.003213Z","steps":["trace[1970698866] 'process raft request' (duration: 201.188464ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:53:30.003407Z","caller":"traceutil/trace.go:171","msg":"trace[1445348245] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"122.848876ms","start":"2026-04-17T05:53:29.880535Z","end":"2026-04-17T05:53:30.003384Z","steps":["trace[1445348245] 'process raft request' (duration: 122.617509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:53:30.003524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.304824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:53:30.003585Z","caller":"traceutil/trace.go:171","msg":"trace[1265956213] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1789; }","duration":"227.395228ms","start":"2026-04-17T05:53:29.776172Z","end":"2026-04-17T05:53:30.003567Z","steps":["trace[1265956213] 'agreement among raft nodes before linearized reading' (duration: 227.297464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:53:30.003703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.687925ms","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-17T05:53:30.003773Z","caller":"traceutil/trace.go:171","msg":"trace[556923528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1789; }","duration":"236.813219ms","start":"2026-04-17T05:53:29.766941Z","end":"2026-04-17T05:53:30.003754Z","steps":["trace[556923528] 'agreement among raft nodes before linearized reading' (duration: 236.579021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:53:30.003778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.353263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a475e74-9dad-4cc3-ac86-54d0c0f9c782\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-17T05:53:30.003823Z","caller":"traceutil/trace.go:171","msg":"trace[98587593] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a475e74-9dad-4cc3-ac86-54d0c0f9c782; range_end:; response_count:1; response_revision:1789; }","duration":"204.425215ms","start":"2026-04-17T05:53:29.799384Z","end":"2026-04-17T05:53:30.003809Z","steps":["trace[98587593] 'agreement among raft nodes before linearized reading' (duration: 204.290141ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:53:30.11557Z","caller":"traceutil/trace.go:171","msg":"trace[157005119] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"103.356911ms","start":"2026-04-17T05:53:30.012189Z","end":"2026-04-17T05:53:30.115546Z","steps":["trace[157005119] 'process raft request' (duration: 103.298589ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:53:30.11568Z","caller":"traceutil/trace.go:171","msg":"trace[374526676] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"107.210681ms","start":"2026-04-17T05:53:30.00843Z","end":"2026-04-17T05:53:30.115641Z","steps":["trace[374526676] 'process raft request' (duration: 98.47958ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:53:30.176203Z","caller":"traceutil/trace.go:171","msg":"trace[567246531] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"158.637029ms","start":"2026-04-17T05:53:30.017525Z","end":"2026-04-17T05:53:30.176162Z","steps":["trace[567246531] 'process raft request' (duration: 158.447303ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:53:38.46536Z","caller":"traceutil/trace.go:171","msg":"trace[236397458] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"143.985418ms","start":"2026-04-17T05:53:38.321351Z","end":"2026-04-17T05:53:38.465336Z","steps":["trace[236397458] 'read index received' (duration: 143.793962ms)","trace[236397458] 'applied index is now lower than readState.Index' (duration: 190.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:53:38.465437Z","caller":"traceutil/trace.go:171","msg":"trace[798388008] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"144.091461ms","start":"2026-04-17T05:53:38.321314Z","end":"2026-04-17T05:53:38.465406Z","steps":["trace[798388008] 'process raft request' (duration: 143.896465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:53:38.465585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.209985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-17T05:53:38.465624Z","caller":"traceutil/trace.go:171","msg":"trace[827411068] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1889; }","duration":"144.286116ms","start":"2026-04-17T05:53:38.321324Z","end":"2026-04-17T05:53:38.465611Z","steps":["trace[827411068] 'agreement among raft nodes before linearized reading' (duration: 144.135382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:07.322865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.267104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:54:07.323189Z","caller":"traceutil/trace.go:171","msg":"trace[1668364374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2098; }","duration":"248.640044ms","start":"2026-04-17T05:54:07.074525Z","end":"2026-04-17T05:54:07.323165Z","steps":["trace[1668364374] 'range keys from in-memory index tree' (duration: 248.060539ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:07.503325Z","caller":"traceutil/trace.go:171","msg":"trace[1135422161] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"175.543737ms","start":"2026-04-17T05:54:07.327758Z","end":"2026-04-17T05:54:07.503302Z","steps":["trace[1135422161] 'read index received' (duration: 175.436144ms)","trace[1135422161] 'applied index is now lower than readState.Index' (duration: 106.583µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:07.503639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.184077ms","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-17T05:54:07.503698Z","caller":"traceutil/trace.go:171","msg":"trace[1045145157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2099; }","duration":"175.270229ms","start":"2026-04-17T05:54:07.328407Z","end":"2026-04-17T05:54:07.503678Z","steps":["trace[1045145157] 'agreement among raft nodes before linearized reading' (duration: 175.066964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:07.504239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.454321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T05:54:07.504343Z","caller":"traceutil/trace.go:171","msg":"trace[225016314] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2099; }","duration":"176.610855ms","start":"2026-04-17T05:54:07.327711Z","end":"2026-04-17T05:54:07.504321Z","steps":["trace[225016314] 'agreement among raft nodes before linearized reading' (duration: 175.737562ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:07.504326Z","caller":"traceutil/trace.go:171","msg":"trace[1964801767] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"175.876035ms","start":"2026-04-17T05:54:07.327521Z","end":"2026-04-17T05:54:07.503397Z","steps":["trace[1964801767] 'process raft request' (duration: 175.624479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:07.674947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.100428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:07.675037Z","caller":"traceutil/trace.go:171","msg":"trace[1037041522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2100; }","duration":"138.247132ms","start":"2026-04-17T05:54:07.536771Z","end":"2026-04-17T05:54:07.675018Z","steps":["trace[1037041522] 'range keys from in-memory index tree' (duration: 137.992625ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:25.287036Z","caller":"traceutil/trace.go:171","msg":"trace[36052438] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"111.766834ms","start":"2026-04-17T05:55:25.175244Z","end":"2026-04-17T05:55:25.28701Z","steps":["trace[36052438] 'process raft request' (duration: 111.619621ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:45.373068Z","caller":"traceutil/trace.go:171","msg":"trace[1014222158] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"102.603545ms","start":"2026-04-17T05:57:45.27044Z","end":"2026-04-17T05:57:45.373044Z","steps":["trace[1014222158] 'process raft request' (duration: 102.493383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:45.537715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.50367ms","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-04-17T05:57:45.537828Z","caller":"traceutil/trace.go:171","msg":"trace[1956827360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4430; }","duration":"105.694033ms","start":"2026-04-17T05:57:45.432119Z","end":"2026-04-17T05:57:45.537813Z","steps":["trace[1956827360] 'range keys from in-memory index tree' (duration: 105.101303ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:57.158653Z","caller":"traceutil/trace.go:171","msg":"trace[230178781] linearizableReadLoop","detail":"{readStateIndex:4813; appliedIndex:4812; }","duration":"126.46184ms","start":"2026-04-17T05:57:57.032171Z","end":"2026-04-17T05:57:57.158633Z","steps":["trace[230178781] 'read index received' (duration: 103.81283ms)","trace[230178781] 'applied index is now lower than readState.Index' (duration: 22.64799ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:57.15875Z","caller":"traceutil/trace.go:171","msg":"trace[412008841] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"128.547998ms","start":"2026-04-17T05:57:57.030192Z","end":"2026-04-17T05:57:57.15874Z","steps":["trace[412008841] 'process raft request' (duration: 105.867877ms)","trace[412008841] 'compare' (duration: 22.309844ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:57:57.159096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.905278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-17T05:57:57.159182Z","caller":"traceutil/trace.go:171","msg":"trace[1324052623] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4614; }","duration":"126.996679ms","start":"2026-04-17T05:57:57.032165Z","end":"2026-04-17T05:57:57.159162Z","steps":["trace[1324052623] 'agreement among raft nodes before linearized reading' (duration: 126.795476ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:57.436743Z","caller":"traceutil/trace.go:171","msg":"trace[1195890190] linearizableReadLoop","detail":"{readStateIndex:4816; appliedIndex:4814; }","duration":"240.112098ms","start":"2026-04-17T05:57:57.196608Z","end":"2026-04-17T05:57:57.43672Z","steps":["trace[1195890190] 'read index received' (duration: 238.253874ms)","trace[1195890190] 'applied index is now lower than readState.Index' (duration: 1.857254ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:57.437093Z","caller":"traceutil/trace.go:171","msg":"trace[918856966] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"270.232444ms","start":"2026-04-17T05:57:57.166831Z","end":"2026-04-17T05:57:57.437063Z","steps":["trace[918856966] 'process raft request' (duration: 268.093526ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:57.437344Z","caller":"traceutil/trace.go:171","msg":"trace[868544543] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"264.754345ms","start":"2026-04-17T05:57:57.172581Z","end":"2026-04-17T05:57:57.437335Z","steps":["trace[868544543] 'process raft request' (duration: 264.079943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:57.437439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.268563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-17T05:57:57.437518Z","caller":"traceutil/trace.go:171","msg":"trace[126263499] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4617; }","duration":"207.379235ms","start":"2026-04-17T05:57:57.230118Z","end":"2026-04-17T05:57:57.437497Z","steps":["trace[126263499] 'agreement among raft nodes before linearized reading' (duration: 207.142031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:57.437668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.056485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-17T05:57:57.437704Z","caller":"traceutil/trace.go:171","msg":"trace[1087099002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4617; }","duration":"241.142346ms","start":"2026-04-17T05:57:57.196552Z","end":"2026-04-17T05:57:57.437695Z","steps":["trace[1087099002] 'agreement among raft nodes before linearized reading' (duration: 241.029965ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:22.994373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3214} {"level":"info","ts":"2026-04-17T06:01:23.088268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3214,"took":"92.842292ms","hash":1332185353,"current-db-size-bytes":59183104,"current-db-size":"59 MB","current-db-size-in-use-bytes":32358400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-17T06:01:23.088372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1332185353,"revision":3214,"compact-revision":-1} {"level":"info","ts":"2026-04-17T06:01:23.682455Z","caller":"traceutil/trace.go:171","msg":"trace[73315791] transaction","detail":"{read_only:false; response_revision:6432; number_of_response:1; }","duration":"128.311281ms","start":"2026-04-17T06:01:23.554121Z","end":"2026-04-17T06:01:23.682432Z","steps":["trace[73315791] 'process raft request' (duration: 127.822533ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:23.682577Z","caller":"traceutil/trace.go:171","msg":"trace[296944463] transaction","detail":"{read_only:false; response_revision:6433; number_of_response:1; }","duration":"127.536982ms","start":"2026-04-17T06:01:23.555033Z","end":"2026-04-17T06:01:23.68257Z","steps":["trace[296944463] 'process raft request' (duration: 127.016243ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:23.6827Z","caller":"traceutil/trace.go:171","msg":"trace[609351577] transaction","detail":"{read_only:false; response_revision:6434; number_of_response:1; }","duration":"115.292763ms","start":"2026-04-17T06:01:23.5674Z","end":"2026-04-17T06:01:23.682693Z","steps":["trace[609351577] 'process raft request' (duration: 114.67475ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:43.624661Z","caller":"traceutil/trace.go:171","msg":"trace[594851510] transaction","detail":"{read_only:false; response_revision:6618; number_of_response:1; }","duration":"127.4078ms","start":"2026-04-17T06:01:43.497235Z","end":"2026-04-17T06:01:43.624643Z","steps":["trace[594851510] 'process raft request' (duration: 127.043297ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:01:54.240239Z","caller":"traceutil/trace.go:171","msg":"trace[63767866] transaction","detail":"{read_only:false; response_revision:6688; number_of_response:1; }","duration":"132.27125ms","start":"2026-04-17T06:01:54.107931Z","end":"2026-04-17T06:01:54.240203Z","steps":["trace[63767866] 'process raft request' (duration: 116.878215ms)","trace[63767866] 'compare' (duration: 14.530244ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:01:54.241344Z","caller":"traceutil/trace.go:171","msg":"trace[1971182919] transaction","detail":"{read_only:false; response_revision:6689; number_of_response:1; }","duration":"132.399404ms","start":"2026-04-17T06:01:54.108918Z","end":"2026-04-17T06:01:54.241318Z","steps":["trace[1971182919] 'process raft request' (duration: 132.315981ms)"],"step_count":1}