I0922 01:58:48.603225 1 options.go:220] external host was not specified, using 162.253.55.241 I0922 01:58:48.603869 1 server.go:148] Version: v1.28.13 I0922 01:58:48.603919 1 server.go:150] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" I0922 01:58:49.019959 1 shared_informer.go:311] Waiting for caches to sync for node_authorizer I0922 01:58:49.036754 1 plugins.go:158] Loaded 12 mutating admission controller(s) successfully in the following order: NamespaceLifecycle,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,RuntimeClass,DefaultIngressClass,MutatingAdmissionWebhook. I0922 01:58:49.036798 1 plugins.go:161] Loaded 13 validating admission controller(s) successfully in the following order: LimitRanger,ServiceAccount,PodSecurity,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,ClusterTrustBundleAttest,CertificateSubjectRestriction,ValidatingAdmissionPolicy,ValidatingAdmissionWebhook,ResourceQuota. I0922 01:58:49.037004 1 instance.go:298] Using reconciler: lease I0922 01:58:49.687620 1 handler.go:275] Adding GroupVersion apiextensions.k8s.io v1 to ResourceManager W0922 01:58:49.687712 1 genericapiserver.go:744] Skipping API apiextensions.k8s.io/v1beta1 because it has no resources. I0922 01:58:49.972342 1 handler.go:275] Adding GroupVersion v1 to ResourceManager I0922 01:58:49.972736 1 instance.go:709] API group "internal.apiserver.k8s.io" is not enabled, skipping. I0922 01:58:50.492428 1 instance.go:709] API group "resource.k8s.io" is not enabled, skipping. I0922 01:58:50.509216 1 handler.go:275] Adding GroupVersion authentication.k8s.io v1 to ResourceManager W0922 01:58:50.509238 1 genericapiserver.go:744] Skipping API authentication.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.509244 1 genericapiserver.go:744] Skipping API authentication.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.509686 1 handler.go:275] Adding GroupVersion authorization.k8s.io v1 to ResourceManager W0922 01:58:50.509710 1 genericapiserver.go:744] Skipping API authorization.k8s.io/v1beta1 because it has no resources. I0922 01:58:50.510571 1 handler.go:275] Adding GroupVersion autoscaling v2 to ResourceManager I0922 01:58:50.511286 1 handler.go:275] Adding GroupVersion autoscaling v1 to ResourceManager W0922 01:58:50.511301 1 genericapiserver.go:744] Skipping API autoscaling/v2beta1 because it has no resources. W0922 01:58:50.511306 1 genericapiserver.go:744] Skipping API autoscaling/v2beta2 because it has no resources. I0922 01:58:50.512801 1 handler.go:275] Adding GroupVersion batch v1 to ResourceManager W0922 01:58:50.512818 1 genericapiserver.go:744] Skipping API batch/v1beta1 because it has no resources. I0922 01:58:50.521684 1 handler.go:275] Adding GroupVersion certificates.k8s.io v1 to ResourceManager W0922 01:58:50.521711 1 genericapiserver.go:744] Skipping API certificates.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.521737 1 genericapiserver.go:744] Skipping API certificates.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.523398 1 handler.go:275] Adding GroupVersion coordination.k8s.io v1 to ResourceManager W0922 01:58:50.523418 1 genericapiserver.go:744] Skipping API coordination.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.523528 1 genericapiserver.go:744] Skipping API discovery.k8s.io/v1beta1 because it has no resources. I0922 01:58:50.527408 1 handler.go:275] Adding GroupVersion discovery.k8s.io v1 to ResourceManager I0922 01:58:50.529457 1 handler.go:275] Adding GroupVersion networking.k8s.io v1 to ResourceManager W0922 01:58:50.529473 1 genericapiserver.go:744] Skipping API networking.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.529478 1 genericapiserver.go:744] Skipping API networking.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.529942 1 handler.go:275] Adding GroupVersion node.k8s.io v1 to ResourceManager W0922 01:58:50.529956 1 genericapiserver.go:744] Skipping API node.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.529961 1 genericapiserver.go:744] Skipping API node.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.530760 1 handler.go:275] Adding GroupVersion policy v1 to ResourceManager W0922 01:58:50.530776 1 genericapiserver.go:744] Skipping API policy/v1beta1 because it has no resources. I0922 01:58:50.532637 1 handler.go:275] Adding GroupVersion rbac.authorization.k8s.io v1 to ResourceManager W0922 01:58:50.532653 1 genericapiserver.go:744] Skipping API rbac.authorization.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.532659 1 genericapiserver.go:744] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.533187 1 handler.go:275] Adding GroupVersion scheduling.k8s.io v1 to ResourceManager W0922 01:58:50.533203 1 genericapiserver.go:744] Skipping API scheduling.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.533208 1 genericapiserver.go:744] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.535537 1 handler.go:275] Adding GroupVersion storage.k8s.io v1 to ResourceManager W0922 01:58:50.535554 1 genericapiserver.go:744] Skipping API storage.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.535559 1 genericapiserver.go:744] Skipping API storage.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.536945 1 handler.go:275] Adding GroupVersion flowcontrol.apiserver.k8s.io v1beta3 to ResourceManager I0922 01:58:50.538220 1 handler.go:275] Adding GroupVersion flowcontrol.apiserver.k8s.io v1beta2 to ResourceManager W0922 01:58:50.538239 1 genericapiserver.go:744] Skipping API flowcontrol.apiserver.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.538244 1 genericapiserver.go:744] Skipping API flowcontrol.apiserver.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.542278 1 handler.go:275] Adding GroupVersion apps v1 to ResourceManager W0922 01:58:50.542295 1 genericapiserver.go:744] Skipping API apps/v1beta2 because it has no resources. W0922 01:58:50.542300 1 genericapiserver.go:744] Skipping API apps/v1beta1 because it has no resources. I0922 01:58:50.543233 1 handler.go:275] Adding GroupVersion admissionregistration.k8s.io v1 to ResourceManager W0922 01:58:50.543248 1 genericapiserver.go:744] Skipping API admissionregistration.k8s.io/v1beta1 because it has no resources. W0922 01:58:50.543253 1 genericapiserver.go:744] Skipping API admissionregistration.k8s.io/v1alpha1 because it has no resources. I0922 01:58:50.543781 1 handler.go:275] Adding GroupVersion events.k8s.io v1 to ResourceManager W0922 01:58:50.543796 1 genericapiserver.go:744] Skipping API events.k8s.io/v1beta1 because it has no resources. I0922 01:58:50.580645 1 handler.go:275] Adding GroupVersion apiregistration.k8s.io v1 to ResourceManager W0922 01:58:50.580677 1 genericapiserver.go:744] Skipping API apiregistration.k8s.io/v1beta1 because it has no resources. I0922 01:58:51.085688 1 dynamic_cafile_content.go:157] "Starting controller" name="request-header::/etc/kubernetes/pki/front-proxy-ca.crt" I0922 01:58:51.085702 1 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" I0922 01:58:51.085895 1 dynamic_serving_content.go:132] "Starting controller" name="serving-cert::/etc/kubernetes/pki/apiserver.crt::/etc/kubernetes/pki/apiserver.key" I0922 01:58:51.086010 1 secure_serving.go:213] Serving securely on [::]:6443 I0922 01:58:51.086085 1 apiservice_controller.go:97] Starting APIServiceRegistrationController I0922 01:58:51.086084 1 tlsconfig.go:240] "Starting DynamicServingCertificateController" I0922 01:58:51.086096 1 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller I0922 01:58:51.086213 1 system_namespaces_controller.go:67] Starting system namespaces controller I0922 01:58:51.086328 1 controller.go:78] Starting OpenAPI AggregationController I0922 01:58:51.086365 1 cluster_authentication_trust_controller.go:440] Starting cluster_authentication_trust_controller controller I0922 01:58:51.086380 1 shared_informer.go:311] Waiting for caches to sync for cluster_authentication_trust_controller I0922 01:58:51.086452 1 controller.go:80] Starting OpenAPI V3 AggregationController I0922 01:58:51.086529 1 customresource_discovery_controller.go:289] Starting DiscoveryController I0922 01:58:51.086577 1 controller.go:85] Starting OpenAPI V3 controller I0922 01:58:51.086609 1 naming_controller.go:291] Starting NamingConditionController I0922 01:58:51.086625 1 establishing_controller.go:76] Starting EstablishingController I0922 01:58:51.086642 1 nonstructuralschema_controller.go:192] Starting NonStructuralSchemaConditionController I0922 01:58:51.086653 1 available_controller.go:423] Starting AvailableConditionController I0922 01:58:51.086670 1 controller.go:116] Starting legacy_token_tracking_controller I0922 01:58:51.086675 1 cache.go:32] Waiting for caches to sync for AvailableConditionController controller I0922 01:58:51.086688 1 shared_informer.go:311] Waiting for caches to sync for configmaps I0922 01:58:51.086697 1 gc_controller.go:78] Starting apiserver lease garbage collector I0922 01:58:51.086676 1 crd_finalizer.go:266] Starting CRDFinalizer I0922 01:58:51.086664 1 apiapproval_controller.go:186] Starting KubernetesAPIApprovalPolicyConformantConditionController I0922 01:58:51.086572 1 dynamic_serving_content.go:132] "Starting controller" name="aggregator-proxy-cert::/etc/kubernetes/pki/front-proxy-client.crt::/etc/kubernetes/pki/front-proxy-client.key" I0922 01:58:51.086923 1 gc_controller.go:78] Starting apiserver lease garbage collector I0922 01:58:51.086961 1 apf_controller.go:374] Starting API Priority and Fairness config controller I0922 01:58:51.087038 1 controller.go:134] Starting OpenAPI controller I0922 01:58:51.087055 1 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" I0922 01:58:51.087149 1 dynamic_cafile_content.go:157] "Starting controller" name="request-header::/etc/kubernetes/pki/front-proxy-ca.crt" I0922 01:58:51.087425 1 aggregator.go:164] waiting for initial CRD sync... I0922 01:58:51.087490 1 handler_discovery.go:412] Starting ResourceDiscoveryManager I0922 01:58:51.087529 1 crdregistration_controller.go:111] Starting crd-autoregister controller I0922 01:58:51.087596 1 shared_informer.go:311] Waiting for caches to sync for crd-autoregister I0922 01:58:51.121660 1 shared_informer.go:318] Caches are synced for node_authorizer E0922 01:58:51.170669 1 controller.go:146] "Failed to ensure lease exists, will retry" err="namespaces \"kube-system\" not found" interval="200ms" I0922 01:58:51.186229 1 cache.go:39] Caches are synced for APIServiceRegistrationController controller I0922 01:58:51.186490 1 shared_informer.go:318] Caches are synced for cluster_authentication_trust_controller I0922 01:58:51.186760 1 shared_informer.go:318] Caches are synced for configmaps I0922 01:58:51.186906 1 cache.go:39] Caches are synced for AvailableConditionController controller I0922 01:58:51.186996 1 apf_controller.go:379] Running API Priority and Fairness config worker I0922 01:58:51.187014 1 apf_controller.go:382] Running API Priority and Fairness periodic rebalancing process I0922 01:58:51.187688 1 shared_informer.go:318] Caches are synced for crd-autoregister I0922 01:58:51.187731 1 aggregator.go:166] initial CRD sync complete... I0922 01:58:51.187826 1 autoregister_controller.go:141] Starting autoregister controller I0922 01:58:51.187844 1 cache.go:32] Waiting for caches to sync for autoregister controller I0922 01:58:51.187854 1 cache.go:39] Caches are synced for autoregister controller I0922 01:58:51.188091 1 controller.go:624] quota admission added evaluator for: namespaces I0922 01:58:51.374121 1 controller.go:624] quota admission added evaluator for: leases.coordination.k8s.io I0922 01:58:52.093768 1 storage_scheduling.go:95] created PriorityClass system-node-critical with value 2000001000 I0922 01:58:52.099837 1 storage_scheduling.go:95] created PriorityClass system-cluster-critical with value 2000000000 I0922 01:58:52.099860 1 storage_scheduling.go:111] all system priority classes are created successfully or already exist. I0922 01:58:52.774093 1 controller.go:624] quota admission added evaluator for: roles.rbac.authorization.k8s.io I0922 01:58:52.834244 1 controller.go:624] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io I0922 01:58:52.900955 1 alloc.go:330] "allocated clusterIPs" service="default/kubernetes" clusterIPs={"IPv4":"10.96.0.1"} W0922 01:58:52.912208 1 lease.go:263] Resetting endpoints for master service "kubernetes" to [162.253.55.241] I0922 01:58:52.913559 1 controller.go:624] quota admission added evaluator for: endpoints I0922 01:58:52.919601 1 controller.go:624] quota admission added evaluator for: endpointslices.discovery.k8s.io I0922 01:58:53.737485 1 controller.go:624] quota admission added evaluator for: serviceaccounts I0922 01:58:54.887759 1 controller.go:624] quota admission added evaluator for: deployments.apps I0922 01:58:54.901264 1 alloc.go:330] "allocated clusterIPs" service="kube-system/kube-dns" clusterIPs={"IPv4":"10.96.0.10"} I0922 01:58:55.056448 1 controller.go:624] quota admission added evaluator for: daemonsets.apps I0922 01:58:58.573962 1 controller.go:624] quota admission added evaluator for: controllerrevisions.apps I0922 01:58:58.975321 1 controller.go:624] quota admission added evaluator for: replicasets.apps I0922 01:59:33.359511 1 trace.go:236] Trace[874772806]: "List" accept:application/json, */*,audit-id:0ec210b0-d576-4d09-b160-4b9e9d5ee440,client:162.253.55.241,protocol:HTTP/2.0,resource:secrets,scope:namespace,url:/api/v1/namespaces/envoy-gateway-system/secrets,user-agent:Helm/3.11.2,verb:LIST (22-Sep-2026 01:59:32.510) (total time: 849ms): Trace[874772806]: ["List(recursive=true) etcd3" audit-id:0ec210b0-d576-4d09-b160-4b9e9d5ee440,key:/secrets/envoy-gateway-system,resourceVersion:,resourceVersionMatch:,limit:0,continue: 849ms (01:59:32.510)] Trace[874772806]: [849.249565ms] [849.249565ms] END I0922 01:59:33.408343 1 trace.go:236] Trace[1377121141]: "GuaranteedUpdate etcd3" audit-id:,key:/masterleases/162.253.55.241,type:*v1.Endpoints,resource:apiServerIPInfo (22-Sep-2026 01:59:32.893) (total time: 514ms): Trace[1377121141]: ---"initial value restored" 465ms (01:59:33.359) Trace[1377121141]: [514.530848ms] [514.530848ms] END I0922 01:59:33.746308 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1 to ResourceManager I0922 01:59:33.746334 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1beta1 to ResourceManager I0922 01:59:33.758751 1 handler.go:275] Adding GroupVersion gateway.networking.x-k8s.io v1alpha1 to ResourceManager I0922 01:59:33.774776 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1alpha2 to ResourceManager I0922 01:59:33.793436 1 handler.go:275] Adding GroupVersion gateway.networking.x-k8s.io v1alpha1 to ResourceManager I0922 01:59:33.820991 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1 to ResourceManager I0922 01:59:33.821021 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1alpha3 to ResourceManager I0922 01:59:33.835513 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1alpha2 to ResourceManager I0922 01:59:33.853811 1 handler.go:275] Adding GroupVersion gateway.networking.x-k8s.io v1alpha1 to ResourceManager I0922 01:59:33.859231 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1beta1 to ResourceManager I0922 01:59:33.912565 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1 to ResourceManager I0922 01:59:33.968951 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1 to ResourceManager I0922 01:59:33.968987 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1beta1 to ResourceManager I0922 01:59:33.986126 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1alpha2 to ResourceManager I0922 01:59:33.986169 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1alpha3 to ResourceManager I0922 01:59:34.079587 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:34.251014 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1 to ResourceManager I0922 01:59:34.251067 1 handler.go:275] Adding GroupVersion gateway.networking.k8s.io v1beta1 to ResourceManager I0922 01:59:34.285568 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:34.306822 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:34.365084 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:34.378347 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:35.065516 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:35.106227 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:35.189874 1 handler.go:275] Adding GroupVersion gateway.envoyproxy.io v1alpha1 to ResourceManager I0922 01:59:40.189818 1 trace.go:236] Trace[423850904]: "Get" accept:application/vnd.kubernetes.protobuf, */*,audit-id:ed2bba73-3b26-4f23-9092-35be43edb761,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-scheduler,user-agent:kube-scheduler/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:GET (22-Sep-2026 01:59:39.656) (total time: 533ms): Trace[423850904]: ---"About to write a response" 533ms (01:59:40.189) Trace[423850904]: [533.702972ms] [533.702972ms] END I0922 01:59:40.438616 1 trace.go:236] Trace[1587184765]: "Get" accept:application/json, */*,audit-id:57527d55-a532-4e4d-a093-74116daf5c61,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-svcs-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:GET (22-Sep-2026 01:59:39.840) (total time: 598ms): Trace[1587184765]: ---"About to write a response" 598ms (01:59:40.438) Trace[1587184765]: [598.481304ms] [598.481304ms] END I0922 01:59:40.445781 1 trace.go:236] Trace[1403735553]: "Update" accept:application/json, */*,audit-id:abeb52a6-8135-4885-91da-73adae43b856,client:162.253.55.241,protocol:HTTP/2.0,resource:secrets,scope:resource,url:/api/v1/namespaces/envoy-gateway-system/secrets/sh.helm.release.v1.envoy-gateway.v1,user-agent:Helm/3.11.2,verb:PUT (22-Sep-2026 01:59:39.637) (total time: 807ms): Trace[1403735553]: ---"Conversion done" 45ms (01:59:39.687) Trace[1403735553]: ["GuaranteedUpdate etcd3" audit-id:abeb52a6-8135-4885-91da-73adae43b856,key:/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1,type:*core.Secret,resource:secrets 758ms (01:59:39.687) Trace[1403735553]: ---"Txn call completed" 709ms (01:59:40.405)] Trace[1403735553]: ---"Writing http response done" 39ms (01:59:40.445) Trace[1403735553]: [807.832195ms] [807.832195ms] END I0922 01:59:41.472183 1 trace.go:236] Trace[709742971]: "Update" accept:application/vnd.kubernetes.protobuf, */*,audit-id:c6699219-a932-4f8e-9043-f6fa3121b8a7,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-controller-manager,user-agent:kube-controller-manager/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:PUT (22-Sep-2026 01:59:40.800) (total time: 672ms): Trace[709742971]: ["GuaranteedUpdate etcd3" audit-id:c6699219-a932-4f8e-9043-f6fa3121b8a7,key:/leases/kube-system/kube-controller-manager,type:*coordination.Lease,resource:leases.coordination.k8s.io 671ms (01:59:40.800) Trace[709742971]: ---"Txn call completed" 671ms (01:59:41.471)] Trace[709742971]: [672.081303ms] [672.081303ms] END I0922 01:59:41.499922 1 trace.go:236] Trace[893047081]: "Update" accept:application/json, */*,audit-id:2df676e2-78bc-46ad-8ac6-88389bb653cb,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-cp-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:PUT (22-Sep-2026 01:59:40.800) (total time: 699ms): Trace[893047081]: ["GuaranteedUpdate etcd3" audit-id:2df676e2-78bc-46ad-8ac6-88389bb653cb,key:/leases/kube-system/plndr-cp-lock,type:*coordination.Lease,resource:leases.coordination.k8s.io 699ms (01:59:40.800) Trace[893047081]: ---"Txn call completed" 698ms (01:59:41.499)] Trace[893047081]: [699.469565ms] [699.469565ms] END I0922 01:59:41.500047 1 trace.go:236] Trace[984499119]: "Delete" accept:application/json,audit-id:19e50173-8a74-4ff4-8555-d38e65d63f28,client:162.253.55.241,protocol:HTTP/2.0,resource:jobs,scope:resource,url:/apis/batch/v1/namespaces/envoy-gateway-system/jobs/envoy-gateway-gateway-helm-certgen,user-agent:Helm/3.11.2,verb:DELETE (22-Sep-2026 01:59:40.810) (total time: 689ms): Trace[984499119]: [689.397077ms] [689.397077ms] END I0922 01:59:42.735207 1 trace.go:236] Trace[593561110]: "Update" accept:application/json, */*,audit-id:2fdcbdb9-d46c-4084-8c93-060f8bae0840,client:162.253.55.241,protocol:HTTP/2.0,resource:secrets,scope:resource,url:/api/v1/namespaces/envoy-gateway-system/secrets/sh.helm.release.v1.envoy-gateway.v1,user-agent:Helm/3.11.2,verb:PUT (22-Sep-2026 01:59:41.851) (total time: 883ms): Trace[593561110]: ---"Conversion done" 16ms (01:59:41.872) Trace[593561110]: ["GuaranteedUpdate etcd3" audit-id:2fdcbdb9-d46c-4084-8c93-060f8bae0840,key:/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1,type:*core.Secret,resource:secrets 862ms (01:59:41.872) Trace[593561110]: ---"Txn call completed" 810ms (01:59:42.688)] Trace[593561110]: ---"Writing http response done" 39ms (01:59:42.735) Trace[593561110]: [883.207746ms] [883.207746ms] END I0922 01:59:42.782424 1 controller.go:624] quota admission added evaluator for: jobs.batch I0922 01:59:43.383524 1 trace.go:236] Trace[1069823976]: "Update" accept:application/vnd.kubernetes.protobuf, */*,audit-id:4e8cf4df-cd62-4a9f-8d70-708f5a4c33fd,client:::1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/apiserver-f3tcohoifagyom4bbi4wgeu7te,user-agent:kube-apiserver/v1.28.13 (linux/amd64) kubernetes/024ab2a,verb:PUT (22-Sep-2026 01:59:42.668) (total time: 715ms): Trace[1069823976]: ["GuaranteedUpdate etcd3" audit-id:4e8cf4df-cd62-4a9f-8d70-708f5a4c33fd,key:/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te,type:*coordination.Lease,resource:leases.coordination.k8s.io 714ms (01:59:42.668) Trace[1069823976]: ---"Txn call completed" 713ms (01:59:43.383)] Trace[1069823976]: [715.0959ms] [715.0959ms] END I0922 01:59:43.391559 1 trace.go:236] Trace[410366117]: "Get" accept:application/json, */*,audit-id:b1ce2380-02e5-4229-a98a-363e999599ef,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-svcs-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:GET (22-Sep-2026 01:59:42.721) (total time: 669ms): Trace[410366117]: ---"About to write a response" 669ms (01:59:43.391) Trace[410366117]: [669.69089ms] [669.69089ms] END I0922 01:59:43.391821 1 trace.go:236] Trace[1888467526]: "Get" accept:application/vnd.kubernetes.protobuf, */*,audit-id:4605c003-8696-4115-a38d-37e4b2f06c06,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-scheduler,user-agent:kube-scheduler/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:GET (22-Sep-2026 01:59:42.441) (total time: 950ms): Trace[1888467526]: ---"About to write a response" 950ms (01:59:43.391) Trace[1888467526]: [950.681767ms] [950.681767ms] END I0922 01:59:43.392525 1 trace.go:236] Trace[386912083]: "Create" accept:application/json,audit-id:b8f58589-043e-481b-9c62-da66b78f334b,client:162.253.55.241,protocol:HTTP/2.0,resource:jobs,scope:resource,url:/apis/batch/v1/namespaces/envoy-gateway-system/jobs,user-agent:Helm/3.11.2,verb:POST (22-Sep-2026 01:59:42.780) (total time: 612ms): Trace[386912083]: ["Create etcd3" audit-id:b8f58589-043e-481b-9c62-da66b78f334b,key:/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen,type:*batch.Job,resource:jobs.batch 609ms (01:59:42.782) Trace[386912083]: ---"Txn call succeeded" 608ms (01:59:43.391)] Trace[386912083]: [612.127083ms] [612.127083ms] END I0922 01:59:43.402746 1 trace.go:236] Trace[298473372]: "GuaranteedUpdate etcd3" audit-id:,key:/masterleases/162.253.55.241,type:*v1.Endpoints,resource:apiServerIPInfo (22-Sep-2026 01:59:42.894) (total time: 508ms): Trace[298473372]: ---"initial value restored" 496ms (01:59:43.390) Trace[298473372]: [508.513731ms] [508.513731ms] END I0922 01:59:44.980673 1 handler.go:275] Adding GroupVersion cilium.io v2alpha1 to ResourceManager I0922 01:59:45.170761 1 handler.go:275] Adding GroupVersion cilium.io v2 to ResourceManager I0922 01:59:45.403361 1 handler.go:275] Adding GroupVersion cilium.io v2alpha1 to ResourceManager I0922 01:59:45.568385 1 handler.go:275] Adding GroupVersion cilium.io v2alpha1 to ResourceManager I0922 01:59:45.773271 1 handler.go:275] Adding GroupVersion cilium.io v2alpha1 to ResourceManager I0922 01:59:45.997886 1 handler.go:275] Adding GroupVersion cilium.io v2alpha1 to ResourceManager I0922 01:59:46.272116 1 handler.go:275] Adding GroupVersion cilium.io v2 to ResourceManager I0922 01:59:46.653550 1 handler.go:275] Adding GroupVersion cilium.io v2 to ResourceManager I0922 01:59:46.845347 1 handler.go:275] Adding GroupVersion cilium.io v2 to ResourceManager I0922 01:59:48.887864 1 handler.go:275] Adding GroupVersion cilium.io v2 to ResourceManager I0922 01:59:49.253829 1 handler.go:275] Adding GroupVersion cilium.io v2 to ResourceManager W0922 01:59:50.132495 1 dispatcher.go:210] Failed calling webhook, failing open topology.webhook.gateway.envoyproxy.io: failed calling webhook "topology.webhook.gateway.envoyproxy.io": failed to call webhook: Post "https://envoy-gateway.envoy-gateway-system.svc:9443/inject-pod-topology?timeout=10s": service "envoy-gateway" not found E0922 01:59:50.132543 1 dispatcher.go:214] failed calling webhook "topology.webhook.gateway.envoyproxy.io": failed to call webhook: Post "https://envoy-gateway.envoy-gateway-system.svc:9443/inject-pod-topology?timeout=10s": service "envoy-gateway" not found I0922 01:59:53.402408 1 controller.go:624] quota admission added evaluator for: ciliumendpoints.cilium.io I0922 02:00:00.474486 1 trace.go:236] Trace[372001656]: "Update" accept:application/json, */*,audit-id:e1993008-0d11-455a-ad28-1bb53f3dca8c,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-svcs-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:PUT (22-Sep-2026 01:59:59.545) (total time: 928ms): Trace[372001656]: ["GuaranteedUpdate etcd3" audit-id:e1993008-0d11-455a-ad28-1bb53f3dca8c,key:/leases/kube-system/plndr-svcs-lock,type:*coordination.Lease,resource:leases.coordination.k8s.io 928ms (01:59:59.545) Trace[372001656]: ---"Txn call completed" 928ms (02:00:00.474)] Trace[372001656]: [928.935779ms] [928.935779ms] END I0922 02:00:00.475028 1 trace.go:236] Trace[1802687742]: "Get" accept:application/vnd.kubernetes.protobuf, */*,audit-id:4fc4cf24-9df9-4dfc-a028-f18e4423006e,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-controller-manager,user-agent:kube-controller-manager/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:GET (22-Sep-2026 01:59:59.558) (total time: 916ms): Trace[1802687742]: ---"About to write a response" 916ms (02:00:00.474) Trace[1802687742]: [916.433662ms] [916.433662ms] END I0922 02:00:00.478714 1 trace.go:236] Trace[1976454805]: "Get" accept:application/json, */*,audit-id:4fb97a9b-b82e-4404-b58a-acb67309652a,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-cp-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:GET (22-Sep-2026 01:59:59.695) (total time: 782ms): Trace[1976454805]: ---"About to write a response" 782ms (02:00:00.478) Trace[1976454805]: [782.64053ms] [782.64053ms] END I0922 02:00:04.450873 1 alloc.go:330] "allocated clusterIPs" service="envoy-gateway-system/envoy-gateway" clusterIPs={"IPv4":"10.104.166.77"} W0922 02:00:04.485432 1 dispatcher.go:210] Failed calling webhook, failing open topology.webhook.gateway.envoyproxy.io: failed calling webhook "topology.webhook.gateway.envoyproxy.io": failed to call webhook: Post "https://envoy-gateway.envoy-gateway-system.svc:9443/inject-pod-topology?timeout=10s": dial tcp 10.104.166.77:9443: connect: connection refused E0922 02:00:04.485542 1 dispatcher.go:214] failed calling webhook "topology.webhook.gateway.envoyproxy.io": failed to call webhook: Post "https://envoy-gateway.envoy-gateway-system.svc:9443/inject-pod-topology?timeout=10s": dial tcp 10.104.166.77:9443: connect: connection refused I0922 02:00:39.054090 1 alloc.go:330] "allocated clusterIPs" service="kube-system/ceph-csi-rbd-nodeplugin-http-metrics" clusterIPs={"IPv4":"10.96.85.51"} I0922 02:00:39.066019 1 alloc.go:330] "allocated clusterIPs" service="kube-system/ceph-csi-rbd-provisioner-http-metrics" clusterIPs={"IPv4":"10.108.231.49"} I0922 02:00:44.077179 1 trace.go:236] Trace[838190968]: "GuaranteedUpdate etcd3" audit-id:,key:/masterleases/162.253.55.241,type:*v1.Endpoints,resource:apiServerIPInfo (22-Sep-2026 02:00:42.896) (total time: 1180ms): Trace[838190968]: ---"Transaction prepared" 625ms (02:00:43.523) Trace[838190968]: ---"Txn call completed" 553ms (02:00:44.077) Trace[838190968]: [1.180212085s] [1.180212085s] END I0922 02:01:02.664779 1 trace.go:236] Trace[2114446173]: "Update" accept:application/vnd.kubernetes.protobuf,application/json,audit-id:385ebb74-0223-496a-a4ab-e339c0e1e041,client:10.0.0.142,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/envoy-gateway-system/leases/5b9825d2.gateway.envoyproxy.io,user-agent:envoy-gateway/v0.0.0 (linux/amd64) kubernetes/$Format/leader-election,verb:PUT (22-Sep-2026 02:01:02.163) (total time: 500ms): Trace[2114446173]: ["GuaranteedUpdate etcd3" audit-id:385ebb74-0223-496a-a4ab-e339c0e1e041,key:/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io,type:*coordination.Lease,resource:leases.coordination.k8s.io 500ms (02:01:02.164) Trace[2114446173]: ---"Txn call completed" 499ms (02:01:02.664)] Trace[2114446173]: [500.756988ms] [500.756988ms] END I0922 02:01:07.574674 1 trace.go:236] Trace[174978739]: "Update" accept:application/vnd.kubernetes.protobuf,application/json,audit-id:ddcf37b1-e772-4295-a8df-dfafb5666ca5,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/instance,user-agent:kubelet/v1.28.13 (linux/amd64) kubernetes/024ab2a,verb:PUT (22-Sep-2026 02:01:06.918) (total time: 656ms): Trace[174978739]: ["GuaranteedUpdate etcd3" audit-id:ddcf37b1-e772-4295-a8df-dfafb5666ca5,key:/leases/kube-node-lease/instance,type:*coordination.Lease,resource:leases.coordination.k8s.io 656ms (02:01:06.918) Trace[174978739]: ---"Txn call completed" 655ms (02:01:07.574)] Trace[174978739]: [656.441999ms] [656.441999ms] END I0922 02:01:07.574707 1 trace.go:236] Trace[519634736]: "Get" accept:application/json, */*,audit-id:cf41a280-fffe-4e72-a4cb-5a9d4d1d28d5,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-cp-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:GET (22-Sep-2026 02:01:06.996) (total time: 578ms): Trace[519634736]: ---"About to write a response" 578ms (02:01:07.574) Trace[519634736]: [578.252406ms] [578.252406ms] END I0922 02:01:07.574762 1 trace.go:236] Trace[1566195305]: "Get" accept:application/vnd.kubernetes.protobuf, */*,audit-id:dc34adee-b08c-402c-be23-eb0db746328d,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-controller-manager,user-agent:kube-controller-manager/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:GET (22-Sep-2026 02:01:06.996) (total time: 578ms): Trace[1566195305]: ---"About to write a response" 578ms (02:01:07.574) Trace[1566195305]: [578.499232ms] [578.499232ms] END I0922 02:01:07.575106 1 trace.go:236] Trace[138689997]: "Get" accept:application/json, */*,audit-id:dd28287b-e85c-432e-9895-cbf851703116,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-svcs-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:GET (22-Sep-2026 02:01:06.997) (total time: 577ms): Trace[138689997]: ---"About to write a response" 577ms (02:01:07.574) Trace[138689997]: [577.9744ms] [577.9744ms] END I0922 02:01:07.575317 1 trace.go:236] Trace[768429758]: "Get" accept:application/vnd.kubernetes.protobuf, */*,audit-id:b6e69fb3-cbf3-4e48-83c4-5c68ffec9c84,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-scheduler,user-agent:kube-scheduler/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:GET (22-Sep-2026 02:01:06.996) (total time: 578ms): Trace[768429758]: ---"About to write a response" 578ms (02:01:07.575) Trace[768429758]: [578.562333ms] [578.562333ms] END I0922 02:01:08.267262 1 trace.go:236] Trace[1984805364]: "Update" accept:application/json, */*,audit-id:58bb0814-fa65-4e2f-9bd5-e13c44144bf9,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-cp-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:PUT (22-Sep-2026 02:01:07.577) (total time: 689ms): Trace[1984805364]: ["GuaranteedUpdate etcd3" audit-id:58bb0814-fa65-4e2f-9bd5-e13c44144bf9,key:/leases/kube-system/plndr-cp-lock,type:*coordination.Lease,resource:leases.coordination.k8s.io 689ms (02:01:07.577) Trace[1984805364]: ---"Txn call completed" 687ms (02:01:08.266)] Trace[1984805364]: [689.414934ms] [689.414934ms] END I0922 02:01:08.267341 1 trace.go:236] Trace[1309285017]: "Update" accept:application/vnd.kubernetes.protobuf, */*,audit-id:8dd4c087-5ba6-4892-9e90-dfe8be15a46c,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-scheduler,user-agent:kube-scheduler/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:PUT (22-Sep-2026 02:01:07.578) (total time: 688ms): Trace[1309285017]: ["GuaranteedUpdate etcd3" audit-id:8dd4c087-5ba6-4892-9e90-dfe8be15a46c,key:/leases/kube-system/kube-scheduler,type:*coordination.Lease,resource:leases.coordination.k8s.io 688ms (02:01:07.578) Trace[1309285017]: ---"Txn call completed" 687ms (02:01:08.267)] Trace[1309285017]: [688.645998ms] [688.645998ms] END I0922 02:01:08.267312 1 trace.go:236] Trace[733724130]: "Update" accept:application/json, */*,audit-id:df09046a-5aab-49b9-8b1f-18822e704eae,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/cilium-operator-resource-lock,user-agent:cilium-operator-generic/v0.0.0 (linux/amd64) kubernetes/$Format/leader-election,verb:PUT (22-Sep-2026 02:01:07.578) (total time: 689ms): Trace[733724130]: ["GuaranteedUpdate etcd3" audit-id:df09046a-5aab-49b9-8b1f-18822e704eae,key:/leases/kube-system/cilium-operator-resource-lock,type:*coordination.Lease,resource:leases.coordination.k8s.io 688ms (02:01:07.578) Trace[733724130]: ---"Txn call completed" 687ms (02:01:08.267)] Trace[733724130]: [689.2336ms] [689.2336ms] END I0922 02:01:08.268037 1 trace.go:236] Trace[1193853265]: "Update" accept:application/vnd.kubernetes.protobuf, */*,audit-id:16d46a85-c745-438f-affc-852677990051,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-controller-manager,user-agent:kube-controller-manager/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:PUT (22-Sep-2026 02:01:07.576) (total time: 691ms): Trace[1193853265]: ["GuaranteedUpdate etcd3" audit-id:16d46a85-c745-438f-affc-852677990051,key:/leases/kube-system/kube-controller-manager,type:*coordination.Lease,resource:leases.coordination.k8s.io 691ms (02:01:07.576) Trace[1193853265]: ---"Txn call completed" 689ms (02:01:08.267)] Trace[1193853265]: [691.527412ms] [691.527412ms] END I0922 02:01:14.947249 1 trace.go:236] Trace[824848853]: "Get" accept:application/vnd.kubernetes.protobuf, */*,audit-id:e062bbc1-c76f-4e4d-94f4-0783757de5e1,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-controller-manager,user-agent:kube-controller-manager/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:GET (22-Sep-2026 02:01:14.323) (total time: 623ms): Trace[824848853]: ---"About to write a response" 623ms (02:01:14.947) Trace[824848853]: [623.845612ms] [623.845612ms] END I0922 02:01:14.947358 1 trace.go:236] Trace[1454418278]: "Get" accept:application/vnd.kubernetes.protobuf, */*,audit-id:d54bc1d2-1799-4e40-835c-03133275e2a4,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/kube-scheduler,user-agent:kube-scheduler/v1.28.13 (linux/amd64) kubernetes/024ab2a/leader-election,verb:GET (22-Sep-2026 02:01:14.326) (total time: 620ms): Trace[1454418278]: ---"About to write a response" 620ms (02:01:14.947) Trace[1454418278]: [620.995628ms] [620.995628ms] END I0922 02:01:14.947401 1 trace.go:236] Trace[914842827]: "Get" accept:application/json, */*,audit-id:4d1daf66-84b4-47ff-9323-5940ec929e08,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-cp-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:GET (22-Sep-2026 02:01:14.322) (total time: 624ms): Trace[914842827]: ---"About to write a response" 624ms (02:01:14.947) Trace[914842827]: [624.841695ms] [624.841695ms] END I0922 02:01:14.947459 1 trace.go:236] Trace[175521544]: "Get" accept:application/json, */*,audit-id:24f4c306-4646-4131-891b-993b6c56f548,client:127.0.0.1,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/plndr-svcs-lock,user-agent:kube-vip/v0.0.0 (linux/amd64) kubernetes/$Format,verb:GET (22-Sep-2026 02:01:13.616) (total time: 1330ms): Trace[175521544]: ---"About to write a response" 1330ms (02:01:14.947) Trace[175521544]: [1.330522032s] [1.330522032s] END I0922 02:01:14.947401 1 trace.go:236] Trace[1753621445]: "Get" accept:application/json, */*,audit-id:b4bd171b-675e-40e9-ae4a-c873e6df29c7,client:162.253.55.241,protocol:HTTP/2.0,resource:leases,scope:resource,url:/apis/coordination.k8s.io/v1/namespaces/kube-system/leases/cilium-operator-resource-lock,user-agent:cilium-operator-generic/v0.0.0 (linux/amd64) kubernetes/$Format/leader-election,verb:GET (22-Sep-2026 02:01:14.327) (total time: 619ms): Trace[1753621445]: ---"About to write a response" 619ms (02:01:14.947) Trace[1753621445]: [619.357252ms] [619.357252ms] END I0922 02:01:14.950443 1 trace.go:236] Trace[57899579]: "Patch" accept:application/vnd.kubernetes.protobuf,application/json,audit-id:ed6e461a-0914-45e9-8483-57c97697ed46,client:162.253.55.241,protocol:HTTP/2.0,resource:pods,scope:resource,url:/api/v1/namespaces/kube-system/pods/ceph-csi-rbd-provisioner-69cb97fb6-bgfkb/status,user-agent:kubelet/v1.28.13 (linux/amd64) kubernetes/024ab2a,verb:PATCH (22-Sep-2026 02:01:13.555) (total time: 1394ms): Trace[57899579]: ["GuaranteedUpdate etcd3" audit-id:ed6e461a-0914-45e9-8483-57c97697ed46,key:/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-bgfkb,type:*core.Pod,resource:pods 1394ms (02:01:13.556) Trace[57899579]: ---"Txn call completed" 1384ms (02:01:14.949)] Trace[57899579]: ---"Object stored in database" 1385ms (02:01:14.950) Trace[57899579]: [1.394419728s] [1.394419728s] END