{"level":"warn","ts":"2026-04-16T22:42:56.989344Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:42:56.989462Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T22:42:56.989547Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:42:56.989558Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-16T22:42:56.989585Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:42:56.991172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-04-16T22:42:56.991279Z","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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T22:42:56.996981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.36803ms"} {"level":"info","ts":"2026-04-16T22:42:57.002507Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-04-16T22:42:57.00256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:42:57.002577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-04-16T22:42:57.002586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:42:57.002594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-04-16T22:42:57.002623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-04-16T22:42:57.007862Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:42:57.008927Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:42:57.009898Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T22:42:57.011852Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:42:57.012047Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:42:57.012275Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:42:57.012841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:42:57.013285Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:42:57.014193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-04-16T22:42:57.014305Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-16T22:42:57.017019Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:42:57.017181Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-16T22:42:57.017222Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-16T22:42:57.017397Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:42:57.01746Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:42:57.503114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:42:57.503186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:42:57.503219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-04-16T22:42:57.503237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:42:57.503249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-16T22:42:57.503266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-04-16T22:42:57.503285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-16T22:42:57.504419Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:42:57.50457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:42:57.504733Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:42:57.504735Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:57.50467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:42:57.504777Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:42:57.505808Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:57.505937Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:57.505994Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:57.508758Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-04-16T22:42:57.51008Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T22:43:03.776989Z","caller":"traceutil/trace.go:171","msg":"trace[1114188473] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"144.672536ms","start":"2026-04-16T22:43:03.632277Z","end":"2026-04-16T22:43:03.77695Z","steps":["trace[1114188473] 'process raft request' (duration: 144.554525ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:03.985265Z","caller":"traceutil/trace.go:171","msg":"trace[733676364] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"200.580465ms","start":"2026-04-16T22:43:03.784655Z","end":"2026-04-16T22:43:03.985236Z","steps":["trace[733676364] 'process raft request' (duration: 128.125275ms)","trace[733676364] 'compare' (duration: 72.342838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:43:26.70098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.39601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:43:26.701277Z","caller":"traceutil/trace.go:171","msg":"trace[308694472] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"189.860144ms","start":"2026-04-16T22:43:26.511388Z","end":"2026-04-16T22:43:26.701248Z","steps":["trace[308694472] 'process raft request' (duration: 44.649576ms)","trace[308694472] 'compare' (duration: 144.256168ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:26.701362Z","caller":"traceutil/trace.go:171","msg":"trace[1145119082] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"153.393756ms","start":"2026-04-16T22:43:26.547951Z","end":"2026-04-16T22:43:26.701344Z","steps":["trace[1145119082] 'process raft request' (duration: 153.284624ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:26.720133Z","caller":"traceutil/trace.go:171","msg":"trace[1842445402] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"130.072881ms","start":"2026-04-16T22:43:26.590032Z","end":"2026-04-16T22:43:26.720105Z","steps":["trace[1842445402] 'process raft request' (duration: 129.883439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:28.033114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.505715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-16T22:43:28.033232Z","caller":"traceutil/trace.go:171","msg":"trace[1574327768] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:550; }","duration":"119.664228ms","start":"2026-04-16T22:43:27.913542Z","end":"2026-04-16T22:43:28.033206Z","steps":["trace[1574327768] 'range keys from in-memory index tree' (duration: 119.379415ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:28.03331Z","caller":"traceutil/trace.go:171","msg":"trace[244275798] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"117.028737ms","start":"2026-04-16T22:43:27.916257Z","end":"2026-04-16T22:43:28.033286Z","steps":["trace[244275798] 'process raft request' (duration: 116.548701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:29.719712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.940102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:43:29.71989Z","caller":"traceutil/trace.go:171","msg":"trace[301972986] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"267.987148ms","start":"2026-04-16T22:43:29.451886Z","end":"2026-04-16T22:43:29.719873Z","steps":["trace[301972986] 'process raft request' (duration: 267.930036ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:29.719931Z","caller":"traceutil/trace.go:171","msg":"trace[967292693] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"268.862468ms","start":"2026-04-16T22:43:29.451033Z","end":"2026-04-16T22:43:29.719895Z","steps":["trace[967292693] 'process raft request' (duration: 120.648742ms)","trace[967292693] 'compare' (duration: 147.819271ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:32.769995Z","caller":"traceutil/trace.go:171","msg":"trace[1101449527] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"124.653312ms","start":"2026-04-16T22:43:32.645317Z","end":"2026-04-16T22:43:32.76997Z","steps":["trace[1101449527] 'process raft request' (duration: 124.184537ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:32.954998Z","caller":"traceutil/trace.go:171","msg":"trace[484883290] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"118.138461ms","start":"2026-04-16T22:43:32.836819Z","end":"2026-04-16T22:43:32.954958Z","steps":["trace[484883290] 'process raft request' (duration: 77.829728ms)","trace[484883290] 'compare' (duration: 40.04196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:43:33.524324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.871801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T22:43:33.52442Z","caller":"traceutil/trace.go:171","msg":"trace[1324365486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"245.013612ms","start":"2026-04-16T22:43:33.279384Z","end":"2026-04-16T22:43:33.524398Z","steps":["trace[1324365486] 'range keys from in-memory index tree' (duration: 244.713119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:33.524454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.453589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T22:43:33.524561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.273775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:43:33.524609Z","caller":"traceutil/trace.go:171","msg":"trace[1481954739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"400.320925ms","start":"2026-04-16T22:43:33.124273Z","end":"2026-04-16T22:43:33.524594Z","steps":["trace[1481954739] 'range keys from in-memory index tree' (duration: 400.208165ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:33.524549Z","caller":"traceutil/trace.go:171","msg":"trace[1621365637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"147.59385ms","start":"2026-04-16T22:43:33.376934Z","end":"2026-04-16T22:43:33.524528Z","steps":["trace[1621365637] 'range keys from in-memory index tree' (duration: 147.197066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:33.524646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:33.124218Z","time spent":"400.419467ms","remote":"127.0.0.1:47746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:43:33.525114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.816357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ll8jh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T22:43:33.525348Z","caller":"traceutil/trace.go:171","msg":"trace[249746273] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ll8jh; range_end:; response_count:1; response_revision:582; }","duration":"297.281942ms","start":"2026-04-16T22:43:33.227997Z","end":"2026-04-16T22:43:33.525279Z","steps":["trace[249746273] 'range keys from in-memory index tree' (duration: 296.558154ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:33.527211Z","caller":"traceutil/trace.go:171","msg":"trace[746679446] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"131.627496ms","start":"2026-04-16T22:43:33.395563Z","end":"2026-04-16T22:43:33.52719Z","steps":["trace[746679446] 'process raft request' (duration: 131.030009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:57.688764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.302504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:43:57.688927Z","caller":"traceutil/trace.go:171","msg":"trace[2104570186] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"333.642242ms","start":"2026-04-16T22:43:57.355267Z","end":"2026-04-16T22:43:57.688909Z","steps":["trace[2104570186] 'process raft request' (duration: 154.103746ms)","trace[2104570186] 'compare' (duration: 179.135892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:43:57.689135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:57.355244Z","time spent":"333.715262ms","remote":"127.0.0.1:46140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:14.215454Z","caller":"traceutil/trace.go:171","msg":"trace[1718779978] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"119.845558ms","start":"2026-04-16T22:45:14.095575Z","end":"2026-04-16T22:45:14.21542Z","steps":["trace[1718779978] 'process raft request' (duration: 105.892099ms)","trace[1718779978] 'compare' (duration: 13.419888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:14.646163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.158834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:45:14.646488Z","caller":"traceutil/trace.go:171","msg":"trace[1920189729] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"330.457771ms","start":"2026-04-16T22:45:14.316004Z","end":"2026-04-16T22:45:14.646462Z","steps":["trace[1920189729] 'process raft request' (duration: 210.918489ms)","trace[1920189729] 'compare' (duration: 119.054831ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:45:14.646681Z","caller":"traceutil/trace.go:171","msg":"trace[1426178592] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"330.46065ms","start":"2026-04-16T22:45:14.316193Z","end":"2026-04-16T22:45:14.646654Z","steps":["trace[1426178592] 'process raft request' (duration: 330.072711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:14.646707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:14.315984Z","time spent":"330.554482ms","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:14.646834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:14.31618Z","time spent":"330.572723ms","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:14.828554Z","caller":"traceutil/trace.go:171","msg":"trace[119819252] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"126.7191ms","start":"2026-04-16T22:45:14.701807Z","end":"2026-04-16T22:45:14.828526Z","steps":["trace[119819252] 'process raft request' (duration: 126.539896ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:48.980429Z","caller":"traceutil/trace.go:171","msg":"trace[1936082296] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"133.279272ms","start":"2026-04-16T22:45:48.847121Z","end":"2026-04-16T22:45:48.9804Z","steps":["trace[1936082296] 'process raft request' (duration: 133.14889ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:49.750523Z","caller":"traceutil/trace.go:171","msg":"trace[2113817466] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"142.992468ms","start":"2026-04-16T22:45:49.607505Z","end":"2026-04-16T22:45:49.750497Z","steps":["trace[2113817466] 'process raft request' (duration: 142.798695ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:26.137322Z","caller":"traceutil/trace.go:171","msg":"trace[564579562] linearizableReadLoop","detail":"{readStateIndex:2809; appliedIndex:2808; }","duration":"125.688026ms","start":"2026-04-16T22:47:26.011604Z","end":"2026-04-16T22:47:26.137292Z","steps":["trace[564579562] 'read index received' (duration: 125.469434ms)","trace[564579562] 'applied index is now lower than readState.Index' (duration: 217.162µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:47:26.137438Z","caller":"traceutil/trace.go:171","msg":"trace[672171439] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"127.001792ms","start":"2026-04-16T22:47:26.010391Z","end":"2026-04-16T22:47:26.137393Z","steps":["trace[672171439] 'process raft request' (duration: 126.643378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:26.137555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.934749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-16T22:47:26.137625Z","caller":"traceutil/trace.go:171","msg":"trace[577531436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2708; }","duration":"126.0366ms","start":"2026-04-16T22:47:26.011572Z","end":"2026-04-16T22:47:26.137609Z","steps":["trace[577531436] 'agreement among raft nodes before linearized reading' (duration: 125.829388ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:19.675947Z","caller":"traceutil/trace.go:171","msg":"trace[501318027] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"111.579247ms","start":"2026-04-16T22:51:19.564349Z","end":"2026-04-16T22:51:19.675928Z","steps":["trace[501318027] 'process raft request' (duration: 111.484125ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:19.676135Z","caller":"traceutil/trace.go:171","msg":"trace[1648196492] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"114.675927ms","start":"2026-04-16T22:51:19.56145Z","end":"2026-04-16T22:51:19.676126Z","steps":["trace[1648196492] 'process raft request' (duration: 15.845698ms)","trace[1648196492] 'compare' (duration: 98.326875ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:19.676168Z","caller":"traceutil/trace.go:171","msg":"trace[966207682] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"110.165042ms","start":"2026-04-16T22:51:19.565981Z","end":"2026-04-16T22:51:19.676146Z","steps":["trace[966207682] 'process raft request' (duration: 110.12063ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:19.676398Z","caller":"traceutil/trace.go:171","msg":"trace[2042184014] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"110.947812ms","start":"2026-04-16T22:51:19.565439Z","end":"2026-04-16T22:51:19.676387Z","steps":["trace[2042184014] 'process raft request' (duration: 110.603543ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:19.676473Z","caller":"traceutil/trace.go:171","msg":"trace[1625403359] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"111.911646ms","start":"2026-04-16T22:51:19.564554Z","end":"2026-04-16T22:51:19.676466Z","steps":["trace[1625403359] 'process raft request' (duration: 111.34409ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.278421Z","caller":"traceutil/trace.go:171","msg":"trace[1207525432] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"153.332096ms","start":"2026-04-16T22:51:31.125039Z","end":"2026-04-16T22:51:31.278371Z","steps":["trace[1207525432] 'read index received' (duration: 109.693771ms)","trace[1207525432] 'applied index is now lower than readState.Index' (duration: 43.637495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:31.278547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.475781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:31.278575Z","caller":"traceutil/trace.go:171","msg":"trace[1126487372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4868; }","duration":"153.570422ms","start":"2026-04-16T22:51:31.124996Z","end":"2026-04-16T22:51:31.278567Z","steps":["trace[1126487372] 'agreement among raft nodes before linearized reading' (duration: 153.49138ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.278568Z","caller":"traceutil/trace.go:171","msg":"trace[1531961468] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"172.146955ms","start":"2026-04-16T22:51:31.106394Z","end":"2026-04-16T22:51:31.278541Z","steps":["trace[1531961468] 'process raft request' (duration: 128.394286ms)","trace[1531961468] 'compare' (duration: 43.265497ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:31.658451Z","caller":"traceutil/trace.go:171","msg":"trace[1486204353] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5050; }","duration":"114.87752ms","start":"2026-04-16T22:51:31.543546Z","end":"2026-04-16T22:51:31.658424Z","steps":["trace[1486204353] 'read index received' (duration: 1.244641ms)","trace[1486204353] 'applied index is now lower than readState.Index' (duration: 113.631979ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:31.658588Z","caller":"traceutil/trace.go:171","msg":"trace[1072912756] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"176.506603ms","start":"2026-04-16T22:51:31.482019Z","end":"2026-04-16T22:51:31.658525Z","steps":["trace[1072912756] 'process raft request' (duration: 176.252247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:31.658666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.101475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-16T22:51:31.658721Z","caller":"traceutil/trace.go:171","msg":"trace[1604358966] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4870; }","duration":"115.194898ms","start":"2026-04-16T22:51:31.543508Z","end":"2026-04-16T22:51:31.658703Z","steps":["trace[1604358966] 'agreement among raft nodes before linearized reading' (duration: 115.013123ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.673775Z","caller":"traceutil/trace.go:171","msg":"trace[519960902] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"127.728129ms","start":"2026-04-16T22:51:31.546029Z","end":"2026-04-16T22:51:31.673757Z","steps":["trace[519960902] 'process raft request' (duration: 127.532804ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:21.988021Z","caller":"traceutil/trace.go:171","msg":"trace[1152699305] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"137.559678ms","start":"2026-04-16T22:52:21.850429Z","end":"2026-04-16T22:52:21.987989Z","steps":["trace[1152699305] 'process raft request' (duration: 102.726123ms)","trace[1152699305] 'compare' (duration: 34.717683ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:57.531318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2875} {"level":"warn","ts":"2026-04-16T22:52:57.696558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.511963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:52:57.69666Z","caller":"traceutil/trace.go:171","msg":"trace[1843958884] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"124.758739ms","start":"2026-04-16T22:52:57.571883Z","end":"2026-04-16T22:52:57.696642Z","steps":["trace[1843958884] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:553; } (duration: 119.330939ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:57.769444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2875,"took":"236.191736ms","hash":4183703878,"current-db-size-bytes":54366208,"current-db-size":"54 MB","current-db-size-in-use-bytes":29343744,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-16T22:52:57.769521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4183703878,"revision":2875,"compact-revision":-1} {"level":"info","ts":"2026-04-16T22:54:57.781778Z","caller":"traceutil/trace.go:171","msg":"trace[2091275283] transaction","detail":"{read_only:false; response_revision:6405; number_of_response:1; }","duration":"100.737694ms","start":"2026-04-16T22:54:57.681009Z","end":"2026-04-16T22:54:57.781747Z","steps":["trace[2091275283] 'process raft request' (duration: 99.546964ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:03.718298Z","caller":"traceutil/trace.go:171","msg":"trace[1314392524] transaction","detail":"{read_only:false; response_revision:6433; number_of_response:1; }","duration":"124.653925ms","start":"2026-04-16T22:55:03.593621Z","end":"2026-04-16T22:55:03.718275Z","steps":["trace[1314392524] 'process raft request' (duration: 124.512291ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:25.456287Z","caller":"traceutil/trace.go:171","msg":"trace[566819650] linearizableReadLoop","detail":"{readStateIndex:6842; appliedIndex:6841; }","duration":"128.390325ms","start":"2026-04-16T22:55:25.327879Z","end":"2026-04-16T22:55:25.45627Z","steps":["trace[566819650] 'read index received' (duration: 128.247062ms)","trace[566819650] 'applied index is now lower than readState.Index' (duration: 142.303µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:55:25.456476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.572498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T22:55:25.456462Z","caller":"traceutil/trace.go:171","msg":"trace[517012267] transaction","detail":"{read_only:false; response_revision:6602; number_of_response:1; }","duration":"159.103795ms","start":"2026-04-16T22:55:25.297331Z","end":"2026-04-16T22:55:25.456435Z","steps":["trace[517012267] 'process raft request' (duration: 158.81629ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:25.456511Z","caller":"traceutil/trace.go:171","msg":"trace[17178714] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6602; }","duration":"128.643099ms","start":"2026-04-16T22:55:25.327858Z","end":"2026-04-16T22:55:25.456501Z","steps":["trace[17178714] 'agreement among raft nodes before linearized reading' (duration: 128.485096ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:57.536162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5597} {"level":"info","ts":"2026-04-16T22:57:57.604807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5597,"took":"67.336494ms","hash":2543467740,"current-db-size-bytes":54366208,"current-db-size":"54 MB","current-db-size-in-use-bytes":18305024,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-16T22:57:57.60488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2543467740,"revision":5597,"compact-revision":2875}