{"level":"warn","ts":"2026-05-09T01:31:59.671509Z","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-09T01:31:59.671582Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-09T01:31:59.671642Z","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-09T01:31:59.671656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-09T01:31:59.671677Z","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-09T01:31:59.672272Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-05-09T01:31:59.672337Z","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.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-09T01:31:59.67615Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.625893ms"} {"level":"info","ts":"2026-05-09T01:31:59.680831Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-05-09T01:31:59.680889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T01:31:59.680917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-05-09T01:31:59.680929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T01:31:59.680938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-05-09T01:31:59.68097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-05-09T01:31:59.694142Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T01:31:59.696025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T01:31:59.696896Z","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-09T01:31:59.699123Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T01:31:59.699345Z","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-09T01:31:59.699343Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T01:31:59.699448Z","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-09T01:31:59.699459Z","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-09T01:31:59.70021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-05-09T01:31:59.70045Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-09T01:31:59.702869Z","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-09T01:31:59.703013Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-09T01:31:59.703047Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-09T01:31:59.703076Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T01:31:59.703122Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T01:32:00.181698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T01:32:00.181763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T01:32:00.181782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-05-09T01:32:00.181796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-05-09T01:32:00.181803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-09T01:32:00.181815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-05-09T01:32:00.181824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-09T01:32:00.185074Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T01:32:00.185111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:32:00.185156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:32:00.185162Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:32:00.185296Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T01:32:00.185314Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T01:32:00.187747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-05-09T01:32:00.188584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T01:32:00.201256Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:32:00.201443Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:32:00.201495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-09T01:32:03.121227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.436304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:32:03.121511Z","caller":"traceutil/trace.go:171","msg":"trace[355878179] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"184.933094ms","start":"2026-05-09T01:32:02.936524Z","end":"2026-05-09T01:32:03.121457Z","steps":["trace[355878179] 'process raft request' (duration: 16.579563ms)","trace[355878179] 'compare' (duration: 167.272471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:32:03.427042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.902176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:32:03.427171Z","caller":"traceutil/trace.go:171","msg":"trace[2010677127] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"298.726348ms","start":"2026-05-09T01:32:03.128424Z","end":"2026-05-09T01:32:03.42715Z","steps":["trace[2010677127] 'process raft request' (duration: 196.640928ms)","trace[2010677127] 'compare' (duration: 101.753854ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:29.51199Z","caller":"traceutil/trace.go:171","msg":"trace[2002515612] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"148.738778ms","start":"2026-05-09T01:32:29.363224Z","end":"2026-05-09T01:32:29.511963Z","steps":["trace[2002515612] 'process raft request' (duration: 148.565926ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:29.516233Z","caller":"traceutil/trace.go:171","msg":"trace[1628492118] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"138.397286ms","start":"2026-05-09T01:32:29.377807Z","end":"2026-05-09T01:32:29.516204Z","steps":["trace[1628492118] 'process raft request' (duration: 138.166883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:32.340224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.886579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:32.340364Z","caller":"traceutil/trace.go:171","msg":"trace[1969999441] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:596; }","duration":"175.076301ms","start":"2026-05-09T01:32:32.165264Z","end":"2026-05-09T01:32:32.34034Z","steps":["trace[1969999441] 'range keys from in-memory index tree' (duration: 174.771888ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:32.344989Z","caller":"traceutil/trace.go:171","msg":"trace[539277203] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"103.116034ms","start":"2026-05-09T01:32:32.241857Z","end":"2026-05-09T01:32:32.344973Z","steps":["trace[539277203] 'process raft request' (duration: 103.030352ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:35.295555Z","caller":"traceutil/trace.go:171","msg":"trace[752673716] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"101.382513ms","start":"2026-05-09T01:32:35.194146Z","end":"2026-05-09T01:32:35.295528Z","steps":["trace[752673716] 'process raft request' (duration: 101.227981ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:35.796001Z","caller":"traceutil/trace.go:171","msg":"trace[159883138] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"350.810208ms","start":"2026-05-09T01:32:35.445166Z","end":"2026-05-09T01:32:35.795976Z","steps":["trace[159883138] 'read index received' (duration: 350.625796ms)","trace[159883138] 'applied index is now lower than readState.Index' (duration: 183.282µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:35.796041Z","caller":"traceutil/trace.go:171","msg":"trace[2127394830] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"417.456126ms","start":"2026-05-09T01:32:35.378555Z","end":"2026-05-09T01:32:35.796011Z","steps":["trace[2127394830] 'process raft request' (duration: 417.284554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:35.796211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.833519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l6jf4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-09T01:32:35.796257Z","caller":"traceutil/trace.go:171","msg":"trace[1240893651] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l6jf4; range_end:; response_count:1; response_revision:618; }","duration":"350.92413ms","start":"2026-05-09T01:32:35.445312Z","end":"2026-05-09T01:32:35.796236Z","steps":["trace[1240893651] 'agreement among raft nodes before linearized reading' (duration: 350.805148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:35.796223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.025741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:32:35.79629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:35.445291Z","time spent":"350.9906ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-l6jf4\" "} {"level":"warn","ts":"2026-05-09T01:32:35.796211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:35.378535Z","time spent":"417.586387ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:32:35.796297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.551507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T01:32:35.796314Z","caller":"traceutil/trace.go:171","msg":"trace[106309280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"351.162112ms","start":"2026-05-09T01:32:35.445132Z","end":"2026-05-09T01:32:35.796295Z","steps":["trace[106309280] 'agreement among raft nodes before linearized reading' (duration: 350.96368ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:35.796346Z","caller":"traceutil/trace.go:171","msg":"trace[153307130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"112.649398ms","start":"2026-05-09T01:32:35.683683Z","end":"2026-05-09T01:32:35.796333Z","steps":["trace[153307130] 'agreement among raft nodes before linearized reading' (duration: 112.467946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:35.796358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:35.445117Z","time spent":"351.233844ms","remote":"127.0.0.1:43324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T01:34:03.65803Z","caller":"traceutil/trace.go:171","msg":"trace[1873576911] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"169.017289ms","start":"2026-05-09T01:34:03.48897Z","end":"2026-05-09T01:34:03.657987Z","steps":["trace[1873576911] 'process raft request' (duration: 168.758572ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:36.585338Z","caller":"traceutil/trace.go:171","msg":"trace[226087563] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"182.554897ms","start":"2026-05-09T01:34:36.402752Z","end":"2026-05-09T01:34:36.585307Z","steps":["trace[226087563] 'process raft request' (duration: 182.360622ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:36.740316Z","caller":"traceutil/trace.go:171","msg":"trace[1761463127] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"337.353295ms","start":"2026-05-09T01:34:36.402937Z","end":"2026-05-09T01:34:36.74029Z","steps":["trace[1761463127] 'process raft request' (duration: 337.167321ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:36.740502Z","caller":"traceutil/trace.go:171","msg":"trace[1143177203] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2156; }","duration":"300.76747ms","start":"2026-05-09T01:34:36.439692Z","end":"2026-05-09T01:34:36.740459Z","steps":["trace[1143177203] 'read index received' (duration: 145.416559ms)","trace[1143177203] 'applied index is now lower than readState.Index' (duration: 155.34931ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:34:36.740587Z","caller":"traceutil/trace.go:171","msg":"trace[1837045058] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"336.27288ms","start":"2026-05-09T01:34:36.404255Z","end":"2026-05-09T01:34:36.740528Z","steps":["trace[1837045058] 'process raft request' (duration: 335.982894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:36.740735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.033155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18adc1c31194b3e3\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-09T01:34:36.740799Z","caller":"traceutil/trace.go:171","msg":"trace[1861510577] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18adc1c31194b3e3; range_end:; response_count:1; response_revision:2076; }","duration":"301.150459ms","start":"2026-05-09T01:34:36.439631Z","end":"2026-05-09T01:34:36.740781Z","steps":["trace[1861510577] 'agreement among raft nodes before linearized reading' (duration: 300.950444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:36.740837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:36.439604Z","time spent":"301.22449ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18adc1c31194b3e3\" "} {"level":"warn","ts":"2026-05-09T01:34:36.741497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:36.402885Z","time spent":"337.503278ms","remote":"127.0.0.1:43514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:34:36.741633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:36.404225Z","time spent":"336.491045ms","remote":"127.0.0.1:43514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:34:36.763169Z","caller":"traceutil/trace.go:171","msg":"trace[1705809497] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"136.252405ms","start":"2026-05-09T01:34:36.626898Z","end":"2026-05-09T01:34:36.763151Z","steps":["trace[1705809497] 'process raft request' (duration: 136.170423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:36.763649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.850607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-05-09T01:34:36.763721Z","caller":"traceutil/trace.go:171","msg":"trace[1935235835] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2077; }","duration":"280.947908ms","start":"2026-05-09T01:34:36.482753Z","end":"2026-05-09T01:34:36.763701Z","steps":["trace[1935235835] 'agreement among raft nodes before linearized reading' (duration: 280.448487ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:38.01352Z","caller":"traceutil/trace.go:171","msg":"trace[504221729] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"185.42955ms","start":"2026-05-09T01:34:37.828055Z","end":"2026-05-09T01:34:38.013485Z","steps":["trace[504221729] 'process raft request' (duration: 185.209435ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:35:19.184112Z","caller":"traceutil/trace.go:171","msg":"trace[980815540] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"247.089573ms","start":"2026-05-09T01:35:18.936974Z","end":"2026-05-09T01:35:19.184064Z","steps":["trace[980815540] 'process raft request' (duration: 245.22352ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:35:19.184741Z","caller":"traceutil/trace.go:171","msg":"trace[245329643] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"245.16747ms","start":"2026-05-09T01:35:18.939548Z","end":"2026-05-09T01:35:19.184716Z","steps":["trace[245329643] 'process raft request' (duration: 244.424977ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:35:19.185614Z","caller":"traceutil/trace.go:171","msg":"trace[1569983192] linearizableReadLoop","detail":"{readStateIndex:2405; appliedIndex:2402; }","duration":"173.394207ms","start":"2026-05-09T01:35:19.01221Z","end":"2026-05-09T01:35:19.185604Z","steps":["trace[1569983192] 'read index received' (duration: 169.981907ms)","trace[1569983192] 'applied index is now lower than readState.Index' (duration: 3.41182ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:35:19.185716Z","caller":"traceutil/trace.go:171","msg":"trace[1286735324] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"166.371405ms","start":"2026-05-09T01:35:19.019321Z","end":"2026-05-09T01:35:19.185693Z","steps":["trace[1286735324] 'process raft request' (duration: 166.168481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:35:19.185748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.5303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T01:35:19.185932Z","caller":"traceutil/trace.go:171","msg":"trace[1729104623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2313; }","duration":"173.785894ms","start":"2026-05-09T01:35:19.012126Z","end":"2026-05-09T01:35:19.185912Z","steps":["trace[1729104623] 'agreement among raft nodes before linearized reading' (duration: 173.576431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:36:26.873631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.281115ms","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-05-09T01:36:26.87372Z","caller":"traceutil/trace.go:171","msg":"trace[1740954727] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2774; }","duration":"151.401507ms","start":"2026-05-09T01:36:26.722294Z","end":"2026-05-09T01:36:26.873695Z","steps":["trace[1740954727] 'range keys from in-memory index tree' (duration: 151.104393ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:57.963955Z","caller":"traceutil/trace.go:171","msg":"trace[95982089] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"133.534089ms","start":"2026-05-09T01:39:57.830402Z","end":"2026-05-09T01:39:57.963936Z","steps":["trace[95982089] 'process raft request' (duration: 94.124295ms)","trace[95982089] 'compare' (duration: 39.183199ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:40:05.193389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.749219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T01:40:05.193795Z","caller":"traceutil/trace.go:171","msg":"trace[1690273160] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4638; }","duration":"244.209921ms","start":"2026-05-09T01:40:04.949569Z","end":"2026-05-09T01:40:05.193779Z","steps":["trace[1690273160] 'count revisions from in-memory index tree' (duration: 243.668857ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.194007Z","caller":"traceutil/trace.go:171","msg":"trace[252891345] linearizableReadLoop","detail":"{readStateIndex:4821; appliedIndex:4820; }","duration":"114.138847ms","start":"2026-05-09T01:40:05.079834Z","end":"2026-05-09T01:40:05.193973Z","steps":["trace[252891345] 'read index received' (duration: 107.091413ms)","trace[252891345] 'applied index is now lower than readState.Index' (duration: 7.046424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:40:05.194202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.352813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:40:05.194252Z","caller":"traceutil/trace.go:171","msg":"trace[626749003] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4639; }","duration":"114.446724ms","start":"2026-05-09T01:40:05.079793Z","end":"2026-05-09T01:40:05.19424Z","steps":["trace[626749003] 'agreement among raft nodes before linearized reading' (duration: 114.318281ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.194313Z","caller":"traceutil/trace.go:171","msg":"trace[997417776] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"197.896215ms","start":"2026-05-09T01:40:04.996349Z","end":"2026-05-09T01:40:05.194245Z","steps":["trace[997417776] 'process raft request' (duration: 190.678177ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.520765Z","caller":"traceutil/trace.go:171","msg":"trace[960150876] linearizableReadLoop","detail":"{readStateIndex:4827; appliedIndex:4826; }","duration":"106.387835ms","start":"2026-05-09T01:40:05.414354Z","end":"2026-05-09T01:40:05.520742Z","steps":["trace[960150876] 'read index received' (duration: 105.77601ms)","trace[960150876] 'applied index is now lower than readState.Index' (duration: 610.825µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:40:05.520956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.55287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:40:05.521041Z","caller":"traceutil/trace.go:171","msg":"trace[1202946984] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4645; }","duration":"106.672813ms","start":"2026-05-09T01:40:05.414348Z","end":"2026-05-09T01:40:05.52102Z","steps":["trace[1202946984] 'agreement among raft nodes before linearized reading' (duration: 106.496469ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.520838Z","caller":"traceutil/trace.go:171","msg":"trace[1383295133] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"132.661564ms","start":"2026-05-09T01:40:05.388151Z","end":"2026-05-09T01:40:05.520812Z","steps":["trace[1383295133] 'process raft request' (duration: 132.406438ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.675577Z","caller":"traceutil/trace.go:171","msg":"trace[2050626967] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4827; }","duration":"132.0952ms","start":"2026-05-09T01:40:05.543452Z","end":"2026-05-09T01:40:05.675547Z","steps":["trace[2050626967] 'read index received' (duration: 25.754895ms)","trace[2050626967] 'applied index is now lower than readState.Index' (duration: 106.338825ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:40:05.675687Z","caller":"traceutil/trace.go:171","msg":"trace[227728278] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"137.640926ms","start":"2026-05-09T01:40:05.538022Z","end":"2026-05-09T01:40:05.675663Z","steps":["trace[227728278] 'process raft request' (duration: 137.307168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:05.675891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.416337ms","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-09T01:40:05.675942Z","caller":"traceutil/trace.go:171","msg":"trace[1323943794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4647; }","duration":"132.50294ms","start":"2026-05-09T01:40:05.543428Z","end":"2026-05-09T01:40:05.675931Z","steps":["trace[1323943794] 'agreement among raft nodes before linearized reading' (duration: 132.314715ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.716053Z","caller":"traceutil/trace.go:171","msg":"trace[1573843106] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"140.21573ms","start":"2026-05-09T01:40:05.575811Z","end":"2026-05-09T01:40:05.716027Z","steps":["trace[1573843106] 'process raft request' (duration: 139.974204ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.716158Z","caller":"traceutil/trace.go:171","msg":"trace[44554720] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"136.347165ms","start":"2026-05-09T01:40:05.579762Z","end":"2026-05-09T01:40:05.716109Z","steps":["trace[44554720] 'process raft request' (duration: 136.225962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:05.716284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.93204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-05-09T01:40:05.71629Z","caller":"traceutil/trace.go:171","msg":"trace[860679233] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"139.980295ms","start":"2026-05-09T01:40:05.576283Z","end":"2026-05-09T01:40:05.716263Z","steps":["trace[860679233] 'process raft request' (duration: 139.656127ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:05.716328Z","caller":"traceutil/trace.go:171","msg":"trace[716499671] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4650; }","duration":"137.063172ms","start":"2026-05-09T01:40:05.579253Z","end":"2026-05-09T01:40:05.716316Z","steps":["trace[716499671] 'agreement among raft nodes before linearized reading' (duration: 136.916989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:05.716381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.538279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-09T01:40:05.716494Z","caller":"traceutil/trace.go:171","msg":"trace[1005810596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4650; }","duration":"138.664722ms","start":"2026-05-09T01:40:05.577801Z","end":"2026-05-09T01:40:05.716465Z","steps":["trace[1005810596] 'agreement among raft nodes before linearized reading' (duration: 138.350964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:05.716519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.273557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126409"} {"level":"info","ts":"2026-05-09T01:40:05.716578Z","caller":"traceutil/trace.go:171","msg":"trace[220905262] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4650; }","duration":"141.336208ms","start":"2026-05-09T01:40:05.575229Z","end":"2026-05-09T01:40:05.716566Z","steps":["trace[220905262] 'agreement among raft nodes before linearized reading' (duration: 141.067632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:05.716615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.471791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:40:05.716657Z","caller":"traceutil/trace.go:171","msg":"trace[2017779394] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4650; }","duration":"126.541303ms","start":"2026-05-09T01:40:05.590104Z","end":"2026-05-09T01:40:05.716645Z","steps":["trace[2017779394] 'agreement among raft nodes before linearized reading' (duration: 125.9964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:08.881612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.890397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T01:40:08.881721Z","caller":"traceutil/trace.go:171","msg":"trace[378184113] linearizableReadLoop","detail":"{readStateIndex:4916; appliedIndex:4915; }","duration":"171.386185ms","start":"2026-05-09T01:40:08.710316Z","end":"2026-05-09T01:40:08.881702Z","steps":["trace[378184113] 'read index received' (duration: 64.351775ms)","trace[378184113] 'applied index is now lower than readState.Index' (duration: 107.03296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:40:08.88199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.643892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T01:40:08.88209Z","caller":"traceutil/trace.go:171","msg":"trace[1398712041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4733; }","duration":"171.775835ms","start":"2026-05-09T01:40:08.710279Z","end":"2026-05-09T01:40:08.882054Z","steps":["trace[1398712041] 'agreement among raft nodes before linearized reading' (duration: 171.519319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:08.882161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.284483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T01:40:08.882254Z","caller":"traceutil/trace.go:171","msg":"trace[350739781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4733; }","duration":"133.459247ms","start":"2026-05-09T01:40:08.748777Z","end":"2026-05-09T01:40:08.882236Z","steps":["trace[350739781] 'agreement among raft nodes before linearized reading' (duration: 133.059308ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:42:00.235753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-05-09T01:42:00.324335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"87.192032ms","hash":2002522944,"current-db-size-bytes":53227520,"current-db-size":"53 MB","current-db-size-in-use-bytes":29569024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T01:42:00.324444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2002522944,"revision":2950,"compact-revision":-1} {"level":"info","ts":"2026-05-09T01:47:00.241802Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5668} {"level":"info","ts":"2026-05-09T01:47:00.312904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5668,"took":"69.884572ms","hash":2963253907,"current-db-size-bytes":53227520,"current-db-size":"53 MB","current-db-size-in-use-bytes":18280448,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-09T01:47:00.312952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2963253907,"revision":5668,"compact-revision":2950}