I0930 02:39:54.436855 1 serving.go:348] Generated self-signed cert in-memory I0930 02:39:54.756621 1 controllermanager.go:189] "Starting" version="v1.28.13" I0930 02:39:54.756647 1 controllermanager.go:191] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" I0930 02:39:54.758327 1 secure_serving.go:213] Serving securely on [::]:10257 I0930 02:39:54.758314 1 dynamic_cafile_content.go:157] "Starting controller" name="request-header::/etc/kubernetes/pki/front-proxy-ca.crt" I0930 02:39:54.758399 1 tlsconfig.go:240] "Starting DynamicServingCertificateController" I0930 02:39:54.758329 1 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" I0930 02:39:54.762075 1 leaderelection.go:250] attempting to acquire leader lease kube-system/kube-controller-manager... E0930 02:39:56.475809 1 leaderelection.go:332] error retrieving resource lock kube-system/kube-controller-manager: leases.coordination.k8s.io "kube-controller-manager" is forbidden: User "system:kube-controller-manager" cannot get resource "leases" in API group "coordination.k8s.io" in the namespace "kube-system" I0930 02:39:59.982473 1 leaderelection.go:260] successfully acquired lease kube-system/kube-controller-manager I0930 02:39:59.982771 1 event.go:307] "Event occurred" object="kube-system/kube-controller-manager" fieldPath="" kind="Lease" apiVersion="coordination.k8s.io/v1" type="Normal" reason="LeaderElection" message="instance_56b12156-9129-4ca8-b2a9-4464f15e7621 became leader" I0930 02:39:59.993868 1 shared_informer.go:311] Waiting for caches to sync for tokens I0930 02:40:00.005910 1 controllermanager.go:642] "Started controller" controller="bootstrap-signer-controller" I0930 02:40:00.005991 1 shared_informer.go:311] Waiting for caches to sync for bootstrap_signer I0930 02:40:00.016469 1 controllermanager.go:642] "Started controller" controller="clusterrole-aggregation-controller" I0930 02:40:00.016544 1 clusterroleaggregation_controller.go:189] "Starting ClusterRoleAggregator controller" I0930 02:40:00.016572 1 shared_informer.go:311] Waiting for caches to sync for ClusterRoleAggregator I0930 02:40:00.028572 1 controllermanager.go:642] "Started controller" controller="root-ca-certificate-publisher-controller" I0930 02:40:00.028633 1 publisher.go:102] "Starting root CA cert publisher controller" I0930 02:40:00.028689 1 shared_informer.go:311] Waiting for caches to sync for crt configmap I0930 02:40:00.039380 1 controllermanager.go:642] "Started controller" controller="endpointslice-controller" I0930 02:40:00.039569 1 endpointslice_controller.go:264] "Starting endpoint slice controller" I0930 02:40:00.039593 1 shared_informer.go:311] Waiting for caches to sync for endpoint_slice I0930 02:40:00.073440 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="ingresses.networking.k8s.io" W0930 02:40:00.073485 1 shared_informer.go:593] resyncPeriod 15h53m15.71848484s is smaller than resyncCheckPeriod 22h36m31.654874058s and the informer has already started. Changing it to 22h36m31.654874058s I0930 02:40:00.073536 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="serviceaccounts" I0930 02:40:00.073576 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="controllerrevisions.apps" I0930 02:40:00.073612 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="cronjobs.batch" I0930 02:40:00.073642 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="podtemplates" I0930 02:40:00.073703 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="networkpolicies.networking.k8s.io" I0930 02:40:00.073730 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="jobs.batch" I0930 02:40:00.073757 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="endpoints" I0930 02:40:00.073785 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="statefulsets.apps" I0930 02:40:00.073814 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="poddisruptionbudgets.policy" I0930 02:40:00.073861 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="deployments.apps" I0930 02:40:00.073898 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="horizontalpodautoscalers.autoscaling" I0930 02:40:00.073933 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="rolebindings.rbac.authorization.k8s.io" I0930 02:40:00.073989 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="daemonsets.apps" I0930 02:40:00.074020 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="replicasets.apps" I0930 02:40:00.074050 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="leases.coordination.k8s.io" I0930 02:40:00.074092 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="roles.rbac.authorization.k8s.io" I0930 02:40:00.074119 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="limitranges" I0930 02:40:00.074143 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="csistoragecapacities.storage.k8s.io" W0930 02:40:00.074162 1 shared_informer.go:593] resyncPeriod 19h42m1.404401224s is smaller than resyncCheckPeriod 22h36m31.654874058s and the informer has already started. Changing it to 22h36m31.654874058s I0930 02:40:00.074214 1 resource_quota_monitor.go:224] "QuotaMonitor created object count evaluator" resource="endpointslices.discovery.k8s.io" I0930 02:40:00.074270 1 controllermanager.go:642] "Started controller" controller="resourcequota-controller" I0930 02:40:00.074564 1 resource_quota_controller.go:294] "Starting resource quota controller" I0930 02:40:00.074651 1 resource_quota_monitor.go:305] "QuotaMonitor running" I0930 02:40:00.074706 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0930 02:40:00.088335 1 controllermanager.go:642] "Started controller" controller="disruption-controller" I0930 02:40:00.088374 1 disruption.go:433] "Sending events to api server." I0930 02:40:00.088429 1 disruption.go:444] "Starting disruption controller" I0930 02:40:00.088446 1 shared_informer.go:311] Waiting for caches to sync for disruption I0930 02:40:00.094007 1 shared_informer.go:318] Caches are synced for tokens I0930 02:40:00.097380 1 controllermanager.go:642] "Started controller" controller="ttl-controller" I0930 02:40:00.097498 1 ttl_controller.go:124] "Starting TTL controller" I0930 02:40:00.097526 1 shared_informer.go:311] Waiting for caches to sync for TTL I0930 02:40:00.106405 1 controllermanager.go:642] "Started controller" controller="persistentvolume-attach-detach-controller" I0930 02:40:00.106589 1 attach_detach_controller.go:337] "Starting attach detach controller" I0930 02:40:00.106628 1 shared_informer.go:311] Waiting for caches to sync for attach detach I0930 02:40:00.115761 1 controllermanager.go:642] "Started controller" controller="ttl-after-finished-controller" I0930 02:40:00.115880 1 ttlafterfinished_controller.go:109] "Starting TTL after finished controller" I0930 02:40:00.115917 1 shared_informer.go:311] Waiting for caches to sync for TTL after finished I0930 02:40:00.239727 1 controllermanager.go:642] "Started controller" controller="endpoints-controller" I0930 02:40:00.239886 1 endpoints_controller.go:177] "Starting endpoint controller" I0930 02:40:00.239905 1 shared_informer.go:311] Waiting for caches to sync for endpoint I0930 02:40:00.389463 1 controllermanager.go:642] "Started controller" controller="serviceaccount-controller" I0930 02:40:00.389552 1 serviceaccounts_controller.go:111] "Starting service account controller" I0930 02:40:00.389564 1 shared_informer.go:311] Waiting for caches to sync for service account I0930 02:40:00.687340 1 controllermanager.go:642] "Started controller" controller="horizontal-pod-autoscaler-controller" I0930 02:40:00.687386 1 controllermanager.go:620] "Warning: skipping controller" controller="node-ipam-controller" I0930 02:40:00.687414 1 core.go:224] "Will not configure cloud provider routes for allocate-node-cidrs" CIDRs=false routes=true I0930 02:40:00.687426 1 controllermanager.go:620] "Warning: skipping controller" controller="node-route-controller" I0930 02:40:00.687444 1 horizontal.go:200] "Starting HPA controller" I0930 02:40:00.687476 1 shared_informer.go:311] Waiting for caches to sync for HPA I0930 02:40:00.945450 1 controllermanager.go:642] "Started controller" controller="namespace-controller" I0930 02:40:00.945538 1 namespace_controller.go:197] "Starting namespace controller" I0930 02:40:00.945550 1 shared_informer.go:311] Waiting for caches to sync for namespace I0930 02:40:01.091688 1 controllermanager.go:642] "Started controller" controller="daemonset-controller" I0930 02:40:01.091876 1 daemon_controller.go:291] "Starting daemon sets controller" I0930 02:40:01.091897 1 shared_informer.go:311] Waiting for caches to sync for daemon sets I0930 02:40:01.238121 1 controllermanager.go:642] "Started controller" controller="replicaset-controller" I0930 02:40:01.238228 1 replica_set.go:214] "Starting controller" name="replicaset" I0930 02:40:01.238242 1 shared_informer.go:311] Waiting for caches to sync for ReplicaSet I0930 02:40:01.389910 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-cleaner-controller" I0930 02:40:01.390016 1 cleaner.go:83] "Starting CSR cleaner controller" I0930 02:40:01.577131 1 controllermanager.go:642] "Started controller" controller="pod-garbage-collector-controller" I0930 02:40:01.577233 1 gc_controller.go:101] "Starting GC controller" I0930 02:40:01.577258 1 shared_informer.go:311] Waiting for caches to sync for GC I0930 02:40:01.694958 1 controllermanager.go:642] "Started controller" controller="persistentvolume-protection-controller" I0930 02:40:01.695049 1 pv_protection_controller.go:78] "Starting PV protection controller" I0930 02:40:01.695242 1 shared_informer.go:311] Waiting for caches to sync for PV protection I0930 02:40:01.839193 1 controllermanager.go:642] "Started controller" controller="ephemeral-volume-controller" I0930 02:40:01.839249 1 controller.go:169] "Starting ephemeral volume controller" I0930 02:40:01.839275 1 shared_informer.go:311] Waiting for caches to sync for ephemeral I0930 02:40:01.988477 1 controllermanager.go:642] "Started controller" controller="deployment-controller" I0930 02:40:01.988551 1 deployment_controller.go:168] "Starting controller" controller="deployment" I0930 02:40:01.988557 1 shared_informer.go:311] Waiting for caches to sync for deployment I0930 02:40:02.040944 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-approving-controller" I0930 02:40:02.041036 1 certificate_controller.go:115] "Starting certificate controller" name="csrapproving" I0930 02:40:02.041053 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrapproving E0930 02:40:02.187960 1 core.go:213] "Failed to start cloud node lifecycle controller" err="no cloud provider provided" I0930 02:40:02.188012 1 controllermanager.go:620] "Warning: skipping controller" controller="cloud-node-lifecycle-controller" I0930 02:40:02.340508 1 controllermanager.go:642] "Started controller" controller="persistentvolumeclaim-protection-controller" I0930 02:40:02.340616 1 pvc_protection_controller.go:102] "Starting PVC protection controller" I0930 02:40:02.340641 1 shared_informer.go:311] Waiting for caches to sync for PVC protection I0930 02:40:02.586712 1 garbagecollector.go:155] "Starting controller" controller="garbagecollector" I0930 02:40:02.586756 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0930 02:40:02.586791 1 controllermanager.go:642] "Started controller" controller="garbage-collector-controller" I0930 02:40:02.586838 1 graph_builder.go:294] "Running" component="GraphBuilder" I0930 02:40:02.839383 1 controllermanager.go:642] "Started controller" controller="job-controller" I0930 02:40:02.839485 1 job_controller.go:226] "Starting job controller" I0930 02:40:02.839494 1 shared_informer.go:311] Waiting for caches to sync for job I0930 02:40:02.886785 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kubelet-serving" I0930 02:40:02.886824 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kubelet-serving I0930 02:40:02.886849 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0930 02:40:02.887268 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kubelet-client" I0930 02:40:02.887325 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kubelet-client I0930 02:40:02.887327 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0930 02:40:02.887632 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-kube-apiserver-client" I0930 02:40:02.887683 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" I0930 02:40:02.887708 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-kube-apiserver-client I0930 02:40:02.887980 1 controllermanager.go:642] "Started controller" controller="certificatesigningrequest-signing-controller" I0930 02:40:02.888062 1 certificate_controller.go:115] "Starting certificate controller" name="csrsigning-legacy-unknown" I0930 02:40:02.888082 1 shared_informer.go:311] Waiting for caches to sync for certificate-csrsigning-legacy-unknown I0930 02:40:02.888122 1 dynamic_serving_content.go:132] "Starting controller" name="csr-controller::/etc/kubernetes/pki/ca.crt::/etc/kubernetes/pki/ca.key" E0930 02:40:03.038797 1 core.go:92] "Failed to start service controller" err="WARNING: no cloud provider provided, services of type LoadBalancer will fail" I0930 02:40:03.038834 1 controllermanager.go:620] "Warning: skipping controller" controller="service-lb-controller" I0930 02:40:03.191147 1 controllermanager.go:642] "Started controller" controller="persistentvolume-expander-controller" I0930 02:40:03.191237 1 expand_controller.go:328] "Starting expand controller" I0930 02:40:03.191248 1 shared_informer.go:311] Waiting for caches to sync for expand I0930 02:40:03.339134 1 controllermanager.go:642] "Started controller" controller="token-cleaner-controller" I0930 02:40:03.339222 1 tokencleaner.go:112] "Starting token cleaner controller" I0930 02:40:03.339250 1 shared_informer.go:311] Waiting for caches to sync for token_cleaner I0930 02:40:03.339265 1 shared_informer.go:318] Caches are synced for token_cleaner I0930 02:40:03.387155 1 node_lifecycle_controller.go:431] "Controller will reconcile labels" I0930 02:40:03.387234 1 controllermanager.go:642] "Started controller" controller="node-lifecycle-controller" I0930 02:40:03.387394 1 node_lifecycle_controller.go:465] "Sending events to api server" I0930 02:40:03.387451 1 node_lifecycle_controller.go:476] "Starting node controller" I0930 02:40:03.387463 1 shared_informer.go:311] Waiting for caches to sync for taint I0930 02:40:03.538527 1 controllermanager.go:642] "Started controller" controller="persistentvolume-binder-controller" I0930 02:40:03.538773 1 pv_controller_base.go:319] "Starting persistent volume controller" I0930 02:40:03.538814 1 shared_informer.go:311] Waiting for caches to sync for persistent volume I0930 02:40:03.689630 1 controllermanager.go:642] "Started controller" controller="endpointslice-mirroring-controller" I0930 02:40:03.689780 1 endpointslicemirroring_controller.go:223] "Starting EndpointSliceMirroring controller" I0930 02:40:03.689813 1 shared_informer.go:311] Waiting for caches to sync for endpoint_slice_mirroring I0930 02:40:03.839747 1 controllermanager.go:642] "Started controller" controller="replicationcontroller-controller" I0930 02:40:03.839972 1 replica_set.go:214] "Starting controller" name="replicationcontroller" I0930 02:40:03.839996 1 shared_informer.go:311] Waiting for caches to sync for ReplicationController I0930 02:40:03.988942 1 controllermanager.go:642] "Started controller" controller="statefulset-controller" I0930 02:40:03.989091 1 stateful_set.go:163] "Starting stateful set controller" I0930 02:40:03.989127 1 shared_informer.go:311] Waiting for caches to sync for stateful set I0930 02:40:04.140754 1 controllermanager.go:642] "Started controller" controller="cronjob-controller" I0930 02:40:04.140925 1 cronjob_controllerv2.go:139] "Starting cronjob controller v2" I0930 02:40:04.140958 1 shared_informer.go:311] Waiting for caches to sync for cronjob I0930 02:40:04.144586 1 shared_informer.go:311] Waiting for caches to sync for resource quota I0930 02:40:04.159540 1 actual_state_of_world.go:547] "Failed to update statusUpdateNeeded field in actual state of world" err="Failed to set statusUpdateNeeded to needed true, because nodeName=\"instance\" does not exist" I0930 02:40:04.167130 1 shared_informer.go:311] Waiting for caches to sync for garbage collector I0930 02:40:04.178004 1 shared_informer.go:318] Caches are synced for GC I0930 02:40:04.187324 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kubelet-serving I0930 02:40:04.187502 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kubelet-client I0930 02:40:04.187539 1 shared_informer.go:318] Caches are synced for HPA I0930 02:40:04.187646 1 shared_informer.go:318] Caches are synced for taint I0930 02:40:04.187769 1 node_lifecycle_controller.go:1225] "Initializing eviction metric for zone" zone="" I0930 02:40:04.187795 1 taint_manager.go:205] "Starting NoExecuteTaintManager" I0930 02:40:04.187884 1 node_lifecycle_controller.go:877] "Missing timestamp for Node. Assuming now as a timestamp" node="instance" I0930 02:40:04.187907 1 taint_manager.go:210] "Sending events to api server" I0930 02:40:04.187956 1 node_lifecycle_controller.go:1029] "Controller detected that all Nodes are not-Ready. Entering master disruption mode" I0930 02:40:04.188155 1 event.go:307] "Event occurred" object="instance" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="RegisteredNode" message="Node instance event: Registered Node instance in Controller" I0930 02:40:04.188182 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-legacy-unknown I0930 02:40:04.188336 1 shared_informer.go:318] Caches are synced for certificate-csrsigning-kube-apiserver-client I0930 02:40:04.188743 1 shared_informer.go:318] Caches are synced for deployment I0930 02:40:04.188767 1 shared_informer.go:318] Caches are synced for disruption I0930 02:40:04.190057 1 shared_informer.go:318] Caches are synced for stateful set I0930 02:40:04.190196 1 shared_informer.go:318] Caches are synced for service account I0930 02:40:04.191492 1 shared_informer.go:318] Caches are synced for expand I0930 02:40:04.192734 1 shared_informer.go:318] Caches are synced for daemon sets I0930 02:40:04.196331 1 shared_informer.go:318] Caches are synced for PV protection I0930 02:40:04.197595 1 shared_informer.go:318] Caches are synced for TTL I0930 02:40:04.206336 1 shared_informer.go:318] Caches are synced for bootstrap_signer I0930 02:40:04.216707 1 shared_informer.go:318] Caches are synced for ClusterRoleAggregator I0930 02:40:04.216736 1 shared_informer.go:318] Caches are synced for TTL after finished I0930 02:40:04.229231 1 shared_informer.go:318] Caches are synced for crt configmap I0930 02:40:04.238405 1 shared_informer.go:318] Caches are synced for ReplicaSet I0930 02:40:04.239599 1 shared_informer.go:318] Caches are synced for job I0930 02:40:04.239687 1 shared_informer.go:318] Caches are synced for ephemeral I0930 02:40:04.239731 1 shared_informer.go:318] Caches are synced for persistent volume I0930 02:40:04.239936 1 shared_informer.go:318] Caches are synced for endpoint I0930 02:40:04.240046 1 shared_informer.go:318] Caches are synced for ReplicationController I0930 02:40:04.240822 1 shared_informer.go:318] Caches are synced for PVC protection I0930 02:40:04.242078 1 shared_informer.go:318] Caches are synced for certificate-csrapproving I0930 02:40:04.246089 1 shared_informer.go:318] Caches are synced for namespace I0930 02:40:04.307169 1 shared_informer.go:318] Caches are synced for attach detach I0930 02:40:04.341020 1 shared_informer.go:318] Caches are synced for cronjob I0930 02:40:04.350807 1 event.go:307] "Event occurred" object="kube-system/kube-vip-instance" fieldPath="" kind="Pod" apiVersion="v1" type="Warning" reason="NodeNotReady" message="Node is not ready" I0930 02:40:04.375591 1 shared_informer.go:318] Caches are synced for resource quota I0930 02:40:04.389974 1 shared_informer.go:318] Caches are synced for endpoint_slice_mirroring I0930 02:40:04.440202 1 shared_informer.go:318] Caches are synced for endpoint_slice I0930 02:40:04.444735 1 shared_informer.go:318] Caches are synced for resource quota I0930 02:40:04.649152 1 event.go:307] "Event occurred" object="kube-system/coredns" fieldPath="" kind="Deployment" apiVersion="apps/v1" type="Normal" reason="ScalingReplicaSet" message="Scaled up replica set coredns-7c96b6546b to 2" I0930 02:40:04.767887 1 shared_informer.go:318] Caches are synced for garbage collector I0930 02:40:04.787370 1 shared_informer.go:318] Caches are synced for garbage collector I0930 02:40:04.787423 1 garbagecollector.go:166] "All resource monitors have synced. Proceeding to collect garbage" I0930 02:40:04.856809 1 event.go:307] "Event occurred" object="kube-system/kube-proxy" fieldPath="" kind="DaemonSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: kube-proxy-6hjvb" I0930 02:40:05.351734 1 event.go:307] "Event occurred" object="kube-system/coredns-7c96b6546b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-7c96b6546b-bbcf9" I0930 02:40:05.366393 1 event.go:307] "Event occurred" object="kube-system/coredns-7c96b6546b" fieldPath="" kind="ReplicaSet" apiVersion="apps/v1" type="Normal" reason="SuccessfulCreate" message="Created pod: coredns-7c96b6546b-5zzhl" I0930 02:40:05.384964 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="735.9953ms" I0930 02:40:05.395356 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="10.329054ms" I0930 02:40:05.395445 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="50.861µs" I0930 02:40:05.399605 1 replica_set.go:676] "Finished syncing" kind="ReplicaSet" key="kube-system/coredns-7c96b6546b" duration="50.851µs"