2026-01-21 17:49:55.015181 | Job console starting 2026-01-21 17:49:55.028714 | Updating git repos 2026-01-21 17:49:55.078543 | Cloning repos into workspace 2026-01-21 17:49:55.165173 | Restoring repo states 2026-01-21 17:49:55.179568 | Merging changes 2026-01-21 17:49:55.736574 | Checking out repos 2026-01-21 17:49:55.995408 | Preparing playbooks 2026-01-21 17:49:57.366900 | Running Ansible setup 2026-01-21 17:50:01.020765 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-21 17:50:01.688400 | 2026-01-21 17:50:01.688548 | PLAY [localhost] 2026-01-21 17:50:01.697081 | 2026-01-21 17:50:01.697156 | TASK [Gathering Facts] 2026-01-21 17:50:02.636408 | localhost | ok 2026-01-21 17:50:02.645981 | 2026-01-21 17:50:02.646056 | TASK [Setup log path fact] 2026-01-21 17:50:02.667216 | localhost | ok 2026-01-21 17:50:02.681509 | 2026-01-21 17:50:02.681575 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-21 17:50:02.709417 | localhost | ok 2026-01-21 17:50:02.718929 | 2026-01-21 17:50:02.719035 | TASK [emit-job-header : Print job information] 2026-01-21 17:50:02.761436 | # Job Information 2026-01-21 17:50:02.761736 | Ansible Version: 2.16.15 2026-01-21 17:50:02.761813 | Job: ansible-collection-kubernetes-molecule-helm 2026-01-21 17:50:02.761867 | Pipeline: check 2026-01-21 17:50:02.761917 | Executor: 3a2793d2bd32 2026-01-21 17:50:02.761964 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/228 2026-01-21 17:50:02.762017 | Event ID: 798a3fa0-f6f1-11f0-8cda-ecf02918b158 2026-01-21 17:50:02.766905 | 2026-01-21 17:50:02.766976 | LOOP [emit-job-header : Print node information] 2026-01-21 17:50:02.850844 | localhost | ok: 2026-01-21 17:50:02.851069 | localhost | # Node Information 2026-01-21 17:50:02.851112 | localhost | Inventory Hostname: controller 2026-01-21 17:50:02.851146 | localhost | Hostname: np0000154369 2026-01-21 17:50:02.851178 | localhost | Username: zuul 2026-01-21 17:50:02.851211 | localhost | Distro: Debian 13.3 2026-01-21 17:50:02.851246 | localhost | Provider: yul1 2026-01-21 17:50:02.851275 | localhost | Region: ca-ymq-1 2026-01-21 17:50:02.851304 | localhost | Label: debian-trixie 2026-01-21 17:50:02.851333 | localhost | Product Name: OpenStack Nova 2026-01-21 17:50:02.851362 | localhost | Interface IP: 162.253.55.218 2026-01-21 17:50:02.862180 | 2026-01-21 17:50:02.862268 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-21 17:50:03.295654 | localhost -> localhost | changed 2026-01-21 17:50:03.300656 | 2026-01-21 17:50:03.300733 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-21 17:50:04.144070 | localhost -> localhost | changed 2026-01-21 17:50:04.152351 | 2026-01-21 17:50:04.152467 | PLAY [all] 2026-01-21 17:50:04.159752 | 2026-01-21 17:50:04.159835 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-21 17:50:04.367606 | controller -> localhost | ok 2026-01-21 17:50:04.372612 | 2026-01-21 17:50:04.372678 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-21 17:50:04.389029 | controller | ok 2026-01-21 17:50:04.401715 | controller | included: /var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-21 17:50:04.407331 | 2026-01-21 17:50:04.407392 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-21 17:50:06.224186 | controller -> localhost | Generating public/private rsa key pair. 2026-01-21 17:50:06.224462 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/work/b9cccf662a654fc497540c40c61ba2be_id_rsa 2026-01-21 17:50:06.224524 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/work/b9cccf662a654fc497540c40c61ba2be_id_rsa.pub 2026-01-21 17:50:06.224574 | controller -> localhost | The key fingerprint is: 2026-01-21 17:50:06.224720 | controller -> localhost | SHA256:h7H9bSehX8fTyvFgcsdCk+aYGqh3BJrsunRwBVdPQ4o zuul-build-sshkey 2026-01-21 17:50:06.224801 | controller -> localhost | The key's randomart image is: 2026-01-21 17:50:06.224851 | controller -> localhost | +---[RSA 3072]----+ 2026-01-21 17:50:06.224899 | controller -> localhost | | . ....+ | 2026-01-21 17:50:06.224945 | controller -> localhost | | o . + . | 2026-01-21 17:50:06.225065 | controller -> localhost | | E . . | 2026-01-21 17:50:06.225126 | controller -> localhost | | ..= . | 2026-01-21 17:50:06.225172 | controller -> localhost | | ...oS.o * | 2026-01-21 17:50:06.225215 | controller -> localhost | | o+ o.. O =.| 2026-01-21 17:50:06.225286 | controller -> localhost | | ... ... * @.O| 2026-01-21 17:50:06.225331 | controller -> localhost | | . .... .o B @o| 2026-01-21 17:50:06.225379 | controller -> localhost | | oo.. .. + .| 2026-01-21 17:50:06.225424 | controller -> localhost | +----[SHA256]-----+ 2026-01-21 17:50:06.225526 | controller -> localhost | ok: Runtime: 0:00:01.359280 2026-01-21 17:50:06.231421 | 2026-01-21 17:50:06.231495 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-21 17:50:06.264396 | controller | ok 2026-01-21 17:50:06.276285 | controller | included: /var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-21 17:50:06.283514 | 2026-01-21 17:50:06.283584 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-21 17:50:06.309076 | controller | skipping: Conditional result was False 2026-01-21 17:50:06.318423 | 2026-01-21 17:50:06.318575 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-21 17:50:06.900702 | controller | changed 2026-01-21 17:50:06.908334 | 2026-01-21 17:50:06.908434 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-21 17:50:07.145236 | controller | ok 2026-01-21 17:50:07.150102 | 2026-01-21 17:50:07.150169 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-21 17:50:07.687513 | controller | changed 2026-01-21 17:50:07.694422 | 2026-01-21 17:50:07.694488 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-21 17:50:08.208045 | controller | changed 2026-01-21 17:50:08.214146 | 2026-01-21 17:50:08.214241 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-21 17:50:08.238597 | controller | skipping: Conditional result was False 2026-01-21 17:50:08.247620 | 2026-01-21 17:50:08.247717 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-21 17:50:08.619330 | controller -> localhost | changed 2026-01-21 17:50:08.634020 | 2026-01-21 17:50:08.634086 | TASK [add-build-sshkey : Add back temp key] 2026-01-21 17:50:08.962418 | controller -> localhost | Identity added: /var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/work/b9cccf662a654fc497540c40c61ba2be_id_rsa (zuul-build-sshkey) 2026-01-21 17:50:08.962746 | controller -> localhost | ok: Runtime: 0:00:00.015907 2026-01-21 17:50:08.969772 | 2026-01-21 17:50:08.969849 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-21 17:50:09.330258 | controller | ok 2026-01-21 17:50:09.334190 | 2026-01-21 17:50:09.334253 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-21 17:50:09.359079 | controller | skipping: Conditional result was False 2026-01-21 17:50:09.370687 | 2026-01-21 17:50:09.370774 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-21 17:50:09.732868 | controller | ok 2026-01-21 17:50:09.739855 | 2026-01-21 17:50:09.739943 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-21 17:50:10.735103 | controller | Output suppressed because no_log was given 2026-01-21 17:50:10.744904 | 2026-01-21 17:50:10.744989 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-21 17:50:10.960415 | controller | ok: "logs" 2026-01-21 17:50:10.960710 | controller | ok: All items complete 2026-01-21 17:50:10.960739 | 2026-01-21 17:50:11.129667 | controller | ok: "artifacts" 2026-01-21 17:50:11.306361 | controller | ok: "docs" 2026-01-21 17:50:11.319210 | 2026-01-21 17:50:11.319400 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-21 17:50:11.527122 | controller | changed: "logs" 2026-01-21 17:50:11.702655 | controller | changed: "artifacts" 2026-01-21 17:50:11.887326 | controller | changed: "docs" 2026-01-21 17:50:11.904139 | 2026-01-21 17:50:11.904996 | PLAY RECAP 2026-01-21 17:50:11.905050 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-01-21 17:50:11.905080 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 17:50:11.905103 | 2026-01-21 17:50:12.023944 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-21 17:50:12.026526 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-21 17:50:12.649835 | 2026-01-21 17:50:12.649966 | PLAY [all] 2026-01-21 17:50:12.660169 | 2026-01-21 17:50:12.660242 | TASK [setup-uv : Extract archive] 2026-01-21 17:50:14.939048 | controller | changed 2026-01-21 17:50:14.946928 | 2026-01-21 17:50:14.947021 | TASK [setup-uv : Print version] 2026-01-21 17:50:15.286017 | controller | uv 0.8.13 2026-01-21 17:50:15.474895 | controller | ok: Runtime: 0:00:00.008850 2026-01-21 17:50:15.480883 | 2026-01-21 17:50:15.480932 | PLAY RECAP 2026-01-21 17:50:15.481245 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 17:50:15.481278 | 2026-01-21 17:50:15.596568 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-21 17:50:15.598610 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-21 17:50:16.216941 | 2026-01-21 17:50:16.217122 | PLAY [all] 2026-01-21 17:50:16.229206 | 2026-01-21 17:50:16.229307 | TASK [Copy inventory file for Zuul] 2026-01-21 17:50:17.217977 | controller | changed 2026-01-21 17:50:17.222112 | 2026-01-21 17:50:17.222186 | TASK [Switch "ansible_host" to private IP] 2026-01-21 17:50:17.524900 | controller | changed: 1 replacements made 2026-01-21 17:50:17.529538 | 2026-01-21 17:50:17.529628 | TASK [Run Molecule scenario] 2026-01-21 17:50:17.940036 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-01-21 17:50:17.940087 | controller | Creating virtual environment at: .venv 2026-01-21 17:50:17.977032 | controller | Downloading cryptography (4.3MiB) 2026-01-21 17:50:17.978294 | controller | Downloading pygments (1.2MiB) 2026-01-21 17:50:17.978793 | controller | Downloading ansible-core (2.3MiB) 2026-01-21 17:50:18.144535 | controller | Downloading cryptography 2026-01-21 17:50:18.179106 | controller | Downloading pygments 2026-01-21 17:50:18.262497 | controller | Downloading ansible-core 2026-01-21 17:50:18.308853 | controller | Installed 36 packages in 45ms 2026-01-21 17:50:18.954230 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-01-21 17:50:18.954256 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-01-21 17:50:19.691432 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-01-21 17:50:19.691539 | controller | INFO default scenario not found, disabling shared state. 2026-01-21 17:50:19.691731 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-01-21 17:50:19.691833 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-01-21 17:50:36.130029 | controller | INFO [helm > dependency] Executing 2026-01-21 17:50:36.130076 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-01-21 17:50:36.130080 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-01-21 17:50:36.130094 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-01-21 17:50:36.140610 | controller | INFO [helm > cleanup] Executing 2026-01-21 17:50:36.141448 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:50:36.147889 | controller | INFO [helm > destroy] Executing 2026-01-21 17:50:36.148014 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-01-21 17:50:36.148089 | controller | INFO [helm > destroy] Executed: Successful 2026-01-21 17:50:36.153522 | controller | INFO [helm > syntax] Executing 2026-01-21 17:50:36.968953 | controller | 2026-01-21 17:50:36.969019 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-01-21 17:50:37.025662 | controller | INFO [helm > syntax] Executed: Successful 2026-01-21 17:50:37.036947 | controller | INFO [helm > create] Executing 2026-01-21 17:50:37.039093 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:50:37.044743 | controller | INFO [helm > prepare] Executing 2026-01-21 17:50:37.851694 | controller | 2026-01-21 17:50:37.851735 | controller | PLAY [Prepare] ***************************************************************** 2026-01-21 17:50:37.851745 | controller | 2026-01-21 17:50:37.851753 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:50:37.851762 | controller | Wednesday 21 January 2026 17:50:37 +0000 (0:00:00.032) 0:00:00.032 ***** 2026-01-21 17:50:39.044430 | 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-01-21 17:50:39.050309 | controller | ok: [controller] 2026-01-21 17:50:39.050330 | controller | 2026-01-21 17:50:39.050339 | controller | TASK [Install Debian packages] ************************************************* 2026-01-21 17:50:39.050347 | controller | Wednesday 21 January 2026 17:50:39 +0000 (0:00:01.205) 0:00:01.237 ***** 2026-01-21 17:50:43.644863 | controller | ok: [controller] 2026-01-21 17:50:43.645053 | controller | 2026-01-21 17:50:43.645215 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:50:43.645384 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-21 17:50:43.645529 | controller | 2026-01-21 17:50:43.645671 | controller | 2026-01-21 17:50:43.645825 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:50:43.645998 | controller | Wednesday 21 January 2026 17:50:43 +0000 (0:00:04.587) 0:00:05.825 ***** 2026-01-21 17:50:43.646146 | controller | =============================================================================== 2026-01-21 17:50:43.646299 | controller | Install Debian packages ------------------------------------------------- 4.59s 2026-01-21 17:50:43.646451 | controller | Gathering Facts --------------------------------------------------------- 1.21s 2026-01-21 17:50:43.713535 | controller | INFO [helm > prepare] Executed: Successful 2026-01-21 17:50:43.722622 | controller | INFO [helm > converge] Executing 2026-01-21 17:50:44.502842 | controller | 2026-01-21 17:50:44.503135 | controller | PLAY [Converge] **************************************************************** 2026-01-21 17:50:44.503365 | controller | 2026-01-21 17:50:44.503623 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:50:44.503873 | controller | Wednesday 21 January 2026 17:50:44 +0000 (0:00:00.012) 0:00:00.012 ***** 2026-01-21 17:50:45.506083 | 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-01-21 17:50:45.510889 | controller | ok: [controller] 2026-01-21 17:50:45.510975 | controller | 2026-01-21 17:50:45.511071 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-01-21 17:50:45.511162 | controller | Wednesday 21 January 2026 17:50:45 +0000 (0:00:01.007) 0:00:01.019 ***** 2026-01-21 17:50:46.007191 | controller | ok: [controller] 2026-01-21 17:50:46.007264 | controller | 2026-01-21 17:50:46.007276 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-01-21 17:50:46.007287 | controller | Wednesday 21 January 2026 17:50:46 +0000 (0:00:00.495) 0:00:01.515 ***** 2026-01-21 17:50:46.923441 | controller | ok: [controller] 2026-01-21 17:50:46.923463 | controller | 2026-01-21 17:50:46.923467 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-01-21 17:50:46.923472 | controller | Wednesday 21 January 2026 17:50:46 +0000 (0:00:00.914) 0:00:02.430 ***** 2026-01-21 17:50:47.322697 | controller | changed: [controller] 2026-01-21 17:50:47.322718 | controller | 2026-01-21 17:50:47.322723 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-21 17:50:47.322727 | controller | Wednesday 21 January 2026 17:50:47 +0000 (0:00:00.401) 0:00:02.831 ***** 2026-01-21 17:50:47.367307 | controller | ok: [controller] => { 2026-01-21 17:50:47.367487 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-01-21 17:50:47.367642 | controller | } 2026-01-21 17:50:47.370831 | controller | 2026-01-21 17:50:47.370844 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-21 17:50:47.370849 | controller | Wednesday 21 January 2026 17:50:47 +0000 (0:00:00.045) 0:00:02.876 ***** 2026-01-21 17:50:49.605542 | 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-01-21 17:50:49.611032 | controller | changed: [controller] 2026-01-21 17:50:49.611196 | controller | 2026-01-21 17:50:49.611369 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-21 17:50:49.611538 | controller | Wednesday 21 January 2026 17:50:49 +0000 (0:00:02.243) 0:00:05.120 ***** 2026-01-21 17:50:51.569144 | controller | changed: [controller] 2026-01-21 17:50:51.569655 | controller | 2026-01-21 17:50:51.569670 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-01-21 17:50:51.569676 | controller | Wednesday 21 January 2026 17:50:51 +0000 (0:00:01.952) 0:00:07.073 ***** 2026-01-21 17:50:52.051493 | controller | ok: [controller] 2026-01-21 17:50:52.051680 | controller | 2026-01-21 17:50:52.051854 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-01-21 17:50:52.052050 | controller | Wednesday 21 January 2026 17:50:52 +0000 (0:00:00.484) 0:00:07.558 ***** 2026-01-21 17:50:52.424445 | controller | changed: [controller] 2026-01-21 17:50:52.424803 | controller | 2026-01-21 17:50:52.425005 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-01-21 17:50:52.425178 | controller | Wednesday 21 January 2026 17:50:52 +0000 (0:00:00.374) 0:00:07.932 ***** 2026-01-21 17:50:52.845344 | controller | ok: [controller] 2026-01-21 17:50:52.845547 | controller | 2026-01-21 17:50:52.845729 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-01-21 17:50:52.845896 | controller | Wednesday 21 January 2026 17:50:52 +0000 (0:00:00.421) 0:00:08.353 ***** 2026-01-21 17:50:53.116652 | controller | changed: [controller] 2026-01-21 17:50:53.116929 | controller | 2026-01-21 17:50:53.117242 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-01-21 17:50:53.117550 | controller | Wednesday 21 January 2026 17:50:53 +0000 (0:00:00.271) 0:00:08.625 ***** 2026-01-21 17:50:53.384750 | controller | ok: [controller] 2026-01-21 17:50:53.384939 | controller | 2026-01-21 17:50:53.385112 | controller | TASK [Install plugin] ********************************************************** 2026-01-21 17:50:53.385281 | controller | Wednesday 21 January 2026 17:50:53 +0000 (0:00:00.266) 0:00:08.892 ***** 2026-01-21 17:50:53.433677 | controller | included: vexxhost.containers.download_artifact for controller 2026-01-21 17:50:53.433948 | controller | 2026-01-21 17:50:53.434214 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-21 17:50:53.434468 | controller | Wednesday 21 January 2026 17:50:53 +0000 (0:00:00.050) 0:00:08.943 ***** 2026-01-21 17:50:53.470685 | controller | ok: [controller] => { 2026-01-21 17:50:53.470707 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-01-21 17:50:53.470711 | controller | } 2026-01-21 17:50:53.470715 | controller | 2026-01-21 17:50:53.470719 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-21 17:50:53.470723 | controller | Wednesday 21 January 2026 17:50:53 +0000 (0:00:00.035) 0:00:08.978 ***** 2026-01-21 17:50:54.222574 | controller | changed: [controller] 2026-01-21 17:50:54.222597 | controller | 2026-01-21 17:50:54.222601 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-21 17:50:54.222606 | controller | Wednesday 21 January 2026 17:50:54 +0000 (0:00:00.746) 0:00:09.725 ***** 2026-01-21 17:50:56.257736 | controller | changed: [controller] 2026-01-21 17:50:56.258052 | controller | 2026-01-21 17:50:56.258369 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:50:56.258674 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-21 17:50:56.258930 | controller | 2026-01-21 17:50:56.259185 | controller | 2026-01-21 17:50:56.259481 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:50:56.259760 | controller | Wednesday 21 January 2026 17:50:56 +0000 (0:00:02.041) 0:00:11.767 ***** 2026-01-21 17:50:56.260083 | controller | =============================================================================== 2026-01-21 17:50:56.260427 | controller | vexxhost.containers.download_artifact : Download item ------------------- 2.24s 2026-01-21 17:50:56.260712 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.04s 2026-01-21 17:50:56.260979 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.95s 2026-01-21 17:50:56.261244 | controller | Gathering Facts --------------------------------------------------------- 1.01s 2026-01-21 17:50:56.261590 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.91s 2026-01-21 17:50:56.261869 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.75s 2026-01-21 17:50:56.262060 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.50s 2026-01-21 17:50:56.262220 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s 2026-01-21 17:50:56.262396 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.42s 2026-01-21 17:50:56.262559 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s 2026-01-21 17:50:56.262736 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.37s 2026-01-21 17:50:56.262904 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.27s 2026-01-21 17:50:56.263072 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.27s 2026-01-21 17:50:56.263231 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-01-21 17:50:56.263404 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-01-21 17:50:56.263572 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-01-21 17:50:56.378724 | controller | INFO [helm > converge] Executed: Successful 2026-01-21 17:50:56.387882 | controller | INFO [helm > idempotence] Executing 2026-01-21 17:50:57.254639 | controller | 2026-01-21 17:50:57.254929 | controller | PLAY [Converge] **************************************************************** 2026-01-21 17:50:57.255158 | controller | 2026-01-21 17:50:57.255400 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:50:57.255643 | controller | Wednesday 21 January 2026 17:50:57 +0000 (0:00:00.012) 0:00:00.012 ***** 2026-01-21 17:50:58.218831 | 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-01-21 17:50:58.224579 | controller | ok: [controller] 2026-01-21 17:50:58.224692 | controller | 2026-01-21 17:50:58.224809 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-01-21 17:50:58.224924 | controller | Wednesday 21 January 2026 17:50:58 +0000 (0:00:00.969) 0:00:00.982 ***** 2026-01-21 17:50:58.619653 | controller | ok: [controller] 2026-01-21 17:50:58.619924 | controller | 2026-01-21 17:50:58.620229 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-01-21 17:50:58.620483 | controller | Wednesday 21 January 2026 17:50:58 +0000 (0:00:00.394) 0:00:01.377 ***** 2026-01-21 17:50:59.542465 | controller | ok: [controller] 2026-01-21 17:50:59.542651 | controller | 2026-01-21 17:50:59.542826 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-01-21 17:50:59.542990 | controller | Wednesday 21 January 2026 17:50:59 +0000 (0:00:00.922) 0:00:02.300 ***** 2026-01-21 17:50:59.934853 | controller | ok: [controller] 2026-01-21 17:50:59.936404 | controller | 2026-01-21 17:50:59.936551 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-21 17:50:59.936677 | controller | Wednesday 21 January 2026 17:50:59 +0000 (0:00:00.392) 0:00:02.692 ***** 2026-01-21 17:50:59.982792 | controller | ok: [controller] => { 2026-01-21 17:50:59.982886 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-01-21 17:50:59.982969 | controller | } 2026-01-21 17:50:59.983047 | controller | 2026-01-21 17:50:59.983138 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-21 17:50:59.983225 | controller | Wednesday 21 January 2026 17:50:59 +0000 (0:00:00.047) 0:00:02.740 ***** 2026-01-21 17:51:00.496308 | controller | ok: [controller] 2026-01-21 17:51:00.496366 | controller | 2026-01-21 17:51:00.496460 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-21 17:51:00.497565 | controller | Wednesday 21 January 2026 17:51:00 +0000 (0:00:00.508) 0:00:03.248 ***** 2026-01-21 17:51:02.082945 | controller | ok: [controller] 2026-01-21 17:51:02.083053 | controller | 2026-01-21 17:51:02.083186 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-01-21 17:51:02.083312 | controller | Wednesday 21 January 2026 17:51:02 +0000 (0:00:01.592) 0:00:04.841 ***** 2026-01-21 17:51:02.597442 | controller | ok: [controller] 2026-01-21 17:51:02.597539 | controller | 2026-01-21 17:51:02.597676 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-01-21 17:51:02.597805 | controller | Wednesday 21 January 2026 17:51:02 +0000 (0:00:00.514) 0:00:05.355 ***** 2026-01-21 17:51:02.974942 | controller | ok: [controller] 2026-01-21 17:51:02.975048 | controller | 2026-01-21 17:51:02.975183 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-01-21 17:51:02.975310 | controller | Wednesday 21 January 2026 17:51:02 +0000 (0:00:00.377) 0:00:05.733 ***** 2026-01-21 17:51:03.402797 | controller | ok: [controller] 2026-01-21 17:51:03.402855 | controller | 2026-01-21 17:51:03.402956 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-01-21 17:51:03.403058 | controller | Wednesday 21 January 2026 17:51:03 +0000 (0:00:00.428) 0:00:06.161 ***** 2026-01-21 17:51:03.684050 | controller | ok: [controller] 2026-01-21 17:51:03.684076 | controller | 2026-01-21 17:51:03.684082 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-01-21 17:51:03.684087 | controller | Wednesday 21 January 2026 17:51:03 +0000 (0:00:00.279) 0:00:06.440 ***** 2026-01-21 17:51:03.955793 | controller | ok: [controller] 2026-01-21 17:51:03.955932 | controller | 2026-01-21 17:51:03.956085 | controller | TASK [Install plugin] ********************************************************** 2026-01-21 17:51:03.956137 | controller | Wednesday 21 January 2026 17:51:03 +0000 (0:00:00.273) 0:00:06.714 ***** 2026-01-21 17:51:04.007658 | controller | included: vexxhost.containers.download_artifact for controller 2026-01-21 17:51:04.007680 | controller | 2026-01-21 17:51:04.007684 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-21 17:51:04.007688 | controller | Wednesday 21 January 2026 17:51:04 +0000 (0:00:00.049) 0:00:06.763 ***** 2026-01-21 17:51:04.048999 | controller | ok: [controller] => { 2026-01-21 17:51:04.049034 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-01-21 17:51:04.049039 | controller | } 2026-01-21 17:51:04.049045 | controller | 2026-01-21 17:51:04.049050 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-21 17:51:04.049055 | controller | Wednesday 21 January 2026 17:51:04 +0000 (0:00:00.042) 0:00:06.806 ***** 2026-01-21 17:51:04.433983 | controller | ok: [controller] 2026-01-21 17:51:04.434044 | controller | 2026-01-21 17:51:04.434138 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-21 17:51:04.434229 | controller | Wednesday 21 January 2026 17:51:04 +0000 (0:00:00.385) 0:00:07.191 ***** 2026-01-21 17:51:05.975131 | controller | ok: [controller] 2026-01-21 17:51:05.975157 | controller | 2026-01-21 17:51:05.975163 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:51:05.975169 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-21 17:51:05.975174 | controller | 2026-01-21 17:51:05.975178 | controller | 2026-01-21 17:51:05.975183 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:51:05.975188 | controller | Wednesday 21 January 2026 17:51:05 +0000 (0:00:01.538) 0:00:08.730 ***** 2026-01-21 17:51:05.975192 | controller | =============================================================================== 2026-01-21 17:51:05.975197 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.59s 2026-01-21 17:51:05.975201 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.54s 2026-01-21 17:51:05.975206 | controller | Gathering Facts --------------------------------------------------------- 0.97s 2026-01-21 17:51:05.975210 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.92s 2026-01-21 17:51:05.975214 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-01-21 17:51:05.975219 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.51s 2026-01-21 17:51:05.975223 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s 2026-01-21 17:51:05.975228 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.39s 2026-01-21 17:51:05.975233 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.39s 2026-01-21 17:51:05.975238 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.39s 2026-01-21 17:51:05.975249 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.38s 2026-01-21 17:51:05.975254 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-01-21 17:51:05.975258 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.27s 2026-01-21 17:51:05.975263 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-01-21 17:51:05.975267 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-01-21 17:51:05.975272 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-01-21 17:51:06.058057 | controller | INFO [helm > idempotence] Executed: Successful 2026-01-21 17:51:06.068287 | controller | INFO [helm > side_effect] Executing 2026-01-21 17:51:06.068761 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:51:06.076125 | controller | INFO [helm > verify] Executing 2026-01-21 17:51:06.848767 | controller | 2026-01-21 17:51:06.849070 | controller | PLAY [Verify] ****************************************************************** 2026-01-21 17:51:06.849304 | controller | 2026-01-21 17:51:06.849552 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:51:06.849800 | controller | Wednesday 21 January 2026 17:51:06 +0000 (0:00:00.009) 0:00:00.009 ***** 2026-01-21 17:51:07.835757 | 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-01-21 17:51:07.842288 | controller | ok: [controller] 2026-01-21 17:51:07.842447 | controller | 2026-01-21 17:51:07.842613 | controller | TASK [Get the current Helm version] ******************************************** 2026-01-21 17:51:07.842775 | controller | Wednesday 21 January 2026 17:51:07 +0000 (0:00:00.993) 0:00:01.002 ***** 2026-01-21 17:51:08.290504 | controller | changed: [controller] 2026-01-21 17:51:08.290634 | controller | 2026-01-21 17:51:08.290749 | controller | TASK [Assert Helm version] ***************************************************** 2026-01-21 17:51:08.290863 | controller | Wednesday 21 January 2026 17:51:08 +0000 (0:00:00.447) 0:00:01.450 ***** 2026-01-21 17:51:08.315756 | controller | ok: [controller] => { 2026-01-21 17:51:08.315892 | controller | "changed": false, 2026-01-21 17:51:08.316028 | controller | "msg": "All assertions passed" 2026-01-21 17:51:08.316137 | controller | } 2026-01-21 17:51:08.316240 | controller | 2026-01-21 17:51:08.316350 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:51:08.316469 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-21 17:51:08.316570 | controller | 2026-01-21 17:51:08.316670 | controller | 2026-01-21 17:51:08.316779 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:51:08.316889 | controller | Wednesday 21 January 2026 17:51:08 +0000 (0:00:00.025) 0:00:01.476 ***** 2026-01-21 17:51:08.316994 | controller | =============================================================================== 2026-01-21 17:51:08.317103 | controller | Gathering Facts --------------------------------------------------------- 0.99s 2026-01-21 17:51:08.317211 | controller | Get the current Helm version -------------------------------------------- 0.45s 2026-01-21 17:51:08.317318 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-01-21 17:51:08.399895 | controller | INFO [helm > verify] Executed: Successful 2026-01-21 17:51:08.410530 | controller | INFO [helm > cleanup] Executing 2026-01-21 17:51:08.410947 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:51:08.418279 | controller | INFO [helm > destroy] Executing 2026-01-21 17:51:08.418405 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-01-21 17:51:08.418522 | controller | INFO [helm > destroy] Executed: Successful 2026-01-21 17:51:08.418872 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-01-21 17:51:08.605880 | controller | ok: Runtime: 0:00:50.605144 2026-01-21 17:51:08.612511 | 2026-01-21 17:51:08.612577 | PLAY RECAP 2026-01-21 17:51:08.612638 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 17:51:08.612668 | 2026-01-21 17:51:08.729052 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-21 17:51:08.731846 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-21 17:51:09.359391 | 2026-01-21 17:51:09.359579 | PLAY [all] 2026-01-21 17:51:09.371445 | 2026-01-21 17:51:09.371606 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-21 17:51:09.417331 | controller | skipping: Conditional result was False 2026-01-21 17:51:09.427438 | 2026-01-21 17:51:09.427746 | TASK [fetch-output : Set log path for single node] 2026-01-21 17:51:09.472698 | controller | ok 2026-01-21 17:51:09.479876 | 2026-01-21 17:51:09.479948 | LOOP [fetch-output : Ensure local output dirs] 2026-01-21 17:51:09.903983 | controller -> localhost | ok: "/var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/work/logs" 2026-01-21 17:51:10.121766 | controller -> localhost | changed: "/var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/work/artifacts" 2026-01-21 17:51:10.344271 | controller -> localhost | changed: "/var/lib/zuul/builds/b9cccf662a654fc497540c40c61ba2be/work/docs" 2026-01-21 17:51:10.360446 | 2026-01-21 17:51:10.360590 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-21 17:51:11.021255 | controller | changed: .d..t...... ./ 2026-01-21 17:51:11.021578 | controller | changed: All items complete 2026-01-21 17:51:11.021637 | 2026-01-21 17:51:11.490598 | controller | changed: .d..t...... ./ 2026-01-21 17:51:11.956826 | controller | changed: .d..t...... ./ 2026-01-21 17:51:11.973997 | 2026-01-21 17:51:11.974456 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-21 17:51:12.405251 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006378 2026-01-21 17:51:12.636891 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008970 2026-01-21 17:51:12.656897 | 2026-01-21 17:51:12.657091 | PLAY [all] 2026-01-21 17:51:12.664160 | 2026-01-21 17:51:12.664256 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-01-21 17:51:13.112993 | controller | changed 2026-01-21 17:51:13.119394 | 2026-01-21 17:51:13.119461 | PLAY RECAP 2026-01-21 17:51:13.119524 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-01-21 17:51:13.119554 | 2026-01-21 17:51:13.243154 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-21 17:51:13.246241 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-01-21 17:51:13.785833 | 2026-01-21 17:51:13.785972 | PLAY [localhost] 2026-01-21 17:51:13.795793 | 2026-01-21 17:51:13.795860 | TASK [Generate Zuul manifest] 2026-01-21 17:51:13.816119 | localhost | ok 2026-01-21 17:51:13.830804 | 2026-01-21 17:51:13.830877 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-21 17:51:14.145208 | localhost | changed 2026-01-21 17:51:14.160680 | 2026-01-21 17:51:14.160764 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-21 17:51:14.192813 | localhost | ok 2026-01-21 17:51:14.199810 | 2026-01-21 17:51:14.199870 | TASK [Upload logs] 2026-01-21 17:51:14.222123 | localhost | ok 2026-01-21 17:51:14.283150 | 2026-01-21 17:51:14.283298 | TASK [Set zuul-log-path fact] 2026-01-21 17:51:14.303342 | localhost | ok 2026-01-21 17:51:14.316609 | 2026-01-21 17:51:14.316678 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-21 17:51:14.350258 | localhost | ok 2026-01-21 17:51:14.357464 | 2026-01-21 17:51:14.357533 | TASK [upload-logs : Create log directories] 2026-01-21 17:51:14.763911 | localhost | changed 2026-01-21 17:51:14.770416 | 2026-01-21 17:51:14.770509 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-01-21 17:51:15.196437 | localhost -> localhost | ok: Runtime: 0:00:00.005882 2026-01-21 17:51:15.203021 | 2026-01-21 17:51:15.203105 | TASK [upload-logs : Upload logs to log server] 2026-01-21 17:51:15.710803 | localhost | Output suppressed because no_log was given 2026-01-21 17:51:15.716086 | 2026-01-21 17:51:15.716148 | LOOP [upload-logs : Compress console log and json output] 2026-01-21 17:51:15.764519 | localhost | skipping: Conditional result was False 2026-01-21 17:51:15.771142 | localhost | skipping: Conditional result was False 2026-01-21 17:51:15.790076 | 2026-01-21 17:51:15.790238 | LOOP [upload-logs : Upload compressed console log and json output] 2026-01-21 17:51:15.841740 | localhost | skipping: Conditional result was False 2026-01-21 17:51:15.842070 | 2026-01-21 17:51:15.846420 | localhost | skipping: Conditional result was False 2026-01-21 17:51:15.863887 | 2026-01-21 17:51:15.864017 | LOOP [upload-logs : Upload console log and json output]