2026-02-28 03:50:20.157300 | Job console starting 2026-02-28 03:50:20.165180 | Updating git repos 2026-02-28 03:50:20.196644 | Cloning repos into workspace 2026-02-28 03:50:20.257923 | Restoring repo states 2026-02-28 03:50:20.279704 | Merging changes 2026-02-28 03:50:20.833445 | Checking out repos 2026-02-28 03:50:21.516170 | Preparing playbooks 2026-02-28 03:50:34.510388 | Running Ansible setup 2026-02-28 03:50:39.726484 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 03:50:40.344894 | 2026-02-28 03:50:40.345048 | PLAY [localhost] 2026-02-28 03:50:40.352857 | 2026-02-28 03:50:40.352927 | TASK [Gathering Facts] 2026-02-28 03:50:41.954838 | localhost | ok 2026-02-28 03:50:41.964159 | 2026-02-28 03:50:41.964236 | TASK [Setup log path fact] 2026-02-28 03:50:41.983315 | localhost | ok 2026-02-28 03:50:41.998990 | 2026-02-28 03:50:41.999059 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 03:50:42.030636 | localhost | ok 2026-02-28 03:50:42.039046 | 2026-02-28 03:50:42.039117 | TASK [emit-job-header : Print job information] 2026-02-28 03:50:42.078625 | # Job Information 2026-02-28 03:50:42.078848 | Ansible Version: 2.16.16 2026-02-28 03:50:42.078880 | Job: ansible-collection-kubernetes-molecule-helm 2026-02-28 03:50:42.078901 | Pipeline: check 2026-02-28 03:50:42.078921 | Executor: 0a8996d2b663 2026-02-28 03:50:42.078940 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/229 2026-02-28 03:50:42.078961 | Event ID: 6e387f70-1458-11f1-9d78-196c161cc82e 2026-02-28 03:50:42.081818 | 2026-02-28 03:50:42.081886 | LOOP [emit-job-header : Print node information] 2026-02-28 03:50:42.284041 | localhost | ok: 2026-02-28 03:50:42.284287 | localhost | # Node Information 2026-02-28 03:50:42.284326 | localhost | Inventory Hostname: controller 2026-02-28 03:50:42.284352 | localhost | Hostname: np0000157455 2026-02-28 03:50:42.284373 | localhost | Username: zuul 2026-02-28 03:50:42.284396 | localhost | Distro: Debian 13.3 2026-02-28 03:50:42.284416 | localhost | Provider: yul1 2026-02-28 03:50:42.284436 | localhost | Region: ca-ymq-1 2026-02-28 03:50:42.284455 | localhost | Label: debian-trixie 2026-02-28 03:50:42.284474 | localhost | Product Name: OpenStack Nova 2026-02-28 03:50:42.284494 | localhost | Interface IP: 199.204.45.113 2026-02-28 03:50:42.305408 | 2026-02-28 03:50:42.305612 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-28 03:50:42.707393 | localhost -> localhost | changed 2026-02-28 03:50:42.717198 | 2026-02-28 03:50:42.717300 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-28 03:50:43.704757 | localhost -> localhost | changed 2026-02-28 03:50:43.727232 | 2026-02-28 03:50:43.727354 | PLAY [all] 2026-02-28 03:50:43.737800 | 2026-02-28 03:50:43.737900 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-28 03:50:43.999818 | controller -> localhost | ok 2026-02-28 03:50:44.006580 | 2026-02-28 03:50:44.006666 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-28 03:50:44.044003 | controller | ok 2026-02-28 03:50:44.059478 | controller | included: /var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-28 03:50:44.065708 | 2026-02-28 03:50:44.065770 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-28 03:50:45.549909 | controller -> localhost | Generating public/private rsa key pair. 2026-02-28 03:50:45.550158 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/work/ef2ec424e47f4e799d8968c5661bcb2a_id_rsa 2026-02-28 03:50:45.550191 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/work/ef2ec424e47f4e799d8968c5661bcb2a_id_rsa.pub 2026-02-28 03:50:45.550241 | controller -> localhost | The key fingerprint is: 2026-02-28 03:50:45.550266 | controller -> localhost | SHA256:ttkhm0qHdWGvYe0aWrqyzRgewoWvTE0HeOosICCoszg zuul-build-sshkey 2026-02-28 03:50:45.550321 | controller -> localhost | The key's randomart image is: 2026-02-28 03:50:45.550345 | controller -> localhost | +---[RSA 3072]----+ 2026-02-28 03:50:45.550396 | controller -> localhost | | | 2026-02-28 03:50:45.550421 | controller -> localhost | |. . | 2026-02-28 03:50:45.550442 | controller -> localhost | |+ . o o | 2026-02-28 03:50:45.550462 | controller -> localhost | |+ + . . + | 2026-02-28 03:50:45.550481 | controller -> localhost | |= o o S = o | 2026-02-28 03:50:45.550500 | controller -> localhost | |o+ + = = X = | 2026-02-28 03:50:45.550519 | controller -> localhost | |E . * B * = . | 2026-02-28 03:50:45.550537 | controller -> localhost | | . + =.O + o | 2026-02-28 03:50:45.550559 | controller -> localhost | | o =o*.. | 2026-02-28 03:50:45.550579 | controller -> localhost | +----[SHA256]-----+ 2026-02-28 03:50:45.550628 | controller -> localhost | ok: Runtime: 0:00:01.030321 2026-02-28 03:50:45.555752 | 2026-02-28 03:50:45.555843 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-28 03:50:45.589490 | controller | ok 2026-02-28 03:50:45.606241 | controller | included: /var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-28 03:50:45.615199 | 2026-02-28 03:50:45.615272 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-28 03:50:45.641259 | controller | skipping: Conditional result was False 2026-02-28 03:50:45.649004 | 2026-02-28 03:50:45.649096 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-28 03:50:46.222935 | controller | changed 2026-02-28 03:50:46.227164 | 2026-02-28 03:50:46.227233 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-28 03:50:46.466542 | controller | ok 2026-02-28 03:50:46.472806 | 2026-02-28 03:50:46.472897 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-28 03:50:47.007790 | controller | changed 2026-02-28 03:50:47.014592 | 2026-02-28 03:50:47.014684 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-28 03:50:47.556762 | controller | changed 2026-02-28 03:50:47.563231 | 2026-02-28 03:50:47.563533 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-28 03:50:47.588363 | controller | skipping: Conditional result was False 2026-02-28 03:50:47.599204 | 2026-02-28 03:50:47.599337 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-28 03:50:47.975538 | controller -> localhost | changed 2026-02-28 03:50:48.004709 | 2026-02-28 03:50:48.004829 | TASK [add-build-sshkey : Add back temp key] 2026-02-28 03:50:48.307485 | controller -> localhost | Identity added: /var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/work/ef2ec424e47f4e799d8968c5661bcb2a_id_rsa (zuul-build-sshkey) 2026-02-28 03:50:48.307672 | controller -> localhost | ok: Runtime: 0:00:00.011274 2026-02-28 03:50:48.311499 | 2026-02-28 03:50:48.323125 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-28 03:50:48.679689 | controller | ok 2026-02-28 03:50:48.683708 | 2026-02-28 03:50:48.683774 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-28 03:50:48.708156 | controller | skipping: Conditional result was False 2026-02-28 03:50:48.715972 | 2026-02-28 03:50:48.716045 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-28 03:50:49.139120 | controller | ok 2026-02-28 03:50:49.146354 | 2026-02-28 03:50:49.146423 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-28 03:50:50.154005 | controller | Output suppressed because no_log was given 2026-02-28 03:50:50.160627 | 2026-02-28 03:50:50.160687 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-28 03:50:50.394552 | controller | ok: "logs" 2026-02-28 03:50:50.394771 | controller | ok: All items complete 2026-02-28 03:50:50.394798 | 2026-02-28 03:50:50.588850 | controller | ok: "artifacts" 2026-02-28 03:50:50.776895 | controller | ok: "docs" 2026-02-28 03:50:50.798241 | 2026-02-28 03:50:50.798466 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-28 03:50:51.013871 | controller | changed: "logs" 2026-02-28 03:50:51.200009 | controller | changed: "artifacts" 2026-02-28 03:50:51.385004 | controller | changed: "docs" 2026-02-28 03:50:51.401028 | 2026-02-28 03:50:51.401169 | PLAY RECAP 2026-02-28 03:50:51.401223 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-28 03:50:51.401252 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:50:51.401272 | 2026-02-28 03:50:51.514279 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 03:50:51.515649 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 03:50:52.138850 | 2026-02-28 03:50:52.138971 | PLAY [all] 2026-02-28 03:50:52.149181 | 2026-02-28 03:50:52.149253 | TASK [setup-uv : Extract archive] 2026-02-28 03:50:54.547519 | controller | changed 2026-02-28 03:50:54.553246 | 2026-02-28 03:50:54.553327 | TASK [setup-uv : Print version] 2026-02-28 03:50:54.969048 | controller | uv 0.8.13 2026-02-28 03:50:55.086272 | controller | ok: Runtime: 0:00:00.012607 2026-02-28 03:50:55.093718 | 2026-02-28 03:50:55.093842 | PLAY RECAP 2026-02-28 03:50:55.094017 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:50:55.094062 | 2026-02-28 03:50:55.265958 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 03:50:55.301312 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 03:50:55.949011 | 2026-02-28 03:50:55.949170 | PLAY [all] 2026-02-28 03:50:55.959746 | 2026-02-28 03:50:55.959823 | TASK [Copy inventory file for Zuul] 2026-02-28 03:50:56.872846 | controller | changed 2026-02-28 03:50:56.880008 | 2026-02-28 03:50:56.880100 | TASK [Switch "ansible_host" to private IP] 2026-02-28 03:50:57.208351 | controller | changed: 1 replacements made 2026-02-28 03:50:57.216977 | 2026-02-28 03:50:57.217046 | TASK [Run Molecule scenario] 2026-02-28 03:50:57.703889 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-28 03:50:57.704025 | controller | Creating virtual environment at: .venv 2026-02-28 03:50:57.970610 | controller | Downloading pygments (1.2MiB) 2026-02-28 03:50:57.972174 | controller | Downloading ansible-core (2.3MiB) 2026-02-28 03:50:57.973642 | controller | Downloading cryptography (4.3MiB) 2026-02-28 03:50:58.150410 | controller | Downloading cryptography 2026-02-28 03:50:58.206307 | controller | Downloading pygments 2026-02-28 03:50:58.376527 | controller | Downloading ansible-core 2026-02-28 03:50:58.426547 | controller | Installed 36 packages in 49ms 2026-02-28 03:50:59.143588 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-28 03:50:59.143746 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-28 03:51:00.083760 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-28 03:51:00.083878 | controller | INFO default scenario not found, disabling shared state. 2026-02-28 03:51:00.083902 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-28 03:51:00.083914 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-02-28 03:51:17.849393 | controller | INFO [helm > dependency] Executing 2026-02-28 03:51:17.849458 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-02-28 03:51:17.849467 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-02-28 03:51:17.849491 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-28 03:51:17.854864 | controller | INFO [helm > cleanup] Executing 2026-02-28 03:51:17.855243 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:17.860858 | controller | INFO [helm > destroy] Executing 2026-02-28 03:51:17.860886 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-28 03:51:17.860969 | controller | INFO [helm > destroy] Executed: Successful 2026-02-28 03:51:17.866623 | controller | INFO [helm > syntax] Executing 2026-02-28 03:51:18.796384 | controller | 2026-02-28 03:51:18.796582 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-02-28 03:51:18.898694 | controller | INFO [helm > syntax] Executed: Successful 2026-02-28 03:51:18.909421 | controller | INFO [helm > create] Executing 2026-02-28 03:51:18.910984 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:18.916503 | controller | INFO [helm > prepare] Executing 2026-02-28 03:51:19.778666 | controller | 2026-02-28 03:51:19.778907 | controller | PLAY [Prepare] ***************************************************************** 2026-02-28 03:51:19.779038 | controller | 2026-02-28 03:51:19.779222 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:19.779415 | controller | Saturday 28 February 2026 03:51:19 +0000 (0:00:00.028) 0:00:00.028 ***** 2026-02-28 03:51:21.062652 | 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-02-28 03:51:21.067740 | controller | ok: [controller] 2026-02-28 03:51:21.067930 | controller | 2026-02-28 03:51:21.068133 | controller | TASK [Install Debian packages] ************************************************* 2026-02-28 03:51:21.068345 | controller | Saturday 28 February 2026 03:51:21 +0000 (0:00:01.289) 0:00:01.318 ***** 2026-02-28 03:51:26.720444 | controller | ok: [controller] 2026-02-28 03:51:26.720574 | controller | 2026-02-28 03:51:26.720779 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:26.721266 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:26.721379 | controller | 2026-02-28 03:51:26.721555 | controller | 2026-02-28 03:51:26.721750 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:26.722030 | controller | Saturday 28 February 2026 03:51:26 +0000 (0:00:05.652) 0:00:06.971 ***** 2026-02-28 03:51:26.722176 | controller | =============================================================================== 2026-02-28 03:51:26.722297 | controller | Install Debian packages ------------------------------------------------- 5.65s 2026-02-28 03:51:26.722422 | controller | Gathering Facts --------------------------------------------------------- 1.29s 2026-02-28 03:51:26.821726 | controller | INFO [helm > prepare] Executed: Successful 2026-02-28 03:51:26.831414 | controller | INFO [helm > converge] Executing 2026-02-28 03:51:27.711259 | controller | 2026-02-28 03:51:27.711323 | controller | PLAY [Converge] **************************************************************** 2026-02-28 03:51:27.711440 | controller | 2026-02-28 03:51:27.711620 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:27.711887 | controller | Saturday 28 February 2026 03:51:27 +0000 (0:00:00.012) 0:00:00.012 ***** 2026-02-28 03:51:28.783227 | 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-02-28 03:51:28.789553 | controller | ok: [controller] 2026-02-28 03:51:28.789733 | controller | 2026-02-28 03:51:28.789940 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-28 03:51:28.790137 | controller | Saturday 28 February 2026 03:51:28 +0000 (0:00:01.078) 0:00:01.091 ***** 2026-02-28 03:51:29.262643 | controller | ok: [controller] 2026-02-28 03:51:29.262667 | controller | 2026-02-28 03:51:29.262671 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-28 03:51:29.262675 | controller | Saturday 28 February 2026 03:51:29 +0000 (0:00:00.470) 0:00:01.561 ***** 2026-02-28 03:51:30.432003 | controller | ok: [controller] 2026-02-28 03:51:30.432185 | controller | 2026-02-28 03:51:30.432440 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-28 03:51:30.432651 | controller | Saturday 28 February 2026 03:51:30 +0000 (0:00:01.171) 0:00:02.733 ***** 2026-02-28 03:51:30.912351 | controller | changed: [controller] 2026-02-28 03:51:30.912532 | controller | 2026-02-28 03:51:30.912749 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:30.912953 | controller | Saturday 28 February 2026 03:51:30 +0000 (0:00:00.480) 0:00:03.213 ***** 2026-02-28 03:51:30.962089 | controller | ok: [controller] => { 2026-02-28 03:51:30.962316 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-28 03:51:30.962579 | controller | } 2026-02-28 03:51:30.962836 | controller | 2026-02-28 03:51:30.963135 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:30.963503 | controller | Saturday 28 February 2026 03:51:30 +0000 (0:00:00.049) 0:00:03.263 ***** 2026-02-28 03:51:31.795865 | 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-02-28 03:51:31.804875 | controller | changed: [controller] 2026-02-28 03:51:31.805094 | controller | 2026-02-28 03:51:31.805336 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:31.805544 | controller | Saturday 28 February 2026 03:51:31 +0000 (0:00:00.843) 0:00:04.106 ***** 2026-02-28 03:51:33.850613 | controller | changed: [controller] 2026-02-28 03:51:33.850752 | controller | 2026-02-28 03:51:33.850921 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-28 03:51:33.851057 | controller | Saturday 28 February 2026 03:51:33 +0000 (0:00:02.040) 0:00:06.146 ***** 2026-02-28 03:51:34.372033 | controller | ok: [controller] 2026-02-28 03:51:34.372173 | controller | 2026-02-28 03:51:34.372386 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-28 03:51:34.372584 | controller | Saturday 28 February 2026 03:51:34 +0000 (0:00:00.525) 0:00:06.672 ***** 2026-02-28 03:51:34.763935 | controller | changed: [controller] 2026-02-28 03:51:34.764085 | controller | 2026-02-28 03:51:34.764338 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-28 03:51:34.764517 | controller | Saturday 28 February 2026 03:51:34 +0000 (0:00:00.392) 0:00:07.065 ***** 2026-02-28 03:51:35.223513 | controller | ok: [controller] 2026-02-28 03:51:35.225833 | controller | 2026-02-28 03:51:35.225847 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-28 03:51:35.225853 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:00.459) 0:00:07.524 ***** 2026-02-28 03:51:35.519625 | controller | changed: [controller] 2026-02-28 03:51:35.519814 | controller | 2026-02-28 03:51:35.520036 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-28 03:51:35.520238 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:00.296) 0:00:07.820 ***** 2026-02-28 03:51:35.810846 | controller | ok: [controller] 2026-02-28 03:51:35.811012 | controller | 2026-02-28 03:51:35.811229 | controller | TASK [Install plugin] ********************************************************** 2026-02-28 03:51:35.811425 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:00.290) 0:00:08.111 ***** 2026-02-28 03:51:35.866712 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-28 03:51:35.866827 | controller | 2026-02-28 03:51:35.867104 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:35.867308 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:00.056) 0:00:08.168 ***** 2026-02-28 03:51:35.910720 | controller | ok: [controller] => { 2026-02-28 03:51:35.910885 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-28 03:51:35.911110 | controller | } 2026-02-28 03:51:35.911285 | controller | 2026-02-28 03:51:35.911483 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:35.911683 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:00.043) 0:00:08.211 ***** 2026-02-28 03:51:36.677618 | controller | changed: [controller] 2026-02-28 03:51:36.677742 | controller | 2026-02-28 03:51:36.677892 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:36.678025 | controller | Saturday 28 February 2026 03:51:36 +0000 (0:00:00.767) 0:00:08.978 ***** 2026-02-28 03:51:38.727759 | controller | changed: [controller] 2026-02-28 03:51:38.727794 | controller | 2026-02-28 03:51:38.727803 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:38.727813 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:38.727820 | controller | 2026-02-28 03:51:38.727827 | controller | 2026-02-28 03:51:38.727835 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:38.727842 | controller | Saturday 28 February 2026 03:51:38 +0000 (0:00:02.047) 0:00:11.025 ***** 2026-02-28 03:51:38.727849 | controller | =============================================================================== 2026-02-28 03:51:38.727856 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s 2026-02-28 03:51:38.727863 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.04s 2026-02-28 03:51:38.727871 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.17s 2026-02-28 03:51:38.727878 | controller | Gathering Facts --------------------------------------------------------- 1.08s 2026-02-28 03:51:38.727885 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.84s 2026-02-28 03:51:38.727892 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.77s 2026-02-28 03:51:38.727899 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s 2026-02-28 03:51:38.727906 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.48s 2026-02-28 03:51:38.727913 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s 2026-02-28 03:51:38.727920 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-02-28 03:51:38.728137 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-02-28 03:51:38.728403 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-02-28 03:51:38.728728 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-02-28 03:51:38.729014 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-02-28 03:51:38.729308 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-28 03:51:38.729567 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-28 03:51:38.826902 | controller | INFO [helm > converge] Executed: Successful 2026-02-28 03:51:38.837133 | controller | INFO [helm > idempotence] Executing 2026-02-28 03:51:39.681080 | controller | 2026-02-28 03:51:39.681430 | controller | PLAY [Converge] **************************************************************** 2026-02-28 03:51:39.681730 | controller | 2026-02-28 03:51:39.681991 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:39.682245 | controller | Saturday 28 February 2026 03:51:39 +0000 (0:00:00.012) 0:00:00.012 ***** 2026-02-28 03:51:40.782537 | 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-02-28 03:51:40.788327 | controller | ok: [controller] 2026-02-28 03:51:40.788618 | controller | 2026-02-28 03:51:40.788912 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-28 03:51:40.789191 | controller | Saturday 28 February 2026 03:51:40 +0000 (0:00:01.106) 0:00:01.119 ***** 2026-02-28 03:51:41.201313 | controller | ok: [controller] 2026-02-28 03:51:41.201613 | controller | 2026-02-28 03:51:41.201894 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-28 03:51:41.202163 | controller | Saturday 28 February 2026 03:51:41 +0000 (0:00:00.412) 0:00:01.532 ***** 2026-02-28 03:51:42.252731 | controller | ok: [controller] 2026-02-28 03:51:42.253053 | controller | 2026-02-28 03:51:42.253444 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-28 03:51:42.253735 | controller | Saturday 28 February 2026 03:51:42 +0000 (0:00:01.051) 0:00:02.583 ***** 2026-02-28 03:51:42.675503 | controller | ok: [controller] 2026-02-28 03:51:42.675562 | controller | 2026-02-28 03:51:42.675724 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:42.675845 | controller | Saturday 28 February 2026 03:51:42 +0000 (0:00:00.420) 0:00:03.004 ***** 2026-02-28 03:51:42.725679 | controller | ok: [controller] => { 2026-02-28 03:51:42.725849 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-28 03:51:42.726000 | controller | } 2026-02-28 03:51:42.726100 | controller | 2026-02-28 03:51:42.726239 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:42.726385 | controller | Saturday 28 February 2026 03:51:42 +0000 (0:00:00.053) 0:00:03.057 ***** 2026-02-28 03:51:43.255632 | controller | ok: [controller] 2026-02-28 03:51:43.255738 | controller | 2026-02-28 03:51:43.256005 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:43.256034 | controller | Saturday 28 February 2026 03:51:43 +0000 (0:00:00.526) 0:00:03.584 ***** 2026-02-28 03:51:45.978710 | controller | ok: [controller] 2026-02-28 03:51:45.978733 | controller | 2026-02-28 03:51:45.978738 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-28 03:51:45.978742 | controller | Saturday 28 February 2026 03:51:45 +0000 (0:00:02.723) 0:00:06.307 ***** 2026-02-28 03:51:46.510710 | controller | ok: [controller] 2026-02-28 03:51:46.510732 | controller | 2026-02-28 03:51:46.510736 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-28 03:51:46.510740 | controller | Saturday 28 February 2026 03:51:46 +0000 (0:00:00.534) 0:00:06.842 ***** 2026-02-28 03:51:46.923074 | controller | ok: [controller] 2026-02-28 03:51:46.923288 | controller | 2026-02-28 03:51:46.926847 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-28 03:51:46.926858 | controller | Saturday 28 February 2026 03:51:46 +0000 (0:00:00.412) 0:00:07.254 ***** 2026-02-28 03:51:47.373589 | controller | ok: [controller] 2026-02-28 03:51:47.373647 | controller | 2026-02-28 03:51:47.373769 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-28 03:51:47.373862 | controller | Saturday 28 February 2026 03:51:47 +0000 (0:00:00.450) 0:00:07.704 ***** 2026-02-28 03:51:47.675214 | controller | ok: [controller] 2026-02-28 03:51:47.675236 | controller | 2026-02-28 03:51:47.675241 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-28 03:51:47.675244 | controller | Saturday 28 February 2026 03:51:47 +0000 (0:00:00.301) 0:00:08.006 ***** 2026-02-28 03:51:47.971645 | controller | ok: [controller] 2026-02-28 03:51:47.971699 | controller | 2026-02-28 03:51:47.971706 | controller | TASK [Install plugin] ********************************************************** 2026-02-28 03:51:47.971711 | controller | Saturday 28 February 2026 03:51:47 +0000 (0:00:00.296) 0:00:08.302 ***** 2026-02-28 03:51:48.028083 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-28 03:51:48.028167 | controller | 2026-02-28 03:51:48.028232 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:48.028312 | controller | Saturday 28 February 2026 03:51:48 +0000 (0:00:00.055) 0:00:08.358 ***** 2026-02-28 03:51:48.073557 | controller | ok: [controller] => { 2026-02-28 03:51:48.073682 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-28 03:51:48.073773 | controller | } 2026-02-28 03:51:48.073887 | controller | 2026-02-28 03:51:48.074012 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:48.074136 | controller | Saturday 28 February 2026 03:51:48 +0000 (0:00:00.046) 0:00:08.405 ***** 2026-02-28 03:51:48.476117 | controller | ok: [controller] 2026-02-28 03:51:48.476174 | controller | 2026-02-28 03:51:48.477886 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:48.477907 | controller | Saturday 28 February 2026 03:51:48 +0000 (0:00:00.399) 0:00:08.804 ***** 2026-02-28 03:51:50.128036 | controller | ok: [controller] 2026-02-28 03:51:50.128089 | controller | 2026-02-28 03:51:50.128174 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:50.128267 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:50.130034 | controller | 2026-02-28 03:51:50.130057 | controller | 2026-02-28 03:51:50.130062 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:50.130066 | controller | Saturday 28 February 2026 03:51:50 +0000 (0:00:01.651) 0:00:10.456 ***** 2026-02-28 03:51:50.130069 | controller | =============================================================================== 2026-02-28 03:51:50.130073 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.72s 2026-02-28 03:51:50.130076 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.65s 2026-02-28 03:51:50.130079 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2026-02-28 03:51:50.130082 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.05s 2026-02-28 03:51:50.130086 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s 2026-02-28 03:51:50.130089 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.53s 2026-02-28 03:51:50.130092 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s 2026-02-28 03:51:50.130095 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s 2026-02-28 03:51:50.130099 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.41s 2026-02-28 03:51:50.130102 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s 2026-02-28 03:51:50.130112 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.40s 2026-02-28 03:51:50.130115 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-02-28 03:51:50.130119 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-02-28 03:51:50.130122 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-02-28 03:51:50.130125 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-28 03:51:50.130128 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-28 03:51:50.244977 | controller | INFO [helm > idempotence] Executed: Successful 2026-02-28 03:51:50.258128 | controller | INFO [helm > side_effect] Executing 2026-02-28 03:51:50.258660 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:50.379574 | controller | INFO [helm > verify] Executing 2026-02-28 03:51:51.205520 | controller | 2026-02-28 03:51:51.205747 | controller | PLAY [Verify] ****************************************************************** 2026-02-28 03:51:51.205925 | controller | 2026-02-28 03:51:51.206111 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:51.206295 | controller | Saturday 28 February 2026 03:51:51 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-02-28 03:51:52.296326 | 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-02-28 03:51:52.303342 | controller | ok: [controller] 2026-02-28 03:51:52.303460 | controller | 2026-02-28 03:51:52.303595 | controller | TASK [Get the current Helm version] ******************************************** 2026-02-28 03:51:52.303797 | controller | Saturday 28 February 2026 03:51:52 +0000 (0:00:01.097) 0:00:01.108 ***** 2026-02-28 03:51:52.783906 | controller | changed: [controller] 2026-02-28 03:51:52.783992 | controller | 2026-02-28 03:51:52.784140 | controller | TASK [Assert Helm version] ***************************************************** 2026-02-28 03:51:52.784283 | controller | Saturday 28 February 2026 03:51:52 +0000 (0:00:00.480) 0:00:01.588 ***** 2026-02-28 03:51:52.817703 | controller | ok: [controller] => { 2026-02-28 03:51:52.817731 | controller | "changed": false, 2026-02-28 03:51:52.817737 | controller | "msg": "All assertions passed" 2026-02-28 03:51:52.817743 | controller | } 2026-02-28 03:51:52.817748 | controller | 2026-02-28 03:51:52.817753 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:52.817758 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:52.817763 | controller | 2026-02-28 03:51:52.817767 | controller | 2026-02-28 03:51:52.817772 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:52.817776 | controller | Saturday 28 February 2026 03:51:52 +0000 (0:00:00.031) 0:00:01.620 ***** 2026-02-28 03:51:52.817781 | controller | =============================================================================== 2026-02-28 03:51:52.817785 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-02-28 03:51:52.817790 | controller | Get the current Helm version -------------------------------------------- 0.48s 2026-02-28 03:51:52.817794 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-02-28 03:51:52.919897 | controller | INFO [helm > verify] Executed: Successful 2026-02-28 03:51:52.930356 | controller | INFO [helm > cleanup] Executing 2026-02-28 03:51:52.930714 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:52.936339 | controller | INFO [helm > destroy] Executing 2026-02-28 03:51:52.936435 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-28 03:51:52.936548 | controller | INFO [helm > destroy] Executed: Successful 2026-02-28 03:51:52.936836 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-28 03:51:53.328083 | controller | ok: Runtime: 0:00:55.406060 2026-02-28 03:51:53.332873 | 2026-02-28 03:51:53.332943 | PLAY RECAP 2026-02-28 03:51:53.333031 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:51:53.333062 | 2026-02-28 03:51:53.440711 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 03:51:53.444452 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 03:51:54.023700 | 2026-02-28 03:51:54.023822 | PLAY [all] 2026-02-28 03:51:54.033939 | 2026-02-28 03:51:54.034015 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-28 03:51:54.079744 | controller | skipping: Conditional result was False 2026-02-28 03:51:54.085231 | 2026-02-28 03:51:54.085331 | TASK [fetch-output : Set log path for single node] 2026-02-28 03:51:54.126467 | controller | ok 2026-02-28 03:51:54.130249 | 2026-02-28 03:51:54.130318 | LOOP [fetch-output : Ensure local output dirs] 2026-02-28 03:51:54.549701 | controller -> localhost | ok: "/var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/work/logs" 2026-02-28 03:51:54.756825 | controller -> localhost | changed: "/var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/work/artifacts" 2026-02-28 03:51:54.952839 | controller -> localhost | changed: "/var/lib/zuul/builds/ef2ec424e47f4e799d8968c5661bcb2a/work/docs" 2026-02-28 03:51:54.966146 | 2026-02-28 03:51:54.966285 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-28 03:51:55.652552 | controller | changed: .d..t...... ./ 2026-02-28 03:51:55.652806 | controller | changed: All items complete 2026-02-28 03:51:55.652832 | 2026-02-28 03:51:56.137929 | controller | changed: .d..t...... ./ 2026-02-28 03:51:56.637880 | controller | changed: .d..t...... ./ 2026-02-28 03:51:56.663238 | 2026-02-28 03:51:56.663400 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-28 03:51:57.059352 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006894 2026-02-28 03:51:57.273964 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008960 2026-02-28 03:51:57.285954 | 2026-02-28 03:51:57.286032 | PLAY [all] 2026-02-28 03:51:57.293629 | 2026-02-28 03:51:57.293697 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-28 03:51:57.833399 | controller | changed 2026-02-28 03:51:57.838357 | 2026-02-28 03:51:57.838408 | PLAY RECAP 2026-02-28 03:51:57.838460 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-28 03:51:57.838482 | 2026-02-28 03:51:57.986827 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 03:51:57.989762 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-28 03:51:58.575233 | 2026-02-28 03:51:58.575340 | PLAY [localhost] 2026-02-28 03:51:58.584994 | 2026-02-28 03:51:58.585072 | TASK [Generate Zuul manifest] 2026-02-28 03:51:58.608163 | localhost | ok 2026-02-28 03:51:58.623796 | 2026-02-28 03:51:58.623879 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-28 03:51:58.946985 | localhost | changed 2026-02-28 03:51:58.956843 | 2026-02-28 03:51:58.956921 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-28 03:51:58.988115 | localhost | ok 2026-02-28 03:51:58.997428 | 2026-02-28 03:51:58.997523 | TASK [Upload logs] 2026-02-28 03:51:59.018132 | localhost | ok 2026-02-28 03:51:59.082871 | 2026-02-28 03:51:59.082997 | TASK [Set zuul-log-path fact] 2026-02-28 03:51:59.115607 | localhost | ok 2026-02-28 03:51:59.128653 | 2026-02-28 03:51:59.128743 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 03:51:59.161974 | localhost | ok 2026-02-28 03:51:59.170071 | 2026-02-28 03:51:59.170146 | TASK [upload-logs : Create log directories] 2026-02-28 03:51:59.577145 | localhost | changed 2026-02-28 03:51:59.584217 | 2026-02-28 03:51:59.584314 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-28 03:51:59.989040 | localhost -> localhost | ok: Runtime: 0:00:00.005415 2026-02-28 03:51:59.994035 | 2026-02-28 03:51:59.994100 | TASK [upload-logs : Upload logs to log server] 2026-02-28 03:52:00.502643 | localhost | Output suppressed because no_log was given 2026-02-28 03:52:00.507648 | 2026-02-28 03:52:00.507734 | LOOP [upload-logs : Compress console log and json output] 2026-02-28 03:52:00.570938 | localhost | skipping: Conditional result was False 2026-02-28 03:52:00.579459 | localhost | skipping: Conditional result was False 2026-02-28 03:52:00.598576 | 2026-02-28 03:52:00.598831 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-28 03:52:00.641831 | localhost | skipping: Conditional result was False 2026-02-28 03:52:00.642337 | 2026-02-28 03:52:00.645599 | localhost | skipping: Conditional result was False 2026-02-28 03:52:00.663960 | 2026-02-28 03:52:00.664101 | LOOP [upload-logs : Upload console log and json output]