2026-03-17 19:46:33.987389 | Job console starting 2026-03-17 19:46:34.084430 | Updating git repos 2026-03-17 19:46:34.120695 | Cloning repos into workspace 2026-03-17 19:46:35.158318 | Restoring repo states 2026-03-17 19:46:35.174551 | Merging changes 2026-03-17 19:46:36.227112 | Checking out repos 2026-03-17 19:46:36.485205 | Preparing playbooks 2026-03-17 19:46:41.262990 | Running Ansible setup 2026-03-17 19:46:45.623250 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 19:46:46.394875 | 2026-03-17 19:46:46.539638 | PLAY [localhost] 2026-03-17 19:46:46.555719 | 2026-03-17 19:46:46.555855 | TASK [Gathering Facts] 2026-03-17 19:46:47.468105 | localhost | ok 2026-03-17 19:46:47.478352 | 2026-03-17 19:46:47.478579 | TASK [Setup log path fact] 2026-03-17 19:46:47.498954 | localhost | ok 2026-03-17 19:46:47.526045 | 2026-03-17 19:46:47.526235 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 19:46:47.558727 | localhost | ok 2026-03-17 19:46:47.566141 | 2026-03-17 19:46:47.566303 | TASK [emit-job-header : Print job information] 2026-03-17 19:46:47.598436 | # Job Information 2026-03-17 19:46:47.668672 | Ansible Version: 2.16.16 2026-03-17 19:46:47.668843 | Job: ansible-collection-kubernetes-molecule-helm 2026-03-17 19:46:47.668909 | Pipeline: check 2026-03-17 19:46:47.669004 | Executor: 0a8996d2b663 2026-03-17 19:46:47.669062 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-17 19:46:47.669116 | Event ID: b11771e0-2239-11f1-9cf9-cf3d0ed948e5 2026-03-17 19:46:47.675237 | 2026-03-17 19:46:47.675349 | LOOP [emit-job-header : Print node information] 2026-03-17 19:46:47.814226 | localhost | ok: 2026-03-17 19:46:47.814367 | localhost | # Node Information 2026-03-17 19:46:47.814396 | localhost | Inventory Hostname: controller 2026-03-17 19:46:47.814418 | localhost | Hostname: np0000159568 2026-03-17 19:46:47.814439 | localhost | Username: zuul 2026-03-17 19:46:47.814461 | localhost | Distro: Debian 13.3 2026-03-17 19:46:47.814480 | localhost | Provider: yul1 2026-03-17 19:46:47.814499 | localhost | Region: ca-ymq-1 2026-03-17 19:46:47.814517 | localhost | Label: debian-trixie 2026-03-17 19:46:47.814535 | localhost | Product Name: OpenStack Nova 2026-03-17 19:46:47.814552 | localhost | Interface IP: 199.19.213.24 2026-03-17 19:46:47.827448 | 2026-03-17 19:46:47.827561 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-17 19:46:48.240112 | localhost -> localhost | changed 2026-03-17 19:46:48.244895 | 2026-03-17 19:46:48.244982 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-17 19:46:49.334254 | localhost -> localhost | changed 2026-03-17 19:46:49.340076 | 2026-03-17 19:46:49.341654 | PLAY [all] 2026-03-17 19:46:49.349606 | 2026-03-17 19:46:49.349698 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-17 19:46:49.598729 | controller -> localhost | ok 2026-03-17 19:46:49.605011 | 2026-03-17 19:46:49.605093 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-17 19:46:49.639320 | controller | ok 2026-03-17 19:46:49.656186 | controller | included: /var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-17 19:46:49.660726 | 2026-03-17 19:46:49.660787 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-17 19:46:50.874729 | controller -> localhost | Generating public/private rsa key pair. 2026-03-17 19:46:50.874882 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/work/cfdaaa7fa9724b168696dbbe38483495_id_rsa 2026-03-17 19:46:50.874912 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/work/cfdaaa7fa9724b168696dbbe38483495_id_rsa.pub 2026-03-17 19:46:50.874935 | controller -> localhost | The key fingerprint is: 2026-03-17 19:46:50.874957 | controller -> localhost | SHA256:2ddrkevjzPWSgwvv1EiteU4f0X1WyWtH/Vb4usceU+4 zuul-build-sshkey 2026-03-17 19:46:50.874987 | controller -> localhost | The key's randomart image is: 2026-03-17 19:46:50.875008 | controller -> localhost | +---[RSA 3072]----+ 2026-03-17 19:46:50.875035 | controller -> localhost | | | 2026-03-17 19:46:50.875057 | controller -> localhost | | ..o| 2026-03-17 19:46:50.875077 | controller -> localhost | | .o=| 2026-03-17 19:46:50.875097 | controller -> localhost | | o o +B| 2026-03-17 19:46:50.875117 | controller -> localhost | | S . o =+@| 2026-03-17 19:46:50.875137 | controller -> localhost | | o =.O=| 2026-03-17 19:46:50.875157 | controller -> localhost | | . =.O*o| 2026-03-17 19:46:50.875177 | controller -> localhost | | +.O==B| 2026-03-17 19:46:50.875199 | controller -> localhost | | .+oB*E| 2026-03-17 19:46:50.875220 | controller -> localhost | +----[SHA256]-----+ 2026-03-17 19:46:50.875265 | controller -> localhost | ok: Runtime: 0:00:00.732289 2026-03-17 19:46:50.879365 | 2026-03-17 19:46:50.879432 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-17 19:46:50.961252 | controller | ok 2026-03-17 19:46:50.969552 | controller | included: /var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-17 19:46:50.975723 | 2026-03-17 19:46:50.975782 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-17 19:46:50.999236 | controller | skipping: Conditional result was False 2026-03-17 19:46:51.006549 | 2026-03-17 19:46:51.006654 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-17 19:46:51.524244 | controller | changed 2026-03-17 19:46:51.532279 | 2026-03-17 19:46:51.538257 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-17 19:46:51.737144 | controller | ok 2026-03-17 19:46:51.741287 | 2026-03-17 19:46:51.741364 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-17 19:46:52.244030 | controller | changed 2026-03-17 19:46:52.248093 | 2026-03-17 19:46:52.248158 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-17 19:46:52.735634 | controller | changed 2026-03-17 19:46:52.740446 | 2026-03-17 19:46:52.740519 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-17 19:46:52.767110 | controller | skipping: Conditional result was False 2026-03-17 19:46:52.775986 | 2026-03-17 19:46:52.776101 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-17 19:46:53.189242 | controller -> localhost | changed 2026-03-17 19:46:53.198204 | 2026-03-17 19:46:53.198306 | TASK [add-build-sshkey : Add back temp key] 2026-03-17 19:46:53.554827 | controller -> localhost | Identity added: /var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/work/cfdaaa7fa9724b168696dbbe38483495_id_rsa (zuul-build-sshkey) 2026-03-17 19:46:53.555032 | controller -> localhost | ok: Runtime: 0:00:00.014404 2026-03-17 19:46:53.560372 | 2026-03-17 19:46:53.560458 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-17 19:46:53.887296 | controller | ok 2026-03-17 19:46:53.890965 | 2026-03-17 19:46:53.891036 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-17 19:46:53.914833 | controller | skipping: Conditional result was False 2026-03-17 19:46:54.014004 | 2026-03-17 19:46:54.014153 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-17 19:46:54.365027 | controller | ok 2026-03-17 19:46:54.369891 | 2026-03-17 19:46:54.369965 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-17 19:46:55.443761 | controller | Output suppressed because no_log was given 2026-03-17 19:46:55.454338 | 2026-03-17 19:46:55.454415 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-17 19:46:55.659992 | controller | ok: "logs" 2026-03-17 19:46:55.712650 | controller | ok: All items complete 2026-03-17 19:46:55.712769 | 2026-03-17 19:46:55.837797 | controller | ok: "artifacts" 2026-03-17 19:46:56.000339 | controller | ok: "docs" 2026-03-17 19:46:56.047372 | 2026-03-17 19:46:56.047492 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-17 19:46:56.244980 | controller | changed: "logs" 2026-03-17 19:46:56.417970 | controller | changed: "artifacts" 2026-03-17 19:46:56.590550 | controller | changed: "docs" 2026-03-17 19:46:56.606205 | 2026-03-17 19:46:56.606321 | PLAY RECAP 2026-03-17 19:46:56.606378 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-17 19:46:56.606414 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 19:46:56.606439 | 2026-03-17 19:46:56.751677 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 19:46:56.753417 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 19:46:57.347432 | 2026-03-17 19:46:57.347554 | PLAY [all] 2026-03-17 19:46:57.357608 | 2026-03-17 19:46:57.357681 | TASK [setup-uv : Extract archive] 2026-03-17 19:47:01.682647 | controller | changed 2026-03-17 19:47:01.690316 | 2026-03-17 19:47:01.690434 | TASK [setup-uv : Print version] 2026-03-17 19:47:02.043586 | controller | uv 0.8.13 2026-03-17 19:47:02.222858 | controller | ok: Runtime: 0:00:00.014109 2026-03-17 19:47:02.230902 | 2026-03-17 19:47:02.230987 | PLAY RECAP 2026-03-17 19:47:02.231030 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 19:47:02.231054 | 2026-03-17 19:47:02.343776 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 19:47:02.345111 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 19:47:02.911068 | 2026-03-17 19:47:02.911181 | PLAY [all] 2026-03-17 19:47:02.921118 | 2026-03-17 19:47:02.921191 | TASK [Copy inventory file for Zuul] 2026-03-17 19:47:03.839232 | controller | changed 2026-03-17 19:47:03.843580 | 2026-03-17 19:47:03.843655 | TASK [Switch "ansible_host" to private IP] 2026-03-17 19:47:04.136169 | controller | changed: 1 replacements made 2026-03-17 19:47:04.145461 | 2026-03-17 19:47:04.145566 | TASK [Run Molecule scenario] 2026-03-17 19:47:04.565287 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-17 19:47:04.565395 | controller | Creating virtual environment at: .venv 2026-03-17 19:47:04.841795 | controller | Downloading cryptography (4.3MiB) 2026-03-17 19:47:04.841980 | controller | Downloading ansible-core (2.3MiB) 2026-03-17 19:47:04.843467 | controller | Downloading pygments (1.2MiB) 2026-03-17 19:47:05.020407 | controller | Downloading cryptography 2026-03-17 19:47:05.079323 | controller | Downloading pygments 2026-03-17 19:47:05.242845 | controller | Downloading ansible-core 2026-03-17 19:47:05.293721 | controller | Installed 36 packages in 50ms 2026-03-17 19:47:05.945590 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-17 19:47:05.945639 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-17 19:47:06.778044 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-17 19:47:06.778092 | controller | INFO default scenario not found, disabling shared state. 2026-03-17 19:47:06.778150 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-17 19:47:06.778249 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-03-17 19:47:23.317271 | controller | INFO [helm > dependency] Executing 2026-03-17 19:47:23.317367 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-03-17 19:47:23.317377 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-03-17 19:47:23.317411 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-17 19:47:23.321189 | controller | INFO [helm > cleanup] Executing 2026-03-17 19:47:23.321543 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:23.326882 | controller | INFO [helm > destroy] Executing 2026-03-17 19:47:23.326952 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-17 19:47:23.327041 | controller | INFO [helm > destroy] Executed: Successful 2026-03-17 19:47:23.332338 | controller | INFO [helm > syntax] Executing 2026-03-17 19:47:24.205442 | controller | 2026-03-17 19:47:24.205674 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-03-17 19:47:24.262192 | controller | INFO [helm > syntax] Executed: Successful 2026-03-17 19:47:24.275056 | controller | INFO [helm > create] Executing 2026-03-17 19:47:24.276554 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:24.281838 | controller | INFO [helm > prepare] Executing 2026-03-17 19:47:25.096856 | controller | 2026-03-17 19:47:25.097050 | controller | PLAY [Prepare] ***************************************************************** 2026-03-17 19:47:25.097347 | controller | 2026-03-17 19:47:25.097518 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:25.097868 | controller | Tuesday 17 March 2026 19:47:25 +0000 (0:00:00.028) 0:00:00.028 ********* 2026-03-17 19:47:26.363800 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 19:47:26.370215 | controller | ok: [controller] 2026-03-17 19:47:26.370354 | controller | 2026-03-17 19:47:26.370599 | controller | TASK [Install Debian packages] ************************************************* 2026-03-17 19:47:26.370760 | controller | Tuesday 17 March 2026 19:47:26 +0000 (0:00:01.274) 0:00:01.302 ********* 2026-03-17 19:47:31.131793 | controller | ok: [controller] 2026-03-17 19:47:31.131929 | controller | 2026-03-17 19:47:31.132142 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:31.132377 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 19:47:31.132554 | controller | 2026-03-17 19:47:31.132775 | controller | 2026-03-17 19:47:31.133886 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:31.133959 | controller | Tuesday 17 March 2026 19:47:31 +0000 (0:00:04.761) 0:00:06.063 ********* 2026-03-17 19:47:31.133967 | controller | =============================================================================== 2026-03-17 19:47:31.133975 | controller | Install Debian packages ------------------------------------------------- 4.76s 2026-03-17 19:47:31.133984 | controller | Gathering Facts --------------------------------------------------------- 1.27s 2026-03-17 19:47:31.214491 | controller | INFO [helm > prepare] Executed: Successful 2026-03-17 19:47:31.225510 | controller | INFO [helm > converge] Executing 2026-03-17 19:47:32.025769 | controller | 2026-03-17 19:47:32.026221 | controller | PLAY [Converge] **************************************************************** 2026-03-17 19:47:32.026456 | controller | 2026-03-17 19:47:32.026684 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:32.026918 | controller | Tuesday 17 March 2026 19:47:32 +0000 (0:00:00.012) 0:00:00.012 ********* 2026-03-17 19:47:33.067913 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 19:47:33.073563 | controller | ok: [controller] 2026-03-17 19:47:33.073739 | controller | 2026-03-17 19:47:33.073938 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-17 19:47:33.074138 | controller | Tuesday 17 March 2026 19:47:33 +0000 (0:00:01.048) 0:00:01.060 ********* 2026-03-17 19:47:33.540946 | controller | ok: [controller] 2026-03-17 19:47:33.541028 | controller | 2026-03-17 19:47:33.541232 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-17 19:47:33.541420 | controller | Tuesday 17 March 2026 19:47:33 +0000 (0:00:00.466) 0:00:01.527 ********* 2026-03-17 19:47:34.775683 | controller | ok: [controller] 2026-03-17 19:47:34.775729 | controller | 2026-03-17 19:47:34.775825 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-17 19:47:34.775912 | controller | Tuesday 17 March 2026 19:47:34 +0000 (0:00:01.235) 0:00:02.762 ********* 2026-03-17 19:47:35.183482 | controller | changed: [controller] 2026-03-17 19:47:35.183606 | controller | 2026-03-17 19:47:35.183828 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-17 19:47:35.184049 | controller | Tuesday 17 March 2026 19:47:35 +0000 (0:00:00.404) 0:00:03.167 ********* 2026-03-17 19:47:35.229867 | controller | ok: [controller] => { 2026-03-17 19:47:35.230061 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-17 19:47:35.230241 | controller | } 2026-03-17 19:47:35.230481 | controller | 2026-03-17 19:47:35.230708 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-17 19:47:35.230876 | controller | Tuesday 17 March 2026 19:47:35 +0000 (0:00:00.049) 0:00:03.216 ********* 2026-03-17 19:47:36.254916 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-03-17 19:47:36.263813 | controller | changed: [controller] 2026-03-17 19:47:36.264010 | controller | 2026-03-17 19:47:36.264207 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-17 19:47:36.264404 | controller | Tuesday 17 March 2026 19:47:36 +0000 (0:00:01.033) 0:00:04.250 ********* 2026-03-17 19:47:39.238552 | controller | changed: [controller] 2026-03-17 19:47:39.238724 | controller | 2026-03-17 19:47:39.238931 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-17 19:47:39.239126 | controller | Tuesday 17 March 2026 19:47:39 +0000 (0:00:02.974) 0:00:07.224 ********* 2026-03-17 19:47:39.716150 | controller | ok: [controller] 2026-03-17 19:47:39.716221 | controller | 2026-03-17 19:47:39.716377 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-17 19:47:39.716503 | controller | Tuesday 17 March 2026 19:47:39 +0000 (0:00:00.473) 0:00:07.698 ********* 2026-03-17 19:47:40.087998 | controller | changed: [controller] 2026-03-17 19:47:40.088165 | controller | 2026-03-17 19:47:40.088376 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-17 19:47:40.088574 | controller | Tuesday 17 March 2026 19:47:40 +0000 (0:00:00.375) 0:00:08.074 ********* 2026-03-17 19:47:40.514503 | controller | ok: [controller] 2026-03-17 19:47:40.514564 | controller | 2026-03-17 19:47:40.514574 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-17 19:47:40.514582 | controller | Tuesday 17 March 2026 19:47:40 +0000 (0:00:00.414) 0:00:08.488 ********* 2026-03-17 19:47:40.791711 | controller | changed: [controller] 2026-03-17 19:47:40.791767 | controller | 2026-03-17 19:47:40.791978 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-17 19:47:40.792051 | controller | Tuesday 17 March 2026 19:47:40 +0000 (0:00:00.286) 0:00:08.775 ********* 2026-03-17 19:47:41.104154 | controller | ok: [controller] 2026-03-17 19:47:41.104208 | controller | 2026-03-17 19:47:41.104291 | controller | TASK [Install plugin] ********************************************************** 2026-03-17 19:47:41.104375 | controller | Tuesday 17 March 2026 19:47:41 +0000 (0:00:00.313) 0:00:09.089 ********* 2026-03-17 19:47:41.158212 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-17 19:47:41.158242 | controller | 2026-03-17 19:47:41.158352 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-17 19:47:41.158437 | controller | Tuesday 17 March 2026 19:47:41 +0000 (0:00:00.056) 0:00:09.145 ********* 2026-03-17 19:47:41.206084 | controller | ok: [controller] => { 2026-03-17 19:47:41.206121 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-17 19:47:41.206230 | controller | } 2026-03-17 19:47:41.206307 | controller | 2026-03-17 19:47:41.206397 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-17 19:47:41.206478 | controller | Tuesday 17 March 2026 19:47:41 +0000 (0:00:00.047) 0:00:09.192 ********* 2026-03-17 19:47:41.979912 | controller | changed: [controller] 2026-03-17 19:47:41.980005 | controller | 2026-03-17 19:47:41.980140 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-17 19:47:41.980263 | controller | Tuesday 17 March 2026 19:47:41 +0000 (0:00:00.770) 0:00:09.963 ********* 2026-03-17 19:47:43.991632 | controller | changed: [controller] 2026-03-17 19:47:43.991666 | controller | 2026-03-17 19:47:43.991676 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:43.991685 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 19:47:43.991692 | controller | 2026-03-17 19:47:43.991700 | controller | 2026-03-17 19:47:43.991707 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:43.991714 | controller | Tuesday 17 March 2026 19:47:43 +0000 (0:00:02.011) 0:00:11.975 ********* 2026-03-17 19:47:43.991721 | controller | =============================================================================== 2026-03-17 19:47:43.991728 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.97s 2026-03-17 19:47:43.991735 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.01s 2026-03-17 19:47:43.991742 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.24s 2026-03-17 19:47:43.991749 | controller | Gathering Facts --------------------------------------------------------- 1.05s 2026-03-17 19:47:43.991755 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.03s 2026-03-17 19:47:43.991762 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.77s 2026-03-17 19:47:43.991769 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.47s 2026-03-17 19:47:43.991776 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s 2026-03-17 19:47:43.991783 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.41s 2026-03-17 19:47:43.992025 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s 2026-03-17 19:47:43.992286 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.38s 2026-03-17 19:47:43.992542 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-03-17 19:47:43.992791 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-03-17 19:47:43.993031 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-03-17 19:47:43.993271 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-17 19:47:43.993509 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-17 19:47:44.079886 | controller | INFO [helm > converge] Executed: Successful 2026-03-17 19:47:44.090151 | controller | INFO [helm > idempotence] Executing 2026-03-17 19:47:44.927043 | controller | 2026-03-17 19:47:44.927410 | controller | PLAY [Converge] **************************************************************** 2026-03-17 19:47:44.927761 | controller | 2026-03-17 19:47:44.928034 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:44.928319 | controller | Tuesday 17 March 2026 19:47:44 +0000 (0:00:00.012) 0:00:00.012 ********* 2026-03-17 19:47:45.938407 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 19:47:45.944051 | controller | ok: [controller] 2026-03-17 19:47:45.944308 | controller | 2026-03-17 19:47:45.944569 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-17 19:47:45.944819 | controller | Tuesday 17 March 2026 19:47:45 +0000 (0:00:01.016) 0:00:01.028 ********* 2026-03-17 19:47:46.345338 | controller | ok: [controller] 2026-03-17 19:47:46.345634 | controller | 2026-03-17 19:47:46.345961 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-17 19:47:46.346219 | controller | Tuesday 17 March 2026 19:47:46 +0000 (0:00:00.400) 0:00:01.429 ********* 2026-03-17 19:47:47.522671 | controller | ok: [controller] 2026-03-17 19:47:47.522982 | controller | 2026-03-17 19:47:47.523259 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-17 19:47:47.523792 | controller | Tuesday 17 March 2026 19:47:47 +0000 (0:00:01.177) 0:00:02.607 ********* 2026-03-17 19:47:47.964404 | controller | ok: [controller] 2026-03-17 19:47:47.964444 | controller | 2026-03-17 19:47:47.964449 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-17 19:47:47.964453 | controller | Tuesday 17 March 2026 19:47:47 +0000 (0:00:00.439) 0:00:03.047 ********* 2026-03-17 19:47:48.020400 | controller | ok: [controller] => { 2026-03-17 19:47:48.020415 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-17 19:47:48.020420 | controller | } 2026-03-17 19:47:48.020426 | controller | 2026-03-17 19:47:48.020431 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-17 19:47:48.020436 | controller | Tuesday 17 March 2026 19:47:48 +0000 (0:00:00.056) 0:00:03.103 ********* 2026-03-17 19:47:48.560342 | controller | ok: [controller] 2026-03-17 19:47:48.560658 | controller | 2026-03-17 19:47:48.560937 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-17 19:47:48.561201 | controller | Tuesday 17 March 2026 19:47:48 +0000 (0:00:00.541) 0:00:03.644 ********* 2026-03-17 19:47:50.208568 | controller | ok: [controller] 2026-03-17 19:47:50.208601 | controller | 2026-03-17 19:47:50.208608 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-17 19:47:50.208614 | controller | Tuesday 17 March 2026 19:47:50 +0000 (0:00:01.646) 0:00:05.291 ********* 2026-03-17 19:47:50.728260 | controller | ok: [controller] 2026-03-17 19:47:50.728343 | controller | 2026-03-17 19:47:50.728411 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-17 19:47:50.728531 | controller | Tuesday 17 March 2026 19:47:50 +0000 (0:00:00.519) 0:00:05.810 ********* 2026-03-17 19:47:51.126655 | controller | ok: [controller] 2026-03-17 19:47:51.126690 | controller | 2026-03-17 19:47:51.126841 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-17 19:47:51.126962 | controller | Tuesday 17 March 2026 19:47:51 +0000 (0:00:00.400) 0:00:06.211 ********* 2026-03-17 19:47:51.571067 | controller | ok: [controller] 2026-03-17 19:47:51.571143 | controller | 2026-03-17 19:47:51.571153 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-17 19:47:51.571235 | controller | Tuesday 17 March 2026 19:47:51 +0000 (0:00:00.444) 0:00:06.655 ********* 2026-03-17 19:47:51.868206 | controller | ok: [controller] 2026-03-17 19:47:51.868280 | controller | 2026-03-17 19:47:51.868452 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-17 19:47:51.868582 | controller | Tuesday 17 March 2026 19:47:51 +0000 (0:00:00.294) 0:00:06.950 ********* 2026-03-17 19:47:52.151663 | controller | ok: [controller] 2026-03-17 19:47:52.151720 | controller | 2026-03-17 19:47:52.151742 | controller | TASK [Install plugin] ********************************************************** 2026-03-17 19:47:52.151921 | controller | Tuesday 17 March 2026 19:47:52 +0000 (0:00:00.285) 0:00:07.235 ********* 2026-03-17 19:47:52.210388 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-17 19:47:52.210465 | controller | 2026-03-17 19:47:52.210674 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-17 19:47:52.210704 | controller | Tuesday 17 March 2026 19:47:52 +0000 (0:00:00.057) 0:00:07.293 ********* 2026-03-17 19:47:52.255905 | controller | ok: [controller] => { 2026-03-17 19:47:52.256363 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-17 19:47:52.256388 | controller | } 2026-03-17 19:47:52.256393 | controller | 2026-03-17 19:47:52.256397 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-17 19:47:52.256401 | controller | Tuesday 17 March 2026 19:47:52 +0000 (0:00:00.045) 0:00:07.339 ********* 2026-03-17 19:47:52.660593 | controller | ok: [controller] 2026-03-17 19:47:52.660782 | controller | 2026-03-17 19:47:52.660948 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-17 19:47:52.661106 | controller | Tuesday 17 March 2026 19:47:52 +0000 (0:00:00.405) 0:00:07.744 ********* 2026-03-17 19:47:54.317994 | controller | ok: [controller] 2026-03-17 19:47:54.318018 | controller | 2026-03-17 19:47:54.318023 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:54.318026 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 19:47:54.318030 | controller | 2026-03-17 19:47:54.318033 | controller | 2026-03-17 19:47:54.318037 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:54.318040 | controller | Tuesday 17 March 2026 19:47:54 +0000 (0:00:01.654) 0:00:09.399 ********* 2026-03-17 19:47:54.318043 | controller | =============================================================================== 2026-03-17 19:47:54.318046 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.65s 2026-03-17 19:47:54.318050 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.65s 2026-03-17 19:47:54.318053 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.18s 2026-03-17 19:47:54.318056 | controller | Gathering Facts --------------------------------------------------------- 1.02s 2026-03-17 19:47:54.318059 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s 2026-03-17 19:47:54.318062 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s 2026-03-17 19:47:54.318065 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s 2026-03-17 19:47:54.318069 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s 2026-03-17 19:47:54.318073 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.41s 2026-03-17 19:47:54.318076 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.40s 2026-03-17 19:47:54.318084 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s 2026-03-17 19:47:54.318088 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-03-17 19:47:54.318091 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-03-17 19:47:54.318094 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-03-17 19:47:54.318097 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-03-17 19:47:54.318101 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-17 19:47:54.414209 | controller | INFO [helm > idempotence] Executed: Successful 2026-03-17 19:47:54.420660 | controller | INFO [helm > side_effect] Executing 2026-03-17 19:47:54.421039 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:54.426297 | controller | INFO [helm > verify] Executing 2026-03-17 19:47:55.256673 | controller | 2026-03-17 19:47:55.256959 | controller | PLAY [Verify] ****************************************************************** 2026-03-17 19:47:55.257195 | controller | 2026-03-17 19:47:55.257471 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:55.257731 | controller | Tuesday 17 March 2026 19:47:55 +0000 (0:00:00.009) 0:00:00.009 ********* 2026-03-17 19:47:56.346121 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 19:47:56.352384 | controller | ok: [controller] 2026-03-17 19:47:56.352689 | controller | 2026-03-17 19:47:56.352949 | controller | TASK [Get the current Helm version] ******************************************** 2026-03-17 19:47:56.353197 | controller | Tuesday 17 March 2026 19:47:56 +0000 (0:00:01.095) 0:00:01.104 ********* 2026-03-17 19:47:56.806630 | controller | changed: [controller] 2026-03-17 19:47:56.806901 | controller | 2026-03-17 19:47:56.807158 | controller | TASK [Assert Helm version] ***************************************************** 2026-03-17 19:47:56.807409 | controller | Tuesday 17 March 2026 19:47:56 +0000 (0:00:00.453) 0:00:01.558 ********* 2026-03-17 19:47:56.841475 | controller | ok: [controller] => { 2026-03-17 19:47:56.841731 | controller | "changed": false, 2026-03-17 19:47:56.841975 | controller | "msg": "All assertions passed" 2026-03-17 19:47:56.842209 | controller | } 2026-03-17 19:47:56.842432 | controller | 2026-03-17 19:47:56.842668 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:56.842933 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 19:47:56.843157 | controller | 2026-03-17 19:47:56.843380 | controller | 2026-03-17 19:47:56.843666 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:56.843920 | controller | Tuesday 17 March 2026 19:47:56 +0000 (0:00:00.035) 0:00:01.594 ********* 2026-03-17 19:47:56.844175 | controller | =============================================================================== 2026-03-17 19:47:56.844418 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-03-17 19:47:56.844661 | controller | Get the current Helm version -------------------------------------------- 0.45s 2026-03-17 19:47:56.844901 | controller | Assert Helm version ----------------------------------------------------- 0.04s 2026-03-17 19:47:56.960925 | controller | INFO [helm > verify] Executed: Successful 2026-03-17 19:47:56.971312 | controller | INFO [helm > cleanup] Executing 2026-03-17 19:47:56.975129 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:56.976977 | controller | INFO [helm > destroy] Executing 2026-03-17 19:47:56.977093 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-17 19:47:56.977186 | controller | INFO [helm > destroy] Executed: Successful 2026-03-17 19:47:56.977516 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-17 19:47:57.206968 | controller | ok: Runtime: 0:00:52.608130 2026-03-17 19:47:57.211856 | 2026-03-17 19:47:57.211922 | PLAY RECAP 2026-03-17 19:47:57.211987 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 19:47:57.212019 | 2026-03-17 19:47:57.376349 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 19:47:57.379231 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 19:47:57.999271 | 2026-03-17 19:47:57.999443 | PLAY [all] 2026-03-17 19:47:58.010144 | 2026-03-17 19:47:58.010221 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-17 19:47:58.057372 | controller | skipping: Conditional result was False 2026-03-17 19:47:58.064921 | 2026-03-17 19:47:58.065055 | TASK [fetch-output : Set log path for single node] 2026-03-17 19:47:58.110341 | controller | ok 2026-03-17 19:47:58.115582 | 2026-03-17 19:47:58.115676 | LOOP [fetch-output : Ensure local output dirs] 2026-03-17 19:47:58.505291 | controller -> localhost | ok: "/var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/work/logs" 2026-03-17 19:47:58.728632 | controller -> localhost | changed: "/var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/work/artifacts" 2026-03-17 19:47:58.942550 | controller -> localhost | changed: "/var/lib/zuul/builds/cfdaaa7fa9724b168696dbbe38483495/work/docs" 2026-03-17 19:47:58.958854 | 2026-03-17 19:47:58.959306 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-17 19:47:59.621568 | controller | ok 2026-03-17 19:47:59.621804 | controller | changed: All items complete 2026-03-17 19:47:59.621844 | 2026-03-17 19:48:00.132636 | controller | changed: .d..t...... ./ 2026-03-17 19:48:00.627761 | controller | changed: .d..t...... ./ 2026-03-17 19:48:00.652710 | 2026-03-17 19:48:00.652877 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-17 19:48:01.096719 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007053 2026-03-17 19:48:01.321739 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006558 2026-03-17 19:48:01.339724 | 2026-03-17 19:48:01.339843 | PLAY [all] 2026-03-17 19:48:01.345010 | 2026-03-17 19:48:01.345083 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-17 19:48:01.801179 | controller | changed 2026-03-17 19:48:01.807355 | 2026-03-17 19:48:01.826525 | PLAY RECAP 2026-03-17 19:48:01.826656 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-17 19:48:01.826710 | 2026-03-17 19:48:01.922632 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 19:48:01.925944 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-17 19:48:02.531488 | 2026-03-17 19:48:02.531933 | PLAY [localhost] 2026-03-17 19:48:02.541802 | 2026-03-17 19:48:02.541874 | TASK [Generate Zuul manifest] 2026-03-17 19:48:02.563013 | localhost | ok 2026-03-17 19:48:02.580398 | 2026-03-17 19:48:02.580476 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-17 19:48:02.882028 | localhost | changed 2026-03-17 19:48:02.891839 | 2026-03-17 19:48:02.891913 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-17 19:48:02.939469 | localhost | ok 2026-03-17 19:48:02.945824 | 2026-03-17 19:48:02.945893 | TASK [Upload logs] 2026-03-17 19:48:02.974286 | localhost | ok 2026-03-17 19:48:03.028978 | 2026-03-17 19:48:03.029070 | TASK [Set zuul-log-path fact] 2026-03-17 19:48:03.046441 | localhost | ok 2026-03-17 19:48:03.056832 | 2026-03-17 19:48:03.056897 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 19:48:03.084984 | localhost | ok 2026-03-17 19:48:03.091262 | 2026-03-17 19:48:03.091328 | TASK [upload-logs : Create log directories] 2026-03-17 19:48:03.497033 | localhost | changed 2026-03-17 19:48:03.502904 | 2026-03-17 19:48:03.502975 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-17 19:48:03.905141 | localhost -> localhost | ok: Runtime: 0:00:00.004617 2026-03-17 19:48:03.910066 | 2026-03-17 19:48:03.910130 | TASK [upload-logs : Upload logs to log server] 2026-03-17 19:48:04.374607 | localhost | Output suppressed because no_log was given 2026-03-17 19:48:04.380185 | 2026-03-17 19:48:04.380311 | LOOP [upload-logs : Compress console log and json output] 2026-03-17 19:48:04.446686 | localhost | skipping: Conditional result was False 2026-03-17 19:48:04.477373 | localhost | skipping: Conditional result was False 2026-03-17 19:48:04.490548 | 2026-03-17 19:48:04.491014 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-17 19:48:04.542419 | localhost | skipping: Conditional result was False 2026-03-17 19:48:04.542996 | 2026-03-17 19:48:04.546159 | localhost | skipping: Conditional result was False 2026-03-17 19:48:04.559481 | 2026-03-17 19:48:04.559643 | LOOP [upload-logs : Upload console log and json output]