2026-03-11 23:16:24.897934 | Job console starting 2026-03-11 23:16:24.935161 | Updating git repos 2026-03-11 23:16:24.965931 | Cloning repos into workspace 2026-03-11 23:16:25.128675 | Restoring repo states 2026-03-11 23:16:25.143733 | Merging changes 2026-03-11 23:16:25.786386 | Checking out repos 2026-03-11 23:16:26.068886 | Preparing playbooks 2026-03-11 23:16:27.961086 | Running Ansible setup 2026-03-11 23:16:31.575594 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 23:16:32.121198 | 2026-03-11 23:16:32.121327 | PLAY [localhost] 2026-03-11 23:16:32.128429 | 2026-03-11 23:16:32.128498 | TASK [Gathering Facts] 2026-03-11 23:16:32.956642 | localhost | ok 2026-03-11 23:16:32.967942 | 2026-03-11 23:16:32.968040 | TASK [Setup log path fact] 2026-03-11 23:16:32.986694 | localhost | ok 2026-03-11 23:16:33.001521 | 2026-03-11 23:16:33.001585 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 23:16:33.030652 | localhost | ok 2026-03-11 23:16:33.037453 | 2026-03-11 23:16:33.037519 | TASK [emit-job-header : Print job information] 2026-03-11 23:16:33.078386 | # Job Information 2026-03-11 23:16:33.078599 | Ansible Version: 2.16.16 2026-03-11 23:16:33.078672 | Job: ansible-collection-kubernetes-molecule-helm 2026-03-11 23:16:33.078724 | Pipeline: check 2026-03-11 23:16:33.078770 | Executor: 0a8996d2b663 2026-03-11 23:16:33.078814 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-11 23:16:33.078864 | Event ID: 2a19e8f0-1da0-11f1-90c2-31080bc8066f 2026-03-11 23:16:33.083210 | 2026-03-11 23:16:33.083295 | LOOP [emit-job-header : Print node information] 2026-03-11 23:16:33.165866 | localhost | ok: 2026-03-11 23:16:33.166137 | localhost | # Node Information 2026-03-11 23:16:33.166202 | localhost | Inventory Hostname: controller 2026-03-11 23:16:33.166254 | localhost | Hostname: np0000158525 2026-03-11 23:16:33.166300 | localhost | Username: zuul 2026-03-11 23:16:33.166352 | localhost | Distro: Debian 13.3 2026-03-11 23:16:33.166397 | localhost | Provider: yul1 2026-03-11 23:16:33.166441 | localhost | Region: ca-ymq-1 2026-03-11 23:16:33.166484 | localhost | Label: debian-trixie 2026-03-11 23:16:33.166527 | localhost | Product Name: OpenStack Nova 2026-03-11 23:16:33.166569 | localhost | Interface IP: 199.204.45.22 2026-03-11 23:16:33.182093 | 2026-03-11 23:16:33.182206 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 23:16:33.582031 | localhost -> localhost | changed 2026-03-11 23:16:33.586909 | 2026-03-11 23:16:33.586976 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 23:16:34.604117 | localhost -> localhost | changed 2026-03-11 23:16:34.613152 | 2026-03-11 23:16:34.613226 | PLAY [all] 2026-03-11 23:16:34.621999 | 2026-03-11 23:16:34.622068 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 23:16:34.860559 | controller -> localhost | ok 2026-03-11 23:16:34.869816 | 2026-03-11 23:16:34.869910 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 23:16:34.902441 | controller | ok 2026-03-11 23:16:34.918625 | controller | included: /var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 23:16:34.923809 | 2026-03-11 23:16:34.923872 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 23:16:36.042762 | controller -> localhost | Generating public/private rsa key pair. 2026-03-11 23:16:36.042941 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/work/ccf936005ba94da4b56305d1c84d39b2_id_rsa 2026-03-11 23:16:36.042971 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/work/ccf936005ba94da4b56305d1c84d39b2_id_rsa.pub 2026-03-11 23:16:36.042993 | controller -> localhost | The key fingerprint is: 2026-03-11 23:16:36.043014 | controller -> localhost | SHA256:dGqmNYYpAqG2z77HXIjiZSZs9RizehpxUNcuOR0FS/I zuul-build-sshkey 2026-03-11 23:16:36.043046 | controller -> localhost | The key's randomart image is: 2026-03-11 23:16:36.043068 | controller -> localhost | +---[RSA 3072]----+ 2026-03-11 23:16:36.043093 | controller -> localhost | |. . o.oo. | 2026-03-11 23:16:36.043114 | controller -> localhost | |... . +o. | 2026-03-11 23:16:36.043134 | controller -> localhost | |oo +Eo . | 2026-03-11 23:16:36.043153 | controller -> localhost | |..o+ + * o | 2026-03-11 23:16:36.043172 | controller -> localhost | |.oo.O * S | 2026-03-11 23:16:36.043191 | controller -> localhost | | ==O + B . | 2026-03-11 23:16:36.043210 | controller -> localhost | |o.Boo o | 2026-03-11 23:16:36.043230 | controller -> localhost | | oo. + | 2026-03-11 23:16:36.043251 | controller -> localhost | | .ooo | 2026-03-11 23:16:36.043271 | controller -> localhost | +----[SHA256]-----+ 2026-03-11 23:16:36.043324 | controller -> localhost | ok: Runtime: 0:00:00.698244 2026-03-11 23:16:36.047556 | 2026-03-11 23:16:36.047621 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 23:16:36.076327 | controller | ok 2026-03-11 23:16:36.084033 | controller | included: /var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 23:16:36.090400 | 2026-03-11 23:16:36.090461 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 23:16:36.115007 | controller | skipping: Conditional result was False 2026-03-11 23:16:36.120656 | 2026-03-11 23:16:36.120721 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 23:16:36.664721 | controller | changed 2026-03-11 23:16:36.668882 | 2026-03-11 23:16:36.668948 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 23:16:36.886120 | controller | ok 2026-03-11 23:16:36.891801 | 2026-03-11 23:16:36.891866 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 23:16:37.414645 | controller | changed 2026-03-11 23:16:37.420677 | 2026-03-11 23:16:37.420774 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 23:16:37.945459 | controller | changed 2026-03-11 23:16:37.951166 | 2026-03-11 23:16:37.951235 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 23:16:37.975416 | controller | skipping: Conditional result was False 2026-03-11 23:16:37.985243 | 2026-03-11 23:16:37.985311 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 23:16:38.385502 | controller -> localhost | changed 2026-03-11 23:16:38.399096 | 2026-03-11 23:16:38.399188 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 23:16:38.704357 | controller -> localhost | Identity added: /var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/work/ccf936005ba94da4b56305d1c84d39b2_id_rsa (zuul-build-sshkey) 2026-03-11 23:16:38.704584 | controller -> localhost | ok: Runtime: 0:00:00.019616 2026-03-11 23:16:38.708915 | 2026-03-11 23:16:38.709009 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 23:16:39.052923 | controller | ok 2026-03-11 23:16:39.058208 | 2026-03-11 23:16:39.058327 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 23:16:39.083278 | controller | skipping: Conditional result was False 2026-03-11 23:16:39.095190 | 2026-03-11 23:16:39.095280 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 23:16:39.437983 | controller | ok 2026-03-11 23:16:39.445449 | 2026-03-11 23:16:39.445525 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 23:16:40.485493 | controller | Output suppressed because no_log was given 2026-03-11 23:16:40.494468 | 2026-03-11 23:16:40.494547 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 23:16:40.720951 | controller | ok: "logs" 2026-03-11 23:16:40.721332 | controller | ok: All items complete 2026-03-11 23:16:40.721360 | 2026-03-11 23:16:40.894353 | controller | ok: "artifacts" 2026-03-11 23:16:41.076472 | controller | ok: "docs" 2026-03-11 23:16:41.085068 | 2026-03-11 23:16:41.085263 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 23:16:41.293399 | controller | changed: "logs" 2026-03-11 23:16:41.483147 | controller | changed: "artifacts" 2026-03-11 23:16:41.667443 | controller | changed: "docs" 2026-03-11 23:16:41.685066 | 2026-03-11 23:16:41.685203 | PLAY RECAP 2026-03-11 23:16:41.685266 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 23:16:41.685309 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 23:16:41.685338 | 2026-03-11 23:16:41.828591 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 23:16:41.830114 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 23:16:42.392534 | 2026-03-11 23:16:42.392771 | PLAY [all] 2026-03-11 23:16:42.406337 | 2026-03-11 23:16:42.406452 | TASK [setup-uv : Extract archive] 2026-03-11 23:16:44.764781 | controller | changed 2026-03-11 23:16:44.771455 | 2026-03-11 23:16:44.771528 | TASK [setup-uv : Print version] 2026-03-11 23:16:45.126952 | controller | uv 0.8.13 2026-03-11 23:16:45.301526 | controller | ok: Runtime: 0:00:00.013400 2026-03-11 23:16:45.309263 | 2026-03-11 23:16:45.309334 | PLAY RECAP 2026-03-11 23:16:45.309396 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 23:16:45.309432 | 2026-03-11 23:16:45.411369 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 23:16:45.450617 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 23:16:46.039438 | 2026-03-11 23:16:46.039619 | PLAY [all] 2026-03-11 23:16:46.050332 | 2026-03-11 23:16:46.050427 | TASK [Copy inventory file for Zuul] 2026-03-11 23:16:47.044420 | controller | changed 2026-03-11 23:16:47.050753 | 2026-03-11 23:16:47.050827 | TASK [Switch "ansible_host" to private IP] 2026-03-11 23:16:47.345807 | controller | changed: 1 replacements made 2026-03-11 23:16:47.353909 | 2026-03-11 23:16:47.354002 | TASK [Run Molecule scenario] 2026-03-11 23:16:47.805290 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-11 23:16:47.805398 | controller | Creating virtual environment at: .venv 2026-03-11 23:16:48.094684 | controller | Downloading pygments (1.2MiB) 2026-03-11 23:16:48.096059 | controller | Downloading cryptography (4.3MiB) 2026-03-11 23:16:48.096458 | controller | Downloading ansible-core (2.3MiB) 2026-03-11 23:16:48.274214 | controller | Downloading cryptography 2026-03-11 23:16:48.320566 | controller | Downloading pygments 2026-03-11 23:16:48.475658 | controller | Downloading ansible-core 2026-03-11 23:16:48.523310 | controller | Installed 36 packages in 46ms 2026-03-11 23:16:49.189256 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-11 23:16:49.189311 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-11 23:16:50.080852 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-11 23:16:50.080891 | controller | INFO default scenario not found, disabling shared state. 2026-03-11 23:16:50.081054 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-11 23:16:50.081085 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-03-11 23:17:09.150273 | controller | INFO [helm > dependency] Executing 2026-03-11 23:17:09.150324 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-03-11 23:17:09.150531 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-03-11 23:17:09.150681 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-11 23:17:09.158234 | controller | INFO [helm > cleanup] Executing 2026-03-11 23:17:09.158548 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:17:09.164199 | controller | INFO [helm > destroy] Executing 2026-03-11 23:17:09.164229 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-11 23:17:09.164288 | controller | INFO [helm > destroy] Executed: Successful 2026-03-11 23:17:09.169666 | controller | INFO [helm > syntax] Executing 2026-03-11 23:17:10.071797 | controller | 2026-03-11 23:17:10.071997 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-03-11 23:17:10.130197 | controller | INFO [helm > syntax] Executed: Successful 2026-03-11 23:17:10.141996 | controller | INFO [helm > create] Executing 2026-03-11 23:17:10.143476 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:17:10.148738 | controller | INFO [helm > prepare] Executing 2026-03-11 23:17:10.968903 | controller | 2026-03-11 23:17:10.968971 | controller | PLAY [Prepare] ***************************************************************** 2026-03-11 23:17:10.969188 | controller | 2026-03-11 23:17:10.969420 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:17:10.969632 | controller | Wednesday 11 March 2026 23:17:10 +0000 (0:00:00.028) 0:00:00.028 ******* 2026-03-11 23:17:12.222016 | 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-11 23:17:12.230319 | controller | ok: [controller] 2026-03-11 23:17:12.230475 | controller | 2026-03-11 23:17:12.230669 | controller | TASK [Install Debian packages] ************************************************* 2026-03-11 23:17:12.230870 | controller | Wednesday 11 March 2026 23:17:12 +0000 (0:00:01.261) 0:00:01.290 ******* 2026-03-11 23:17:17.284901 | controller | ok: [controller] 2026-03-11 23:17:17.285030 | controller | 2026-03-11 23:17:17.285224 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:17:17.285439 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 23:17:17.285643 | controller | 2026-03-11 23:17:17.285810 | controller | 2026-03-11 23:17:17.285988 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:17:17.286179 | controller | Wednesday 11 March 2026 23:17:17 +0000 (0:00:05.054) 0:00:06.345 ******* 2026-03-11 23:17:17.286353 | controller | =============================================================================== 2026-03-11 23:17:17.286537 | controller | Install Debian packages ------------------------------------------------- 5.05s 2026-03-11 23:17:17.286719 | controller | Gathering Facts --------------------------------------------------------- 1.26s 2026-03-11 23:17:17.402218 | controller | INFO [helm > prepare] Executed: Successful 2026-03-11 23:17:17.411904 | controller | INFO [helm > converge] Executing 2026-03-11 23:17:18.225649 | controller | 2026-03-11 23:17:18.225744 | controller | PLAY [Converge] **************************************************************** 2026-03-11 23:17:18.225925 | controller | 2026-03-11 23:17:18.226127 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:17:18.226316 | controller | Wednesday 11 March 2026 23:17:18 +0000 (0:00:00.013) 0:00:00.013 ******* 2026-03-11 23:17:20.248287 | 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-11 23:17:20.254821 | controller | ok: [controller] 2026-03-11 23:17:20.254999 | controller | 2026-03-11 23:17:20.255246 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-11 23:17:20.255444 | controller | Wednesday 11 March 2026 23:17:20 +0000 (0:00:02.029) 0:00:02.042 ******* 2026-03-11 23:17:20.713133 | controller | ok: [controller] 2026-03-11 23:17:20.713267 | controller | 2026-03-11 23:17:20.713573 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-11 23:17:20.713656 | controller | Wednesday 11 March 2026 23:17:20 +0000 (0:00:00.458) 0:00:02.500 ******* 2026-03-11 23:17:21.709496 | controller | ok: [controller] 2026-03-11 23:17:21.709668 | controller | 2026-03-11 23:17:21.709882 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-11 23:17:21.710086 | controller | Wednesday 11 March 2026 23:17:21 +0000 (0:00:00.996) 0:00:03.497 ******* 2026-03-11 23:17:22.129740 | controller | changed: [controller] 2026-03-11 23:17:22.129762 | controller | 2026-03-11 23:17:22.129768 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-11 23:17:22.129773 | controller | Wednesday 11 March 2026 23:17:22 +0000 (0:00:00.417) 0:00:03.915 ******* 2026-03-11 23:17:22.179427 | controller | ok: [controller] => { 2026-03-11 23:17:22.179549 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-11 23:17:22.180495 | controller | } 2026-03-11 23:17:22.180512 | controller | 2026-03-11 23:17:22.180521 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-11 23:17:22.180528 | controller | Wednesday 11 March 2026 23:17:22 +0000 (0:00:00.049) 0:00:03.964 ******* 2026-03-11 23:17:23.110545 | 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-11 23:17:23.121757 | controller | changed: [controller] 2026-03-11 23:17:23.121958 | controller | 2026-03-11 23:17:23.122175 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-11 23:17:23.122387 | controller | Wednesday 11 March 2026 23:17:23 +0000 (0:00:00.944) 0:00:04.908 ******* 2026-03-11 23:17:25.106486 | controller | changed: [controller] 2026-03-11 23:17:25.106644 | controller | 2026-03-11 23:17:25.106849 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-11 23:17:25.107046 | controller | Wednesday 11 March 2026 23:17:25 +0000 (0:00:01.985) 0:00:06.894 ******* 2026-03-11 23:17:25.595818 | controller | ok: [controller] 2026-03-11 23:17:25.595918 | controller | 2026-03-11 23:17:25.596060 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-11 23:17:25.596193 | controller | Wednesday 11 March 2026 23:17:25 +0000 (0:00:00.488) 0:00:07.382 ******* 2026-03-11 23:17:25.984782 | controller | changed: [controller] 2026-03-11 23:17:25.984945 | controller | 2026-03-11 23:17:25.985147 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-11 23:17:25.985339 | controller | Wednesday 11 March 2026 23:17:25 +0000 (0:00:00.389) 0:00:07.772 ******* 2026-03-11 23:17:26.420965 | controller | ok: [controller] 2026-03-11 23:17:26.421022 | controller | 2026-03-11 23:17:26.421183 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-11 23:17:26.421385 | controller | Wednesday 11 March 2026 23:17:26 +0000 (0:00:00.433) 0:00:08.205 ******* 2026-03-11 23:17:26.698216 | controller | changed: [controller] 2026-03-11 23:17:26.698400 | controller | 2026-03-11 23:17:26.698652 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-11 23:17:26.698885 | controller | Wednesday 11 March 2026 23:17:26 +0000 (0:00:00.280) 0:00:08.485 ******* 2026-03-11 23:17:26.993324 | controller | ok: [controller] 2026-03-11 23:17:26.993374 | controller | 2026-03-11 23:17:26.993443 | controller | TASK [Install plugin] ********************************************************** 2026-03-11 23:17:26.993532 | controller | Wednesday 11 March 2026 23:17:26 +0000 (0:00:00.294) 0:00:08.780 ******* 2026-03-11 23:17:27.050865 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-11 23:17:27.051032 | controller | 2026-03-11 23:17:27.051292 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-11 23:17:27.051506 | controller | Wednesday 11 March 2026 23:17:27 +0000 (0:00:00.058) 0:00:08.838 ******* 2026-03-11 23:17:27.094280 | controller | ok: [controller] => { 2026-03-11 23:17:27.094376 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-11 23:17:27.094494 | controller | } 2026-03-11 23:17:27.094599 | controller | 2026-03-11 23:17:27.094727 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-11 23:17:27.094840 | controller | Wednesday 11 March 2026 23:17:27 +0000 (0:00:00.043) 0:00:08.881 ******* 2026-03-11 23:17:27.914585 | controller | changed: [controller] 2026-03-11 23:17:27.914628 | controller | 2026-03-11 23:17:27.914711 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-11 23:17:27.914802 | controller | Wednesday 11 March 2026 23:17:27 +0000 (0:00:00.820) 0:00:09.702 ******* 2026-03-11 23:17:29.919631 | controller | changed: [controller] 2026-03-11 23:17:29.919757 | controller | 2026-03-11 23:17:29.919939 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:17:29.920086 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 23:17:29.920203 | controller | 2026-03-11 23:17:29.920308 | controller | 2026-03-11 23:17:29.923160 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:17:29.923173 | controller | Wednesday 11 March 2026 23:17:29 +0000 (0:00:02.002) 0:00:11.704 ******* 2026-03-11 23:17:29.923180 | controller | =============================================================================== 2026-03-11 23:17:29.923188 | controller | Gathering Facts --------------------------------------------------------- 2.03s 2026-03-11 23:17:29.923195 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.00s 2026-03-11 23:17:29.923202 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.99s 2026-03-11 23:17:29.923209 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.00s 2026-03-11 23:17:29.923216 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.94s 2026-03-11 23:17:29.923223 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.82s 2026-03-11 23:17:29.923230 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.49s 2026-03-11 23:17:29.923237 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.46s 2026-03-11 23:17:29.923244 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s 2026-03-11 23:17:29.923251 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s 2026-03-11 23:17:29.923268 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-03-11 23:17:29.923276 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-03-11 23:17:29.923283 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-03-11 23:17:29.923290 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-03-11 23:17:29.923296 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-11 23:17:29.923303 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-03-11 23:17:30.014936 | controller | INFO [helm > converge] Executed: Successful 2026-03-11 23:17:30.027148 | controller | INFO [helm > idempotence] Executing 2026-03-11 23:17:30.884727 | controller | 2026-03-11 23:17:30.884969 | controller | PLAY [Converge] **************************************************************** 2026-03-11 23:17:30.885115 | controller | 2026-03-11 23:17:30.885319 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:17:30.885525 | controller | Wednesday 11 March 2026 23:17:30 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-03-11 23:17:31.948296 | 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-11 23:17:31.953509 | controller | ok: [controller] 2026-03-11 23:17:31.953591 | controller | 2026-03-11 23:17:31.953683 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-11 23:17:31.953770 | controller | Wednesday 11 March 2026 23:17:31 +0000 (0:00:01.068) 0:00:01.081 ******* 2026-03-11 23:17:32.389176 | controller | ok: [controller] 2026-03-11 23:17:32.389267 | controller | 2026-03-11 23:17:32.389375 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-11 23:17:32.389480 | controller | Wednesday 11 March 2026 23:17:32 +0000 (0:00:00.435) 0:00:01.517 ******* 2026-03-11 23:17:33.328853 | controller | ok: [controller] 2026-03-11 23:17:33.328894 | controller | 2026-03-11 23:17:33.328904 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-11 23:17:33.328913 | controller | Wednesday 11 March 2026 23:17:33 +0000 (0:00:00.938) 0:00:02.456 ******* 2026-03-11 23:17:33.755421 | controller | ok: [controller] 2026-03-11 23:17:33.755477 | controller | 2026-03-11 23:17:33.755522 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-11 23:17:33.755622 | controller | Wednesday 11 March 2026 23:17:33 +0000 (0:00:00.427) 0:00:02.883 ******* 2026-03-11 23:17:33.810703 | controller | ok: [controller] => { 2026-03-11 23:17:33.810898 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-11 23:17:33.811078 | controller | } 2026-03-11 23:17:33.811734 | controller | 2026-03-11 23:17:33.811780 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-11 23:17:33.811791 | controller | Wednesday 11 March 2026 23:17:33 +0000 (0:00:00.055) 0:00:02.938 ******* 2026-03-11 23:17:34.339504 | controller | ok: [controller] 2026-03-11 23:17:34.339567 | controller | 2026-03-11 23:17:34.339693 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-11 23:17:34.339873 | controller | Wednesday 11 March 2026 23:17:34 +0000 (0:00:00.526) 0:00:03.464 ******* 2026-03-11 23:17:36.013891 | controller | ok: [controller] 2026-03-11 23:17:36.013944 | controller | 2026-03-11 23:17:36.014096 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-11 23:17:36.014227 | controller | Wednesday 11 March 2026 23:17:36 +0000 (0:00:01.677) 0:00:05.142 ******* 2026-03-11 23:17:36.500227 | controller | ok: [controller] 2026-03-11 23:17:36.500332 | controller | 2026-03-11 23:17:36.500534 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-11 23:17:36.500784 | controller | Wednesday 11 March 2026 23:17:36 +0000 (0:00:00.485) 0:00:05.627 ******* 2026-03-11 23:17:36.894417 | controller | ok: [controller] 2026-03-11 23:17:36.894491 | controller | 2026-03-11 23:17:36.894613 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-11 23:17:36.894735 | controller | Wednesday 11 March 2026 23:17:36 +0000 (0:00:00.392) 0:00:06.019 ******* 2026-03-11 23:17:37.330084 | controller | ok: [controller] 2026-03-11 23:17:37.330140 | controller | 2026-03-11 23:17:37.330272 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-11 23:17:37.330399 | controller | Wednesday 11 March 2026 23:17:37 +0000 (0:00:00.437) 0:00:06.457 ******* 2026-03-11 23:17:37.612932 | controller | ok: [controller] 2026-03-11 23:17:37.612986 | controller | 2026-03-11 23:17:37.613031 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-11 23:17:37.613155 | controller | Wednesday 11 March 2026 23:17:37 +0000 (0:00:00.282) 0:00:06.740 ******* 2026-03-11 23:17:37.923733 | controller | ok: [controller] 2026-03-11 23:17:37.923874 | controller | 2026-03-11 23:17:37.924123 | controller | TASK [Install plugin] ********************************************************** 2026-03-11 23:17:37.924321 | controller | Wednesday 11 March 2026 23:17:37 +0000 (0:00:00.308) 0:00:07.048 ******* 2026-03-11 23:17:37.977191 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-11 23:17:37.977242 | controller | 2026-03-11 23:17:37.977311 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-11 23:17:37.977410 | controller | Wednesday 11 March 2026 23:17:37 +0000 (0:00:00.057) 0:00:07.105 ******* 2026-03-11 23:17:38.022362 | controller | ok: [controller] => { 2026-03-11 23:17:38.022504 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-11 23:17:38.022604 | controller | } 2026-03-11 23:17:38.022713 | controller | 2026-03-11 23:17:38.022842 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-11 23:17:38.022957 | controller | Wednesday 11 March 2026 23:17:38 +0000 (0:00:00.042) 0:00:07.148 ******* 2026-03-11 23:17:38.421362 | controller | ok: [controller] 2026-03-11 23:17:38.421420 | controller | 2026-03-11 23:17:38.421518 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-11 23:17:38.421639 | controller | Wednesday 11 March 2026 23:17:38 +0000 (0:00:00.400) 0:00:07.549 ******* 2026-03-11 23:17:40.001700 | controller | ok: [controller] 2026-03-11 23:17:40.001728 | controller | 2026-03-11 23:17:40.001733 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:17:40.001737 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 23:17:40.001740 | controller | 2026-03-11 23:17:40.001744 | controller | 2026-03-11 23:17:40.001747 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:17:40.001750 | controller | Wednesday 11 March 2026 23:17:39 +0000 (0:00:01.576) 0:00:09.125 ******* 2026-03-11 23:17:40.001754 | controller | =============================================================================== 2026-03-11 23:17:40.001757 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.68s 2026-03-11 23:17:40.001760 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.58s 2026-03-11 23:17:40.001763 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-03-11 23:17:40.001766 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.94s 2026-03-11 23:17:40.001770 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.53s 2026-03-11 23:17:40.001773 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.49s 2026-03-11 23:17:40.001776 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s 2026-03-11 23:17:40.001779 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.44s 2026-03-11 23:17:40.001783 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-03-11 23:17:40.001786 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.40s 2026-03-11 23:17:40.001799 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-03-11 23:17:40.001802 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-03-11 23:17:40.001805 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-03-11 23:17:40.001808 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-03-11 23:17:40.001811 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-03-11 23:17:40.001815 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-03-11 23:17:40.083832 | controller | INFO [helm > idempotence] Executed: Successful 2026-03-11 23:17:40.093707 | controller | INFO [helm > side_effect] Executing 2026-03-11 23:17:40.094228 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:17:40.101307 | controller | INFO [helm > verify] Executing 2026-03-11 23:17:40.879696 | controller | 2026-03-11 23:17:40.880021 | controller | PLAY [Verify] ****************************************************************** 2026-03-11 23:17:40.880264 | controller | 2026-03-11 23:17:40.880518 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:17:40.880771 | controller | Wednesday 11 March 2026 23:17:40 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-03-11 23:17:41.873866 | 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-11 23:17:41.880332 | controller | ok: [controller] 2026-03-11 23:17:41.880598 | controller | 2026-03-11 23:17:41.880873 | controller | TASK [Get the current Helm version] ******************************************** 2026-03-11 23:17:41.881144 | controller | Wednesday 11 March 2026 23:17:41 +0000 (0:00:01.000) 0:00:01.010 ******* 2026-03-11 23:17:42.313408 | controller | changed: [controller] 2026-03-11 23:17:42.313681 | controller | 2026-03-11 23:17:42.314134 | controller | TASK [Assert Helm version] ***************************************************** 2026-03-11 23:17:42.314399 | controller | Wednesday 11 March 2026 23:17:42 +0000 (0:00:00.432) 0:00:01.442 ******* 2026-03-11 23:17:42.346440 | controller | ok: [controller] => { 2026-03-11 23:17:42.346720 | controller | "changed": false, 2026-03-11 23:17:42.346977 | controller | "msg": "All assertions passed" 2026-03-11 23:17:42.347260 | controller | } 2026-03-11 23:17:42.347492 | controller | 2026-03-11 23:17:42.347738 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:17:42.348001 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 23:17:42.348225 | controller | 2026-03-11 23:17:42.348449 | controller | 2026-03-11 23:17:42.348689 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:17:42.348936 | controller | Wednesday 11 March 2026 23:17:42 +0000 (0:00:00.033) 0:00:01.476 ******* 2026-03-11 23:17:42.349170 | controller | =============================================================================== 2026-03-11 23:17:42.349410 | controller | Gathering Facts --------------------------------------------------------- 1.00s 2026-03-11 23:17:42.349653 | controller | Get the current Helm version -------------------------------------------- 0.43s 2026-03-11 23:17:42.349927 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-03-11 23:17:42.439058 | controller | INFO [helm > verify] Executed: Successful 2026-03-11 23:17:42.452289 | controller | INFO [helm > cleanup] Executing 2026-03-11 23:17:42.453003 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:17:42.463071 | controller | INFO [helm > destroy] Executing 2026-03-11 23:17:42.463251 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-11 23:17:42.463373 | controller | INFO [helm > destroy] Executed: Successful 2026-03-11 23:17:42.463766 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-11 23:17:42.938795 | controller | ok: Runtime: 0:00:54.808957 2026-03-11 23:17:42.944802 | 2026-03-11 23:17:42.944860 | PLAY RECAP 2026-03-11 23:17:42.944912 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 23:17:42.944933 | 2026-03-11 23:17:43.097341 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 23:17:43.099921 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 23:17:43.692573 | 2026-03-11 23:17:43.692730 | PLAY [all] 2026-03-11 23:17:43.703655 | 2026-03-11 23:17:43.703729 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 23:17:43.749991 | controller | skipping: Conditional result was False 2026-03-11 23:17:43.758008 | 2026-03-11 23:17:43.758088 | TASK [fetch-output : Set log path for single node] 2026-03-11 23:17:43.791094 | controller | ok 2026-03-11 23:17:43.794669 | 2026-03-11 23:17:43.794733 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 23:17:44.165798 | controller -> localhost | ok: "/var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/work/logs" 2026-03-11 23:17:44.372793 | controller -> localhost | changed: "/var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/work/artifacts" 2026-03-11 23:17:44.576486 | controller -> localhost | changed: "/var/lib/zuul/builds/ccf936005ba94da4b56305d1c84d39b2/work/docs" 2026-03-11 23:17:44.589727 | 2026-03-11 23:17:44.589896 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 23:17:45.295177 | controller | ok 2026-03-11 23:17:45.295666 | controller | changed: All items complete 2026-03-11 23:17:45.295750 | 2026-03-11 23:17:45.758129 | controller | changed: .d..t...... ./ 2026-03-11 23:17:46.277335 | controller | changed: .d..t...... ./ 2026-03-11 23:17:46.297819 | 2026-03-11 23:17:46.298002 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 23:17:46.712419 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006448 2026-03-11 23:17:46.973900 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008947 2026-03-11 23:17:46.991600 | 2026-03-11 23:17:46.991709 | PLAY [all] 2026-03-11 23:17:46.997813 | 2026-03-11 23:17:46.997886 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 23:17:47.455029 | controller | changed 2026-03-11 23:17:47.460288 | 2026-03-11 23:17:47.460390 | PLAY RECAP 2026-03-11 23:17:47.460503 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 23:17:47.460543 | 2026-03-11 23:17:47.577249 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 23:17:47.578570 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 23:17:48.148848 | 2026-03-11 23:17:48.149029 | PLAY [localhost] 2026-03-11 23:17:48.159402 | 2026-03-11 23:17:48.159475 | TASK [Generate Zuul manifest] 2026-03-11 23:17:48.181653 | localhost | ok 2026-03-11 23:17:48.198287 | 2026-03-11 23:17:48.198366 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 23:17:48.514895 | localhost | changed 2026-03-11 23:17:48.527107 | 2026-03-11 23:17:48.527188 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 23:17:48.559760 | localhost | ok 2026-03-11 23:17:48.568517 | 2026-03-11 23:17:48.568585 | TASK [Upload logs] 2026-03-11 23:17:48.591665 | localhost | ok 2026-03-11 23:17:48.654024 | 2026-03-11 23:17:48.654169 | TASK [Set zuul-log-path fact] 2026-03-11 23:17:48.677859 | localhost | ok 2026-03-11 23:17:48.689537 | 2026-03-11 23:17:48.689611 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 23:17:48.723652 | localhost | ok 2026-03-11 23:17:48.730004 | 2026-03-11 23:17:48.730077 | TASK [upload-logs : Create log directories] 2026-03-11 23:17:49.150229 | localhost | changed 2026-03-11 23:17:49.156895 | 2026-03-11 23:17:49.156989 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 23:17:49.570986 | localhost -> localhost | ok: Runtime: 0:00:00.006382 2026-03-11 23:17:49.576350 | 2026-03-11 23:17:49.576420 | TASK [upload-logs : Upload logs to log server] 2026-03-11 23:17:50.035294 | localhost | Output suppressed because no_log was given 2026-03-11 23:17:50.041018 | 2026-03-11 23:17:50.041125 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 23:17:50.090320 | localhost | skipping: Conditional result was False 2026-03-11 23:17:50.133019 | localhost | skipping: Conditional result was False 2026-03-11 23:17:50.146942 | 2026-03-11 23:17:50.147196 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 23:17:50.203697 | localhost | skipping: Conditional result was False 2026-03-11 23:17:50.204193 | 2026-03-11 23:17:50.208810 | localhost | skipping: Conditional result was False 2026-03-11 23:17:50.223922 | 2026-03-11 23:17:50.224234 | LOOP [upload-logs : Upload console log and json output]