{"level":"warn","ts":"2026-08-20T01:07:48.865279Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:07:48.865366Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.182: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.182:2380","--initial-cluster=instance=https://199.204.45.182:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.182:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.182: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-08-20T01:07:48.865425Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:07:48.865438Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-20T01:07:48.865457Z","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-08-20T01:07:48.866003Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"]} {"level":"info","ts":"2026-08-20T01:07:48.866072Z","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.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.182: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-08-20T01:07:48.87374Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.476586ms"} {"level":"info","ts":"2026-08-20T01:07:48.884279Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e44ae1545340c14b","cluster-id":"7284b69afd367451"} {"level":"info","ts":"2026-08-20T01:07:48.884432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:07:48.88446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 0"} {"level":"info","ts":"2026-08-20T01:07:48.884505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e44ae1545340c14b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:07:48.884524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 1"} {"level":"info","ts":"2026-08-20T01:07:48.884584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"warn","ts":"2026-08-20T01:07:48.896829Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:07:48.898841Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:07:48.900162Z","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-08-20T01:07:48.905824Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e44ae1545340c14b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:07:48.908209Z","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-08-20T01:07:48.908592Z","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-08-20T01:07:48.908704Z","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-08-20T01:07:48.909587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"info","ts":"2026-08-20T01:07:48.910196Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e44ae1545340c14b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:07:48.910455Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","added-peer-id":"e44ae1545340c14b","added-peer-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-20T01:07:48.911022Z","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-08-20T01:07:48.91115Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-20T01:07:48.911262Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-20T01:07:48.911443Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e44ae1545340c14b","initial-advertise-peer-urls":["https://199.204.45.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:07:48.911484Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:07:48.985404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:07:48.985475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:07:48.985509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgPreVoteResp from e44ae1545340c14b at term 1"} {"level":"info","ts":"2026-08-20T01:07:48.985528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:07:48.985539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgVoteResp from e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-20T01:07:48.98556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became leader at term 2"} {"level":"info","ts":"2026-08-20T01:07:48.985577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e44ae1545340c14b elected leader e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-20T01:07:48.986965Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e44ae1545340c14b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.182:2379]}","request-path":"/0/members/e44ae1545340c14b/attributes","cluster-id":"7284b69afd367451","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:07:48.987024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:07:48.987168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:07:48.987314Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:07:48.987363Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:07:48.987578Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:07:48.988356Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:07:48.988591Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:07:48.988727Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:07:48.991066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.182:2379"} {"level":"info","ts":"2026-08-20T01:07:48.992422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:08:05.228564Z","caller":"traceutil/trace.go:171","msg":"trace[261128836] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"126.152669ms","start":"2026-08-20T01:08:05.102383Z","end":"2026-08-20T01:08:05.228536Z","steps":["trace[261128836] 'process raft request' (duration: 126.002578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:08:05.531622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.82468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:08:05.53177Z","caller":"traceutil/trace.go:171","msg":"trace[1345201335] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"298.96846ms","start":"2026-08-20T01:08:05.232778Z","end":"2026-08-20T01:08:05.531747Z","steps":["trace[1345201335] 'process raft request' (duration: 192.429963ms)","trace[1345201335] 'compare' (duration: 105.576988ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:08:05.531853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.171929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:08:05.531748Z","caller":"traceutil/trace.go:171","msg":"trace[985769766] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"143.052828ms","start":"2026-08-20T01:08:05.388681Z","end":"2026-08-20T01:08:05.531733Z","steps":["trace[985769766] 'read index received' (duration: 36.513761ms)","trace[985769766] 'applied index is now lower than readState.Index' (duration: 106.535607ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:08:05.53197Z","caller":"traceutil/trace.go:171","msg":"trace[427413467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"143.325941ms","start":"2026-08-20T01:08:05.388634Z","end":"2026-08-20T01:08:05.531959Z","steps":["trace[427413467] 'agreement among raft nodes before linearized reading' (duration: 143.18228ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:05.70169Z","caller":"traceutil/trace.go:171","msg":"trace[1805591179] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"157.709968ms","start":"2026-08-20T01:08:05.543962Z","end":"2026-08-20T01:08:05.701672Z","steps":["trace[1805591179] 'process raft request' (duration: 157.619007ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:05.825073Z","caller":"traceutil/trace.go:171","msg":"trace[636526262] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"115.315235ms","start":"2026-08-20T01:08:05.70973Z","end":"2026-08-20T01:08:05.825045Z","steps":["trace[636526262] 'process raft request' (duration: 114.926671ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:24.595698Z","caller":"traceutil/trace.go:171","msg":"trace[1082526720] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"105.547985ms","start":"2026-08-20T01:08:24.490117Z","end":"2026-08-20T01:08:24.595665Z","steps":["trace[1082526720] 'process raft request' (duration: 75.754685ms)","trace[1082526720] 'compare' (duration: 29.587729ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:08:24.595741Z","caller":"traceutil/trace.go:171","msg":"trace[1814522410] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"104.312415ms","start":"2026-08-20T01:08:24.491409Z","end":"2026-08-20T01:08:24.595721Z","steps":["trace[1814522410] 'process raft request' (duration: 104.210855ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:26.140365Z","caller":"traceutil/trace.go:171","msg":"trace[2029426793] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"100.817608ms","start":"2026-08-20T01:08:26.039522Z","end":"2026-08-20T01:08:26.14034Z","steps":["trace[2029426793] 'process raft request' (duration: 100.649867ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:26.149475Z","caller":"traceutil/trace.go:171","msg":"trace[1603230789] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"106.243436ms","start":"2026-08-20T01:08:26.043202Z","end":"2026-08-20T01:08:26.149446Z","steps":["trace[1603230789] 'process raft request' (duration: 106.066055ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:26.149749Z","caller":"traceutil/trace.go:171","msg":"trace[811438268] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"106.445977ms","start":"2026-08-20T01:08:26.043275Z","end":"2026-08-20T01:08:26.149721Z","steps":["trace[811438268] 'process raft request' (duration: 106.070634ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:32.350711Z","caller":"traceutil/trace.go:171","msg":"trace[379669443] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"162.78218ms","start":"2026-08-20T01:08:32.187902Z","end":"2026-08-20T01:08:32.350684Z","steps":["trace[379669443] 'read index received' (duration: 161.932265ms)","trace[379669443] 'applied index is now lower than readState.Index' (duration: 848.765µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:08:32.350882Z","caller":"traceutil/trace.go:171","msg":"trace[221003021] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"199.278941ms","start":"2026-08-20T01:08:32.151513Z","end":"2026-08-20T01:08:32.350792Z","steps":["trace[221003021] 'process raft request' (duration: 198.537117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:08:32.351039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.103112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m8z2k\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-20T01:08:32.351143Z","caller":"traceutil/trace.go:171","msg":"trace[473607225] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m8z2k; range_end:; response_count:1; response_revision:591; }","duration":"163.247924ms","start":"2026-08-20T01:08:32.187867Z","end":"2026-08-20T01:08:32.351115Z","steps":["trace[473607225] 'agreement among raft nodes before linearized reading' (duration: 162.944802ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:32.675317Z","caller":"traceutil/trace.go:171","msg":"trace[2025841612] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"271.208864ms","start":"2026-08-20T01:08:32.404085Z","end":"2026-08-20T01:08:32.675294Z","steps":["trace[2025841612] 'process raft request' (duration: 271.154824ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:08:32.675447Z","caller":"traceutil/trace.go:171","msg":"trace[896316340] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"306.089294ms","start":"2026-08-20T01:08:32.369303Z","end":"2026-08-20T01:08:32.675392Z","steps":["trace[896316340] 'process raft request' (duration: 275.05066ms)","trace[896316340] 'compare' (duration: 30.749772ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:08:32.675654Z","caller":"traceutil/trace.go:171","msg":"trace[486654921] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"298.920985ms","start":"2026-08-20T01:08:32.376722Z","end":"2026-08-20T01:08:32.675643Z","steps":["trace[486654921] 'read index received' (duration: 267.64621ms)","trace[486654921] 'applied index is now lower than readState.Index' (duration: 31.273765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:08:32.675648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:08:32.369282Z","time spent":"306.259055ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:08:32.675782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.041466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-20T01:08:32.675813Z","caller":"traceutil/trace.go:171","msg":"trace[1404479949] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:594; }","duration":"299.131098ms","start":"2026-08-20T01:08:32.376673Z","end":"2026-08-20T01:08:32.675804Z","steps":["trace[1404479949] 'agreement among raft nodes before linearized reading' (duration: 299.010647ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:09:14.505313Z","caller":"traceutil/trace.go:171","msg":"trace[698724016] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"186.873535ms","start":"2026-08-20T01:09:14.318406Z","end":"2026-08-20T01:09:14.50528Z","steps":["trace[698724016] 'process raft request' (duration: 174.484172ms)","trace[698724016] 'compare' (duration: 12.145112ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:09:40.695396Z","caller":"traceutil/trace.go:171","msg":"trace[1205248550] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"106.14225ms","start":"2026-08-20T01:09:40.589213Z","end":"2026-08-20T01:09:40.695355Z","steps":["trace[1205248550] 'process raft request' (duration: 105.773848ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:10:13.655636Z","caller":"traceutil/trace.go:171","msg":"trace[1812080984] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"179.830495ms","start":"2026-08-20T01:10:13.475765Z","end":"2026-08-20T01:10:13.655595Z","steps":["trace[1812080984] 'process raft request' (duration: 179.695431ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:10:13.836224Z","caller":"traceutil/trace.go:171","msg":"trace[1834976412] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"235.632813ms","start":"2026-08-20T01:10:13.600562Z","end":"2026-08-20T01:10:13.836195Z","steps":["trace[1834976412] 'read index received' (duration: 55.04483ms)","trace[1834976412] 'applied index is now lower than readState.Index' (duration: 180.587143ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:10:13.836454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.874369ms","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-08-20T01:10:13.836525Z","caller":"traceutil/trace.go:171","msg":"trace[2080786976] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1856; }","duration":"235.999802ms","start":"2026-08-20T01:10:13.600508Z","end":"2026-08-20T01:10:13.836508Z","steps":["trace[2080786976] 'agreement among raft nodes before linearized reading' (duration: 235.795777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:13.836589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.668045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e884b91f-c683-497e-ad0e-485c5703d1c2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-20T01:10:13.836661Z","caller":"traceutil/trace.go:171","msg":"trace[1270116377] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e884b91f-c683-497e-ad0e-485c5703d1c2; range_end:; response_count:1; response_revision:1856; }","duration":"168.789067ms","start":"2026-08-20T01:10:13.667856Z","end":"2026-08-20T01:10:13.836645Z","steps":["trace[1270116377] 'agreement among raft nodes before linearized reading' (duration: 168.588733ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:10:13.836775Z","caller":"traceutil/trace.go:171","msg":"trace[895136860] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"342.836883ms","start":"2026-08-20T01:10:13.49344Z","end":"2026-08-20T01:10:13.836276Z","steps":["trace[895136860] 'process raft request' (duration: 342.597947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:13.836941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:10:13.493412Z","time spent":"343.445818ms","remote":"127.0.0.1:50770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:10:13.837067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.15117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-20T01:10:13.837097Z","caller":"traceutil/trace.go:171","msg":"trace[124194514] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"153.217532ms","start":"2026-08-20T01:10:13.683869Z","end":"2026-08-20T01:10:13.837086Z","steps":["trace[124194514] 'agreement among raft nodes before linearized reading' (duration: 152.580186ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:10:22.345593Z","caller":"traceutil/trace.go:171","msg":"trace[723606179] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"148.72989ms","start":"2026-08-20T01:10:22.196839Z","end":"2026-08-20T01:10:22.345569Z","steps":["trace[723606179] 'process raft request' (duration: 148.385123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:27.736203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.92066ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T01:10:48.155676Z","caller":"traceutil/trace.go:171","msg":"trace[420441885] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"260.383841ms","start":"2026-08-20T01:10:47.895269Z","end":"2026-08-20T01:10:48.155653Z","steps":["trace[420441885] 'process raft request' (duration: 260.204608ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:10:48.26817Z","caller":"traceutil/trace.go:171","msg":"trace[403062414] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2181; }","duration":"250.085459ms","start":"2026-08-20T01:10:48.018036Z","end":"2026-08-20T01:10:48.268122Z","steps":["trace[403062414] 'read index received' (duration: 137.558603ms)","trace[403062414] 'applied index is now lower than readState.Index' (duration: 112.525596ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:10:48.268224Z","caller":"traceutil/trace.go:171","msg":"trace[720281487] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"273.909905ms","start":"2026-08-20T01:10:47.994281Z","end":"2026-08-20T01:10:48.268191Z","steps":["trace[720281487] 'process raft request' (duration: 203.502959ms)","trace[720281487] 'compare' (duration: 69.929308ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:10:48.26828Z","caller":"traceutil/trace.go:171","msg":"trace[437658874] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"225.237135ms","start":"2026-08-20T01:10:48.043011Z","end":"2026-08-20T01:10:48.268248Z","steps":["trace[437658874] 'process raft request' (duration: 225.031192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:48.268469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.422266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-20T01:10:48.268515Z","caller":"traceutil/trace.go:171","msg":"trace[866518801] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2099; }","duration":"250.500707ms","start":"2026-08-20T01:10:48.018002Z","end":"2026-08-20T01:10:48.268503Z","steps":["trace[866518801] 'agreement among raft nodes before linearized reading' (duration: 250.354694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:48.268723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.980442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-20T01:10:48.26875Z","caller":"traceutil/trace.go:171","msg":"trace[2066125178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2099; }","duration":"184.035913ms","start":"2026-08-20T01:10:48.084707Z","end":"2026-08-20T01:10:48.268743Z","steps":["trace[2066125178] 'agreement among raft nodes before linearized reading' (duration: 183.966392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:48.268887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.680523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-20T01:10:48.268992Z","caller":"traceutil/trace.go:171","msg":"trace[1413270545] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"208.933668ms","start":"2026-08-20T01:10:48.060033Z","end":"2026-08-20T01:10:48.268966Z","steps":["trace[1413270545] 'agreement among raft nodes before linearized reading' (duration: 208.621223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:48.624061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.747705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:10:48.624139Z","caller":"traceutil/trace.go:171","msg":"trace[1582187459] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2101; }","duration":"242.871537ms","start":"2026-08-20T01:10:48.381248Z","end":"2026-08-20T01:10:48.62412Z","steps":["trace[1582187459] 'count revisions from in-memory index tree' (duration: 242.677454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:10:48.624135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.253153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18cd5e3d0ee4dd6a\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-08-20T01:10:48.624146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.105472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-20T01:10:48.624112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.4447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-20T01:10:48.624666Z","caller":"traceutil/trace.go:171","msg":"trace[346948559] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2101; }","duration":"104.029342ms","start":"2026-08-20T01:10:48.520603Z","end":"2026-08-20T01:10:48.624632Z","steps":["trace[346948559] 'range keys from in-memory index tree' (duration: 103.209386ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:10:48.624387Z","caller":"traceutil/trace.go:171","msg":"trace[1819384007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2101; }","duration":"156.353846ms","start":"2026-08-20T01:10:48.467969Z","end":"2026-08-20T01:10:48.624323Z","steps":["trace[1819384007] 'range keys from in-memory index tree' (duration: 155.918058ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:10:48.62422Z","caller":"traceutil/trace.go:171","msg":"trace[687618318] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18cd5e3d0ee4dd6a; range_end:; response_count:1; response_revision:2101; }","duration":"146.363484ms","start":"2026-08-20T01:10:48.47783Z","end":"2026-08-20T01:10:48.624193Z","steps":["trace[687618318] 'range keys from in-memory index tree' (duration: 146.084389ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:11:53.096829Z","caller":"traceutil/trace.go:171","msg":"trace[1215287728] linearizableReadLoop","detail":"{readStateIndex:2651; appliedIndex:2650; }","duration":"172.140185ms","start":"2026-08-20T01:11:52.924672Z","end":"2026-08-20T01:11:53.096812Z","steps":["trace[1215287728] 'read index received' (duration: 171.972323ms)","trace[1215287728] 'applied index is now lower than readState.Index' (duration: 167.052µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:11:53.096986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.295827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-20T01:11:53.097011Z","caller":"traceutil/trace.go:171","msg":"trace[123347608] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2551; }","duration":"172.358988ms","start":"2026-08-20T01:11:52.924642Z","end":"2026-08-20T01:11:53.097001Z","steps":["trace[123347608] 'agreement among raft nodes before linearized reading' (duration: 172.243347ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:11:53.097099Z","caller":"traceutil/trace.go:171","msg":"trace[885454389] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"173.327312ms","start":"2026-08-20T01:11:52.923764Z","end":"2026-08-20T01:11:53.097092Z","steps":["trace[885454389] 'process raft request' (duration: 172.931146ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:12:31.191368Z","caller":"traceutil/trace.go:171","msg":"trace[1175562891] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"126.367096ms","start":"2026-08-20T01:12:31.06498Z","end":"2026-08-20T01:12:31.191347Z","steps":["trace[1175562891] 'process raft request' (duration: 126.225594ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:13.800755Z","caller":"traceutil/trace.go:171","msg":"trace[1787293639] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"104.830857ms","start":"2026-08-20T01:16:13.695898Z","end":"2026-08-20T01:16:13.800729Z","steps":["trace[1787293639] 'process raft request' (duration: 104.678534ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:15.622305Z","caller":"traceutil/trace.go:171","msg":"trace[24035522] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"140.476649ms","start":"2026-08-20T01:16:15.481803Z","end":"2026-08-20T01:16:15.62228Z","steps":["trace[24035522] 'process raft request' (duration: 122.524795ms)","trace[24035522] 'compare' (duration: 17.833291ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:26.207238Z","caller":"traceutil/trace.go:171","msg":"trace[1591459616] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"263.824626ms","start":"2026-08-20T01:16:25.943366Z","end":"2026-08-20T01:16:26.20719Z","steps":["trace[1591459616] 'process raft request' (duration: 263.678693ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:26.2707Z","caller":"traceutil/trace.go:171","msg":"trace[695091760] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5056; }","duration":"151.470357ms","start":"2026-08-20T01:16:26.119203Z","end":"2026-08-20T01:16:26.270673Z","steps":["trace[695091760] 'read index received' (duration: 87.819075ms)","trace[695091760] 'applied index is now lower than readState.Index' (duration: 63.649722ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:26.270757Z","caller":"traceutil/trace.go:171","msg":"trace[2120489800] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"161.918358ms","start":"2026-08-20T01:16:26.108814Z","end":"2026-08-20T01:16:26.270733Z","steps":["trace[2120489800] 'process raft request' (duration: 161.665924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:26.271019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.808073ms","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-08-20T01:16:26.271078Z","caller":"traceutil/trace.go:171","msg":"trace[883233635] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4865; }","duration":"151.920436ms","start":"2026-08-20T01:16:26.119141Z","end":"2026-08-20T01:16:26.271061Z","steps":["trace[883233635] 'agreement among raft nodes before linearized reading' (duration: 151.670921ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:26.598699Z","caller":"traceutil/trace.go:171","msg":"trace[849942108] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"107.481364ms","start":"2026-08-20T01:16:26.491193Z","end":"2026-08-20T01:16:26.598675Z","steps":["trace[849942108] 'read index received' (duration: 107.298631ms)","trace[849942108] 'applied index is now lower than readState.Index' (duration: 181.823µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:26.598822Z","caller":"traceutil/trace.go:171","msg":"trace[1831088716] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"117.226681ms","start":"2026-08-20T01:16:26.481584Z","end":"2026-08-20T01:16:26.59881Z","steps":["trace[1831088716] 'process raft request' (duration: 116.956785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:26.59903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.82218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-20T01:16:26.599085Z","caller":"traceutil/trace.go:171","msg":"trace[767634536] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4867; }","duration":"107.924392ms","start":"2026-08-20T01:16:26.491149Z","end":"2026-08-20T01:16:26.599074Z","steps":["trace[767634536] 'agreement among raft nodes before linearized reading' (duration: 107.77542ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:49.379372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2859} {"level":"info","ts":"2026-08-20T01:17:49.46739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2859,"took":"86.511172ms","hash":2132047005,"current-db-size-bytes":53960704,"current-db-size":"54 MB","current-db-size-in-use-bytes":30064640,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-20T01:17:49.467471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2132047005,"revision":2859,"compact-revision":-1} {"level":"info","ts":"2026-08-20T01:22:49.385002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5594} {"level":"info","ts":"2026-08-20T01:22:49.467558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5594,"took":"81.376392ms","hash":1866406360,"current-db-size-bytes":53960704,"current-db-size":"54 MB","current-db-size-in-use-bytes":18571264,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-20T01:22:49.467599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1866406360,"revision":5594,"compact-revision":2859}