{"level":"warn","ts":"2026-05-05T12:44:04.753916Z","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-05T12:44:04.754026Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-05T12:44:04.754114Z","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-05T12:44:04.754125Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-05T12:44:04.754162Z","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-05T12:44:04.754891Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-05T12:44:04.754995Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-05T12:44:04.76086Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.626862ms"} {"level":"info","ts":"2026-05-05T12:44:04.770815Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-05T12:44:04.770951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:44:04.770989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-05T12:44:04.77101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T12:44:04.771029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-05T12:44:04.771153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-05T12:44:04.780037Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:44:04.781415Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:44:04.782863Z","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-05T12:44:04.786434Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T12:44:04.786873Z","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-05T12:44:04.786928Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T12:44:04.786988Z","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-05T12:44:04.787Z","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-05T12:44:04.788812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-05T12:44:04.788973Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-05T12:44:04.792224Z","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-05T12:44:04.792404Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-05T12:44:04.792427Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-05T12:44:04.792681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T12:44:04.792723Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:44:05.072168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:44:05.072276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:44:05.072316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-05T12:44:05.072342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:44:05.072363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-05T12:44:05.072384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-05T12:44:05.072407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-05T12:44:05.073358Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T12:44:05.073639Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:44:05.073789Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:44:05.073944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:44:05.074197Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:44:05.074268Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:44:05.074609Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:44:05.074802Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:44:05.07484Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:44:05.078016Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T12:44:05.078407Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"warn","ts":"2026-05-05T12:44:23.643388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.726477ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T12:44:23.643511Z","caller":"traceutil/trace.go:171","msg":"trace[582831875] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"263.440227ms","start":"2026-05-05T12:44:23.380054Z","end":"2026-05-05T12:44:23.643494Z","steps":["trace[582831875] 'read index received' (duration: 59.772µs)","trace[582831875] 'applied index is now lower than readState.Index' (duration: 263.378774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:44:23.643674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.303794ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:44:23.643746Z","caller":"traceutil/trace.go:171","msg":"trace[1738436166] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:403; }","duration":"201.42333ms","start":"2026-05-05T12:44:23.442314Z","end":"2026-05-05T12:44:23.643737Z","steps":["trace[1738436166] 'agreement among raft nodes before linearized reading' (duration: 201.288054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:23.643716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.642197ms","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-05T12:44:23.643805Z","caller":"traceutil/trace.go:171","msg":"trace[892233138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"263.782014ms","start":"2026-05-05T12:44:23.379999Z","end":"2026-05-05T12:44:23.643781Z","steps":["trace[892233138] 'agreement among raft nodes before linearized reading' (duration: 263.561884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:23.784489Z","caller":"traceutil/trace.go:171","msg":"trace[273908209] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"136.531432ms","start":"2026-05-05T12:44:23.647931Z","end":"2026-05-05T12:44:23.784462Z","steps":["trace[273908209] 'process raft request' (duration: 136.328702ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:23.876747Z","caller":"traceutil/trace.go:171","msg":"trace[1752679782] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"224.606411ms","start":"2026-05-05T12:44:23.652104Z","end":"2026-05-05T12:44:23.87671Z","steps":["trace[1752679782] 'process raft request' (duration: 220.330368ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:41.237662Z","caller":"traceutil/trace.go:171","msg":"trace[400799769] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"101.641217ms","start":"2026-05-05T12:44:41.136Z","end":"2026-05-05T12:44:41.237641Z","steps":["trace[400799769] 'process raft request' (duration: 101.462551ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:42.065848Z","caller":"traceutil/trace.go:171","msg":"trace[101111360] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"223.802875ms","start":"2026-05-05T12:44:41.842009Z","end":"2026-05-05T12:44:42.065812Z","steps":["trace[101111360] 'read index received' (duration: 223.496475ms)","trace[101111360] 'applied index is now lower than readState.Index' (duration: 304.61µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:44:42.066117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.078543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:44:42.066208Z","caller":"traceutil/trace.go:171","msg":"trace[1732608063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"224.213117ms","start":"2026-05-05T12:44:41.841972Z","end":"2026-05-05T12:44:42.066185Z","steps":["trace[1732608063] 'agreement among raft nodes before linearized reading' (duration: 224.012231ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:42.065915Z","caller":"traceutil/trace.go:171","msg":"trace[1842318101] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"328.59907ms","start":"2026-05-05T12:44:41.737283Z","end":"2026-05-05T12:44:42.065882Z","steps":["trace[1842318101] 'process raft request' (duration: 328.212077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:42.066924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:44:41.737253Z","time spent":"329.166607ms","remote":"127.0.0.1:57544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:44:42.066957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.101206ms","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-05T12:44:42.0671Z","caller":"traceutil/trace.go:171","msg":"trace[549955289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"198.328273ms","start":"2026-05-05T12:44:41.868693Z","end":"2026-05-05T12:44:42.067021Z","steps":["trace[549955289] 'agreement among raft nodes before linearized reading' (duration: 197.784097ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:46:24.689052Z","caller":"traceutil/trace.go:171","msg":"trace[1668112990] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"101.555704ms","start":"2026-05-05T12:46:24.587473Z","end":"2026-05-05T12:46:24.689029Z","steps":["trace[1668112990] 'process raft request' (duration: 101.444073ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:46:45.797474Z","caller":"traceutil/trace.go:171","msg":"trace[29757808] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"118.218724ms","start":"2026-05-05T12:46:45.679224Z","end":"2026-05-05T12:46:45.797442Z","steps":["trace[29757808] 'process raft request' (duration: 114.645887ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:46:46.528729Z","caller":"traceutil/trace.go:171","msg":"trace[590660674] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"197.527043ms","start":"2026-05-05T12:46:46.331176Z","end":"2026-05-05T12:46:46.528703Z","steps":["trace[590660674] 'process raft request' (duration: 197.349752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:24.866821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.500928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:48:24.867482Z","caller":"traceutil/trace.go:171","msg":"trace[1222008642] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"249.589794ms","start":"2026-05-05T12:48:24.617872Z","end":"2026-05-05T12:48:24.867462Z","steps":["trace[1222008642] 'process raft request' (duration: 249.06555ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:24.86778Z","caller":"traceutil/trace.go:171","msg":"trace[1919915726] linearizableReadLoop","detail":"{readStateIndex:2777; appliedIndex:2775; }","duration":"179.409771ms","start":"2026-05-05T12:48:24.688339Z","end":"2026-05-05T12:48:24.867749Z","steps":["trace[1919915726] 'read index received' (duration: 18.69371ms)","trace[1919915726] 'applied index is now lower than readState.Index' (duration: 160.713111ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:48:24.867914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.561675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T12:48:24.867972Z","caller":"traceutil/trace.go:171","msg":"trace[697909387] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2682; }","duration":"179.672038ms","start":"2026-05-05T12:48:24.688274Z","end":"2026-05-05T12:48:24.867946Z","steps":["trace[697909387] 'agreement among raft nodes before linearized reading' (duration: 179.578506ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:24.867914Z","caller":"traceutil/trace.go:171","msg":"trace[1472251658] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"252.763208ms","start":"2026-05-05T12:48:24.614483Z","end":"2026-05-05T12:48:24.867247Z","steps":["trace[1472251658] 'process raft request' (duration: 92.54483ms)","trace[1472251658] 'compare' (duration: 159.363295ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:48:30.115518Z","caller":"traceutil/trace.go:171","msg":"trace[1679500602] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"131.419872ms","start":"2026-05-05T12:48:29.98407Z","end":"2026-05-05T12:48:30.11549Z","steps":["trace[1679500602] 'process raft request' (duration: 131.259228ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:09.507613Z","caller":"traceutil/trace.go:171","msg":"trace[850269218] linearizableReadLoop","detail":"{readStateIndex:4689; appliedIndex:4688; }","duration":"108.720354ms","start":"2026-05-05T12:52:09.398871Z","end":"2026-05-05T12:52:09.507591Z","steps":["trace[850269218] 'read index received' (duration: 95.485273ms)","trace[850269218] 'applied index is now lower than readState.Index' (duration: 13.234281ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:09.507761Z","caller":"traceutil/trace.go:171","msg":"trace[1692505670] transaction","detail":"{read_only:false; response_revision:4521; number_of_response:1; }","duration":"156.47216ms","start":"2026-05-05T12:52:09.351226Z","end":"2026-05-05T12:52:09.507698Z","steps":["trace[1692505670] 'process raft request' (duration: 143.086575ms)","trace[1692505670] 'compare' (duration: 13.18621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:09.507837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.95498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T12:52:09.507865Z","caller":"traceutil/trace.go:171","msg":"trace[602073217] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4521; }","duration":"109.039122ms","start":"2026-05-05T12:52:09.398817Z","end":"2026-05-05T12:52:09.507857Z","steps":["trace[602073217] 'agreement among raft nodes before linearized reading' (duration: 108.914139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:14.906067Z","caller":"traceutil/trace.go:171","msg":"trace[1839265888] linearizableReadLoop","detail":"{readStateIndex:4792; appliedIndex:4790; }","duration":"124.256589ms","start":"2026-05-05T12:52:14.781785Z","end":"2026-05-05T12:52:14.906042Z","steps":["trace[1839265888] 'read index received' (duration: 124.046623ms)","trace[1839265888] 'applied index is now lower than readState.Index' (duration: 208.856µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:14.906097Z","caller":"traceutil/trace.go:171","msg":"trace[2092751718] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"168.369724ms","start":"2026-05-05T12:52:14.73771Z","end":"2026-05-05T12:52:14.90608Z","steps":["trace[2092751718] 'process raft request' (duration: 168.25347ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:14.906313Z","caller":"traceutil/trace.go:171","msg":"trace[1176474227] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"168.925398ms","start":"2026-05-05T12:52:14.737114Z","end":"2026-05-05T12:52:14.906039Z","steps":["trace[1176474227] 'process raft request' (duration: 168.709293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:14.906341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.529916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-05T12:52:14.906469Z","caller":"traceutil/trace.go:171","msg":"trace[926053804] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4622; }","duration":"124.68608ms","start":"2026-05-05T12:52:14.781745Z","end":"2026-05-05T12:52:14.906431Z","steps":["trace[926053804] 'agreement among raft nodes before linearized reading' (duration: 124.381782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:14.953905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.143836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:14.953991Z","caller":"traceutil/trace.go:171","msg":"trace[1556046847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4623; }","duration":"111.272769ms","start":"2026-05-05T12:52:14.842701Z","end":"2026-05-05T12:52:14.953974Z","steps":["trace[1556046847] 'agreement among raft nodes before linearized reading' (duration: 111.123215ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.11926Z","caller":"traceutil/trace.go:171","msg":"trace[131333051] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"149.145626ms","start":"2026-05-05T12:52:14.970076Z","end":"2026-05-05T12:52:15.119222Z","steps":["trace[131333051] 'process raft request' (duration: 71.884978ms)","trace[131333051] 'compare' (duration: 76.310453ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:15.119258Z","caller":"traceutil/trace.go:171","msg":"trace[926198085] linearizableReadLoop","detail":"{readStateIndex:4795; appliedIndex:4794; }","duration":"148.420547ms","start":"2026-05-05T12:52:14.970793Z","end":"2026-05-05T12:52:15.119213Z","steps":["trace[926198085] 'read index received' (duration: 71.148819ms)","trace[926198085] 'applied index is now lower than readState.Index' (duration: 77.269738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:15.119492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.652824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:699"} {"level":"warn","ts":"2026-05-05T12:52:15.119726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.556393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:15.11974Z","caller":"traceutil/trace.go:171","msg":"trace[78742379] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:4625; }","duration":"148.937071ms","start":"2026-05-05T12:52:14.970778Z","end":"2026-05-05T12:52:15.119715Z","steps":["trace[78742379] 'agreement among raft nodes before linearized reading' (duration: 148.53488ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.119811Z","caller":"traceutil/trace.go:171","msg":"trace[529393428] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4625; }","duration":"147.744589ms","start":"2026-05-05T12:52:14.972035Z","end":"2026-05-05T12:52:15.11978Z","steps":["trace[529393428] 'agreement among raft nodes before linearized reading' (duration: 147.515693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:15.120029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.150771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-05T12:52:15.120154Z","caller":"traceutil/trace.go:171","msg":"trace[1254200624] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4625; }","duration":"135.313375ms","start":"2026-05-05T12:52:14.984817Z","end":"2026-05-05T12:52:15.120131Z","steps":["trace[1254200624] 'agreement among raft nodes before linearized reading' (duration: 134.810072ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.508301Z","caller":"traceutil/trace.go:171","msg":"trace[1853084691] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"174.556143ms","start":"2026-05-05T12:52:15.333708Z","end":"2026-05-05T12:52:15.508264Z","steps":["trace[1853084691] 'process raft request' (duration: 174.337138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.572264Z","caller":"traceutil/trace.go:171","msg":"trace[1389280437] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"238.093464ms","start":"2026-05-05T12:52:15.334108Z","end":"2026-05-05T12:52:15.572201Z","steps":["trace[1389280437] 'process raft request' (duration: 236.79308ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.572331Z","caller":"traceutil/trace.go:171","msg":"trace[662185124] linearizableReadLoop","detail":"{readStateIndex:4809; appliedIndex:4804; }","duration":"150.972162ms","start":"2026-05-05T12:52:15.421336Z","end":"2026-05-05T12:52:15.572308Z","steps":["trace[662185124] 'read index received' (duration: 86.65915ms)","trace[662185124] 'applied index is now lower than readState.Index' (duration: 64.311862ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:15.572349Z","caller":"traceutil/trace.go:171","msg":"trace[1688394318] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"237.712064ms","start":"2026-05-05T12:52:15.334613Z","end":"2026-05-05T12:52:15.572325Z","steps":["trace[1688394318] 'process raft request' (duration: 237.55959ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.572399Z","caller":"traceutil/trace.go:171","msg":"trace[321260200] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"237.869808ms","start":"2026-05-05T12:52:15.334523Z","end":"2026-05-05T12:52:15.572392Z","steps":["trace[321260200] 'process raft request' (duration: 237.587731ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.572429Z","caller":"traceutil/trace.go:171","msg":"trace[891997586] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"229.577006ms","start":"2026-05-05T12:52:15.342847Z","end":"2026-05-05T12:52:15.572424Z","steps":["trace[891997586] 'process raft request' (duration: 229.399391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:15.57307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.726693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-05T12:52:15.573125Z","caller":"traceutil/trace.go:171","msg":"trace[35235485] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4639; }","duration":"151.836325ms","start":"2026-05-05T12:52:15.421271Z","end":"2026-05-05T12:52:15.573107Z","steps":["trace[35235485] 'agreement among raft nodes before linearized reading' (duration: 151.115346ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:15.676076Z","caller":"traceutil/trace.go:171","msg":"trace[362835833] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"149.670487ms","start":"2026-05-05T12:52:15.526351Z","end":"2026-05-05T12:52:15.676021Z","steps":["trace[362835833] 'process raft request' (duration: 60.467889ms)","trace[362835833] 'compare' (duration: 88.018827ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:15.676256Z","caller":"traceutil/trace.go:171","msg":"trace[1324901036] linearizableReadLoop","detail":"{readStateIndex:4810; appliedIndex:4809; }","duration":"103.606484ms","start":"2026-05-05T12:52:15.572619Z","end":"2026-05-05T12:52:15.676226Z","steps":["trace[1324901036] 'read index received' (duration: 14.210751ms)","trace[1324901036] 'applied index is now lower than readState.Index' (duration: 89.392213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:15.676888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.974806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T12:52:15.676989Z","caller":"traceutil/trace.go:171","msg":"trace[1669126278] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4647; }","duration":"116.14043ms","start":"2026-05-05T12:52:15.560823Z","end":"2026-05-05T12:52:15.676963Z","steps":["trace[1669126278] 'agreement among raft nodes before linearized reading' (duration: 115.868772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:15.677399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.836265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-05-05T12:52:15.677455Z","caller":"traceutil/trace.go:171","msg":"trace[234182352] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4647; }","duration":"114.957837ms","start":"2026-05-05T12:52:15.562484Z","end":"2026-05-05T12:52:15.677442Z","steps":["trace[234182352] 'agreement among raft nodes before linearized reading' (duration: 113.921709ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:26.930223Z","caller":"traceutil/trace.go:171","msg":"trace[166181799] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"114.984074ms","start":"2026-05-05T12:52:26.815214Z","end":"2026-05-05T12:52:26.930198Z","steps":["trace[166181799] 'process raft request' (duration: 114.536242ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:27.102134Z","caller":"traceutil/trace.go:171","msg":"trace[805007065] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"113.731103ms","start":"2026-05-05T12:52:26.98836Z","end":"2026-05-05T12:52:27.102091Z","steps":["trace[805007065] 'process raft request' (duration: 110.07006ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:05.309593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-05-05T12:54:05.382995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"71.859754ms","hash":392019356,"current-db-size-bytes":54063104,"current-db-size":"54 MB","current-db-size-in-use-bytes":29159424,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T12:54:05.383094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":392019356,"revision":2892,"compact-revision":-1} {"level":"info","ts":"2026-05-05T12:59:05.31586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5611} {"level":"info","ts":"2026-05-05T12:59:05.388486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5611,"took":"70.99871ms","hash":1623280133,"current-db-size-bytes":54063104,"current-db-size":"54 MB","current-db-size-in-use-bytes":18153472,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T12:59:05.388566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1623280133,"revision":5611,"compact-revision":2892}