{"level":"warn","ts":"2026-05-13T14:15:27.765894Z","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-13T14:15:27.76599Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-13T14:15:27.766061Z","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-13T14:15:27.766074Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-13T14:15:27.766093Z","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-13T14:15:27.766716Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-05-13T14:15:27.766789Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-13T14:15:27.770363Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.356517ms"} {"level":"info","ts":"2026-05-13T14:15:27.775909Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-05-13T14:15:27.775976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-05-13T14:15:27.775996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-05-13T14:15:27.776007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-13T14:15:27.776017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-05-13T14:15:27.776049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-05-13T14:15:27.781889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-13T14:15:27.783571Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-13T14:15:27.785299Z","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-13T14:15:27.787953Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-13T14:15:27.78815Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-13T14:15:27.788439Z","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-13T14:15:27.789171Z","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-13T14:15:27.789252Z","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-13T14:15:27.790093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-05-13T14:15:27.791828Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-13T14:15:27.792408Z","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-13T14:15:27.79258Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-13T14:15:27.792606Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-13T14:15:27.792655Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-13T14:15:27.792666Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-13T14:15:28.177131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-05-13T14:15:28.177196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-13T14:15:28.177239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-05-13T14:15:28.177261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-05-13T14:15:28.177279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-13T14:15:28.177297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-05-13T14:15:28.177317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-13T14:15:28.17829Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T14:15:28.17906Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-05-13T14:15:28.179258Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T14:15:28.179377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T14:15:28.17982Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-13T14:15:28.179895Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-13T14:15:28.179997Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T14:15:28.180123Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T14:15:28.180163Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T14:15:28.183323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-13T14:15:28.183332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-05-13T14:15:40.011334Z","caller":"traceutil/trace.go:171","msg":"trace[1313065907] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"137.657976ms","start":"2026-05-13T14:15:39.873642Z","end":"2026-05-13T14:15:40.0113Z","steps":["trace[1313065907] 'process raft request' (duration: 137.454735ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:15:59.16248Z","caller":"traceutil/trace.go:171","msg":"trace[2041285513] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"101.004886ms","start":"2026-05-13T14:15:59.061448Z","end":"2026-05-13T14:15:59.162453Z","steps":["trace[2041285513] 'process raft request' (duration: 88.503588ms)","trace[2041285513] 'compare' (duration: 12.356577ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T14:15:59.191022Z","caller":"traceutil/trace.go:171","msg":"trace[817439060] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"102.322951ms","start":"2026-05-13T14:15:59.088682Z","end":"2026-05-13T14:15:59.191005Z","steps":["trace[817439060] 'process raft request' (duration: 102.218751ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:15:59.191302Z","caller":"traceutil/trace.go:171","msg":"trace[693383867] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"118.823734ms","start":"2026-05-13T14:15:59.072449Z","end":"2026-05-13T14:15:59.191273Z","steps":["trace[693383867] 'process raft request' (duration: 118.280382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:16:05.400637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.480691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-13T14:16:05.401097Z","caller":"traceutil/trace.go:171","msg":"trace[1362527048] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"374.749967ms","start":"2026-05-13T14:16:05.026246Z","end":"2026-05-13T14:16:05.400996Z","steps":["trace[1362527048] 'process raft request' (duration: 237.483382ms)","trace[1362527048] 'compare' (duration: 136.384891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T14:16:05.401425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T14:16:05.026226Z","time spent":"374.977277ms","remote":"127.0.0.1:47276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T14:16:06.589172Z","caller":"traceutil/trace.go:171","msg":"trace[1574898262] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"115.478262ms","start":"2026-05-13T14:16:06.473658Z","end":"2026-05-13T14:16:06.589136Z","steps":["trace[1574898262] 'process raft request' (duration: 115.294902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:16:21.592066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.199032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g2kkw\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-13T14:16:21.592604Z","caller":"traceutil/trace.go:171","msg":"trace[2037062974] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g2kkw; range_end:; response_count:1; response_revision:782; }","duration":"118.489022ms","start":"2026-05-13T14:16:21.473768Z","end":"2026-05-13T14:16:21.592257Z","steps":["trace[2037062974] 'range keys from in-memory index tree' (duration: 117.902711ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:16:50.807817Z","caller":"traceutil/trace.go:171","msg":"trace[821366235] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"237.054112ms","start":"2026-05-13T14:16:50.570712Z","end":"2026-05-13T14:16:50.807766Z","steps":["trace[821366235] 'process raft request' (duration: 236.831495ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:16:50.808792Z","caller":"traceutil/trace.go:171","msg":"trace[841079830] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"149.505119ms","start":"2026-05-13T14:16:50.65924Z","end":"2026-05-13T14:16:50.808745Z","steps":["trace[841079830] 'process raft request' (duration: 149.344004ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:17:41.610536Z","caller":"traceutil/trace.go:171","msg":"trace[2124981292] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"353.190177ms","start":"2026-05-13T14:17:41.25731Z","end":"2026-05-13T14:17:41.6105Z","steps":["trace[2124981292] 'read index received' (duration: 352.866611ms)","trace[2124981292] 'applied index is now lower than readState.Index' (duration: 321.976µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T14:17:41.610623Z","caller":"traceutil/trace.go:171","msg":"trace[2089860314] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"354.231527ms","start":"2026-05-13T14:17:41.256328Z","end":"2026-05-13T14:17:41.610559Z","steps":["trace[2089860314] 'process raft request' (duration: 353.937522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:17:41.610967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.612515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-13T14:17:41.611096Z","caller":"traceutil/trace.go:171","msg":"trace[1970916065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1874; }","duration":"353.803329ms","start":"2026-05-13T14:17:41.257272Z","end":"2026-05-13T14:17:41.611075Z","steps":["trace[1970916065] 'agreement among raft nodes before linearized reading' (duration: 353.37186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:17:41.61118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T14:17:41.257181Z","time spent":"353.956182ms","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-13T14:17:41.611139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.511596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2418543d-1cc0-4bda-bbeb-cfe6cf645f2b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-13T14:17:41.611456Z","caller":"traceutil/trace.go:171","msg":"trace[1736656494] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2418543d-1cc0-4bda-bbeb-cfe6cf645f2b; range_end:; response_count:1; response_revision:1874; }","duration":"107.918384ms","start":"2026-05-13T14:17:41.503508Z","end":"2026-05-13T14:17:41.611426Z","steps":["trace[1736656494] 'agreement among raft nodes before linearized reading' (duration: 107.281811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:17:41.611329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T14:17:41.256305Z","time spent":"354.558274ms","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T14:18:05.201894Z","caller":"traceutil/trace.go:171","msg":"trace[785815157] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"101.554308ms","start":"2026-05-13T14:18:05.100308Z","end":"2026-05-13T14:18:05.201863Z","steps":["trace[785815157] 'process raft request' (duration: 95.408758ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:18:31.222508Z","caller":"traceutil/trace.go:171","msg":"trace[411230870] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"104.852339ms","start":"2026-05-13T14:18:31.117628Z","end":"2026-05-13T14:18:31.22248Z","steps":["trace[411230870] 'process raft request' (duration: 104.608406ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:18:31.281354Z","caller":"traceutil/trace.go:171","msg":"trace[720815792] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"128.169725ms","start":"2026-05-13T14:18:31.153111Z","end":"2026-05-13T14:18:31.281281Z","steps":["trace[720815792] 'process raft request' (duration: 127.79222ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:18:32.316251Z","caller":"traceutil/trace.go:171","msg":"trace[1390623933] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"122.208352ms","start":"2026-05-13T14:18:32.19392Z","end":"2026-05-13T14:18:32.316129Z","steps":["trace[1390623933] 'process raft request' (duration: 121.666365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:18:32.655493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.586836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T14:18:32.655642Z","caller":"traceutil/trace.go:171","msg":"trace[914753914] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"320.053395ms","start":"2026-05-13T14:18:32.335561Z","end":"2026-05-13T14:18:32.655615Z","steps":["trace[914753914] 'process raft request' (duration: 56.214406ms)","trace[914753914] 'compare' (duration: 263.451764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T14:18:32.655737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T14:18:32.335544Z","time spent":"320.143486ms","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T14:18:32.899335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.48489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18af25c31d1873d5\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-13T14:18:32.899414Z","caller":"traceutil/trace.go:171","msg":"trace[1052501920] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18af25c31d1873d5; range_end:; response_count:1; response_revision:2201; }","duration":"123.610453ms","start":"2026-05-13T14:18:32.775784Z","end":"2026-05-13T14:18:32.899394Z","steps":["trace[1052501920] 'range keys from in-memory index tree' (duration: 123.213427ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:19:29.846781Z","caller":"traceutil/trace.go:171","msg":"trace[352944180] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"177.776478ms","start":"2026-05-13T14:19:29.668982Z","end":"2026-05-13T14:19:29.846758Z","steps":["trace[352944180] 'process raft request' (duration: 176.563846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:20:02.868741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.096314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T14:20:02.86892Z","caller":"traceutil/trace.go:171","msg":"trace[746467958] linearizableReadLoop","detail":"{readStateIndex:2901; appliedIndex:2900; }","duration":"278.081175ms","start":"2026-05-13T14:20:02.590819Z","end":"2026-05-13T14:20:02.8689Z","steps":["trace[746467958] 'read index received' (duration: 132.834009ms)","trace[746467958] 'applied index is now lower than readState.Index' (duration: 145.245566ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T14:20:02.868996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.280047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T14:20:02.869034Z","caller":"traceutil/trace.go:171","msg":"trace[474853313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2796; }","duration":"278.408807ms","start":"2026-05-13T14:20:02.590613Z","end":"2026-05-13T14:20:02.869022Z","steps":["trace[474853313] 'agreement among raft nodes before linearized reading' (duration: 278.344416ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:20:02.868966Z","caller":"traceutil/trace.go:171","msg":"trace[226709361] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"309.521807ms","start":"2026-05-13T14:20:02.55941Z","end":"2026-05-13T14:20:02.868932Z","steps":["trace[226709361] 'process raft request' (duration: 164.11475ms)","trace[226709361] 'compare' (duration: 144.865312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T14:20:02.869281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T14:20:02.559393Z","time spent":"309.780469ms","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T14:24:04.786883Z","caller":"traceutil/trace.go:171","msg":"trace[186335336] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"192.299942ms","start":"2026-05-13T14:24:04.594561Z","end":"2026-05-13T14:24:04.786861Z","steps":["trace[186335336] 'process raft request' (duration: 192.1419ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:04.786955Z","caller":"traceutil/trace.go:171","msg":"trace[571947150] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"192.325512ms","start":"2026-05-13T14:24:04.594612Z","end":"2026-05-13T14:24:04.786938Z","steps":["trace[571947150] 'process raft request' (duration: 192.20718ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:04.787064Z","caller":"traceutil/trace.go:171","msg":"trace[2095309495] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"187.764404ms","start":"2026-05-13T14:24:04.599239Z","end":"2026-05-13T14:24:04.787003Z","steps":["trace[2095309495] 'process raft request' (duration: 187.608241ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:04.787279Z","caller":"traceutil/trace.go:171","msg":"trace[211356351] linearizableReadLoop","detail":"{readStateIndex:4866; appliedIndex:4863; }","duration":"153.998172ms","start":"2026-05-13T14:24:04.632901Z","end":"2026-05-13T14:24:04.786899Z","steps":["trace[211356351] 'read index received' (duration: 153.827829ms)","trace[211356351] 'applied index is now lower than readState.Index' (duration: 169.443µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T14:24:04.787482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.554661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-13T14:24:04.787618Z","caller":"traceutil/trace.go:171","msg":"trace[215408559] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4689; }","duration":"154.678773ms","start":"2026-05-13T14:24:04.63287Z","end":"2026-05-13T14:24:04.787549Z","steps":["trace[215408559] 'agreement among raft nodes before linearized reading' (duration: 154.233357ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:04.819841Z","caller":"traceutil/trace.go:171","msg":"trace[1741747731] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"139.406828ms","start":"2026-05-13T14:24:04.680418Z","end":"2026-05-13T14:24:04.819824Z","steps":["trace[1741747731] 'process raft request' (duration: 139.131474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:24:04.820037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.956179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b937c742-aff8-4e06-b9d6-de39a290d1eb\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-05-13T14:24:04.820095Z","caller":"traceutil/trace.go:171","msg":"trace[365836199] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b937c742-aff8-4e06-b9d6-de39a290d1eb; range_end:; response_count:1; response_revision:4691; }","duration":"185.047491ms","start":"2026-05-13T14:24:04.635032Z","end":"2026-05-13T14:24:04.82008Z","steps":["trace[365836199] 'agreement among raft nodes before linearized reading' (duration: 184.848378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T14:24:04.820141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.156453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:56734"} {"level":"info","ts":"2026-05-13T14:24:04.820163Z","caller":"traceutil/trace.go:171","msg":"trace[631323130] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4691; }","duration":"187.201853ms","start":"2026-05-13T14:24:04.632956Z","end":"2026-05-13T14:24:04.820158Z","steps":["trace[631323130] 'agreement among raft nodes before linearized reading' (duration: 187.062921ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:04.820302Z","caller":"traceutil/trace.go:171","msg":"trace[2142966306] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"139.677901ms","start":"2026-05-13T14:24:04.680619Z","end":"2026-05-13T14:24:04.820297Z","steps":["trace[2142966306] 'process raft request' (duration: 139.020641ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:12.775879Z","caller":"traceutil/trace.go:171","msg":"trace[1166555403] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"124.367871ms","start":"2026-05-13T14:24:12.651484Z","end":"2026-05-13T14:24:12.775852Z","steps":["trace[1166555403] 'process raft request' (duration: 46.330371ms)","trace[1166555403] 'compare' (duration: 77.904028ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T14:24:13.113486Z","caller":"traceutil/trace.go:171","msg":"trace[1634775441] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"142.003204ms","start":"2026-05-13T14:24:12.971444Z","end":"2026-05-13T14:24:13.113447Z","steps":["trace[1634775441] 'process raft request' (duration: 99.568724ms)","trace[1634775441] 'compare' (duration: 41.953583ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T14:24:13.113502Z","caller":"traceutil/trace.go:171","msg":"trace[1408418589] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"129.023629ms","start":"2026-05-13T14:24:12.984452Z","end":"2026-05-13T14:24:13.113476Z","steps":["trace[1408418589] 'process raft request' (duration: 128.894427ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:13.113774Z","caller":"traceutil/trace.go:171","msg":"trace[411804087] linearizableReadLoop","detail":"{readStateIndex:5019; appliedIndex:5016; }","duration":"104.553718ms","start":"2026-05-13T14:24:13.009192Z","end":"2026-05-13T14:24:13.113745Z","steps":["trace[411804087] 'read index received' (duration: 61.803813ms)","trace[411804087] 'applied index is now lower than readState.Index' (duration: 42.748165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T14:24:13.114063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.835042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-13T14:24:13.114129Z","caller":"traceutil/trace.go:171","msg":"trace[1761125494] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4837; }","duration":"104.935613ms","start":"2026-05-13T14:24:13.009161Z","end":"2026-05-13T14:24:13.114096Z","steps":["trace[1761125494] 'agreement among raft nodes before linearized reading' (duration: 104.684939ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:24:26.102327Z","caller":"traceutil/trace.go:171","msg":"trace[67196796] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"115.968922ms","start":"2026-05-13T14:24:25.9863Z","end":"2026-05-13T14:24:26.102269Z","steps":["trace[67196796] 'process raft request' (duration: 115.345953ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:25:28.489262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2932} {"level":"warn","ts":"2026-05-13T14:25:28.620867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.218936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T14:25:28.621043Z","caller":"traceutil/trace.go:171","msg":"trace[2091566177] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"130.008397ms","start":"2026-05-13T14:25:28.491009Z","end":"2026-05-13T14:25:28.621018Z","steps":["trace[2091566177] 'process raft request' (duration: 22.545378ms)","trace[2091566177] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/jobs/openstack/keystone-db-init; req_size:5641; } (duration: 106.915032ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T14:25:28.622276Z","caller":"traceutil/trace.go:171","msg":"trace[1448995458] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"127.133888ms","start":"2026-05-13T14:25:28.495125Z","end":"2026-05-13T14:25:28.622259Z","steps":["trace[1448995458] 'process raft request' (duration: 125.877222ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:25:28.660756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2932,"took":"168.788307ms","hash":709334488,"current-db-size-bytes":53420032,"current-db-size":"53 MB","current-db-size-in-use-bytes":28762112,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-13T14:25:28.660806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":709334488,"revision":2932,"compact-revision":-1} {"level":"info","ts":"2026-05-13T14:28:43.941661Z","caller":"traceutil/trace.go:171","msg":"trace[1016737661] transaction","detail":"{read_only:false; response_revision:6841; number_of_response:1; }","duration":"118.771958ms","start":"2026-05-13T14:28:43.822862Z","end":"2026-05-13T14:28:43.941634Z","steps":["trace[1016737661] 'process raft request' (duration: 83.7516ms)","trace[1016737661] 'compare' (duration: 34.878406ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T14:28:43.944126Z","caller":"traceutil/trace.go:171","msg":"trace[1618562695] transaction","detail":"{read_only:false; response_revision:6842; number_of_response:1; }","duration":"121.203525ms","start":"2026-05-13T14:28:43.822902Z","end":"2026-05-13T14:28:43.944106Z","steps":["trace[1618562695] 'process raft request' (duration: 121.000173ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:28:43.94416Z","caller":"traceutil/trace.go:171","msg":"trace[1947035577] transaction","detail":"{read_only:false; response_revision:6843; number_of_response:1; }","duration":"120.560897ms","start":"2026-05-13T14:28:43.823573Z","end":"2026-05-13T14:28:43.944133Z","steps":["trace[1947035577] 'process raft request' (duration: 120.475646ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:28:43.944488Z","caller":"traceutil/trace.go:171","msg":"trace[1473578287] transaction","detail":"{read_only:false; response_revision:6844; number_of_response:1; }","duration":"100.732753ms","start":"2026-05-13T14:28:43.843716Z","end":"2026-05-13T14:28:43.944448Z","steps":["trace[1473578287] 'process raft request' (duration: 100.374539ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T14:30:28.498578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5548} {"level":"info","ts":"2026-05-13T14:30:28.599413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5548,"took":"99.056009ms","hash":2798484385,"current-db-size-bytes":53420032,"current-db-size":"53 MB","current-db-size-in-use-bytes":18493440,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-13T14:30:28.599485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2798484385,"revision":5548,"compact-revision":2932}