{"level":"warn","ts":"2026-05-07T00:44:02.454502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-07T00:44:02.454585Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-05-07T00:44:02.454649Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-07T00:44:02.454662Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]}
{"level":"info","ts":"2026-05-07T00:44:02.454714Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-05-07T00:44:02.455415Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]}
{"level":"info","ts":"2026-05-07T00:44:02.455494Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-05-07T00:44:02.459568Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.871408ms"}
{"level":"info","ts":"2026-05-07T00:44:02.469883Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"}
{"level":"info","ts":"2026-05-07T00:44:02.471767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"}
{"level":"info","ts":"2026-05-07T00:44:02.472098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"}
{"level":"info","ts":"2026-05-07T00:44:02.472231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-05-07T00:44:02.472273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"}
{"level":"info","ts":"2026-05-07T00:44:02.472348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"}
{"level":"warn","ts":"2026-05-07T00:44:02.480281Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-05-07T00:44:02.481645Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-05-07T00:44:02.482586Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-05-07T00:44:02.484262Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-05-07T00:44:02.484446Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-07T00:44:02.48452Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-07T00:44:02.48453Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-07T00:44:02.484391Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-05-07T00:44:02.485871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"}
{"level":"info","ts":"2026-05-07T00:44:02.486048Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]}
{"level":"info","ts":"2026-05-07T00:44:02.490049Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-05-07T00:44:02.490209Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"}
{"level":"info","ts":"2026-05-07T00:44:02.492321Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"}
{"level":"info","ts":"2026-05-07T00:44:02.492499Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-05-07T00:44:02.492531Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-05-07T00:44:02.673443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"}
{"level":"info","ts":"2026-05-07T00:44:02.673522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"}
{"level":"info","ts":"2026-05-07T00:44:02.673558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"}
{"level":"info","ts":"2026-05-07T00:44:02.67359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"}
{"level":"info","ts":"2026-05-07T00:44:02.673601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"}
{"level":"info","ts":"2026-05-07T00:44:02.673618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"}
{"level":"info","ts":"2026-05-07T00:44:02.673673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"}
{"level":"info","ts":"2026-05-07T00:44:02.674714Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-07T00:44:02.675515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-07T00:44:02.675611Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-07T00:44:02.67587Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-05-07T00:44:02.67633Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-05-07T00:44:02.678007Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-05-07T00:44:02.679073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"}
{"level":"info","ts":"2026-05-07T00:44:02.679324Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-07T00:44:02.679472Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-07T00:44:02.679512Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-07T00:44:02.675488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"}
{"level":"warn","ts":"2026-05-07T00:44:07.273142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.623638ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421303573200644657 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/secrets/kube-system/bootstrap-token-r61p41\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/bootstrap-token-r61p41\" value_size:617 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-05-07T00:44:07.273387Z","caller":"traceutil/trace.go:171","msg":"trace[600884286] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:236; }","duration":"290.401782ms","start":"2026-05-07T00:44:06.982946Z","end":"2026-05-07T00:44:07.273348Z","steps":["trace[600884286] 'read index received'  (duration: 118.047252ms)","trace[600884286] 'applied index is now lower than readState.Index'  (duration: 172.35343ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:44:07.273449Z","caller":"traceutil/trace.go:171","msg":"trace[889110553] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"338.337049ms","start":"2026-05-07T00:44:06.935085Z","end":"2026-05-07T00:44:07.273422Z","steps":["trace[889110553] 'process raft request'  (duration: 165.971398ms)","trace[889110553] 'compare'  (duration: 171.486424ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:44:07.273551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.587739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-07T00:44:07.273569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:44:06.935075Z","time spent":"338.449873ms","remote":"127.0.0.1:51666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/secrets/kube-system/bootstrap-token-r61p41\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/bootstrap-token-r61p41\" value_size:617 >> failure:<>"}
{"level":"info","ts":"2026-05-07T00:44:07.273635Z","caller":"traceutil/trace.go:171","msg":"trace[1337397483] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:231; }","duration":"290.725262ms","start":"2026-05-07T00:44:06.98289Z","end":"2026-05-07T00:44:07.273616Z","steps":["trace[1337397483] 'agreement among raft nodes before linearized reading'  (duration: 290.547227ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:44:07.630259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.069151ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421303573200644660 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterroles/kubeadm:get-nodes\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterroles/kubeadm:get-nodes\" value_size:242 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-05-07T00:44:07.630363Z","caller":"traceutil/trace.go:171","msg":"trace[1452972805] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"352.817486ms","start":"2026-05-07T00:44:07.277525Z","end":"2026-05-07T00:44:07.630342Z","steps":["trace[1452972805] 'process raft request'  (duration: 151.59873ms)","trace[1452972805] 'compare'  (duration: 200.920707ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:44:07.630601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:44:07.277507Z","time spent":"352.891338ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":290,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/clusterroles/kubeadm:get-nodes\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterroles/kubeadm:get-nodes\" value_size:242 >> failure:<>"}
{"level":"info","ts":"2026-05-07T00:44:07.631302Z","caller":"traceutil/trace.go:171","msg":"trace[1483566265] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"212.292142ms","start":"2026-05-07T00:44:07.418981Z","end":"2026-05-07T00:44:07.631273Z","steps":["trace[1483566265] 'process raft request'  (duration: 212.23423ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:07.631311Z","caller":"traceutil/trace.go:171","msg":"trace[1475371634] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"351.871077ms","start":"2026-05-07T00:44:07.2794Z","end":"2026-05-07T00:44:07.631271Z","steps":["trace[1475371634] 'process raft request'  (duration: 351.697542ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:44:07.632338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:44:07.27939Z","time spent":"352.879697ms","remote":"127.0.0.1:51764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/token-cleaner\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/token-cleaner\" value_size:118 >> failure:<>"}
{"level":"info","ts":"2026-05-07T00:44:33.56261Z","caller":"traceutil/trace.go:171","msg":"trace[1121138844] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"255.911668ms","start":"2026-05-07T00:44:33.306675Z","end":"2026-05-07T00:44:33.562586Z","steps":["trace[1121138844] 'process raft request'  (duration: 255.480045ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.562602Z","caller":"traceutil/trace.go:171","msg":"trace[1997651339] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"253.61525ms","start":"2026-05-07T00:44:33.308957Z","end":"2026-05-07T00:44:33.562572Z","steps":["trace[1997651339] 'process raft request'  (duration: 253.417134ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.562816Z","caller":"traceutil/trace.go:171","msg":"trace[459339448] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"235.069157ms","start":"2026-05-07T00:44:33.327717Z","end":"2026-05-07T00:44:33.562786Z","steps":["trace[459339448] 'process raft request'  (duration: 234.796599ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.562896Z","caller":"traceutil/trace.go:171","msg":"trace[2129027124] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"231.715498ms","start":"2026-05-07T00:44:33.331163Z","end":"2026-05-07T00:44:33.562879Z","steps":["trace[2129027124] 'process raft request'  (duration: 231.389238ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.562909Z","caller":"traceutil/trace.go:171","msg":"trace[1263286399] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"185.42982ms","start":"2026-05-07T00:44:33.37746Z","end":"2026-05-07T00:44:33.56289Z","steps":["trace[1263286399] 'process raft request'  (duration: 185.374518ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.562968Z","caller":"traceutil/trace.go:171","msg":"trace[2050592493] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"243.22906ms","start":"2026-05-07T00:44:33.319728Z","end":"2026-05-07T00:44:33.562957Z","steps":["trace[2050592493] 'process raft request'  (duration: 242.702144ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.562837Z","caller":"traceutil/trace.go:171","msg":"trace[814217164] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"231.245043ms","start":"2026-05-07T00:44:33.331579Z","end":"2026-05-07T00:44:33.562824Z","steps":["trace[814217164] 'process raft request'  (duration: 231.027517ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.563042Z","caller":"traceutil/trace.go:171","msg":"trace[298707509] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"241.402616ms","start":"2026-05-07T00:44:33.321631Z","end":"2026-05-07T00:44:33.563033Z","steps":["trace[298707509] 'process raft request'  (duration: 240.83867ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.56304Z","caller":"traceutil/trace.go:171","msg":"trace[748697018] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"210.867567ms","start":"2026-05-07T00:44:33.352162Z","end":"2026-05-07T00:44:33.563029Z","steps":["trace[748697018] 'process raft request'  (duration: 210.555978ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.563113Z","caller":"traceutil/trace.go:171","msg":"trace[487791101] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"208.054595ms","start":"2026-05-07T00:44:33.355051Z","end":"2026-05-07T00:44:33.563106Z","steps":["trace[487791101] 'process raft request'  (duration: 207.725885ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.578986Z","caller":"traceutil/trace.go:171","msg":"trace[909505634] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"100.679618ms","start":"2026-05-07T00:44:33.478289Z","end":"2026-05-07T00:44:33.578968Z","steps":["trace[909505634] 'process raft request'  (duration: 100.628166ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.579316Z","caller":"traceutil/trace.go:171","msg":"trace[1782188261] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"189.348038ms","start":"2026-05-07T00:44:33.389916Z","end":"2026-05-07T00:44:33.579264Z","steps":["trace[1782188261] 'process raft request'  (duration: 188.726959ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.579453Z","caller":"traceutil/trace.go:171","msg":"trace[1794616350] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"189.419049ms","start":"2026-05-07T00:44:33.390027Z","end":"2026-05-07T00:44:33.579446Z","steps":["trace[1794616350] 'process raft request'  (duration: 188.761259ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.579594Z","caller":"traceutil/trace.go:171","msg":"trace[1693090733] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"187.746199ms","start":"2026-05-07T00:44:33.391841Z","end":"2026-05-07T00:44:33.579587Z","steps":["trace[1693090733] 'process raft request'  (duration: 186.984857ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.579756Z","caller":"traceutil/trace.go:171","msg":"trace[7174042] transaction","detail":"{read_only:false; number_of_response:1; response_revision:489; }","duration":"185.06828ms","start":"2026-05-07T00:44:33.394681Z","end":"2026-05-07T00:44:33.579749Z","steps":["trace[7174042] 'process raft request'  (duration: 184.183594ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.913954Z","caller":"traceutil/trace.go:171","msg":"trace[1075496154] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"142.244055ms","start":"2026-05-07T00:44:33.771688Z","end":"2026-05-07T00:44:33.913932Z","steps":["trace[1075496154] 'process raft request'  (duration: 142.049359ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.957077Z","caller":"traceutil/trace.go:171","msg":"trace[400704250] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"167.516997ms","start":"2026-05-07T00:44:33.789543Z","end":"2026-05-07T00:44:33.95706Z","steps":["trace[400704250] 'process raft request'  (duration: 163.356053ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:33.957295Z","caller":"traceutil/trace.go:171","msg":"trace[1952002180] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"155.957823ms","start":"2026-05-07T00:44:33.801289Z","end":"2026-05-07T00:44:33.957246Z","steps":["trace[1952002180] 'process raft request'  (duration: 155.744707ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:44:39.058434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.606603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-05-07T00:44:39.05859Z","caller":"traceutil/trace.go:171","msg":"trace[1105269235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"150.811089ms","start":"2026-05-07T00:44:38.907751Z","end":"2026-05-07T00:44:39.058562Z","steps":["trace[1105269235] 'range keys from in-memory index tree'  (duration: 150.422458ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:39.67817Z","caller":"traceutil/trace.go:171","msg":"trace[18781818] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"132.887046ms","start":"2026-05-07T00:44:39.545246Z","end":"2026-05-07T00:44:39.678133Z","steps":["trace[18781818] 'process raft request'  (duration: 127.284609ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:39.882574Z","caller":"traceutil/trace.go:171","msg":"trace[1991172624] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"124.671902ms","start":"2026-05-07T00:44:39.757878Z","end":"2026-05-07T00:44:39.88255Z","steps":["trace[1991172624] 'process raft request'  (duration: 124.556428ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:44:40.464658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.934923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-07T00:44:40.46474Z","caller":"traceutil/trace.go:171","msg":"trace[1959882614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"393.045666ms","start":"2026-05-07T00:44:40.071676Z","end":"2026-05-07T00:44:40.464722Z","steps":["trace[1959882614] 'range keys from in-memory index tree'  (duration: 392.866601ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:44:40.46478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:44:40.071659Z","time spent":"393.112868ms","remote":"127.0.0.1:51596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-05-07T00:44:40.464808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.747358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nbdbc\" ","response":"range_response_count:1 size:16961"}
{"level":"info","ts":"2026-05-07T00:44:40.464896Z","caller":"traceutil/trace.go:171","msg":"trace[2000501729] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nbdbc; range_end:; response_count:1; response_revision:584; }","duration":"295.844671ms","start":"2026-05-07T00:44:40.169036Z","end":"2026-05-07T00:44:40.464881Z","steps":["trace[2000501729] 'range keys from in-memory index tree'  (duration: 295.655946ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:40.466075Z","caller":"traceutil/trace.go:171","msg":"trace[443925017] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"177.121478ms","start":"2026-05-07T00:44:40.288923Z","end":"2026-05-07T00:44:40.466045Z","steps":["trace[443925017] 'process raft request'  (duration: 176.421277ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:54.035162Z","caller":"traceutil/trace.go:171","msg":"trace[6371587] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"117.451922ms","start":"2026-05-07T00:44:53.917688Z","end":"2026-05-07T00:44:54.03514Z","steps":["trace[6371587] 'process raft request'  (duration: 117.331698ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:44:56.445927Z","caller":"traceutil/trace.go:171","msg":"trace[297531093] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"177.829073ms","start":"2026-05-07T00:44:56.268074Z","end":"2026-05-07T00:44:56.445903Z","steps":["trace[297531093] 'process raft request'  (duration: 177.624777ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:45:15.408252Z","caller":"traceutil/trace.go:171","msg":"trace[501085291] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"101.600459ms","start":"2026-05-07T00:45:15.306627Z","end":"2026-05-07T00:45:15.408227Z","steps":["trace[501085291] 'read index received'  (duration: 16.8445ms)","trace[501085291] 'applied index is now lower than readState.Index'  (duration: 84.754379ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:45:15.408583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.942273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:55804"}
{"level":"info","ts":"2026-05-07T00:45:15.408627Z","caller":"traceutil/trace.go:171","msg":"trace[1268267579] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:997; }","duration":"102.033635ms","start":"2026-05-07T00:45:15.306581Z","end":"2026-05-07T00:45:15.408615Z","steps":["trace[1268267579] 'agreement among raft nodes before linearized reading'  (duration: 101.767822ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:45:15.408729Z","caller":"traceutil/trace.go:171","msg":"trace[1889528672] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"102.757185ms","start":"2026-05-07T00:45:15.305934Z","end":"2026-05-07T00:45:15.408691Z","steps":["trace[1889528672] 'process raft request'  (duration: 74.816843ms)","trace[1889528672] 'compare'  (duration: 27.226873ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:45:15.408864Z","caller":"traceutil/trace.go:171","msg":"trace[1409863448] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"102.307779ms","start":"2026-05-07T00:45:15.306475Z","end":"2026-05-07T00:45:15.408782Z","steps":["trace[1409863448] 'process raft request'  (duration: 101.66802ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:45:15.666184Z","caller":"traceutil/trace.go:171","msg":"trace[1036863923] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1032; }","duration":"100.027468ms","start":"2026-05-07T00:45:15.566135Z","end":"2026-05-07T00:45:15.666163Z","steps":["trace[1036863923] 'agreement among raft nodes before linearized reading'  (duration: 100.006358ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:45:27.153749Z","caller":"traceutil/trace.go:171","msg":"trace[1427529553] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"134.803402ms","start":"2026-05-07T00:45:27.018916Z","end":"2026-05-07T00:45:27.153719Z","steps":["trace[1427529553] 'process raft request'  (duration: 134.6422ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:46:11.667427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.983643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"}
{"level":"info","ts":"2026-05-07T00:46:11.667519Z","caller":"traceutil/trace.go:171","msg":"trace[1511533585] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1796; }","duration":"117.095745ms","start":"2026-05-07T00:46:11.550403Z","end":"2026-05-07T00:46:11.667499Z","steps":["trace[1511533585] 'range keys from in-memory index tree'  (duration: 116.86399ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:46:11.667683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.426188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-05-07T00:46:11.667427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.931918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"}
{"level":"warn","ts":"2026-05-07T00:46:11.667698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.175761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-05-07T00:46:11.66774Z","caller":"traceutil/trace.go:171","msg":"trace[1744194329] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1796; }","duration":"322.307366ms","start":"2026-05-07T00:46:11.34542Z","end":"2026-05-07T00:46:11.667727Z","steps":["trace[1744194329] 'range keys from in-memory index tree'  (duration: 321.763134ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:46:11.667777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:11.345397Z","time spent":"322.369007ms","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-05-07T00:46:11.667777Z","caller":"traceutil/trace.go:171","msg":"trace[464094383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1796; }","duration":"315.264033ms","start":"2026-05-07T00:46:11.352497Z","end":"2026-05-07T00:46:11.667761Z","steps":["trace[464094383] 'range keys from in-memory index tree'  (duration: 315.030988ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:46:11.667817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:46:11.352447Z","time spent":"315.361395ms","remote":"127.0.0.1:51832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-05-07T00:46:11.66773Z","caller":"traceutil/trace.go:171","msg":"trace[622410605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1796; }","duration":"126.499029ms","start":"2026-05-07T00:46:11.541218Z","end":"2026-05-07T00:46:11.667717Z","steps":["trace[622410605] 'range keys from in-memory index tree'  (duration: 126.344226ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:11.812212Z","caller":"traceutil/trace.go:171","msg":"trace[1791614793] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"139.202456ms","start":"2026-05-07T00:46:11.672963Z","end":"2026-05-07T00:46:11.812165Z","steps":["trace[1791614793] 'process raft request'  (duration: 104.533372ms)","trace[1791614793] 'compare'  (duration: 34.543581ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:46:11.836332Z","caller":"traceutil/trace.go:171","msg":"trace[1460690395] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"162.197275ms","start":"2026-05-07T00:46:11.6741Z","end":"2026-05-07T00:46:11.836297Z","steps":["trace[1460690395] 'process raft request'  (duration: 161.843118ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:11.83782Z","caller":"traceutil/trace.go:171","msg":"trace[897688426] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"162.258957ms","start":"2026-05-07T00:46:11.675539Z","end":"2026-05-07T00:46:11.837798Z","steps":["trace[897688426] 'process raft request'  (duration: 160.629182ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:11.837938Z","caller":"traceutil/trace.go:171","msg":"trace[145821528] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"161.399569ms","start":"2026-05-07T00:46:11.676518Z","end":"2026-05-07T00:46:11.837918Z","steps":["trace[145821528] 'process raft request'  (duration: 160.927348ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:19.411966Z","caller":"traceutil/trace.go:171","msg":"trace[1691005187] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"135.611209ms","start":"2026-05-07T00:46:19.27633Z","end":"2026-05-07T00:46:19.411941Z","steps":["trace[1691005187] 'read index received'  (duration: 135.392995ms)","trace[1691005187] 'applied index is now lower than readState.Index'  (duration: 217.384µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:46:19.412206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.843814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"}
{"level":"info","ts":"2026-05-07T00:46:19.412207Z","caller":"traceutil/trace.go:171","msg":"trace[2040331379] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"148.023268ms","start":"2026-05-07T00:46:19.264121Z","end":"2026-05-07T00:46:19.412144Z","steps":["trace[2040331379] 'process raft request'  (duration: 147.67729ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:19.412246Z","caller":"traceutil/trace.go:171","msg":"trace[1528478098] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1888; }","duration":"135.948737ms","start":"2026-05-07T00:46:19.276287Z","end":"2026-05-07T00:46:19.412235Z","steps":["trace[1528478098] 'agreement among raft nodes before linearized reading'  (duration: 135.765083ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:48.317556Z","caller":"traceutil/trace.go:171","msg":"trace[509546258] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"105.035358ms","start":"2026-05-07T00:46:48.212493Z","end":"2026-05-07T00:46:48.317528Z","steps":["trace[509546258] 'process raft request'  (duration: 104.906284ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:53.975458Z","caller":"traceutil/trace.go:171","msg":"trace[1275471991] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"145.621247ms","start":"2026-05-07T00:46:53.829811Z","end":"2026-05-07T00:46:53.975432Z","steps":["trace[1275471991] 'process raft request'  (duration: 143.588627ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:54.139353Z","caller":"traceutil/trace.go:171","msg":"trace[1185321763] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2204; }","duration":"122.719361ms","start":"2026-05-07T00:46:54.016602Z","end":"2026-05-07T00:46:54.139321Z","steps":["trace[1185321763] 'read index received'  (duration: 119.341579ms)","trace[1185321763] 'applied index is now lower than readState.Index'  (duration: 3.376762ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:46:54.139755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.136411ms","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-05-07T00:46:54.139804Z","caller":"traceutil/trace.go:171","msg":"trace[605389407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2136; }","duration":"123.217054ms","start":"2026-05-07T00:46:54.016575Z","end":"2026-05-07T00:46:54.139792Z","steps":["trace[605389407] 'agreement among raft nodes before linearized reading'  (duration: 122.864395ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:46:54.13942Z","caller":"traceutil/trace.go:171","msg":"trace[1992978807] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"123.741457ms","start":"2026-05-07T00:46:54.015637Z","end":"2026-05-07T00:46:54.139378Z","steps":["trace[1992978807] 'process raft request'  (duration: 120.273262ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:47:38.814859Z","caller":"traceutil/trace.go:171","msg":"trace[931929604] linearizableReadLoop","detail":"{readStateIndex:2629; appliedIndex:2628; }","duration":"110.751688ms","start":"2026-05-07T00:47:38.704088Z","end":"2026-05-07T00:47:38.814839Z","steps":["trace[931929604] 'read index received'  (duration: 110.639755ms)","trace[931929604] 'applied index is now lower than readState.Index'  (duration: 111.293µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:47:38.814987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.874911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-07T00:47:38.815014Z","caller":"traceutil/trace.go:171","msg":"trace[1276656421] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2540; }","duration":"110.963713ms","start":"2026-05-07T00:47:38.704044Z","end":"2026-05-07T00:47:38.815007Z","steps":["trace[1276656421] 'agreement among raft nodes before linearized reading'  (duration: 110.895612ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:47:38.815004Z","caller":"traceutil/trace.go:171","msg":"trace[315231945] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"229.885374ms","start":"2026-05-07T00:47:38.58509Z","end":"2026-05-07T00:47:38.814975Z","steps":["trace[315231945] 'process raft request'  (duration: 229.625708ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:47:38.815059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.744576ms","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-05-07T00:47:38.815174Z","caller":"traceutil/trace.go:171","msg":"trace[1293497294] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2540; }","duration":"108.89741ms","start":"2026-05-07T00:47:38.706256Z","end":"2026-05-07T00:47:38.815154Z","steps":["trace[1293497294] 'agreement among raft nodes before linearized reading'  (duration: 108.745076ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:48:06.765138Z","caller":"traceutil/trace.go:171","msg":"trace[1092736026] linearizableReadLoop","detail":"{readStateIndex:2836; appliedIndex:2834; }","duration":"227.345986ms","start":"2026-05-07T00:48:06.537771Z","end":"2026-05-07T00:48:06.765117Z","steps":["trace[1092736026] 'read index received'  (duration: 90.57975ms)","trace[1092736026] 'applied index is now lower than readState.Index'  (duration: 136.765146ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:48:06.765259Z","caller":"traceutil/trace.go:171","msg":"trace[976481935] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"228.496236ms","start":"2026-05-07T00:48:06.536751Z","end":"2026-05-07T00:48:06.765247Z","steps":["trace[976481935] 'process raft request'  (duration: 228.219279ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:48:06.765549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.705285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1245"}
{"level":"info","ts":"2026-05-07T00:48:06.765609Z","caller":"traceutil/trace.go:171","msg":"trace[221354642] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2735; }","duration":"227.826668ms","start":"2026-05-07T00:48:06.537767Z","end":"2026-05-07T00:48:06.765593Z","steps":["trace[221354642] 'agreement among raft nodes before linearized reading'  (duration: 227.548811ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:48:06.999415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.123664ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421303573200650115 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:2684 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-07T00:48:06.999527Z","caller":"traceutil/trace.go:171","msg":"trace[968360719] linearizableReadLoop","detail":"{readStateIndex:2837; appliedIndex:2836; }","duration":"234.29661ms","start":"2026-05-07T00:48:06.765214Z","end":"2026-05-07T00:48:06.99951Z","steps":["trace[968360719] 'read index received'  (duration: 116.826216ms)","trace[968360719] 'applied index is now lower than readState.Index'  (duration: 117.469204ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:48:06.999608Z","caller":"traceutil/trace.go:171","msg":"trace[652671014] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"305.665391ms","start":"2026-05-07T00:48:06.693901Z","end":"2026-05-07T00:48:06.999567Z","steps":["trace[652671014] 'process raft request'  (duration: 188.153917ms)","trace[652671014] 'compare'  (duration: 116.942099ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:48:06.999784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.421157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5908"}
{"level":"warn","ts":"2026-05-07T00:48:06.999827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.716589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad220e14e5608c\" ","response":"range_response_count:1 size:779"}
{"level":"warn","ts":"2026-05-07T00:48:06.999799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.064025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-07T00:48:06.999873Z","caller":"traceutil/trace.go:171","msg":"trace[947114982] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad220e14e5608c; range_end:; response_count:1; response_revision:2736; }","duration":"240.866944ms","start":"2026-05-07T00:48:06.758994Z","end":"2026-05-07T00:48:06.999861Z","steps":["trace[947114982] 'agreement among raft nodes before linearized reading'  (duration: 240.601807ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:48:06.999927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:48:06.693871Z","time spent":"305.876337ms","remote":"127.0.0.1:51832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:2684 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"info","ts":"2026-05-07T00:48:06.999889Z","caller":"traceutil/trace.go:171","msg":"trace[378602586] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2736; }","duration":"230.574091ms","start":"2026-05-07T00:48:06.769281Z","end":"2026-05-07T00:48:06.999855Z","steps":["trace[378602586] 'agreement among raft nodes before linearized reading'  (duration: 230.342055ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:48:07.000021Z","caller":"traceutil/trace.go:171","msg":"trace[43763339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2736; }","duration":"122.330412ms","start":"2026-05-07T00:48:06.877616Z","end":"2026-05-07T00:48:06.999946Z","steps":["trace[43763339] 'agreement among raft nodes before linearized reading'  (duration: 122.024704ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:50:21.851826Z","caller":"traceutil/trace.go:171","msg":"trace[1669296680] transaction","detail":"{read_only:false; response_revision:4202; number_of_response:1; }","duration":"106.897053ms","start":"2026-05-07T00:50:21.744865Z","end":"2026-05-07T00:50:21.851762Z","steps":["trace[1669296680] 'process raft request'  (duration: 89.086003ms)","trace[1669296680] 'compare'  (duration: 17.539564ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:50:21.866415Z","caller":"traceutil/trace.go:171","msg":"trace[1386633779] transaction","detail":"{read_only:false; response_revision:4204; number_of_response:1; }","duration":"109.780266ms","start":"2026-05-07T00:50:21.756623Z","end":"2026-05-07T00:50:21.866403Z","steps":["trace[1386633779] 'process raft request'  (duration: 109.739695ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:50:21.866591Z","caller":"traceutil/trace.go:171","msg":"trace[2054174288] transaction","detail":"{read_only:false; response_revision:4203; number_of_response:1; }","duration":"120.376748ms","start":"2026-05-07T00:50:21.746163Z","end":"2026-05-07T00:50:21.86654Z","steps":["trace[2054174288] 'process raft request'  (duration: 120.130093ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-07T00:50:28.828544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.110223ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421303573200653963 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:4307 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-07T00:50:28.828694Z","caller":"traceutil/trace.go:171","msg":"trace[811116502] linearizableReadLoop","detail":"{readStateIndex:4529; appliedIndex:4528; }","duration":"126.342611ms","start":"2026-05-07T00:50:28.70233Z","end":"2026-05-07T00:50:28.828672Z","steps":["trace[811116502] 'read index received'  (duration: 57.721µs)","trace[811116502] 'applied index is now lower than readState.Index'  (duration: 126.283349ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:50:28.828891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.558375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-05-07T00:50:28.828924Z","caller":"traceutil/trace.go:171","msg":"trace[825112040] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4345; }","duration":"126.636607ms","start":"2026-05-07T00:50:28.702274Z","end":"2026-05-07T00:50:28.82891Z","steps":["trace[825112040] 'agreement among raft nodes before linearized reading'  (duration: 126.455053ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:50:28.829138Z","caller":"traceutil/trace.go:171","msg":"trace[1803293391] transaction","detail":"{read_only:false; response_revision:4345; number_of_response:1; }","duration":"334.565276ms","start":"2026-05-07T00:50:28.494538Z","end":"2026-05-07T00:50:28.829103Z","steps":["trace[1803293391] 'process raft request'  (duration: 56.759238ms)","trace[1803293391] 'compare'  (duration: 276.876767ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:50:28.829345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T00:50:28.494503Z","time spent":"334.745999ms","remote":"127.0.0.1:51832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:4307 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-05-07T00:50:34.252838Z","caller":"traceutil/trace.go:171","msg":"trace[1857566928] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"135.04951ms","start":"2026-05-07T00:50:34.117751Z","end":"2026-05-07T00:50:34.252801Z","steps":["trace[1857566928] 'process raft request'  (duration: 134.833925ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:50:37.483405Z","caller":"traceutil/trace.go:171","msg":"trace[406672809] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"119.605606ms","start":"2026-05-07T00:50:37.363732Z","end":"2026-05-07T00:50:37.483338Z","steps":["trace[406672809] 'process raft request'  (duration: 119.388271ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:50:37.837327Z","caller":"traceutil/trace.go:171","msg":"trace[1241364927] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"105.303715ms","start":"2026-05-07T00:50:37.731936Z","end":"2026-05-07T00:50:37.837239Z","steps":["trace[1241364927] 'process raft request'  (duration: 58.278859ms)","trace[1241364927] 'compare'  (duration: 46.820342ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:50:43.006115Z","caller":"traceutil/trace.go:171","msg":"trace[1523184590] transaction","detail":"{read_only:false; response_revision:4576; number_of_response:1; }","duration":"101.24887ms","start":"2026-05-07T00:50:42.904829Z","end":"2026-05-07T00:50:43.006078Z","steps":["trace[1523184590] 'process raft request'  (duration: 90.424516ms)","trace[1523184590] 'compare'  (duration: 10.239741ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:50:45.294372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.757264ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421303573200654584 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:4578 > success:<request_put:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" value_size:549 >> failure:<request_range:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-07T00:50:45.294476Z","caller":"traceutil/trace.go:171","msg":"trace[244265102] linearizableReadLoop","detail":"{readStateIndex:4804; appliedIndex:4803; }","duration":"167.354421ms","start":"2026-05-07T00:50:45.127105Z","end":"2026-05-07T00:50:45.294459Z","steps":["trace[244265102] 'read index received'  (duration: 64.398593ms)","trace[244265102] 'applied index is now lower than readState.Index'  (duration: 102.954468ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:50:45.294681Z","caller":"traceutil/trace.go:171","msg":"trace[2140033706] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"184.690304ms","start":"2026-05-07T00:50:45.10998Z","end":"2026-05-07T00:50:45.29467Z","steps":["trace[2140033706] 'process raft request'  (duration: 81.546281ms)","trace[2140033706] 'compare'  (duration: 102.654662ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:50:45.294895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.794441ms","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-05-07T00:50:45.294927Z","caller":"traceutil/trace.go:171","msg":"trace[286547245] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4609; }","duration":"167.824432ms","start":"2026-05-07T00:50:45.127092Z","end":"2026-05-07T00:50:45.294916Z","steps":["trace[286547245] 'agreement among raft nodes before linearized reading'  (duration: 167.724219ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:51:10.873892Z","caller":"traceutil/trace.go:171","msg":"trace[1108677640] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"219.416632ms","start":"2026-05-07T00:51:10.654436Z","end":"2026-05-07T00:51:10.873853Z","steps":["trace[1108677640] 'process raft request'  (duration: 218.767707ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:51:55.991612Z","caller":"traceutil/trace.go:171","msg":"trace[1681878203] linearizableReadLoop","detail":"{readStateIndex:5578; appliedIndex:5577; }","duration":"113.078751ms","start":"2026-05-07T00:51:55.878501Z","end":"2026-05-07T00:51:55.991579Z","steps":["trace[1681878203] 'read index received'  (duration: 37.160424ms)","trace[1681878203] 'applied index is now lower than readState.Index'  (duration: 75.916757ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:51:55.992006Z","caller":"traceutil/trace.go:171","msg":"trace[875341987] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"129.090321ms","start":"2026-05-07T00:51:55.862881Z","end":"2026-05-07T00:51:55.991971Z","steps":["trace[875341987] 'process raft request'  (duration: 52.816385ms)","trace[875341987] 'compare'  (duration: 75.686591ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-07T00:51:55.992168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.626534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-05-07T00:51:55.992278Z","caller":"traceutil/trace.go:171","msg":"trace[1008670078] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:5351; }","duration":"113.761708ms","start":"2026-05-07T00:51:55.878496Z","end":"2026-05-07T00:51:55.992257Z","steps":["trace[1008670078] 'agreement among raft nodes before linearized reading'  (duration: 113.42937ms)"],"step_count":1}
{"level":"info","ts":"2026-05-07T00:54:02.935815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3205}
{"level":"info","ts":"2026-05-07T00:54:03.021531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3205,"took":"83.99556ms","hash":1705595837,"current-db-size-bytes":59531264,"current-db-size":"60 MB","current-db-size-in-use-bytes":32010240,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-07T00:54:03.021593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1705595837,"revision":3205,"compact-revision":-1}
{"level":"warn","ts":"2026-05-07T00:54:38.16654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.509855ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421303573200660158 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:6688 > success:<request_put:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" value_size:549 >> failure:<request_range:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-07T00:54:38.167224Z","caller":"traceutil/trace.go:171","msg":"trace[404424264] transaction","detail":"{read_only:false; response_revision:6702; number_of_response:1; }","duration":"161.453976ms","start":"2026-05-07T00:54:38.005747Z","end":"2026-05-07T00:54:38.167201Z","steps":["trace[404424264] 'process raft request'  (duration: 23.176411ms)","trace[404424264] 'compare'  (duration: 137.265899ms)"],"step_count":2}
{"level":"info","ts":"2026-05-07T00:54:38.301613Z","caller":"traceutil/trace.go:171","msg":"trace[1689666230] transaction","detail":"{read_only:false; response_revision:6703; number_of_response:1; }","duration":"127.375341ms","start":"2026-05-07T00:54:38.174133Z","end":"2026-05-07T00:54:38.301509Z","steps":["trace[1689666230] 'process raft request'  (duration: 127.226227ms)"],"step_count":1}