{"level":"warn","ts":"2026-04-11T17:46:32.37663Z","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-11T17:46:32.376773Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.117:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.117:2380","--initial-cluster=instance=https://199.19.213.117:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.117:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.117: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-11T17:46:32.376909Z","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-11T17:46:32.376929Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-11T17:46:32.376982Z","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-11T17:46:32.377861Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"]} {"level":"info","ts":"2026-04-11T17:46:32.378099Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.117: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-11T17:46:32.3822Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.796324ms"} {"level":"info","ts":"2026-04-11T17:46:32.393534Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f9f905057b5b268","cluster-id":"293172f52aeb3f03"} {"level":"info","ts":"2026-04-11T17:46:32.393622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:46:32.393648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 0"} {"level":"info","ts":"2026-04-11T17:46:32.39367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f9f905057b5b268 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:46:32.39368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 1"} {"level":"info","ts":"2026-04-11T17:46:32.393795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"warn","ts":"2026-04-11T17:46:32.402169Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:46:32.403777Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:46:32.405291Z","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-11T17:46:32.407663Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f9f905057b5b268","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:46:32.407798Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f9f905057b5b268","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:46:32.407911Z","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-11T17:46:32.408053Z","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-11T17:46:32.408068Z","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-11T17:46:32.409208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"info","ts":"2026-04-11T17:46:32.409391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","added-peer-id":"5f9f905057b5b268","added-peer-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-11T17:46:32.412511Z","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-11T17:46:32.412752Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f9f905057b5b268","initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:46:32.412819Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:46:32.412785Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-11T17:46:32.412997Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-11T17:46:32.894599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:46:32.894656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:46:32.894697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgPreVoteResp from 5f9f905057b5b268 at term 1"} {"level":"info","ts":"2026-04-11T17:46:32.894712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:46:32.89472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgVoteResp from 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-11T17:46:32.894741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became leader at term 2"} {"level":"info","ts":"2026-04-11T17:46:32.894751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f9f905057b5b268 elected leader 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-11T17:46:32.895746Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:46:32.896626Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:46:32.896627Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f9f905057b5b268","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.117:2379]}","request-path":"/0/members/5f9f905057b5b268/attributes","cluster-id":"293172f52aeb3f03","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:46:32.896924Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:46:32.89743Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:46:32.897491Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:46:32.897472Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:46:32.897827Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:46:32.897891Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:46:32.899932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.117:2379"} {"level":"info","ts":"2026-04-11T17:46:32.900412Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:47:10.762394Z","caller":"traceutil/trace.go:171","msg":"trace[427486726] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"229.157382ms","start":"2026-04-11T17:47:10.533216Z","end":"2026-04-11T17:47:10.762373Z","steps":["trace[427486726] 'read index received' (duration: 228.426991ms)","trace[427486726] 'applied index is now lower than readState.Index' (duration: 729.34µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:10.76253Z","caller":"traceutil/trace.go:171","msg":"trace[420717148] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"245.42036ms","start":"2026-04-11T17:47:10.517076Z","end":"2026-04-11T17:47:10.762496Z","steps":["trace[420717148] 'process raft request' (duration: 244.843574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:10.762571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.339907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:10.762651Z","caller":"traceutil/trace.go:171","msg":"trace[1416975478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"229.482501ms","start":"2026-04-11T17:47:10.533159Z","end":"2026-04-11T17:47:10.762641Z","steps":["trace[1416975478] 'agreement among raft nodes before linearized reading' (duration: 229.359228ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:10.934598Z","caller":"traceutil/trace.go:171","msg":"trace[619139008] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"154.919297ms","start":"2026-04-11T17:47:10.779654Z","end":"2026-04-11T17:47:10.934573Z","steps":["trace[619139008] 'process raft request' (duration: 154.752603ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:10.971734Z","caller":"traceutil/trace.go:171","msg":"trace[147518123] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"149.822376ms","start":"2026-04-11T17:47:10.821883Z","end":"2026-04-11T17:47:10.971705Z","steps":["trace[147518123] 'process raft request' (duration: 149.680892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:11.421633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.830048ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-11T17:47:11.421741Z","caller":"traceutil/trace.go:171","msg":"trace[2128014362] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"438.665652ms","start":"2026-04-11T17:47:10.983057Z","end":"2026-04-11T17:47:11.421723Z","steps":["trace[2128014362] 'read index received' (duration: 24.493854ms)","trace[2128014362] 'applied index is now lower than readState.Index' (duration: 414.170018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:11.421808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.742164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:11.421841Z","caller":"traceutil/trace.go:171","msg":"trace[1630345013] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:623; }","duration":"438.816836ms","start":"2026-04-11T17:47:10.983015Z","end":"2026-04-11T17:47:11.421832Z","steps":["trace[1630345013] 'agreement among raft nodes before linearized reading' (duration: 438.753054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:11.421874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:10.982995Z","time spent":"438.871338ms","remote":"127.0.0.1:46750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-11T17:47:26.699203Z","caller":"traceutil/trace.go:171","msg":"trace[833606365] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"130.34183ms","start":"2026-04-11T17:47:26.568411Z","end":"2026-04-11T17:47:26.698753Z","steps":["trace[833606365] 'process raft request' (duration: 130.123004ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:37.301013Z","caller":"traceutil/trace.go:171","msg":"trace[255776255] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"146.194466ms","start":"2026-04-11T17:48:37.154789Z","end":"2026-04-11T17:48:37.300984Z","steps":["trace[255776255] 'process raft request' (duration: 146.032651ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:37.30906Z","caller":"traceutil/trace.go:171","msg":"trace[1413664726] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"106.383592ms","start":"2026-04-11T17:48:37.202648Z","end":"2026-04-11T17:48:37.309032Z","steps":["trace[1413664726] 'process raft request' (duration: 106.230788ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:50.166318Z","caller":"traceutil/trace.go:171","msg":"trace[1209633205] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"111.877873ms","start":"2026-04-11T17:48:50.05442Z","end":"2026-04-11T17:48:50.166298Z","steps":["trace[1209633205] 'process raft request' (duration: 111.75414ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:53.87616Z","caller":"traceutil/trace.go:171","msg":"trace[43873386] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"177.29694ms","start":"2026-04-11T17:48:53.69883Z","end":"2026-04-11T17:48:53.876127Z","steps":["trace[43873386] 'process raft request' (duration: 177.074144ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:53.878463Z","caller":"traceutil/trace.go:171","msg":"trace[1850735893] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2040; }","duration":"115.801561ms","start":"2026-04-11T17:48:53.762624Z","end":"2026-04-11T17:48:53.878426Z","steps":["trace[1850735893] 'read index received' (duration: 113.283642ms)","trace[1850735893] 'applied index is now lower than readState.Index' (duration: 2.516269ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:53.878505Z","caller":"traceutil/trace.go:171","msg":"trace[2039993085] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"162.673679ms","start":"2026-04-11T17:48:53.715807Z","end":"2026-04-11T17:48:53.87848Z","steps":["trace[2039993085] 'process raft request' (duration: 162.452483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:53.878723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.081928ms","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-11T17:48:53.87878Z","caller":"traceutil/trace.go:171","msg":"trace[1104064317] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1964; }","duration":"116.177821ms","start":"2026-04-11T17:48:53.762584Z","end":"2026-04-11T17:48:53.878762Z","steps":["trace[1104064317] 'agreement among raft nodes before linearized reading' (duration: 115.963595ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:26.598433Z","caller":"traceutil/trace.go:171","msg":"trace[1766092381] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"129.711074ms","start":"2026-04-11T17:49:26.468679Z","end":"2026-04-11T17:49:26.598391Z","steps":["trace[1766092381] 'process raft request' (duration: 129.462257ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:03.913741Z","caller":"traceutil/trace.go:171","msg":"trace[782431260] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"114.166456ms","start":"2026-04-11T17:50:03.799534Z","end":"2026-04-11T17:50:03.9137Z","steps":["trace[782431260] 'process raft request' (duration: 113.9333ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:03.914169Z","caller":"traceutil/trace.go:171","msg":"trace[1568337880] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"112.777759ms","start":"2026-04-11T17:50:03.801369Z","end":"2026-04-11T17:50:03.914147Z","steps":["trace[1568337880] 'process raft request' (duration: 112.676336ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:34.175006Z","caller":"traceutil/trace.go:171","msg":"trace[192255430] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"115.687975ms","start":"2026-04-11T17:50:34.059288Z","end":"2026-04-11T17:50:34.174976Z","steps":["trace[192255430] 'process raft request' (duration: 114.180883ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:31.971806Z","caller":"traceutil/trace.go:171","msg":"trace[5063640] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"182.043344ms","start":"2026-04-11T17:51:31.789716Z","end":"2026-04-11T17:51:31.971759Z","steps":["trace[5063640] 'process raft request' (duration: 181.875349ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:53:10.351743Z","caller":"traceutil/trace.go:171","msg":"trace[164861810] transaction","detail":"{read_only:false; response_revision:3570; number_of_response:1; }","duration":"240.747444ms","start":"2026-04-11T17:53:10.110967Z","end":"2026-04-11T17:53:10.351714Z","steps":["trace[164861810] 'process raft request' (duration: 240.084386ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:54:42.258041Z","caller":"traceutil/trace.go:171","msg":"trace[1339363809] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"231.564544ms","start":"2026-04-11T17:54:42.026437Z","end":"2026-04-11T17:54:42.258002Z","steps":["trace[1339363809] 'process raft request' (duration: 231.252146ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:54:42.292218Z","caller":"traceutil/trace.go:171","msg":"trace[1010417623] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"264.265142ms","start":"2026-04-11T17:54:42.027912Z","end":"2026-04-11T17:54:42.292178Z","steps":["trace[1010417623] 'process raft request' (duration: 263.986975ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:55:01.876668Z","caller":"traceutil/trace.go:171","msg":"trace[1093050561] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"122.332727ms","start":"2026-04-11T17:55:01.754313Z","end":"2026-04-11T17:55:01.876645Z","steps":["trace[1093050561] 'process raft request' (duration: 122.192864ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:55:02.024228Z","caller":"traceutil/trace.go:171","msg":"trace[751096774] linearizableReadLoop","detail":"{readStateIndex:5384; appliedIndex:5383; }","duration":"116.035344ms","start":"2026-04-11T17:55:01.908172Z","end":"2026-04-11T17:55:02.024207Z","steps":["trace[751096774] 'read index received' (duration: 73.092425ms)","trace[751096774] 'applied index is now lower than readState.Index' (duration: 42.941739ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:55:02.024297Z","caller":"traceutil/trace.go:171","msg":"trace[729568050] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"125.938166ms","start":"2026-04-11T17:55:01.898326Z","end":"2026-04-11T17:55:02.024264Z","steps":["trace[729568050] 'process raft request' (duration: 125.752591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:55:02.024438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.23112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49c54645-91d1-4d88-a2bb-7afb117037c5\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-11T17:55:02.024476Z","caller":"traceutil/trace.go:171","msg":"trace[2042131686] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49c54645-91d1-4d88-a2bb-7afb117037c5; range_end:; response_count:1; response_revision:5187; }","duration":"116.359433ms","start":"2026-04-11T17:55:01.908107Z","end":"2026-04-11T17:55:02.024466Z","steps":["trace[2042131686] 'agreement among raft nodes before linearized reading' (duration: 116.197299ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:55:50.18714Z","caller":"traceutil/trace.go:171","msg":"trace[1974344365] transaction","detail":"{read_only:false; response_revision:5674; number_of_response:1; }","duration":"113.422034ms","start":"2026-04-11T17:55:50.07368Z","end":"2026-04-11T17:55:50.187102Z","steps":["trace[1974344365] 'process raft request' (duration: 78.272519ms)","trace[1974344365] 'compare' (duration: 34.95943ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:56:32.925984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2874} {"level":"info","ts":"2026-04-11T17:56:32.985102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2874,"took":"57.602031ms","hash":1661493272,"current-db-size-bytes":57409536,"current-db-size":"57 MB","current-db-size-in-use-bytes":33337344,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-11T17:56:32.98516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1661493272,"revision":2874,"compact-revision":-1} {"level":"info","ts":"2026-04-11T18:00:53.332021Z","caller":"traceutil/trace.go:171","msg":"trace[1117553285] transaction","detail":"{read_only:false; response_revision:7946; number_of_response:1; }","duration":"130.522842ms","start":"2026-04-11T18:00:53.201475Z","end":"2026-04-11T18:00:53.331998Z","steps":["trace[1117553285] 'process raft request' (duration: 130.369478ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:00:53.440943Z","caller":"traceutil/trace.go:171","msg":"trace[1324039862] linearizableReadLoop","detail":"{readStateIndex:8230; appliedIndex:8229; }","duration":"214.743103ms","start":"2026-04-11T18:00:53.226173Z","end":"2026-04-11T18:00:53.440916Z","steps":["trace[1324039862] 'read index received' (duration: 105.831234ms)","trace[1324039862] 'applied index is now lower than readState.Index' (duration: 108.910529ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:00:53.441118Z","caller":"traceutil/trace.go:171","msg":"trace[955373385] transaction","detail":"{read_only:false; response_revision:7947; number_of_response:1; }","duration":"234.585739ms","start":"2026-04-11T18:00:53.206485Z","end":"2026-04-11T18:00:53.441071Z","steps":["trace[955373385] 'process raft request' (duration: 229.51226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:00:53.441182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.998631ms","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-11T18:00:53.44122Z","caller":"traceutil/trace.go:171","msg":"trace[832720318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7947; }","duration":"215.113684ms","start":"2026-04-11T18:00:53.226096Z","end":"2026-04-11T18:00:53.44121Z","steps":["trace[832720318] 'agreement among raft nodes before linearized reading' (duration: 214.919659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:06.313084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.525156ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-04-11T18:01:06.313196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T18:01:06.005092Z","time spent":"308.100321ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-11T18:01:06.335022Z","caller":"traceutil/trace.go:171","msg":"trace[2359137] transaction","detail":"{read_only:false; response_revision:8012; number_of_response:1; }","duration":"325.87742ms","start":"2026-04-11T18:01:06.009117Z","end":"2026-04-11T18:01:06.334994Z","steps":["trace[2359137] 'process raft request' (duration: 325.702505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:06.335612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T18:01:06.0091Z","time spent":"325.997723ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T18:01:06.350497Z","caller":"traceutil/trace.go:171","msg":"trace[1066390335] linearizableReadLoop","detail":"{readStateIndex:8299; appliedIndex:8297; }","duration":"201.37588ms","start":"2026-04-11T18:01:06.1491Z","end":"2026-04-11T18:01:06.350476Z","steps":["trace[1066390335] 'read index received' (duration: 185.690459ms)","trace[1066390335] 'applied index is now lower than readState.Index' (duration: 15.681881ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:01:06.350576Z","caller":"traceutil/trace.go:171","msg":"trace[444277197] transaction","detail":"{read_only:false; response_revision:8013; number_of_response:1; }","duration":"243.905638ms","start":"2026-04-11T18:01:06.106613Z","end":"2026-04-11T18:01:06.350519Z","steps":["trace[444277197] 'process raft request' (duration: 243.680122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:06.350719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.615767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-11T18:01:06.350767Z","caller":"traceutil/trace.go:171","msg":"trace[1255606702] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8014; }","duration":"201.7265ms","start":"2026-04-11T18:01:06.149028Z","end":"2026-04-11T18:01:06.350755Z","steps":["trace[1255606702] 'agreement among raft nodes before linearized reading' (duration: 201.542645ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:01:32.931742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5991} {"level":"info","ts":"2026-04-11T18:01:33.036357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5991,"took":"102.838144ms","hash":2005399978,"current-db-size-bytes":57409536,"current-db-size":"57 MB","current-db-size-in-use-bytes":22728704,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-11T18:01:33.036444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2005399978,"revision":5991,"compact-revision":2874} {"level":"info","ts":"2026-04-11T18:01:33.927171Z","caller":"traceutil/trace.go:171","msg":"trace[1414532725] linearizableReadLoop","detail":"{readStateIndex:8510; appliedIndex:8509; }","duration":"221.74683ms","start":"2026-04-11T18:01:33.705388Z","end":"2026-04-11T18:01:33.927135Z","steps":["trace[1414532725] 'read index received' (duration: 221.475602ms)","trace[1414532725] 'applied index is now lower than readState.Index' (duration: 264.817µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:01:33.927758Z","caller":"traceutil/trace.go:171","msg":"trace[1797265472] transaction","detail":"{read_only:false; response_revision:8216; number_of_response:1; }","duration":"264.683989ms","start":"2026-04-11T18:01:33.663021Z","end":"2026-04-11T18:01:33.927705Z","steps":["trace[1797265472] 'process raft request' (duration: 263.887057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:33.927858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.812957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T18:01:33.92796Z","caller":"traceutil/trace.go:171","msg":"trace[1528656863] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8216; }","duration":"136.974632ms","start":"2026-04-11T18:01:33.790972Z","end":"2026-04-11T18:01:33.927946Z","steps":["trace[1528656863] 'agreement among raft nodes before linearized reading' (duration: 136.737815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:33.928406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.992625ms","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-11T18:01:33.928512Z","caller":"traceutil/trace.go:171","msg":"trace[1069756252] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8216; }","duration":"223.116898ms","start":"2026-04-11T18:01:33.705373Z","end":"2026-04-11T18:01:33.928489Z","steps":["trace[1069756252] 'agreement among raft nodes before linearized reading' (duration: 222.81714ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:01:46.609464Z","caller":"traceutil/trace.go:171","msg":"trace[970043005] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8321; }","duration":"100.091978ms","start":"2026-04-11T18:01:46.509345Z","end":"2026-04-11T18:01:46.609437Z","steps":["trace[970043005] 'process raft request' (duration: 87.088161ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:01:46.756269Z","caller":"traceutil/trace.go:171","msg":"trace[1645334453] linearizableReadLoop","detail":"{readStateIndex:8626; appliedIndex:8624; }","duration":"138.659638ms","start":"2026-04-11T18:01:46.617576Z","end":"2026-04-11T18:01:46.756236Z","steps":["trace[1645334453] 'read index received' (duration: 18.836997ms)","trace[1645334453] 'applied index is now lower than readState.Index' (duration: 119.820501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:01:46.756549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.949586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mgvxm\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-04-11T18:01:46.756428Z","caller":"traceutil/trace.go:171","msg":"trace[1134047101] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"139.155681ms","start":"2026-04-11T18:01:46.617215Z","end":"2026-04-11T18:01:46.756371Z","steps":["trace[1134047101] 'process raft request' (duration: 126.833383ms)","trace[1134047101] 'compare' (duration: 12.059591ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:01:46.756609Z","caller":"traceutil/trace.go:171","msg":"trace[1959944048] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mgvxm; range_end:; response_count:1; response_revision:8325; }","duration":"139.047518ms","start":"2026-04-11T18:01:46.617542Z","end":"2026-04-11T18:01:46.75659Z","steps":["trace[1959944048] 'agreement among raft nodes before linearized reading' (duration: 138.861133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:46.756668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.255552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-11T18:01:46.756738Z","caller":"traceutil/trace.go:171","msg":"trace[1505311744] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8325; }","duration":"128.409577ms","start":"2026-04-11T18:01:46.628307Z","end":"2026-04-11T18:01:46.756717Z","steps":["trace[1505311744] 'agreement among raft nodes before linearized reading' (duration: 128.15108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:47.362431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.512268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T18:01:47.362542Z","caller":"traceutil/trace.go:171","msg":"trace[836637311] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:8328; }","duration":"105.716103ms","start":"2026-04-11T18:01:47.256801Z","end":"2026-04-11T18:01:47.362517Z","steps":["trace[836637311] 'count revisions from in-memory index tree' (duration: 105.369143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:47.362782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.16795ms","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-11T18:01:47.362884Z","caller":"traceutil/trace.go:171","msg":"trace[171915556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8328; }","duration":"262.338704ms","start":"2026-04-11T18:01:47.100521Z","end":"2026-04-11T18:01:47.36286Z","steps":["trace[171915556] 'range keys from in-memory index tree' (duration: 261.925214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:01:47.362829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.27338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-11T18:01:47.362978Z","caller":"traceutil/trace.go:171","msg":"trace[940181073] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8328; }","duration":"192.456526ms","start":"2026-04-11T18:01:47.170505Z","end":"2026-04-11T18:01:47.362961Z","steps":["trace[940181073] 'range keys from in-memory index tree' (duration: 192.085065ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:01:47.542614Z","caller":"traceutil/trace.go:171","msg":"trace[697656416] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"162.819081ms","start":"2026-04-11T18:01:47.379768Z","end":"2026-04-11T18:01:47.542587Z","steps":["trace[697656416] 'process raft request' (duration: 162.76286ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:01:47.542794Z","caller":"traceutil/trace.go:171","msg":"trace[366361627] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"163.524181ms","start":"2026-04-11T18:01:47.379256Z","end":"2026-04-11T18:01:47.542781Z","steps":["trace[366361627] 'process raft request' (duration: 88.789009ms)","trace[366361627] 'compare' (duration: 74.314511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:01:48.594234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.338805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T18:01:48.594534Z","caller":"traceutil/trace.go:171","msg":"trace[617106527] transaction","detail":"{read_only:false; response_revision:8337; number_of_response:1; }","duration":"321.061938ms","start":"2026-04-11T18:01:48.273435Z","end":"2026-04-11T18:01:48.594497Z","steps":["trace[617106527] 'process raft request' (duration: 157.368252ms)","trace[617106527] 'compare' (duration: 163.220773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:01:48.594679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T18:01:48.273413Z","time spent":"321.18687ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T18:02:52.174604Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002438.wal"} {"level":"info","ts":"2026-04-11T18:02:56.002725Z","caller":"traceutil/trace.go:171","msg":"trace[892747264] transaction","detail":"{read_only:false; response_revision:8970; number_of_response:1; }","duration":"107.863672ms","start":"2026-04-11T18:02:55.894833Z","end":"2026-04-11T18:02:56.002696Z","steps":["trace[892747264] 'process raft request' (duration: 107.619466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:56.185502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.142871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T18:02:56.185617Z","caller":"traceutil/trace.go:171","msg":"trace[1210842656] linearizableReadLoop","detail":"{readStateIndex:9290; appliedIndex:9288; }","duration":"194.752838ms","start":"2026-04-11T18:02:55.990846Z","end":"2026-04-11T18:02:56.185599Z","steps":["trace[1210842656] 'read index received' (duration: 11.66148ms)","trace[1210842656] 'applied index is now lower than readState.Index' (duration: 183.089888ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:02:56.185731Z","caller":"traceutil/trace.go:171","msg":"trace[1199143615] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"260.71525ms","start":"2026-04-11T18:02:55.925004Z","end":"2026-04-11T18:02:56.185719Z","steps":["trace[1199143615] 'process raft request' (duration: 129.275531ms)","trace[1199143615] 'compare' (duration: 130.886524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:02:56.186173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.312244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-11T18:02:56.186263Z","caller":"traceutil/trace.go:171","msg":"trace[275866590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8971; }","duration":"195.462038ms","start":"2026-04-11T18:02:55.990777Z","end":"2026-04-11T18:02:56.186239Z","steps":["trace[275866590] 'agreement among raft nodes before linearized reading' (duration: 195.200701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:56.18657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.433335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-11T18:02:56.186649Z","caller":"traceutil/trace.go:171","msg":"trace[1446785077] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:8971; }","duration":"188.520088ms","start":"2026-04-11T18:02:55.998111Z","end":"2026-04-11T18:02:56.186631Z","steps":["trace[1446785077] 'agreement among raft nodes before linearized reading' (duration: 187.717325ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:18.611755Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f9f905057b5b268","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-11T18:04:18.617317Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T18:04:18.617485Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-11T18:06:16.363415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.233828ms","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-11T18:06:16.363605Z","caller":"traceutil/trace.go:171","msg":"trace[646531066] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10497; }","duration":"109.429854ms","start":"2026-04-11T18:06:16.254117Z","end":"2026-04-11T18:06:16.363547Z","steps":["trace[646531066] 'range keys from in-memory index tree' (duration: 109.098705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:06:16.363415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.066609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-11T18:06:16.363805Z","caller":"traceutil/trace.go:171","msg":"trace[1809315334] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:10497; }","duration":"111.47094ms","start":"2026-04-11T18:06:16.252254Z","end":"2026-04-11T18:06:16.363725Z","steps":["trace[1809315334] 'range keys from in-memory index tree' (duration: 110.839872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:06:17.4716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.877318ms","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-11T18:06:17.471675Z","caller":"traceutil/trace.go:171","msg":"trace[380093679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10502; }","duration":"286.008091ms","start":"2026-04-11T18:06:17.185651Z","end":"2026-04-11T18:06:17.471659Z","steps":["trace[380093679] 'range keys from in-memory index tree' (duration: 285.675633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:06:17.47173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.198196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T18:06:17.471821Z","caller":"traceutil/trace.go:171","msg":"trace[1428035644] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:10502; }","duration":"345.375231ms","start":"2026-04-11T18:06:17.126424Z","end":"2026-04-11T18:06:17.471799Z","steps":["trace[1428035644] 'count revisions from in-memory index tree' (duration: 345.037402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:06:17.471902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T18:06:17.126397Z","time spent":"345.482604ms","remote":"127.0.0.1:56610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-11T18:06:17.745609Z","caller":"traceutil/trace.go:171","msg":"trace[786897850] transaction","detail":"{read_only:false; response_revision:10505; number_of_response:1; }","duration":"110.808182ms","start":"2026-04-11T18:06:17.634776Z","end":"2026-04-11T18:06:17.745585Z","steps":["trace[786897850] 'process raft request' (duration: 110.647308ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:06:20.04556Z","caller":"traceutil/trace.go:171","msg":"trace[1417777652] transaction","detail":"{read_only:false; response_revision:10520; number_of_response:1; }","duration":"293.826977ms","start":"2026-04-11T18:06:19.751708Z","end":"2026-04-11T18:06:20.045534Z","steps":["trace[1417777652] 'process raft request' (duration: 292.160701ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:06:20.045564Z","caller":"traceutil/trace.go:171","msg":"trace[1405090698] linearizableReadLoop","detail":"{readStateIndex:10889; appliedIndex:10888; }","duration":"287.793541ms","start":"2026-04-11T18:06:19.757716Z","end":"2026-04-11T18:06:20.04551Z","steps":["trace[1405090698] 'read index received' (duration: 286.052293ms)","trace[1405090698] 'applied index is now lower than readState.Index' (duration: 1.739218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:06:20.045851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.869558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T18:06:20.045963Z","caller":"traceutil/trace.go:171","msg":"trace[1606102547] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:10520; }","duration":"137.018501ms","start":"2026-04-11T18:06:19.908877Z","end":"2026-04-11T18:06:20.045896Z","steps":["trace[1606102547] 'agreement among raft nodes before linearized reading' (duration: 136.880318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:06:20.046015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.261893ms","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-11T18:06:20.046932Z","caller":"traceutil/trace.go:171","msg":"trace[927136333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10520; }","duration":"289.076606ms","start":"2026-04-11T18:06:19.757664Z","end":"2026-04-11T18:06:20.046741Z","steps":["trace[927136333] 'agreement among raft nodes before linearized reading' (duration: 287.951085ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:06:32.938277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8210} {"level":"info","ts":"2026-04-11T18:06:33.018221Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8210,"took":"77.655112ms","hash":909495855,"current-db-size-bytes":57409536,"current-db-size":"57 MB","current-db-size-in-use-bytes":24461312,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-11T18:06:33.018316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":909495855,"revision":8210,"compact-revision":5991} {"level":"info","ts":"2026-04-11T18:11:32.944752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10589} {"level":"info","ts":"2026-04-11T18:11:33.020707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10589,"took":"73.653031ms","hash":1941254974,"current-db-size-bytes":57409536,"current-db-size":"57 MB","current-db-size-in-use-bytes":20115456,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-11T18:11:33.020762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1941254974,"revision":10589,"compact-revision":8210}