{"level":"warn","ts":"2026-04-25T15:59:25.317597Z","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-25T15:59:25.3177Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-25T15:59:25.317773Z","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-25T15:59:25.317784Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-25T15:59:25.3178Z","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-25T15:59:25.318444Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-25T15:59:25.318528Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-25T15:59:25.322335Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.564439ms"} {"level":"info","ts":"2026-04-25T15:59:25.332616Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-25T15:59:25.332691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-25T15:59:25.332715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-25T15:59:25.332733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-25T15:59:25.332745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-25T15:59:25.332785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-25T15:59:25.34192Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-25T15:59:25.343471Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-25T15:59:25.344747Z","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-25T15:59:25.347555Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-25T15:59:25.348273Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-25T15:59:25.348369Z","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-25T15:59:25.349712Z","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-25T15:59:25.349805Z","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-25T15:59:25.349849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-25T15:59:25.35012Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-25T15:59:25.352959Z","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-25T15:59:25.353081Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-25T15:59:25.353132Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-25T15:59:25.353322Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-25T15:59:25.353482Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-25T15:59:26.033937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-25T15:59:26.033987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-25T15:59:26.034006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-25T15:59:26.03402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-25T15:59:26.034028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-25T15:59:26.03404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-25T15:59:26.034049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-25T15:59:26.034894Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:26.035723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T15:59:26.035724Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-25T15:59:26.035754Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T15:59:26.036047Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-25T15:59:26.036117Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-25T15:59:26.036287Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:26.036372Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:26.036409Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T15:59:26.039094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-25T15:59:26.039658Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-25T15:59:59.322255Z","caller":"traceutil/trace.go:171","msg":"trace[763782131] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"107.036373ms","start":"2026-04-25T15:59:59.215181Z","end":"2026-04-25T15:59:59.322217Z","steps":["trace[763782131] 'process raft request' (duration: 106.17623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:34.617401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.458483ms","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-25T16:01:34.617606Z","caller":"traceutil/trace.go:171","msg":"trace[1817459429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1535; }","duration":"590.72847ms","start":"2026-04-25T16:01:34.026843Z","end":"2026-04-25T16:01:34.617572Z","steps":["trace[1817459429] 'range keys from in-memory index tree' (duration: 590.164955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:34.617681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:34.026828Z","time spent":"590.838263ms","remote":"127.0.0.1:53134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-25T16:01:34.675492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113923684474998,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-25T16:01:34.93683Z","caller":"traceutil/trace.go:171","msg":"trace[1677761211] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"861.076347ms","start":"2026-04-25T16:01:34.075723Z","end":"2026-04-25T16:01:34.936799Z","steps":["trace[1677761211] 'process raft request' (duration: 860.616383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:34.938153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:34.0757Z","time spent":"861.221151ms","remote":"127.0.0.1:47244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":412677,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T16:01:34.95486Z","caller":"traceutil/trace.go:171","msg":"trace[1495616388] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"821.920971ms","start":"2026-04-25T16:01:34.132919Z","end":"2026-04-25T16:01:34.95484Z","steps":["trace[1495616388] 'process raft request' (duration: 821.753386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:34.954938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:34.132893Z","time spent":"822.007583ms","remote":"127.0.0.1:52944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T16:01:35.023458Z","caller":"traceutil/trace.go:171","msg":"trace[285102952] linearizableReadLoop","detail":"{readStateIndex:1586; appliedIndex:1582; }","duration":"848.125227ms","start":"2026-04-25T16:01:34.175269Z","end":"2026-04-25T16:01:35.023395Z","steps":["trace[285102952] 'read index received' (duration: 760.806923ms)","trace[285102952] 'applied index is now lower than readState.Index' (duration: 87.314414ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:01:35.023515Z","caller":"traceutil/trace.go:171","msg":"trace[1648228297] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"400.789321ms","start":"2026-04-25T16:01:34.622696Z","end":"2026-04-25T16:01:35.023485Z","steps":["trace[1648228297] 'process raft request' (duration: 399.842645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:35.024009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:34.622669Z","time spent":"401.075389ms","remote":"127.0.0.1:53134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T16:01:35.083588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.290468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:01:35.083678Z","caller":"traceutil/trace.go:171","msg":"trace[1841610209] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1538; }","duration":"908.420832ms","start":"2026-04-25T16:01:34.175232Z","end":"2026-04-25T16:01:35.083653Z","steps":["trace[1841610209] 'agreement among raft nodes before linearized reading' (duration: 848.287832ms)","trace[1841610209] 'count revisions from in-memory index tree' (duration: 59.975436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:01:35.083725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:34.175219Z","time spent":"908.494934ms","remote":"127.0.0.1:34728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-25T16:01:35.08397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.54697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hvxtg\" ","response":"range_response_count:1 size:11628"} {"level":"info","ts":"2026-04-25T16:01:35.084948Z","caller":"traceutil/trace.go:171","msg":"trace[1933939947] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hvxtg; range_end:; response_count:1; response_revision:1538; }","duration":"527.557288ms","start":"2026-04-25T16:01:34.557375Z","end":"2026-04-25T16:01:35.084933Z","steps":["trace[1933939947] 'agreement among raft nodes before linearized reading' (duration: 466.323598ms)","trace[1933939947] 'range keys from in-memory index tree' (duration: 60.154351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:01:35.084989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:34.557353Z","time spent":"527.62434ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11651,"request content":"key:\"/registry/pods/openstack/openvswitch-hvxtg\" "} {"level":"warn","ts":"2026-04-25T16:01:35.084105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.844645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:01:35.085171Z","caller":"traceutil/trace.go:171","msg":"trace[1320165546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1538; }","duration":"692.964146ms","start":"2026-04-25T16:01:34.392196Z","end":"2026-04-25T16:01:35.08516Z","steps":["trace[1320165546] 'agreement among raft nodes before linearized reading' (duration: 631.487569ms)","trace[1320165546] 'range keys from in-memory index tree' (duration: 60.405358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:01:35.085203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:34.392163Z","time spent":"693.031588ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-25T16:01:35.084176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.664206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-25T16:01:35.085653Z","caller":"traceutil/trace.go:171","msg":"trace[886612824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1538; }","duration":"219.178407ms","start":"2026-04-25T16:01:34.86646Z","end":"2026-04-25T16:01:35.085639Z","steps":["trace[886612824] 'agreement among raft nodes before linearized reading' (duration: 157.234027ms)","trace[886612824] 'range keys from in-memory index tree' (duration: 60.426678ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:01:42.982167Z","caller":"traceutil/trace.go:171","msg":"trace[1018139673] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"330.813797ms","start":"2026-04-25T16:01:42.651329Z","end":"2026-04-25T16:01:42.982142Z","steps":["trace[1018139673] 'process raft request' (duration: 330.61083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:42.982421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T16:01:42.651312Z","time spent":"330.96046ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T16:01:44.239383Z","caller":"traceutil/trace.go:171","msg":"trace[2067537247] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"227.785109ms","start":"2026-04-25T16:01:44.011572Z","end":"2026-04-25T16:01:44.239357Z","steps":["trace[2067537247] 'process raft request' (duration: 227.415479ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:01:44.251365Z","caller":"traceutil/trace.go:171","msg":"trace[1120347925] linearizableReadLoop","detail":"{readStateIndex:1778; appliedIndex:1776; }","duration":"121.953277ms","start":"2026-04-25T16:01:44.129394Z","end":"2026-04-25T16:01:44.251347Z","steps":["trace[1120347925] 'read index received' (duration: 109.85085ms)","trace[1120347925] 'applied index is now lower than readState.Index' (duration: 12.101637ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:01:44.251408Z","caller":"traceutil/trace.go:171","msg":"trace[770163398] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"194.708558ms","start":"2026-04-25T16:01:44.05668Z","end":"2026-04-25T16:01:44.251389Z","steps":["trace[770163398] 'process raft request' (duration: 194.566244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:44.251565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.156692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:31386"} {"level":"info","ts":"2026-04-25T16:01:44.251606Z","caller":"traceutil/trace.go:171","msg":"trace[2016747318] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1728; }","duration":"122.237444ms","start":"2026-04-25T16:01:44.129354Z","end":"2026-04-25T16:01:44.251592Z","steps":["trace[2016747318] 'agreement among raft nodes before linearized reading' (duration: 122.103161ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:01:45.33709Z","caller":"traceutil/trace.go:171","msg":"trace[193556620] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"118.518995ms","start":"2026-04-25T16:01:45.218538Z","end":"2026-04-25T16:01:45.337057Z","steps":["trace[193556620] 'process raft request' (duration: 101.070034ms)","trace[193556620] 'compare' (duration: 17.294866ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:01:45.347214Z","caller":"traceutil/trace.go:171","msg":"trace[921817123] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"127.99959ms","start":"2026-04-25T16:01:45.21919Z","end":"2026-04-25T16:01:45.347189Z","steps":["trace[921817123] 'process raft request' (duration: 127.880277ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:01:53.396588Z","caller":"traceutil/trace.go:171","msg":"trace[1468404791] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"131.422372ms","start":"2026-04-25T16:01:53.265142Z","end":"2026-04-25T16:01:53.396564Z","steps":["trace[1468404791] 'process raft request' (duration: 131.256617ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:01:53.423245Z","caller":"traceutil/trace.go:171","msg":"trace[51151180] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1923; }","duration":"111.151886ms","start":"2026-04-25T16:01:53.312067Z","end":"2026-04-25T16:01:53.423219Z","steps":["trace[51151180] 'read index received' (duration: 84.312303ms)","trace[51151180] 'applied index is now lower than readState.Index' (duration: 26.838113ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:01:53.423339Z","caller":"traceutil/trace.go:171","msg":"trace[771374166] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"158.05985ms","start":"2026-04-25T16:01:53.26522Z","end":"2026-04-25T16:01:53.42328Z","steps":["trace[771374166] 'process raft request' (duration: 157.849964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:01:53.423593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.501345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hvxtg\" ","response":"range_response_count:1 size:12779"} {"level":"info","ts":"2026-04-25T16:01:53.423659Z","caller":"traceutil/trace.go:171","msg":"trace[395456004] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hvxtg; range_end:; response_count:1; response_revision:1870; }","duration":"111.630648ms","start":"2026-04-25T16:01:53.312011Z","end":"2026-04-25T16:01:53.423642Z","steps":["trace[395456004] 'agreement among raft nodes before linearized reading' (duration: 111.371401ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:02:02.014852Z","caller":"traceutil/trace.go:171","msg":"trace[512436187] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"231.801012ms","start":"2026-04-25T16:02:01.783021Z","end":"2026-04-25T16:02:02.014822Z","steps":["trace[512436187] 'process raft request' (duration: 196.940027ms)","trace[512436187] 'compare' (duration: 34.744072ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:05:06.250616Z","caller":"traceutil/trace.go:171","msg":"trace[2057481838] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"140.542037ms","start":"2026-04-25T16:05:06.110021Z","end":"2026-04-25T16:05:06.250563Z","steps":["trace[2057481838] 'process raft request' (duration: 140.254689ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:05:39.476053Z","caller":"traceutil/trace.go:171","msg":"trace[1888137692] linearizableReadLoop","detail":"{readStateIndex:4281; appliedIndex:4280; }","duration":"141.939224ms","start":"2026-04-25T16:05:39.334083Z","end":"2026-04-25T16:05:39.476022Z","steps":["trace[1888137692] 'read index received' (duration: 141.77935ms)","trace[1888137692] 'applied index is now lower than readState.Index' (duration: 158.704µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:05:39.476411Z","caller":"traceutil/trace.go:171","msg":"trace[1590951200] transaction","detail":"{read_only:false; response_revision:4130; number_of_response:1; }","duration":"182.312373ms","start":"2026-04-25T16:05:39.294063Z","end":"2026-04-25T16:05:39.476376Z","steps":["trace[1590951200] 'process raft request' (duration: 181.734358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:39.476898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.856115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c197884c-b6c6-48d3-b275-f2319ffda363\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-04-25T16:05:39.476958Z","caller":"traceutil/trace.go:171","msg":"trace[310071424] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c197884c-b6c6-48d3-b275-f2319ffda363; range_end:; response_count:1; response_revision:4130; }","duration":"118.959718ms","start":"2026-04-25T16:05:39.357983Z","end":"2026-04-25T16:05:39.476943Z","steps":["trace[310071424] 'agreement among raft nodes before linearized reading' (duration: 118.761932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:05:39.477275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.191338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-25T16:05:39.477344Z","caller":"traceutil/trace.go:171","msg":"trace[719495643] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:4130; }","duration":"143.28829ms","start":"2026-04-25T16:05:39.334018Z","end":"2026-04-25T16:05:39.477306Z","steps":["trace[719495643] 'agreement among raft nodes before linearized reading' (duration: 142.598022ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:06:24.873671Z","caller":"traceutil/trace.go:171","msg":"trace[476522103] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"149.926206ms","start":"2026-04-25T16:06:24.723725Z","end":"2026-04-25T16:06:24.873651Z","steps":["trace[476522103] 'process raft request' (duration: 78.694609ms)","trace[476522103] 'compare' (duration: 71.126704ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:09:26.064365Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3543} {"level":"info","ts":"2026-04-25T16:09:26.166824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3543,"took":"100.67566ms","hash":668740031,"current-db-size-bytes":46153728,"current-db-size":"46 MB","current-db-size-in-use-bytes":19218432,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-25T16:09:26.166915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":668740031,"revision":3543,"compact-revision":-1} {"level":"info","ts":"2026-04-25T16:10:49.776909Z","caller":"traceutil/trace.go:171","msg":"trace[380898442] transaction","detail":"{read_only:false; response_revision:6759; number_of_response:1; }","duration":"103.9502ms","start":"2026-04-25T16:10:49.672929Z","end":"2026-04-25T16:10:49.776879Z","steps":["trace[380898442] 'process raft request' (duration: 77.944339ms)","trace[380898442] 'compare' (duration: 25.777194ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:11:11.278783Z","caller":"traceutil/trace.go:171","msg":"trace[1923479292] transaction","detail":"{read_only:false; response_revision:7471; number_of_response:1; }","duration":"142.263827ms","start":"2026-04-25T16:11:11.136498Z","end":"2026-04-25T16:11:11.278762Z","steps":["trace[1923479292] 'process raft request' (duration: 141.892317ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:13.062778Z","caller":"traceutil/trace.go:171","msg":"trace[239813809] transaction","detail":"{read_only:false; response_revision:7496; number_of_response:1; }","duration":"100.180918ms","start":"2026-04-25T16:11:12.962558Z","end":"2026-04-25T16:11:13.062739Z","steps":["trace[239813809] 'process raft request' (duration: 100.067384ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:13.062833Z","caller":"traceutil/trace.go:171","msg":"trace[1702755139] transaction","detail":"{read_only:false; response_revision:7495; number_of_response:1; }","duration":"108.893005ms","start":"2026-04-25T16:11:12.953913Z","end":"2026-04-25T16:11:13.062806Z","steps":["trace[1702755139] 'process raft request' (duration: 106.607353ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:14.378609Z","caller":"traceutil/trace.go:171","msg":"trace[305492187] transaction","detail":"{read_only:false; response_revision:7520; number_of_response:1; }","duration":"110.384187ms","start":"2026-04-25T16:11:14.268152Z","end":"2026-04-25T16:11:14.378536Z","steps":["trace[305492187] 'process raft request' (duration: 40.369604ms)","trace[305492187] 'compare' (duration: 69.867219ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:11:26.117468Z","caller":"traceutil/trace.go:171","msg":"trace[957116852] transaction","detail":"{read_only:false; response_revision:7648; number_of_response:1; }","duration":"202.665627ms","start":"2026-04-25T16:11:25.914778Z","end":"2026-04-25T16:11:26.117444Z","steps":["trace[957116852] 'process raft request' (duration: 202.544324ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:26.138672Z","caller":"traceutil/trace.go:171","msg":"trace[1903727269] transaction","detail":"{read_only:false; response_revision:7650; number_of_response:1; }","duration":"138.095824ms","start":"2026-04-25T16:11:26.000017Z","end":"2026-04-25T16:11:26.138113Z","steps":["trace[1903727269] 'process raft request' (duration: 138.003801ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:26.138815Z","caller":"traceutil/trace.go:171","msg":"trace[827329146] transaction","detail":"{read_only:false; response_revision:7649; number_of_response:1; }","duration":"143.1167ms","start":"2026-04-25T16:11:25.995603Z","end":"2026-04-25T16:11:26.13872Z","steps":["trace[827329146] 'process raft request' (duration: 142.290877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:28.313707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.518863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-25T16:11:28.313801Z","caller":"traceutil/trace.go:171","msg":"trace[573597206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7695; }","duration":"105.666967ms","start":"2026-04-25T16:11:28.208115Z","end":"2026-04-25T16:11:28.313782Z","steps":["trace[573597206] 'range keys from in-memory index tree' (duration: 105.330218ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:28.471031Z","caller":"traceutil/trace.go:171","msg":"trace[112979368] transaction","detail":"{read_only:false; response_revision:7696; number_of_response:1; }","duration":"121.384766ms","start":"2026-04-25T16:11:28.349607Z","end":"2026-04-25T16:11:28.470991Z","steps":["trace[112979368] 'process raft request' (duration: 121.171751ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:28.589129Z","caller":"traceutil/trace.go:171","msg":"trace[1144011676] linearizableReadLoop","detail":"{readStateIndex:7944; appliedIndex:7943; }","duration":"117.100279ms","start":"2026-04-25T16:11:28.471994Z","end":"2026-04-25T16:11:28.589094Z","steps":["trace[1144011676] 'read index received' (duration: 89.716642ms)","trace[1144011676] 'applied index is now lower than readState.Index' (duration: 27.381547ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:11:28.589181Z","caller":"traceutil/trace.go:171","msg":"trace[665917113] transaction","detail":"{read_only:false; response_revision:7697; number_of_response:1; }","duration":"146.671687ms","start":"2026-04-25T16:11:28.442477Z","end":"2026-04-25T16:11:28.589148Z","steps":["trace[665917113] 'process raft request' (duration: 119.191468ms)","trace[665917113] 'compare' (duration: 27.11936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:11:28.589882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.870901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:11:28.589976Z","caller":"traceutil/trace.go:171","msg":"trace[1895217653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7697; }","duration":"117.961593ms","start":"2026-04-25T16:11:28.471987Z","end":"2026-04-25T16:11:28.589949Z","steps":["trace[1895217653] 'agreement among raft nodes before linearized reading' (duration: 117.277095ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.30569Z","caller":"traceutil/trace.go:171","msg":"trace[1080329733] transaction","detail":"{read_only:false; response_revision:7742; number_of_response:1; }","duration":"122.761234ms","start":"2026-04-25T16:11:31.182904Z","end":"2026-04-25T16:11:31.305665Z","steps":["trace[1080329733] 'process raft request' (duration: 122.60356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:31.479906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.495934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:11:31.480095Z","caller":"traceutil/trace.go:171","msg":"trace[1927926533] transaction","detail":"{read_only:false; response_revision:7744; number_of_response:1; }","duration":"172.258836ms","start":"2026-04-25T16:11:31.307818Z","end":"2026-04-25T16:11:31.480077Z","steps":["trace[1927926533] 'process raft request' (duration: 172.189664ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.480191Z","caller":"traceutil/trace.go:171","msg":"trace[495539704] linearizableReadLoop","detail":"{readStateIndex:7991; appliedIndex:7989; }","duration":"214.265454ms","start":"2026-04-25T16:11:31.265873Z","end":"2026-04-25T16:11:31.480139Z","steps":["trace[495539704] 'read index received' (duration: 39.589812ms)","trace[495539704] 'applied index is now lower than readState.Index' (duration: 174.672572ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:11:31.480217Z","caller":"traceutil/trace.go:171","msg":"trace[1165844156] transaction","detail":"{read_only:false; response_revision:7743; number_of_response:1; }","duration":"284.528833ms","start":"2026-04-25T16:11:31.195662Z","end":"2026-04-25T16:11:31.480191Z","steps":["trace[1165844156] 'process raft request' (duration: 145.497615ms)","trace[1165844156] 'compare' (duration: 138.395101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:11:31.480343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.44529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-25T16:11:31.480409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.304721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/placement-ks-user-9x9dg\" ","response":"range_response_count:1 size:8830"} {"level":"info","ts":"2026-04-25T16:11:31.480422Z","caller":"traceutil/trace.go:171","msg":"trace[251169065] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:7744; }","duration":"214.580173ms","start":"2026-04-25T16:11:31.265825Z","end":"2026-04-25T16:11:31.480405Z","steps":["trace[251169065] 'agreement among raft nodes before linearized reading' (duration: 214.433539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:31.480414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.023535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:11:31.480454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.643354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:11:31.480439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.000687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:11:31.480496Z","caller":"traceutil/trace.go:171","msg":"trace[1473343846] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:7744; }","duration":"147.707336ms","start":"2026-04-25T16:11:31.332777Z","end":"2026-04-25T16:11:31.480484Z","steps":["trace[1473343846] 'agreement among raft nodes before linearized reading' (duration: 147.611314ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.480499Z","caller":"traceutil/trace.go:171","msg":"trace[1419683431] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:7744; }","duration":"148.122377ms","start":"2026-04-25T16:11:31.332354Z","end":"2026-04-25T16:11:31.480476Z","steps":["trace[1419683431] 'agreement among raft nodes before linearized reading' (duration: 147.989303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:31.480528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.280471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"warn","ts":"2026-04-25T16:11:31.480548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.543939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:11:31.480501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.357285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:11:31.4806Z","caller":"traceutil/trace.go:171","msg":"trace[1504210277] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:7744; }","duration":"148.60699ms","start":"2026-04-25T16:11:31.331981Z","end":"2026-04-25T16:11:31.480588Z","steps":["trace[1504210277] 'agreement among raft nodes before linearized reading' (duration: 148.529738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:31.480602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.803625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:11:31.480616Z","caller":"traceutil/trace.go:171","msg":"trace[1267261245] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:7744; }","duration":"144.494678ms","start":"2026-04-25T16:11:31.336102Z","end":"2026-04-25T16:11:31.480596Z","steps":["trace[1267261245] 'agreement among raft nodes before linearized reading' (duration: 144.319753ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.480651Z","caller":"traceutil/trace.go:171","msg":"trace[632843031] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:7744; }","duration":"148.874557ms","start":"2026-04-25T16:11:31.331764Z","end":"2026-04-25T16:11:31.480638Z","steps":["trace[632843031] 'agreement among raft nodes before linearized reading' (duration: 148.816076ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.480562Z","caller":"traceutil/trace.go:171","msg":"trace[1866629668] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:7744; }","duration":"148.322682ms","start":"2026-04-25T16:11:31.332232Z","end":"2026-04-25T16:11:31.480554Z","steps":["trace[1866629668] 'agreement among raft nodes before linearized reading' (duration: 148.24051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:31.480614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.905356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T16:11:31.480744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.275697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:1 size:4839"} {"level":"info","ts":"2026-04-25T16:11:31.480767Z","caller":"traceutil/trace.go:171","msg":"trace[2126925467] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:7744; }","duration":"146.074961ms","start":"2026-04-25T16:11:31.334681Z","end":"2026-04-25T16:11:31.480756Z","steps":["trace[2126925467] 'agreement among raft nodes before linearized reading' (duration: 145.908706ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.480776Z","caller":"traceutil/trace.go:171","msg":"trace[958643402] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:1; response_revision:7744; }","duration":"146.342249ms","start":"2026-04-25T16:11:31.334425Z","end":"2026-04-25T16:11:31.480767Z","steps":["trace[958643402] 'agreement among raft nodes before linearized reading' (duration: 146.208195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:31.480822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.11607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:1 size:4839"} {"level":"info","ts":"2026-04-25T16:11:31.480855Z","caller":"traceutil/trace.go:171","msg":"trace[1588194243] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:1; response_revision:7744; }","duration":"147.190932ms","start":"2026-04-25T16:11:31.333656Z","end":"2026-04-25T16:11:31.480847Z","steps":["trace[1588194243] 'agreement among raft nodes before linearized reading' (duration: 147.088569ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.48056Z","caller":"traceutil/trace.go:171","msg":"trace[893436631] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:7744; }","duration":"147.1211ms","start":"2026-04-25T16:11:31.333393Z","end":"2026-04-25T16:11:31.480514Z","steps":["trace[893436631] 'agreement among raft nodes before linearized reading' (duration: 146.933635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:11:31.480877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.484037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:11:31.48045Z","caller":"traceutil/trace.go:171","msg":"trace[55805126] range","detail":"{range_begin:/registry/pods/openstack/placement-ks-user-9x9dg; range_end:; response_count:1; response_revision:7744; }","duration":"171.370953ms","start":"2026-04-25T16:11:31.309068Z","end":"2026-04-25T16:11:31.480439Z","steps":["trace[55805126] 'agreement among raft nodes before linearized reading' (duration: 171.25032ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:31.480919Z","caller":"traceutil/trace.go:171","msg":"trace[445210128] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:7744; }","duration":"148.532909ms","start":"2026-04-25T16:11:31.332376Z","end":"2026-04-25T16:11:31.480909Z","steps":["trace[445210128] 'agreement among raft nodes before linearized reading' (duration: 148.473657ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:39.504064Z","caller":"traceutil/trace.go:171","msg":"trace[722926129] linearizableReadLoop","detail":"{readStateIndex:8079; appliedIndex:8078; }","duration":"110.694915ms","start":"2026-04-25T16:11:39.393343Z","end":"2026-04-25T16:11:39.504038Z","steps":["trace[722926129] 'read index received' (duration: 110.5251ms)","trace[722926129] 'applied index is now lower than readState.Index' (duration: 168.345µs)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:11:39.504253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.88625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T16:11:39.504335Z","caller":"traceutil/trace.go:171","msg":"trace[629616894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7829; }","duration":"111.011883ms","start":"2026-04-25T16:11:39.393304Z","end":"2026-04-25T16:11:39.504316Z","steps":["trace[629616894] 'agreement among raft nodes before linearized reading' (duration: 110.86528ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:11:42.00636Z","caller":"traceutil/trace.go:171","msg":"trace[1918929748] transaction","detail":"{read_only:false; response_revision:7864; number_of_response:1; }","duration":"203.903682ms","start":"2026-04-25T16:11:41.802422Z","end":"2026-04-25T16:11:42.006326Z","steps":["trace[1918929748] 'process raft request' (duration: 153.220067ms)","trace[1918929748] 'compare' (duration: 50.074818ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:11:42.012784Z","caller":"traceutil/trace.go:171","msg":"trace[206124419] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7865; }","duration":"210.08472ms","start":"2026-04-25T16:11:41.802673Z","end":"2026-04-25T16:11:42.012758Z","steps":["trace[206124419] 'process raft request' (duration: 209.627418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:12:09.029937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.210627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T16:12:09.030042Z","caller":"traceutil/trace.go:171","msg":"trace[1833830926] linearizableReadLoop","detail":"{readStateIndex:8468; appliedIndex:8467; }","duration":"109.28174ms","start":"2026-04-25T16:12:08.920747Z","end":"2026-04-25T16:12:09.030029Z","steps":["trace[1833830926] 'read index received' (duration: 29.821µs)","trace[1833830926] 'applied index is now lower than readState.Index' (duration: 109.250849ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T16:12:09.030067Z","caller":"traceutil/trace.go:171","msg":"trace[2083416230] transaction","detail":"{read_only:false; response_revision:8212; number_of_response:1; }","duration":"152.720019ms","start":"2026-04-25T16:12:08.877322Z","end":"2026-04-25T16:12:09.030042Z","steps":["trace[2083416230] 'process raft request' (duration: 39.324156ms)","trace[2083416230] 'compare' (duration: 112.94484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T16:12:09.030326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.584749ms","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-25T16:12:09.030359Z","caller":"traceutil/trace.go:171","msg":"trace[2120328067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8212; }","duration":"109.64403ms","start":"2026-04-25T16:12:08.920707Z","end":"2026-04-25T16:12:09.030351Z","steps":["trace[2120328067] 'agreement among raft nodes before linearized reading' (duration: 109.405904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T16:12:09.030451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.888807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-25T16:12:09.030629Z","caller":"traceutil/trace.go:171","msg":"trace[1317862330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8212; }","duration":"106.097103ms","start":"2026-04-25T16:12:08.924456Z","end":"2026-04-25T16:12:09.030554Z","steps":["trace[1317862330] 'agreement among raft nodes before linearized reading' (duration: 105.746683ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T16:14:26.070413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5660} {"level":"info","ts":"2026-04-25T16:14:26.146749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5660,"took":"74.243251ms","hash":1072686919,"current-db-size-bytes":46153728,"current-db-size":"46 MB","current-db-size-in-use-bytes":29749248,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-25T16:14:26.146818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1072686919,"revision":5660,"compact-revision":3543} {"level":"info","ts":"2026-04-25T16:15:00.121184Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-25T16:15:00.127767Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-25T16:15:00.127872Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}