2026-03-11 23:24:58.341821 | Job console starting 2026-03-11 23:24:58.363583 | Updating git repos 2026-03-11 23:24:58.452434 | Cloning repos into workspace 2026-03-11 23:24:58.569714 | Restoring repo states 2026-03-11 23:24:58.642663 | Merging changes 2026-03-11 23:24:59.461852 | Checking out repos 2026-03-11 23:24:59.628423 | Preparing playbooks 2026-03-11 23:25:03.107982 | Running Ansible setup 2026-03-11 23:25:08.417684 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 23:25:09.063817 | 2026-03-11 23:25:09.063963 | PLAY [localhost] 2026-03-11 23:25:09.071636 | 2026-03-11 23:25:09.071707 | TASK [Gathering Facts] 2026-03-11 23:25:09.990232 | localhost | ok 2026-03-11 23:25:09.997946 | 2026-03-11 23:25:09.998027 | TASK [Setup log path fact] 2026-03-11 23:25:10.019331 | localhost | ok 2026-03-11 23:25:10.032939 | 2026-03-11 23:25:10.033079 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 23:25:10.062783 | localhost | ok 2026-03-11 23:25:10.070555 | 2026-03-11 23:25:10.070640 | TASK [emit-job-header : Print job information] 2026-03-11 23:25:10.112285 | # Job Information 2026-03-11 23:25:10.112497 | Ansible Version: 2.16.16 2026-03-11 23:25:10.112567 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-11 23:25:10.112615 | Pipeline: check 2026-03-11 23:25:10.112659 | Executor: 0a8996d2b663 2026-03-11 23:25:10.112703 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-11 23:25:10.112751 | Event ID: 5ebdc030-1da1-11f1-811d-5a677c16b3ff 2026-03-11 23:25:10.116376 | 2026-03-11 23:25:10.116438 | LOOP [emit-job-header : Print node information] 2026-03-11 23:25:10.200495 | localhost | ok: 2026-03-11 23:25:10.201364 | localhost | # Node Information 2026-03-11 23:25:10.201398 | localhost | Inventory Hostname: controller 2026-03-11 23:25:10.201420 | localhost | Hostname: np0000158553 2026-03-11 23:25:10.201440 | localhost | Username: zuul 2026-03-11 23:25:10.201463 | localhost | Distro: Debian 13.3 2026-03-11 23:25:10.201483 | localhost | Provider: yul1 2026-03-11 23:25:10.201504 | localhost | Region: ca-ymq-1 2026-03-11 23:25:10.201525 | localhost | Label: debian-trixie 2026-03-11 23:25:10.201544 | localhost | Product Name: OpenStack Nova 2026-03-11 23:25:10.201561 | localhost | Interface IP: 199.204.45.132 2026-03-11 23:25:10.214999 | 2026-03-11 23:25:10.215138 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 23:25:10.638329 | localhost -> localhost | changed 2026-03-11 23:25:10.649020 | 2026-03-11 23:25:10.649092 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 23:25:11.492415 | localhost -> localhost | changed 2026-03-11 23:25:11.503413 | 2026-03-11 23:25:11.503499 | PLAY [all] 2026-03-11 23:25:11.510690 | 2026-03-11 23:25:11.510762 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 23:25:11.748480 | controller -> localhost | ok 2026-03-11 23:25:11.756877 | 2026-03-11 23:25:11.756996 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 23:25:11.804770 | controller | ok 2026-03-11 23:25:11.826032 | controller | included: /var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 23:25:11.830994 | 2026-03-11 23:25:11.831057 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 23:25:13.086366 | controller -> localhost | Generating public/private rsa key pair. 2026-03-11 23:25:13.087094 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/work/730aa4edf6d64c83bcdd6d91e0844dd5_id_rsa 2026-03-11 23:25:13.087160 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/work/730aa4edf6d64c83bcdd6d91e0844dd5_id_rsa.pub 2026-03-11 23:25:13.087187 | controller -> localhost | The key fingerprint is: 2026-03-11 23:25:13.087210 | controller -> localhost | SHA256:2pk/Px7/Yb9oN/TrljACU/Bnasvxb9zrq5yDOjPLnmk zuul-build-sshkey 2026-03-11 23:25:13.087247 | controller -> localhost | The key's randomart image is: 2026-03-11 23:25:13.087268 | controller -> localhost | +---[RSA 3072]----+ 2026-03-11 23:25:13.087339 | controller -> localhost | | .. | 2026-03-11 23:25:13.087364 | controller -> localhost | | .. | 2026-03-11 23:25:13.087385 | controller -> localhost | | .. o | 2026-03-11 23:25:13.087405 | controller -> localhost | | o + | 2026-03-11 23:25:13.087425 | controller -> localhost | | S o+ | 2026-03-11 23:25:13.087444 | controller -> localhost | | o oo.+o . | 2026-03-11 23:25:13.087463 | controller -> localhost | | . + o+.=o+| 2026-03-11 23:25:13.087482 | controller -> localhost | | .E+.o==B=| 2026-03-11 23:25:13.087505 | controller -> localhost | | oBB++*XXO| 2026-03-11 23:25:13.087525 | controller -> localhost | +----[SHA256]-----+ 2026-03-11 23:25:13.087576 | controller -> localhost | ok: Runtime: 0:00:00.784872 2026-03-11 23:25:13.091772 | 2026-03-11 23:25:13.091836 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 23:25:13.124240 | controller | ok 2026-03-11 23:25:13.135716 | controller | included: /var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 23:25:13.142181 | 2026-03-11 23:25:13.142239 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 23:25:13.166922 | controller | skipping: Conditional result was False 2026-03-11 23:25:13.177540 | 2026-03-11 23:25:13.177602 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 23:25:13.722573 | controller | changed 2026-03-11 23:25:13.726797 | 2026-03-11 23:25:13.726873 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 23:25:13.951966 | controller | ok 2026-03-11 23:25:13.956494 | 2026-03-11 23:25:13.956560 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 23:25:14.506861 | controller | changed 2026-03-11 23:25:14.511036 | 2026-03-11 23:25:14.511103 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 23:25:15.029894 | controller | changed 2026-03-11 23:25:15.035793 | 2026-03-11 23:25:15.035889 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 23:25:15.061029 | controller | skipping: Conditional result was False 2026-03-11 23:25:15.070026 | 2026-03-11 23:25:15.070124 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 23:25:15.417218 | controller -> localhost | changed 2026-03-11 23:25:15.431019 | 2026-03-11 23:25:15.431110 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 23:25:15.732697 | controller -> localhost | Identity added: /var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/work/730aa4edf6d64c83bcdd6d91e0844dd5_id_rsa (zuul-build-sshkey) 2026-03-11 23:25:15.732886 | controller -> localhost | ok: Runtime: 0:00:00.013960 2026-03-11 23:25:15.737056 | 2026-03-11 23:25:15.737123 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 23:25:16.120295 | controller | ok 2026-03-11 23:25:16.124393 | 2026-03-11 23:25:16.124459 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 23:25:16.150131 | controller | skipping: Conditional result was False 2026-03-11 23:25:16.161777 | 2026-03-11 23:25:16.161847 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 23:25:16.526688 | controller | ok 2026-03-11 23:25:16.534829 | 2026-03-11 23:25:16.534892 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 23:25:17.615250 | controller | Output suppressed because no_log was given 2026-03-11 23:25:17.623098 | 2026-03-11 23:25:17.623245 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 23:25:17.841499 | controller | ok: "logs" 2026-03-11 23:25:17.841951 | controller | ok: All items complete 2026-03-11 23:25:17.842020 | 2026-03-11 23:25:18.022922 | controller | ok: "artifacts" 2026-03-11 23:25:18.205875 | controller | ok: "docs" 2026-03-11 23:25:18.216090 | 2026-03-11 23:25:18.216158 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 23:25:18.423447 | controller | changed: "logs" 2026-03-11 23:25:18.601183 | controller | changed: "artifacts" 2026-03-11 23:25:18.793084 | controller | changed: "docs" 2026-03-11 23:25:18.803662 | 2026-03-11 23:25:18.803804 | PLAY RECAP 2026-03-11 23:25:18.803861 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 23:25:18.803890 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 23:25:18.803912 | 2026-03-11 23:25:18.961489 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 23:25:18.964374 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 23:25:19.536564 | 2026-03-11 23:25:19.536693 | PLAY [all] 2026-03-11 23:25:19.547411 | 2026-03-11 23:25:19.547499 | TASK [setup-uv : Extract archive] 2026-03-11 23:25:22.213762 | controller | changed 2026-03-11 23:25:22.220174 | 2026-03-11 23:25:22.220255 | TASK [setup-uv : Print version] 2026-03-11 23:25:21.371299 | controller | uv 0.8.13 2026-03-11 23:25:22.888648 | controller | ok: Runtime: 0:00:00.015224 2026-03-11 23:25:22.895551 | 2026-03-11 23:25:22.895616 | PLAY RECAP 2026-03-11 23:25:22.895676 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 23:25:22.895709 | 2026-03-11 23:25:22.991401 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 23:25:22.993821 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 23:25:23.560178 | 2026-03-11 23:25:23.560317 | PLAY [all] 2026-03-11 23:25:23.570352 | 2026-03-11 23:25:23.570424 | TASK [Copy inventory file for Zuul] 2026-03-11 23:25:24.498285 | controller | changed 2026-03-11 23:25:24.502618 | 2026-03-11 23:25:24.502698 | TASK [Switch "ansible_host" to private IP] 2026-03-11 23:25:24.789626 | controller | changed: 1 replacements made 2026-03-11 23:25:24.795581 | 2026-03-11 23:25:24.795654 | TASK [Run Molecule scenario] 2026-03-11 23:25:23.998953 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-11 23:25:23.999042 | controller | Creating virtual environment at: .venv 2026-03-11 23:25:25.217620 | controller | Downloading cryptography (4.3MiB) 2026-03-11 23:25:25.221039 | controller | Downloading ansible-core (2.3MiB) 2026-03-11 23:25:25.221466 | controller | Downloading pygments (1.2MiB) 2026-03-11 23:25:25.381859 | controller | Downloading cryptography 2026-03-11 23:25:25.435238 | controller | Downloading pygments 2026-03-11 23:25:25.580143 | controller | Downloading ansible-core 2026-03-11 23:25:25.626092 | controller | Installed 36 packages in 45ms 2026-03-11 23:25:26.274608 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-11 23:25:26.274677 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-11 23:25:27.101365 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-11 23:25:27.101425 | controller | INFO default scenario not found, disabling shared state. 2026-03-11 23:25:27.101530 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-11 23:25:27.101651 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-11 23:31:34.451340 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-11 23:31:34.451401 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-11 23:31:34.451411 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-11 23:31:34.451439 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-11 23:31:34.458698 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 23:31:34.458971 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:31:34.464305 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 23:31:34.464332 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 23:31:34.464433 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 23:31:34.469747 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-11 23:31:35.312797 | controller | 2026-03-11 23:31:35.313006 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-11 23:31:35.373971 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-11 23:31:35.385368 | controller | INFO [upload-helm-chart > create] Executing 2026-03-11 23:31:35.387384 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:31:35.393584 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-11 23:31:35.393849 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:31:35.399118 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-11 23:31:36.240633 | controller | 2026-03-11 23:31:36.240874 | controller | PLAY [Converge] **************************************************************** 2026-03-11 23:31:36.241073 | controller | 2026-03-11 23:31:36.241444 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:31:36.241662 | controller | Wednesday 11 March 2026 23:31:36 +0000 (0:00:00.029) 0:00:00.029 ******* 2026-03-11 23:31:37.457301 | 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:31:37.462460 | controller | ok: [controller] 2026-03-11 23:31:37.462637 | controller | 2026-03-11 23:31:37.462843 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 23:31:37.463044 | controller | Wednesday 11 March 2026 23:31:37 +0000 (0:00:01.222) 0:00:01.251 ******* 2026-03-11 23:31:37.537288 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 23:31:37.537444 | controller | 2026-03-11 23:31:37.537636 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 23:31:37.537825 | controller | Wednesday 11 March 2026 23:31:37 +0000 (0:00:00.074) 0:00:01.326 ******* 2026-03-11 23:31:38.244695 | controller | changed: [controller] 2026-03-11 23:31:38.244860 | controller | 2026-03-11 23:31:38.245075 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 23:31:38.245309 | controller | Wednesday 11 March 2026 23:31:38 +0000 (0:00:00.706) 0:00:02.033 ******* 2026-03-11 23:31:38.287511 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 23:31:38.287524 | controller | 2026-03-11 23:31:38.287528 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 23:31:38.287532 | controller | Wednesday 11 March 2026 23:31:38 +0000 (0:00:00.041) 0:00:02.075 ******* 2026-03-11 23:31:38.672469 | controller | changed: [controller] 2026-03-11 23:31:38.672587 | controller | 2026-03-11 23:31:38.672762 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 23:31:38.672911 | controller | Wednesday 11 March 2026 23:31:38 +0000 (0:00:00.386) 0:00:02.461 ******* 2026-03-11 23:31:39.729216 | controller | changed: [controller] 2026-03-11 23:31:39.729371 | controller | 2026-03-11 23:31:39.729551 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 23:31:39.729736 | controller | Wednesday 11 March 2026 23:31:39 +0000 (0:00:01.056) 0:00:03.517 ******* 2026-03-11 23:31:40.107833 | controller | ok: [controller] 2026-03-11 23:31:40.107968 | controller | 2026-03-11 23:31:40.108183 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 23:31:40.108377 | controller | Wednesday 11 March 2026 23:31:40 +0000 (0:00:00.379) 0:00:03.897 ******* 2026-03-11 23:31:40.157080 | controller | skipping: [controller] 2026-03-11 23:31:40.157235 | controller | 2026-03-11 23:31:40.157474 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:31:40.157640 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 23:31:40.157856 | controller | 2026-03-11 23:31:40.157958 | controller | 2026-03-11 23:31:40.158162 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:31:40.158330 | controller | Wednesday 11 March 2026 23:31:40 +0000 (0:00:00.049) 0:00:03.946 ******* 2026-03-11 23:31:40.158501 | controller | =============================================================================== 2026-03-11 23:31:40.158682 | controller | Gathering Facts --------------------------------------------------------- 1.22s 2026-03-11 23:31:40.158877 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.06s 2026-03-11 23:31:40.159062 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.71s 2026-03-11 23:31:40.159256 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.39s 2026-03-11 23:31:40.159429 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s 2026-03-11 23:31:40.159618 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 23:31:40.159814 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-11 23:31:40.160004 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 23:31:40.241920 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-11 23:31:40.251799 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-11 23:31:41.051582 | controller | 2026-03-11 23:31:41.051757 | controller | PLAY [Converge] **************************************************************** 2026-03-11 23:31:41.051975 | controller | 2026-03-11 23:31:41.052135 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:31:41.052324 | controller | Wednesday 11 March 2026 23:31:41 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-03-11 23:31:42.071850 | 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:31:42.077044 | controller | ok: [controller] 2026-03-11 23:31:42.077357 | controller | 2026-03-11 23:31:42.077630 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 23:31:42.077960 | controller | Wednesday 11 March 2026 23:31:42 +0000 (0:00:01.025) 0:00:01.036 ******* 2026-03-11 23:31:42.149583 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 23:31:42.149784 | controller | 2026-03-11 23:31:42.150084 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 23:31:42.150314 | controller | Wednesday 11 March 2026 23:31:42 +0000 (0:00:00.072) 0:00:01.108 ******* 2026-03-11 23:31:42.850764 | controller | ok: [controller] 2026-03-11 23:31:42.850911 | controller | 2026-03-11 23:31:42.851145 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 23:31:42.851346 | controller | Wednesday 11 March 2026 23:31:42 +0000 (0:00:00.700) 0:00:01.809 ******* 2026-03-11 23:31:42.891524 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 23:31:42.891690 | controller | 2026-03-11 23:31:42.891902 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 23:31:42.892083 | controller | Wednesday 11 March 2026 23:31:42 +0000 (0:00:00.041) 0:00:01.850 ******* 2026-03-11 23:31:43.279984 | controller | ok: [controller] 2026-03-11 23:31:43.280151 | controller | 2026-03-11 23:31:43.280416 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 23:31:43.280647 | controller | Wednesday 11 March 2026 23:31:43 +0000 (0:00:00.387) 0:00:02.238 ******* 2026-03-11 23:31:44.311314 | controller | ok: [controller] 2026-03-11 23:31:44.311453 | controller | 2026-03-11 23:31:44.311665 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 23:31:44.311863 | controller | Wednesday 11 March 2026 23:31:44 +0000 (0:00:01.031) 0:00:03.270 ******* 2026-03-11 23:31:44.688050 | controller | ok: [controller] 2026-03-11 23:31:44.688198 | controller | 2026-03-11 23:31:44.688410 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 23:31:44.688602 | controller | Wednesday 11 March 2026 23:31:44 +0000 (0:00:00.376) 0:00:03.646 ******* 2026-03-11 23:31:44.726755 | controller | skipping: [controller] 2026-03-11 23:31:44.726906 | controller | 2026-03-11 23:31:44.727114 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:31:44.727337 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 23:31:44.727503 | controller | 2026-03-11 23:31:44.727665 | controller | 2026-03-11 23:31:44.727833 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:31:44.728022 | controller | Wednesday 11 March 2026 23:31:44 +0000 (0:00:00.039) 0:00:03.686 ******* 2026-03-11 23:31:44.728185 | controller | =============================================================================== 2026-03-11 23:31:44.728375 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.03s 2026-03-11 23:31:44.728551 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-03-11 23:31:44.728730 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.70s 2026-03-11 23:31:44.728912 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.39s 2026-03-11 23:31:44.729092 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s 2026-03-11 23:31:44.729325 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 23:31:44.729512 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 23:31:44.729701 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-03-11 23:31:44.813964 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-11 23:31:44.825148 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-11 23:31:45.614371 | controller | 2026-03-11 23:31:45.614481 | controller | PLAY [Side effect] ************************************************************* 2026-03-11 23:31:45.614691 | controller | 2026-03-11 23:31:45.614828 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:31:45.615015 | controller | Wednesday 11 March 2026 23:31:45 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-03-11 23:31:46.634707 | 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:31:46.640059 | controller | ok: [controller] 2026-03-11 23:31:46.640240 | controller | 2026-03-11 23:31:46.640448 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 23:31:46.640631 | controller | Wednesday 11 March 2026 23:31:46 +0000 (0:00:01.025) 0:00:01.036 ******* 2026-03-11 23:31:46.711163 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 23:31:46.711320 | controller | 2026-03-11 23:31:46.711519 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 23:31:46.711735 | controller | Wednesday 11 March 2026 23:31:46 +0000 (0:00:00.071) 0:00:01.107 ******* 2026-03-11 23:31:47.400328 | controller | changed: [controller] 2026-03-11 23:31:47.400415 | controller | 2026-03-11 23:31:47.400570 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 23:31:47.400694 | controller | Wednesday 11 March 2026 23:31:47 +0000 (0:00:00.689) 0:00:01.796 ******* 2026-03-11 23:31:47.443605 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 23:31:47.443686 | controller | 2026-03-11 23:31:47.443774 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 23:31:47.443859 | controller | Wednesday 11 March 2026 23:31:47 +0000 (0:00:00.041) 0:00:01.837 ******* 2026-03-11 23:31:47.829480 | controller | ok: [controller] 2026-03-11 23:31:47.829622 | controller | 2026-03-11 23:31:47.829884 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 23:31:47.830084 | controller | Wednesday 11 March 2026 23:31:47 +0000 (0:00:00.387) 0:00:02.225 ******* 2026-03-11 23:31:48.851017 | controller | ok: [controller] 2026-03-11 23:31:48.851052 | controller | 2026-03-11 23:31:48.851061 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 23:31:48.851069 | controller | Wednesday 11 March 2026 23:31:48 +0000 (0:00:01.021) 0:00:03.246 ******* 2026-03-11 23:31:49.225205 | controller | ok: [controller] 2026-03-11 23:31:49.225256 | controller | 2026-03-11 23:31:49.225333 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 23:31:49.225418 | controller | Wednesday 11 March 2026 23:31:49 +0000 (0:00:00.372) 0:00:03.618 ******* 2026-03-11 23:31:49.520185 | controller | changed: [controller] => (item=/file) 2026-03-11 23:31:49.520224 | controller | 2026-03-11 23:31:49.520306 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:31:49.520398 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 23:31:49.520477 | controller | 2026-03-11 23:31:49.520547 | controller | 2026-03-11 23:31:49.521284 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:31:49.521335 | controller | Wednesday 11 March 2026 23:31:49 +0000 (0:00:00.298) 0:00:03.916 ******* 2026-03-11 23:31:49.521344 | controller | =============================================================================== 2026-03-11 23:31:49.521352 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-03-11 23:31:49.521360 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.02s 2026-03-11 23:31:49.521368 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.69s 2026-03-11 23:31:49.521376 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.39s 2026-03-11 23:31:49.521383 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.37s 2026-03-11 23:31:49.521399 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.30s 2026-03-11 23:31:49.521407 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 23:31:49.521497 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 23:31:49.609221 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-11 23:31:49.618261 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-11 23:31:50.404877 | controller | 2026-03-11 23:31:50.404988 | controller | PLAY [Verify] ****************************************************************** 2026-03-11 23:31:50.405246 | controller | 2026-03-11 23:31:50.405416 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 23:31:50.405606 | controller | Wednesday 11 March 2026 23:31:50 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-03-11 23:31:51.431138 | 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:31:51.437789 | controller | ok: [controller] 2026-03-11 23:31:51.437815 | controller | 2026-03-11 23:31:51.437819 | controller | TASK [Stat file 1] ************************************************************* 2026-03-11 23:31:51.437823 | controller | Wednesday 11 March 2026 23:31:51 +0000 (0:00:01.029) 0:00:01.038 ******* 2026-03-11 23:31:51.813361 | controller | ok: [controller] 2026-03-11 23:31:51.813400 | controller | 2026-03-11 23:31:51.813416 | controller | TASK [Stat file 2] ************************************************************* 2026-03-11 23:31:51.813467 | controller | Wednesday 11 March 2026 23:31:51 +0000 (0:00:00.378) 0:00:01.417 ******* 2026-03-11 23:31:52.091735 | controller | ok: [controller] 2026-03-11 23:31:52.091770 | controller | 2026-03-11 23:31:52.091779 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-11 23:31:52.091787 | controller | Wednesday 11 March 2026 23:31:52 +0000 (0:00:00.273) 0:00:01.691 ******* 2026-03-11 23:31:52.360728 | controller | ok: [controller] 2026-03-11 23:31:52.360869 | controller | 2026-03-11 23:31:52.361114 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-11 23:31:52.361366 | controller | Wednesday 11 March 2026 23:31:52 +0000 (0:00:00.273) 0:00:01.964 ******* 2026-03-11 23:31:52.641794 | controller | ok: [controller] 2026-03-11 23:31:52.641933 | controller | 2026-03-11 23:31:52.642146 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-11 23:31:52.642339 | controller | Wednesday 11 March 2026 23:31:52 +0000 (0:00:00.281) 0:00:02.246 ******* 2026-03-11 23:31:52.670431 | controller | ok: [controller] => { 2026-03-11 23:31:52.670597 | controller | "changed": false, 2026-03-11 23:31:52.670798 | controller | "msg": "All assertions passed" 2026-03-11 23:31:52.670977 | controller | } 2026-03-11 23:31:52.671140 | controller | 2026-03-11 23:31:52.671314 | controller | PLAY RECAP ********************************************************************* 2026-03-11 23:31:52.671508 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 23:31:52.671743 | controller | 2026-03-11 23:31:52.671903 | controller | 2026-03-11 23:31:52.672081 | controller | TASKS RECAP ******************************************************************** 2026-03-11 23:31:52.672264 | controller | Wednesday 11 March 2026 23:31:52 +0000 (0:00:00.029) 0:00:02.275 ******* 2026-03-11 23:31:52.672431 | controller | =============================================================================== 2026-03-11 23:31:52.672608 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-03-11 23:31:52.672802 | controller | Stat file 1 ------------------------------------------------------------- 0.38s 2026-03-11 23:31:52.672988 | controller | Stat removed file 2 ----------------------------------------------------- 0.28s 2026-03-11 23:31:52.673215 | controller | Stat file 2 ------------------------------------------------------------- 0.27s 2026-03-11 23:31:52.673400 | controller | Stat removed file 1 ----------------------------------------------------- 0.27s 2026-03-11 23:31:52.673578 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-03-11 23:31:52.754830 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-11 23:31:52.765321 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 23:31:52.765723 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 23:31:52.772050 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 23:31:52.772103 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 23:31:52.772163 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 23:31:52.772466 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-11 23:31:53.059501 | controller | ok: Runtime: 0:06:28.925992 2026-03-11 23:31:53.063813 | 2026-03-11 23:31:53.063877 | PLAY RECAP 2026-03-11 23:31:53.063935 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 23:31:53.063966 | 2026-03-11 23:31:53.166234 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 23:31:53.169243 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 23:31:53.737440 | 2026-03-11 23:31:53.737561 | PLAY [all] 2026-03-11 23:31:53.747022 | 2026-03-11 23:31:53.747091 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 23:31:53.792381 | controller | skipping: Conditional result was False 2026-03-11 23:31:53.800754 | 2026-03-11 23:31:53.800925 | TASK [fetch-output : Set log path for single node] 2026-03-11 23:31:53.843895 | controller | ok 2026-03-11 23:31:53.848602 | 2026-03-11 23:31:53.848704 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 23:31:54.235884 | controller -> localhost | ok: "/var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/work/logs" 2026-03-11 23:31:54.446667 | controller -> localhost | changed: "/var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/work/artifacts" 2026-03-11 23:31:54.685770 | controller -> localhost | changed: "/var/lib/zuul/builds/730aa4edf6d64c83bcdd6d91e0844dd5/work/docs" 2026-03-11 23:31:54.705617 | 2026-03-11 23:31:54.705759 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 23:31:55.329949 | controller | changed: .d..t...... ./ 2026-03-11 23:31:55.330177 | controller | changed: All items complete 2026-03-11 23:31:55.330215 | 2026-03-11 23:31:55.810547 | controller | changed: .d..t...... ./ 2026-03-11 23:31:56.279965 | controller | changed: .d..t...... ./ 2026-03-11 23:31:56.292090 | 2026-03-11 23:31:56.292179 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 23:31:56.689546 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006546 2026-03-11 23:31:56.893128 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006518 2026-03-11 23:31:56.909832 | 2026-03-11 23:31:56.909912 | PLAY [all] 2026-03-11 23:31:56.915313 | 2026-03-11 23:31:56.915376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 23:31:57.353145 | controller | changed 2026-03-11 23:31:57.358740 | 2026-03-11 23:31:57.358788 | PLAY RECAP 2026-03-11 23:31:57.358830 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 23:31:57.358852 | 2026-03-11 23:31:57.459328 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 23:31:57.461927 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 23:31:57.996002 | 2026-03-11 23:31:57.996119 | PLAY [localhost] 2026-03-11 23:31:58.007039 | 2026-03-11 23:31:58.007140 | TASK [Generate Zuul manifest] 2026-03-11 23:31:58.026159 | localhost | ok 2026-03-11 23:31:58.042096 | 2026-03-11 23:31:58.042174 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 23:31:58.341492 | localhost | changed 2026-03-11 23:31:58.352058 | 2026-03-11 23:31:58.352129 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 23:31:58.383886 | localhost | ok 2026-03-11 23:31:58.392546 | 2026-03-11 23:31:58.392609 | TASK [Upload logs] 2026-03-11 23:31:58.412213 | localhost | ok 2026-03-11 23:31:58.468934 | 2026-03-11 23:31:58.469058 | TASK [Set zuul-log-path fact] 2026-03-11 23:31:58.489102 | localhost | ok 2026-03-11 23:31:58.501354 | 2026-03-11 23:31:58.501420 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 23:31:58.532718 | localhost | ok 2026-03-11 23:31:58.541094 | 2026-03-11 23:31:58.541158 | TASK [upload-logs : Create log directories] 2026-03-11 23:31:58.928418 | localhost | changed 2026-03-11 23:31:58.934219 | 2026-03-11 23:31:58.934286 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 23:31:59.329871 | localhost -> localhost | ok: Runtime: 0:00:00.005375 2026-03-11 23:31:59.336319 | 2026-03-11 23:31:59.336380 | TASK [upload-logs : Upload logs to log server] 2026-03-11 23:31:59.773084 | localhost | Output suppressed because no_log was given 2026-03-11 23:31:59.776807 | 2026-03-11 23:31:59.776869 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 23:31:59.818616 | localhost | skipping: Conditional result was False 2026-03-11 23:31:59.825543 | localhost | skipping: Conditional result was False 2026-03-11 23:31:59.839060 | 2026-03-11 23:31:59.839183 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 23:31:59.884910 | localhost | skipping: Conditional result was False 2026-03-11 23:31:59.885468 | 2026-03-11 23:31:59.890872 | localhost | skipping: Conditional result was False 2026-03-11 23:31:59.903190 | 2026-03-11 23:31:59.903308 | LOOP [upload-logs : Upload console log and json output]