2026-02-16 21:11:02.713083 | Job console starting 2026-02-16 21:11:02.725364 | Updating git repos 2026-02-16 21:11:02.750937 | Cloning repos into workspace 2026-02-16 21:11:02.800257 | Restoring repo states 2026-02-16 21:11:02.812773 | Merging changes 2026-02-16 21:11:03.425861 | Checking out repos 2026-02-16 21:11:03.654786 | Preparing playbooks 2026-02-16 21:11:05.984378 | Running Ansible setup 2026-02-16 21:11:09.436201 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:11:10.170584 | 2026-02-16 21:11:10.170850 | PLAY [localhost] 2026-02-16 21:11:10.181514 | 2026-02-16 21:11:10.181602 | TASK [Gathering Facts] 2026-02-16 21:11:11.113846 | localhost | ok 2026-02-16 21:11:11.124618 | 2026-02-16 21:11:11.124839 | TASK [Setup log path fact] 2026-02-16 21:11:11.150799 | localhost | ok 2026-02-16 21:11:11.177629 | 2026-02-16 21:11:11.177817 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:11:11.207722 | localhost | ok 2026-02-16 21:11:11.235377 | 2026-02-16 21:11:11.235563 | TASK [emit-job-header : Print job information] 2026-02-16 21:11:11.276293 | # Job Information 2026-02-16 21:11:11.276499 | Ansible Version: 2.16.15 2026-02-16 21:11:11.276548 | Job: ansible-collection-kubernetes-molecule-helm 2026-02-16 21:11:11.276581 | Pipeline: check 2026-02-16 21:11:11.276611 | Executor: 3a2793d2bd32 2026-02-16 21:11:11.276641 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/232 2026-02-16 21:11:11.276674 | Event ID: dd7fa8d0-0b7b-11f1-80a0-1b5177b5933d 2026-02-16 21:11:11.280977 | 2026-02-16 21:11:11.281075 | LOOP [emit-job-header : Print node information] 2026-02-16 21:11:11.361657 | localhost | ok: 2026-02-16 21:11:11.361866 | localhost | # Node Information 2026-02-16 21:11:11.361901 | localhost | Inventory Hostname: controller 2026-02-16 21:11:11.361923 | localhost | Hostname: np0000155828 2026-02-16 21:11:11.361943 | localhost | Username: zuul 2026-02-16 21:11:11.361965 | localhost | Distro: Debian 13.3 2026-02-16 21:11:11.361984 | localhost | Provider: yul1 2026-02-16 21:11:11.362002 | localhost | Region: ca-ymq-1 2026-02-16 21:11:11.362019 | localhost | Label: debian-trixie 2026-02-16 21:11:11.362038 | localhost | Product Name: OpenStack Nova 2026-02-16 21:11:11.362055 | localhost | Interface IP: 199.204.45.113 2026-02-16 21:11:11.379664 | 2026-02-16 21:11:11.379913 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 21:11:11.877739 | localhost -> localhost | changed 2026-02-16 21:11:11.883629 | 2026-02-16 21:11:11.883715 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 21:11:12.776196 | localhost -> localhost | changed 2026-02-16 21:11:12.784118 | 2026-02-16 21:11:12.784190 | PLAY [all] 2026-02-16 21:11:12.796392 | 2026-02-16 21:11:12.796503 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 21:11:13.017212 | controller -> localhost | ok 2026-02-16 21:11:13.026658 | 2026-02-16 21:11:13.026761 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 21:11:13.058631 | controller | ok 2026-02-16 21:11:13.072712 | controller | included: /var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 21:11:13.078628 | 2026-02-16 21:11:13.078688 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 21:11:15.226936 | controller -> localhost | Generating public/private rsa key pair. 2026-02-16 21:11:15.227676 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/work/25456133e52a4081ae12323a8873fc1e_id_rsa 2026-02-16 21:11:15.227714 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/work/25456133e52a4081ae12323a8873fc1e_id_rsa.pub 2026-02-16 21:11:15.227738 | controller -> localhost | The key fingerprint is: 2026-02-16 21:11:15.227759 | controller -> localhost | SHA256:Lu3lPgQ+IUdc/d0/oWmG+RLxN+kEmXF3n5SsmixO9uk zuul-build-sshkey 2026-02-16 21:11:15.227795 | controller -> localhost | The key's randomart image is: 2026-02-16 21:11:15.227816 | controller -> localhost | +---[RSA 3072]----+ 2026-02-16 21:11:15.227840 | controller -> localhost | | . ... . .| 2026-02-16 21:11:15.227861 | controller -> localhost | | o .. =o| 2026-02-16 21:11:15.227880 | controller -> localhost | | . .B.*| 2026-02-16 21:11:15.227900 | controller -> localhost | | . + . =.o+| 2026-02-16 21:11:15.227919 | controller -> localhost | | +So. B + +| 2026-02-16 21:11:15.227938 | controller -> localhost | | oo+.B * *.| 2026-02-16 21:11:15.227956 | controller -> localhost | | . *o+ * + o| 2026-02-16 21:11:15.227975 | controller -> localhost | | o +.+ . . | 2026-02-16 21:11:15.227998 | controller -> localhost | | ..+E. | 2026-02-16 21:11:15.228017 | controller -> localhost | +----[SHA256]-----+ 2026-02-16 21:11:15.228071 | controller -> localhost | ok: Runtime: 0:00:01.714268 2026-02-16 21:11:15.232380 | 2026-02-16 21:11:15.232441 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 21:11:15.264174 | controller | ok 2026-02-16 21:11:15.274218 | controller | included: /var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 21:11:15.281379 | 2026-02-16 21:11:15.281445 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 21:11:15.305694 | controller | skipping: Conditional result was False 2026-02-16 21:11:15.310578 | 2026-02-16 21:11:15.310669 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 21:11:15.915618 | controller | changed 2026-02-16 21:11:15.921320 | 2026-02-16 21:11:15.921386 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 21:11:16.141106 | controller | ok 2026-02-16 21:11:16.145502 | 2026-02-16 21:11:16.145565 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 21:11:16.697663 | controller | changed 2026-02-16 21:11:16.701983 | 2026-02-16 21:11:16.702048 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 21:11:17.216057 | controller | changed 2026-02-16 21:11:17.221665 | 2026-02-16 21:11:17.221728 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 21:11:17.244537 | controller | skipping: Conditional result was False 2026-02-16 21:11:17.253535 | 2026-02-16 21:11:17.253663 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 21:11:17.630548 | controller -> localhost | changed 2026-02-16 21:11:17.640124 | 2026-02-16 21:11:17.640187 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 21:11:17.924404 | controller -> localhost | Identity added: /var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/work/25456133e52a4081ae12323a8873fc1e_id_rsa (zuul-build-sshkey) 2026-02-16 21:11:17.924614 | controller -> localhost | ok: Runtime: 0:00:00.013549 2026-02-16 21:11:17.929079 | 2026-02-16 21:11:17.929141 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 21:11:18.283825 | controller | ok 2026-02-16 21:11:18.287516 | 2026-02-16 21:11:18.287577 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 21:11:18.311696 | controller | skipping: Conditional result was False 2026-02-16 21:11:18.324737 | 2026-02-16 21:11:18.324817 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 21:11:18.763334 | controller | ok 2026-02-16 21:11:18.769283 | 2026-02-16 21:11:18.769347 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 21:11:19.765474 | controller | Output suppressed because no_log was given 2026-02-16 21:11:19.773904 | 2026-02-16 21:11:19.773969 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 21:11:19.995728 | controller | ok: "logs" 2026-02-16 21:11:19.996018 | controller | ok: All items complete 2026-02-16 21:11:19.996047 | 2026-02-16 21:11:20.170615 | controller | ok: "artifacts" 2026-02-16 21:11:20.350085 | controller | ok: "docs" 2026-02-16 21:11:20.422901 | 2026-02-16 21:11:20.423025 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 21:11:20.626712 | controller | changed: "logs" 2026-02-16 21:11:20.814568 | controller | changed: "artifacts" 2026-02-16 21:11:21.000557 | controller | changed: "docs" 2026-02-16 21:11:21.012614 | 2026-02-16 21:11:21.012750 | PLAY RECAP 2026-02-16 21:11:21.012847 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 21:11:21.012919 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:11:21.012985 | 2026-02-16 21:11:21.146994 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:11:21.149634 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:11:21.797634 | 2026-02-16 21:11:21.797777 | PLAY [all] 2026-02-16 21:11:21.808613 | 2026-02-16 21:11:21.808689 | TASK [setup-uv : Extract archive] 2026-02-16 21:11:24.153592 | controller | changed 2026-02-16 21:11:24.164230 | 2026-02-16 21:11:24.164311 | TASK [setup-uv : Print version] 2026-02-16 21:11:24.551535 | controller | uv 0.8.13 2026-02-16 21:11:24.699906 | controller | ok: Runtime: 0:00:00.012081 2026-02-16 21:11:24.709606 | 2026-02-16 21:11:24.709657 | PLAY RECAP 2026-02-16 21:11:24.709727 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:11:24.709762 | 2026-02-16 21:11:24.833500 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:11:24.836341 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:11:25.429462 | 2026-02-16 21:11:25.429583 | PLAY [all] 2026-02-16 21:11:25.439834 | 2026-02-16 21:11:25.439909 | TASK [Copy inventory file for Zuul] 2026-02-16 21:11:26.322402 | controller | changed 2026-02-16 21:11:26.326989 | 2026-02-16 21:11:26.327074 | TASK [Switch "ansible_host" to private IP] 2026-02-16 21:11:26.612781 | controller | changed: 1 replacements made 2026-02-16 21:11:26.618727 | 2026-02-16 21:11:26.618794 | TASK [Run Molecule scenario] 2026-02-16 21:11:27.062298 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-16 21:11:27.062369 | controller | Creating virtual environment at: .venv 2026-02-16 21:11:27.108345 | controller | Downloading pygments (1.2MiB) 2026-02-16 21:11:27.109345 | controller | Downloading ansible-core (2.3MiB) 2026-02-16 21:11:27.136852 | controller | Downloading cryptography (4.3MiB) 2026-02-16 21:11:27.276950 | controller | Downloading cryptography 2026-02-16 21:11:27.314302 | controller | Downloading pygments 2026-02-16 21:11:27.393801 | controller | Downloading ansible-core 2026-02-16 21:11:27.442781 | controller | Installed 36 packages in 47ms 2026-02-16 21:11:28.082760 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-16 21:11:28.082792 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-16 21:11:28.823463 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-16 21:11:28.823578 | controller | INFO default scenario not found, disabling shared state. 2026-02-16 21:11:28.823790 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-16 21:11:28.823893 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-02-16 21:11:44.534683 | controller | INFO [helm > dependency] Executing 2026-02-16 21:11:44.534950 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-02-16 21:11:44.535217 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-02-16 21:11:44.535382 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-16 21:11:44.542605 | controller | INFO [helm > cleanup] Executing 2026-02-16 21:11:44.542976 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:11:44.548218 | controller | INFO [helm > destroy] Executing 2026-02-16 21:11:44.548264 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-16 21:11:44.548337 | controller | INFO [helm > destroy] Executed: Successful 2026-02-16 21:11:44.553687 | controller | INFO [helm > syntax] Executing 2026-02-16 21:11:45.394060 | controller | 2026-02-16 21:11:45.394337 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-02-16 21:11:45.450156 | controller | INFO [helm > syntax] Executed: Successful 2026-02-16 21:11:45.463449 | controller | INFO [helm > create] Executing 2026-02-16 21:11:45.465165 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:11:45.470435 | controller | INFO [helm > prepare] Executing 2026-02-16 21:11:46.265116 | controller | 2026-02-16 21:11:46.265137 | controller | PLAY [Prepare] ***************************************************************** 2026-02-16 21:11:46.265142 | controller | 2026-02-16 21:11:46.265145 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:11:46.265150 | controller | Monday 16 February 2026 21:11:46 +0000 (0:00:00.028) 0:00:00.028 ******* 2026-02-16 21:11:47.422678 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:11:47.428362 | controller | ok: [controller] 2026-02-16 21:11:47.428482 | controller | 2026-02-16 21:11:47.428631 | controller | TASK [Install Debian packages] ************************************************* 2026-02-16 21:11:47.428767 | controller | Monday 16 February 2026 21:11:47 +0000 (0:00:01.165) 0:00:01.193 ******* 2026-02-16 21:11:52.038768 | controller | ok: [controller] 2026-02-16 21:11:52.038794 | controller | 2026-02-16 21:11:52.038800 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:11:52.038806 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:11:52.038811 | controller | 2026-02-16 21:11:52.038816 | controller | 2026-02-16 21:11:52.038820 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:11:52.038834 | controller | Monday 16 February 2026 21:11:52 +0000 (0:00:04.607) 0:00:05.800 ******* 2026-02-16 21:11:52.038839 | controller | =============================================================================== 2026-02-16 21:11:52.038843 | controller | Install Debian packages ------------------------------------------------- 4.61s 2026-02-16 21:11:52.038848 | controller | Gathering Facts --------------------------------------------------------- 1.17s 2026-02-16 21:11:52.119832 | controller | INFO [helm > prepare] Executed: Successful 2026-02-16 21:11:52.125620 | controller | INFO [helm > converge] Executing 2026-02-16 21:11:52.921647 | controller | 2026-02-16 21:11:52.921850 | controller | PLAY [Converge] **************************************************************** 2026-02-16 21:11:52.922023 | controller | 2026-02-16 21:11:52.922222 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:11:52.922410 | controller | Monday 16 February 2026 21:11:52 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-02-16 21:11:53.905837 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:11:53.909402 | controller | ok: [controller] 2026-02-16 21:11:53.909430 | controller | 2026-02-16 21:11:53.909436 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-16 21:11:53.909441 | controller | Monday 16 February 2026 21:11:53 +0000 (0:00:00.987) 0:00:01.000 ******* 2026-02-16 21:11:54.340556 | controller | ok: [controller] 2026-02-16 21:11:54.343035 | controller | 2026-02-16 21:11:54.343045 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-16 21:11:54.343049 | controller | Monday 16 February 2026 21:11:54 +0000 (0:00:00.431) 0:00:01.431 ******* 2026-02-16 21:11:55.243590 | controller | ok: [controller] 2026-02-16 21:11:55.243624 | controller | 2026-02-16 21:11:55.243630 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-16 21:11:55.243636 | controller | Monday 16 February 2026 21:11:55 +0000 (0:00:00.902) 0:00:02.333 ******* 2026-02-16 21:11:55.643025 | controller | changed: [controller] 2026-02-16 21:11:55.643046 | controller | 2026-02-16 21:11:55.643050 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:11:55.643054 | controller | Monday 16 February 2026 21:11:55 +0000 (0:00:00.397) 0:00:02.731 ******* 2026-02-16 21:11:55.686624 | controller | ok: [controller] => { 2026-02-16 21:11:55.686638 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-16 21:11:55.686643 | controller | } 2026-02-16 21:11:55.686649 | controller | 2026-02-16 21:11:55.686654 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:11:55.686659 | controller | Monday 16 February 2026 21:11:55 +0000 (0:00:00.045) 0:00:02.776 ******* 2026-02-16 21:11:56.540849 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-02-16 21:11:56.547649 | controller | changed: [controller] 2026-02-16 21:11:56.547818 | controller | 2026-02-16 21:11:56.547993 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:11:56.548159 | controller | Monday 16 February 2026 21:11:56 +0000 (0:00:00.861) 0:00:03.638 ******* 2026-02-16 21:11:58.491291 | controller | changed: [controller] 2026-02-16 21:11:58.491397 | controller | 2026-02-16 21:11:58.491530 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-16 21:11:58.491665 | controller | Monday 16 February 2026 21:11:58 +0000 (0:00:01.942) 0:00:05.580 ******* 2026-02-16 21:11:58.974127 | controller | ok: [controller] 2026-02-16 21:11:58.974199 | controller | 2026-02-16 21:11:58.974324 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-16 21:11:58.974442 | controller | Monday 16 February 2026 21:11:58 +0000 (0:00:00.481) 0:00:06.061 ******* 2026-02-16 21:11:59.347817 | controller | changed: [controller] 2026-02-16 21:11:59.347876 | controller | 2026-02-16 21:11:59.347970 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-16 21:11:59.348056 | controller | Monday 16 February 2026 21:11:59 +0000 (0:00:00.376) 0:00:06.438 ******* 2026-02-16 21:11:59.782236 | controller | ok: [controller] 2026-02-16 21:11:59.782298 | controller | 2026-02-16 21:11:59.782405 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-16 21:11:59.782512 | controller | Monday 16 February 2026 21:11:59 +0000 (0:00:00.434) 0:00:06.872 ******* 2026-02-16 21:12:00.065809 | controller | changed: [controller] 2026-02-16 21:12:00.065870 | controller | 2026-02-16 21:12:00.065969 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-16 21:12:00.066057 | controller | Monday 16 February 2026 21:12:00 +0000 (0:00:00.282) 0:00:07.154 ******* 2026-02-16 21:12:00.337932 | controller | ok: [controller] 2026-02-16 21:12:00.338026 | controller | 2026-02-16 21:12:00.338128 | controller | TASK [Install plugin] ********************************************************** 2026-02-16 21:12:00.338219 | controller | Monday 16 February 2026 21:12:00 +0000 (0:00:00.273) 0:00:07.428 ******* 2026-02-16 21:12:00.385879 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-16 21:12:00.385940 | controller | 2026-02-16 21:12:00.386026 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:12:00.386114 | controller | Monday 16 February 2026 21:12:00 +0000 (0:00:00.048) 0:00:07.476 ******* 2026-02-16 21:12:00.426293 | controller | ok: [controller] => { 2026-02-16 21:12:00.426320 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-16 21:12:00.426324 | controller | } 2026-02-16 21:12:00.426329 | controller | 2026-02-16 21:12:00.426332 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:12:00.426337 | controller | Monday 16 February 2026 21:12:00 +0000 (0:00:00.039) 0:00:07.516 ******* 2026-02-16 21:12:01.138188 | controller | changed: [controller] 2026-02-16 21:12:01.138322 | controller | 2026-02-16 21:12:01.138441 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:12:01.138555 | controller | Monday 16 February 2026 21:12:01 +0000 (0:00:00.712) 0:00:08.228 ******* 2026-02-16 21:12:03.172576 | controller | changed: [controller] 2026-02-16 21:12:03.172776 | controller | 2026-02-16 21:12:03.172940 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:12:03.173115 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:12:03.173263 | controller | 2026-02-16 21:12:03.173407 | controller | 2026-02-16 21:12:03.173564 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:12:03.173730 | controller | Monday 16 February 2026 21:12:03 +0000 (0:00:02.035) 0:00:10.263 ******* 2026-02-16 21:12:03.173882 | controller | =============================================================================== 2026-02-16 21:12:03.174040 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.04s 2026-02-16 21:12:03.174199 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.94s 2026-02-16 21:12:03.174354 | controller | Gathering Facts --------------------------------------------------------- 0.99s 2026-02-16 21:12:03.174531 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.90s 2026-02-16 21:12:03.174690 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.86s 2026-02-16 21:12:03.174910 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.71s 2026-02-16 21:12:03.175072 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s 2026-02-16 21:12:03.175230 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s 2026-02-16 21:12:03.175386 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s 2026-02-16 21:12:03.175548 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s 2026-02-16 21:12:03.175755 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.38s 2026-02-16 21:12:03.175920 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-02-16 21:12:03.176084 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.27s 2026-02-16 21:12:03.176238 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-02-16 21:12:03.176396 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-16 21:12:03.176553 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-16 21:12:03.254730 | controller | INFO [helm > converge] Executed: Successful 2026-02-16 21:12:03.263882 | controller | INFO [helm > idempotence] Executing 2026-02-16 21:12:04.044660 | controller | 2026-02-16 21:12:04.044685 | controller | PLAY [Converge] **************************************************************** 2026-02-16 21:12:04.044690 | controller | 2026-02-16 21:12:04.044693 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:12:04.044697 | controller | Monday 16 February 2026 21:12:04 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-02-16 21:12:05.005254 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:12:05.011721 | controller | ok: [controller] 2026-02-16 21:12:05.011833 | controller | 2026-02-16 21:12:05.011956 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-16 21:12:05.012076 | controller | Monday 16 February 2026 21:12:05 +0000 (0:00:00.968) 0:00:00.981 ******* 2026-02-16 21:12:05.401923 | controller | ok: [controller] 2026-02-16 21:12:05.402132 | controller | 2026-02-16 21:12:05.402353 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-16 21:12:05.402559 | controller | Monday 16 February 2026 21:12:05 +0000 (0:00:00.389) 0:00:01.371 ******* 2026-02-16 21:12:06.309627 | controller | ok: [controller] 2026-02-16 21:12:06.309745 | controller | 2026-02-16 21:12:06.309874 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-16 21:12:06.309992 | controller | Monday 16 February 2026 21:12:06 +0000 (0:00:00.907) 0:00:02.278 ******* 2026-02-16 21:12:06.707122 | controller | ok: [controller] 2026-02-16 21:12:06.707306 | controller | 2026-02-16 21:12:06.707533 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:12:06.707770 | controller | Monday 16 February 2026 21:12:06 +0000 (0:00:00.397) 0:00:02.675 ******* 2026-02-16 21:12:06.752614 | controller | ok: [controller] => { 2026-02-16 21:12:06.752704 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-16 21:12:06.752786 | controller | } 2026-02-16 21:12:06.752863 | controller | 2026-02-16 21:12:06.752953 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:12:06.753038 | controller | Monday 16 February 2026 21:12:06 +0000 (0:00:00.045) 0:00:02.721 ******* 2026-02-16 21:12:07.270750 | controller | ok: [controller] 2026-02-16 21:12:07.270854 | controller | 2026-02-16 21:12:07.270980 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:12:07.271099 | controller | Monday 16 February 2026 21:12:07 +0000 (0:00:00.515) 0:00:03.237 ******* 2026-02-16 21:12:08.807174 | controller | ok: [controller] 2026-02-16 21:12:08.807227 | controller | 2026-02-16 21:12:08.807324 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-16 21:12:08.807413 | controller | Monday 16 February 2026 21:12:08 +0000 (0:00:01.538) 0:00:04.776 ******* 2026-02-16 21:12:09.276141 | controller | ok: [controller] 2026-02-16 21:12:09.276192 | controller | 2026-02-16 21:12:09.276289 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-16 21:12:09.276382 | controller | Monday 16 February 2026 21:12:09 +0000 (0:00:00.468) 0:00:05.244 ******* 2026-02-16 21:12:09.642388 | controller | ok: [controller] 2026-02-16 21:12:09.642454 | controller | 2026-02-16 21:12:09.642549 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-16 21:12:09.642642 | controller | Monday 16 February 2026 21:12:09 +0000 (0:00:00.367) 0:00:05.611 ******* 2026-02-16 21:12:10.042364 | controller | ok: [controller] 2026-02-16 21:12:10.042388 | controller | 2026-02-16 21:12:10.042395 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-16 21:12:10.042400 | controller | Monday 16 February 2026 21:12:10 +0000 (0:00:00.396) 0:00:06.008 ******* 2026-02-16 21:12:10.309232 | controller | ok: [controller] 2026-02-16 21:12:10.309253 | controller | 2026-02-16 21:12:10.309258 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-16 21:12:10.309261 | controller | Monday 16 February 2026 21:12:10 +0000 (0:00:00.269) 0:00:06.278 ******* 2026-02-16 21:12:10.563583 | controller | ok: [controller] 2026-02-16 21:12:10.563713 | controller | 2026-02-16 21:12:10.563830 | controller | TASK [Install plugin] ********************************************************** 2026-02-16 21:12:10.563943 | controller | Monday 16 February 2026 21:12:10 +0000 (0:00:00.254) 0:00:06.532 ******* 2026-02-16 21:12:10.607089 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-16 21:12:10.607104 | controller | 2026-02-16 21:12:10.607109 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:12:10.607113 | controller | Monday 16 February 2026 21:12:10 +0000 (0:00:00.043) 0:00:06.576 ******* 2026-02-16 21:12:10.645047 | controller | ok: [controller] => { 2026-02-16 21:12:10.645187 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-16 21:12:10.645299 | controller | } 2026-02-16 21:12:10.645404 | controller | 2026-02-16 21:12:10.645520 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:12:10.646864 | controller | Monday 16 February 2026 21:12:10 +0000 (0:00:00.038) 0:00:06.614 ******* 2026-02-16 21:12:11.012294 | controller | ok: [controller] 2026-02-16 21:12:11.012356 | controller | 2026-02-16 21:12:11.012454 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:12:11.012572 | controller | Monday 16 February 2026 21:12:11 +0000 (0:00:00.366) 0:00:06.981 ******* 2026-02-16 21:12:12.527082 | controller | ok: [controller] 2026-02-16 21:12:12.527126 | controller | 2026-02-16 21:12:12.527220 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:12:12.527315 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:12:12.527393 | controller | 2026-02-16 21:12:12.527464 | controller | 2026-02-16 21:12:12.527556 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:12:12.527639 | controller | Monday 16 February 2026 21:12:12 +0000 (0:00:01.511) 0:00:08.492 ******* 2026-02-16 21:12:12.527719 | controller | =============================================================================== 2026-02-16 21:12:12.527806 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.54s 2026-02-16 21:12:12.527891 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.51s 2026-02-16 21:12:12.527976 | controller | Gathering Facts --------------------------------------------------------- 0.97s 2026-02-16 21:12:12.528063 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.91s 2026-02-16 21:12:12.528149 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.52s 2026-02-16 21:12:12.528236 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.47s 2026-02-16 21:12:12.528323 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s 2026-02-16 21:12:12.528409 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.40s 2026-02-16 21:12:12.528495 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.39s 2026-02-16 21:12:12.528580 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.37s 2026-02-16 21:12:12.528667 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.37s 2026-02-16 21:12:12.528759 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.27s 2026-02-16 21:12:12.528850 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.25s 2026-02-16 21:12:12.528942 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-16 21:12:12.529100 | controller | Install plugin ---------------------------------------------------------- 0.04s 2026-02-16 21:12:12.529166 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-16 21:12:12.614758 | controller | INFO [helm > idempotence] Executed: Successful 2026-02-16 21:12:12.618625 | controller | INFO [helm > side_effect] Executing 2026-02-16 21:12:12.619002 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:12:12.624260 | controller | INFO [helm > verify] Executing 2026-02-16 21:12:13.393489 | controller | 2026-02-16 21:12:13.393824 | controller | PLAY [Verify] ****************************************************************** 2026-02-16 21:12:13.394060 | controller | 2026-02-16 21:12:13.394308 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:12:13.394557 | controller | Monday 16 February 2026 21:12:13 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-02-16 21:12:14.435222 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:12:14.440446 | controller | ok: [controller] 2026-02-16 21:12:14.440561 | controller | 2026-02-16 21:12:14.440678 | controller | TASK [Get the current Helm version] ******************************************** 2026-02-16 21:12:14.440806 | controller | Monday 16 February 2026 21:12:14 +0000 (0:00:01.047) 0:00:01.057 ******* 2026-02-16 21:12:14.844605 | controller | changed: [controller] 2026-02-16 21:12:14.846781 | controller | 2026-02-16 21:12:14.846791 | controller | TASK [Assert Helm version] ***************************************************** 2026-02-16 21:12:14.846796 | controller | Monday 16 February 2026 21:12:14 +0000 (0:00:00.403) 0:00:01.461 ******* 2026-02-16 21:12:14.868927 | controller | ok: [controller] => { 2026-02-16 21:12:14.869190 | controller | "changed": false, 2026-02-16 21:12:14.869436 | controller | "msg": "All assertions passed" 2026-02-16 21:12:14.869673 | controller | } 2026-02-16 21:12:14.869900 | controller | 2026-02-16 21:12:14.870141 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:12:14.870403 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:12:14.870630 | controller | 2026-02-16 21:12:14.870896 | controller | 2026-02-16 21:12:14.871202 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:12:14.871457 | controller | Monday 16 February 2026 21:12:14 +0000 (0:00:00.024) 0:00:01.485 ******* 2026-02-16 21:12:14.871691 | controller | =============================================================================== 2026-02-16 21:12:14.871967 | controller | Gathering Facts --------------------------------------------------------- 1.05s 2026-02-16 21:12:14.872112 | controller | Get the current Helm version -------------------------------------------- 0.40s 2026-02-16 21:12:14.872219 | controller | Assert Helm version ----------------------------------------------------- 0.02s 2026-02-16 21:12:14.948440 | controller | INFO [helm > verify] Executed: Successful 2026-02-16 21:12:14.958190 | controller | INFO [helm > cleanup] Executing 2026-02-16 21:12:14.958614 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:12:14.964283 | controller | INFO [helm > destroy] Executing 2026-02-16 21:12:14.964377 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-16 21:12:14.964486 | controller | INFO [helm > destroy] Executed: Successful 2026-02-16 21:12:14.964761 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-16 21:12:15.179646 | controller | ok: Runtime: 0:00:48.030199 2026-02-16 21:12:15.183674 | 2026-02-16 21:12:15.183727 | PLAY RECAP 2026-02-16 21:12:15.183772 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:12:15.183794 | 2026-02-16 21:12:15.289896 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:12:15.292480 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:12:15.920927 | 2026-02-16 21:12:15.921094 | PLAY [all] 2026-02-16 21:12:15.931230 | 2026-02-16 21:12:15.931304 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 21:12:15.977654 | controller | skipping: Conditional result was False 2026-02-16 21:12:15.984853 | 2026-02-16 21:12:15.984965 | TASK [fetch-output : Set log path for single node] 2026-02-16 21:12:16.027897 | controller | ok 2026-02-16 21:12:16.032741 | 2026-02-16 21:12:16.032807 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 21:12:16.462122 | controller -> localhost | ok: "/var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/work/logs" 2026-02-16 21:12:16.685938 | controller -> localhost | changed: "/var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/work/artifacts" 2026-02-16 21:12:16.911248 | controller -> localhost | changed: "/var/lib/zuul/builds/25456133e52a4081ae12323a8873fc1e/work/docs" 2026-02-16 21:12:16.930313 | 2026-02-16 21:12:16.931263 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 21:12:17.588059 | controller | changed: .d..t...... ./ 2026-02-16 21:12:17.588321 | controller | changed: All items complete 2026-02-16 21:12:17.588359 | 2026-02-16 21:12:18.092285 | controller | changed: .d..t...... ./ 2026-02-16 21:12:18.615727 | controller | changed: .d..t...... ./ 2026-02-16 21:12:18.637805 | 2026-02-16 21:12:18.637981 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 21:12:19.075820 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010147 2026-02-16 21:12:19.345719 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009340 2026-02-16 21:12:19.363231 | 2026-02-16 21:12:19.363289 | PLAY [all] 2026-02-16 21:12:19.368150 | 2026-02-16 21:12:19.368213 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 21:12:19.873963 | controller | changed 2026-02-16 21:12:19.878889 | 2026-02-16 21:12:19.878942 | PLAY RECAP 2026-02-16 21:12:19.878990 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 21:12:19.879012 | 2026-02-16 21:12:19.999371 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:12:20.000788 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 21:12:20.593438 | 2026-02-16 21:12:20.593578 | PLAY [localhost] 2026-02-16 21:12:20.603874 | 2026-02-16 21:12:20.603982 | TASK [Generate Zuul manifest] 2026-02-16 21:12:20.623101 | localhost | ok 2026-02-16 21:12:20.637209 | 2026-02-16 21:12:20.637284 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 21:12:20.962965 | localhost | changed 2026-02-16 21:12:20.975417 | 2026-02-16 21:12:20.975492 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 21:12:21.007722 | localhost | ok 2026-02-16 21:12:21.015629 | 2026-02-16 21:12:21.015703 | TASK [Upload logs] 2026-02-16 21:12:21.048591 | localhost | ok 2026-02-16 21:12:21.107328 | 2026-02-16 21:12:21.107436 | TASK [Set zuul-log-path fact] 2026-02-16 21:12:21.131143 | localhost | ok 2026-02-16 21:12:21.144035 | 2026-02-16 21:12:21.144104 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:12:21.174663 | localhost | ok 2026-02-16 21:12:21.183160 | 2026-02-16 21:12:21.183221 | TASK [upload-logs : Create log directories] 2026-02-16 21:12:21.612461 | localhost | changed 2026-02-16 21:12:21.618877 | 2026-02-16 21:12:21.618946 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 21:12:22.070040 | localhost -> localhost | ok: Runtime: 0:00:00.006251 2026-02-16 21:12:22.075524 | 2026-02-16 21:12:22.075591 | TASK [upload-logs : Upload logs to log server] 2026-02-16 21:12:22.543776 | localhost | Output suppressed because no_log was given 2026-02-16 21:12:22.549285 | 2026-02-16 21:12:22.549376 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 21:12:22.597536 | localhost | skipping: Conditional result was False 2026-02-16 21:12:22.604048 | localhost | skipping: Conditional result was False 2026-02-16 21:12:22.612974 | 2026-02-16 21:12:22.613240 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 21:12:22.656635 | localhost | skipping: Conditional result was False 2026-02-16 21:12:22.657111 | 2026-02-16 21:12:22.659879 | localhost | skipping: Conditional result was False 2026-02-16 21:12:22.670777 | 2026-02-16 21:12:22.670918 | LOOP [upload-logs : Upload console log and json output]