2026-02-16 21:04:47.358587 | Job console starting 2026-02-16 21:04:47.371132 | Updating git repos 2026-02-16 21:04:47.438781 | Cloning repos into workspace 2026-02-16 21:04:47.486980 | Restoring repo states 2026-02-16 21:04:47.501113 | Merging changes 2026-02-16 21:04:48.285815 | Checking out repos 2026-02-16 21:04:48.339460 | Preparing playbooks 2026-02-16 21:04:50.022161 | Running Ansible setup 2026-02-16 21:04:53.704325 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:04:54.287128 | 2026-02-16 21:04:54.287264 | PLAY [localhost] 2026-02-16 21:04:54.294672 | 2026-02-16 21:04:54.294743 | TASK [Gathering Facts] 2026-02-16 21:04:55.190946 | localhost | ok 2026-02-16 21:04:55.197444 | 2026-02-16 21:04:55.197517 | TASK [Setup log path fact] 2026-02-16 21:04:55.214644 | localhost | ok 2026-02-16 21:04:55.228921 | 2026-02-16 21:04:55.229034 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:04:55.259230 | localhost | ok 2026-02-16 21:04:55.266859 | 2026-02-16 21:04:55.266932 | TASK [emit-job-header : Print job information] 2026-02-16 21:04:55.307875 | # Job Information 2026-02-16 21:04:55.313768 | Ansible Version: 2.16.15 2026-02-16 21:04:55.313826 | Job: ansible-collection-kubernetes-molecule-helm 2026-02-16 21:04:55.313858 | Pipeline: check 2026-02-16 21:04:55.313887 | Executor: 3a2793d2bd32 2026-02-16 21:04:55.313914 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/229 2026-02-16 21:04:55.313946 | Event ID: 05f21470-0b7b-11f1-8965-6c0a04ea4243 2026-02-16 21:04:55.317654 | 2026-02-16 21:04:55.317743 | LOOP [emit-job-header : Print node information] 2026-02-16 21:04:55.407488 | localhost | ok: 2026-02-16 21:04:55.426284 | localhost | # Node Information 2026-02-16 21:04:55.426394 | localhost | Inventory Hostname: controller 2026-02-16 21:04:55.426449 | localhost | Hostname: np0000155743 2026-02-16 21:04:55.426495 | localhost | Username: zuul 2026-02-16 21:04:55.426572 | localhost | Distro: Debian 13.3 2026-02-16 21:04:55.426621 | localhost | Provider: yul1 2026-02-16 21:04:55.426664 | localhost | Region: ca-ymq-1 2026-02-16 21:04:55.426704 | localhost | Label: debian-trixie 2026-02-16 21:04:55.426747 | localhost | Product Name: OpenStack Nova 2026-02-16 21:04:55.426787 | localhost | Interface IP: 199.204.45.222 2026-02-16 21:04:55.447757 | 2026-02-16 21:04:55.447889 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 21:04:55.856205 | localhost -> localhost | changed 2026-02-16 21:04:55.861467 | 2026-02-16 21:04:55.861537 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 21:04:57.070062 | localhost -> localhost | changed 2026-02-16 21:04:57.077016 | 2026-02-16 21:04:57.077076 | PLAY [all] 2026-02-16 21:04:57.083980 | 2026-02-16 21:04:57.084045 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 21:04:57.298520 | controller -> localhost | ok 2026-02-16 21:04:57.305571 | 2026-02-16 21:04:57.305665 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 21:04:57.337915 | controller | ok 2026-02-16 21:04:57.354209 | controller | included: /var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 21:04:57.360437 | 2026-02-16 21:04:57.360500 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 21:04:58.506303 | controller -> localhost | Generating public/private rsa key pair. 2026-02-16 21:04:58.506531 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/work/76c81701ebb8445c9f50e0277cacb6cb_id_rsa 2026-02-16 21:04:58.506572 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/work/76c81701ebb8445c9f50e0277cacb6cb_id_rsa.pub 2026-02-16 21:04:58.506604 | controller -> localhost | The key fingerprint is: 2026-02-16 21:04:58.506633 | controller -> localhost | SHA256:mMD5ppIASvwVSl/mK6Dc2emw22D2qGgQUpgxgu7MHfc zuul-build-sshkey 2026-02-16 21:04:58.506684 | controller -> localhost | The key's randomart image is: 2026-02-16 21:04:58.506716 | controller -> localhost | +---[RSA 3072]----+ 2026-02-16 21:04:58.506755 | controller -> localhost | |=+ . . o | 2026-02-16 21:04:58.506801 | controller -> localhost | |*.o.o.= | 2026-02-16 21:04:58.506833 | controller -> localhost | |o+ o+o . | 2026-02-16 21:04:58.507033 | controller -> localhost | |B.+.=+.o. | 2026-02-16 21:04:58.507070 | controller -> localhost | |B+.=o+*.S | 2026-02-16 21:04:58.507099 | controller -> localhost | |.= o+o.E | 2026-02-16 21:04:58.507127 | controller -> localhost | |. o=.. | 2026-02-16 21:04:58.507155 | controller -> localhost | |..o.* | 2026-02-16 21:04:58.507186 | controller -> localhost | |o..o o | 2026-02-16 21:04:58.507213 | controller -> localhost | +----[SHA256]-----+ 2026-02-16 21:04:58.507360 | controller -> localhost | ok: Runtime: 0:00:00.695583 2026-02-16 21:04:58.513779 | 2026-02-16 21:04:58.513871 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 21:04:58.546141 | controller | ok 2026-02-16 21:04:58.560034 | controller | included: /var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 21:04:58.569751 | 2026-02-16 21:04:58.569888 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 21:04:58.595578 | controller | skipping: Conditional result was False 2026-02-16 21:04:58.601972 | 2026-02-16 21:04:58.602074 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 21:04:59.134625 | controller | changed 2026-02-16 21:04:59.139594 | 2026-02-16 21:04:59.139655 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 21:04:59.354135 | controller | ok 2026-02-16 21:04:59.359854 | 2026-02-16 21:04:59.359942 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 21:04:59.891081 | controller | changed 2026-02-16 21:04:59.896977 | 2026-02-16 21:04:59.897070 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 21:05:00.415616 | controller | changed 2026-02-16 21:05:00.422038 | 2026-02-16 21:05:00.422202 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 21:05:00.448000 | controller | skipping: Conditional result was False 2026-02-16 21:05:00.455362 | 2026-02-16 21:05:00.455514 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 21:05:00.877998 | controller -> localhost | changed 2026-02-16 21:05:00.902170 | 2026-02-16 21:05:00.902258 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 21:05:01.208344 | controller -> localhost | Identity added: /var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/work/76c81701ebb8445c9f50e0277cacb6cb_id_rsa (zuul-build-sshkey) 2026-02-16 21:05:01.208548 | controller -> localhost | ok: Runtime: 0:00:00.017147 2026-02-16 21:05:01.212422 | 2026-02-16 21:05:01.212483 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 21:05:01.621505 | controller | ok 2026-02-16 21:05:01.626350 | 2026-02-16 21:05:01.626419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 21:05:01.650508 | controller | skipping: Conditional result was False 2026-02-16 21:05:01.665442 | 2026-02-16 21:05:01.665546 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 21:05:02.071554 | controller | ok 2026-02-16 21:05:02.080783 | 2026-02-16 21:05:02.081018 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 21:05:03.161146 | controller | Output suppressed because no_log was given 2026-02-16 21:05:03.179377 | 2026-02-16 21:05:03.179522 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 21:05:03.397780 | controller | ok: "logs" 2026-02-16 21:05:03.398109 | controller | ok: All items complete 2026-02-16 21:05:03.398142 | 2026-02-16 21:05:03.594396 | controller | ok: "artifacts" 2026-02-16 21:05:03.794951 | controller | ok: "docs" 2026-02-16 21:05:03.815104 | 2026-02-16 21:05:03.815245 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 21:05:04.049983 | controller | changed: "logs" 2026-02-16 21:05:04.239103 | controller | changed: "artifacts" 2026-02-16 21:05:04.422146 | controller | changed: "docs" 2026-02-16 21:05:04.485827 | 2026-02-16 21:05:04.485964 | PLAY RECAP 2026-02-16 21:05:04.486033 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 21:05:04.486074 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:05:04.486105 | 2026-02-16 21:05:04.652318 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:05:04.655515 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:05:05.294413 | 2026-02-16 21:05:05.294571 | PLAY [all] 2026-02-16 21:05:05.306337 | 2026-02-16 21:05:05.306485 | TASK [setup-uv : Extract archive] 2026-02-16 21:05:07.657215 | controller | changed 2026-02-16 21:05:07.663097 | 2026-02-16 21:05:07.663181 | TASK [setup-uv : Print version] 2026-02-16 21:05:08.277681 | controller | uv 0.8.13 2026-02-16 21:05:08.206892 | controller | ok: Runtime: 0:00:00.013660 2026-02-16 21:05:08.212031 | 2026-02-16 21:05:08.212089 | PLAY RECAP 2026-02-16 21:05:08.212149 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:05:08.212176 | 2026-02-16 21:05:08.370865 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:05:08.385579 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:05:09.034232 | 2026-02-16 21:05:09.034453 | PLAY [all] 2026-02-16 21:05:09.044523 | 2026-02-16 21:05:09.044680 | TASK [Copy inventory file for Zuul] 2026-02-16 21:05:09.940493 | controller | changed 2026-02-16 21:05:09.950664 | 2026-02-16 21:05:09.950790 | TASK [Switch "ansible_host" to private IP] 2026-02-16 21:05:10.275716 | controller | changed: 1 replacements made 2026-02-16 21:05:10.330913 | 2026-02-16 21:05:10.331160 | TASK [Run Molecule scenario] 2026-02-16 21:05:10.921712 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-16 21:05:10.921929 | controller | Creating virtual environment at: .venv 2026-02-16 21:05:10.959923 | controller | Downloading cryptography (4.3MiB) 2026-02-16 21:05:10.970775 | controller | Downloading pygments (1.2MiB) 2026-02-16 21:05:10.971908 | controller | Downloading ansible-core (2.3MiB) 2026-02-16 21:05:11.172550 | controller | Downloading cryptography 2026-02-16 21:05:11.209559 | controller | Downloading pygments 2026-02-16 21:05:11.311497 | controller | Downloading ansible-core 2026-02-16 21:05:11.365674 | controller | Installed 36 packages in 53ms 2026-02-16 21:05:12.011797 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-16 21:05:12.011852 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-16 21:05:12.727532 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-16 21:05:12.727601 | controller | INFO default scenario not found, disabling shared state. 2026-02-16 21:05:12.727790 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-16 21:05:12.727908 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-02-16 21:05:29.856058 | controller | INFO [helm > dependency] Executing 2026-02-16 21:05:29.856096 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-02-16 21:05:29.856100 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-02-16 21:05:29.856113 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-16 21:05:29.858795 | controller | INFO [helm > cleanup] Executing 2026-02-16 21:05:29.859185 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:05:29.864541 | controller | INFO [helm > destroy] Executing 2026-02-16 21:05:29.864636 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-16 21:05:29.864725 | controller | INFO [helm > destroy] Executed: Successful 2026-02-16 21:05:29.870065 | controller | INFO [helm > syntax] Executing 2026-02-16 21:05:30.727141 | controller | 2026-02-16 21:05:30.727561 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-02-16 21:05:30.785963 | controller | INFO [helm > syntax] Executed: Successful 2026-02-16 21:05:30.797947 | controller | INFO [helm > create] Executing 2026-02-16 21:05:30.799443 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:05:30.804820 | controller | INFO [helm > prepare] Executing 2026-02-16 21:05:31.632040 | controller | 2026-02-16 21:05:31.632274 | controller | PLAY [Prepare] ***************************************************************** 2026-02-16 21:05:31.632429 | controller | 2026-02-16 21:05:31.632590 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:05:31.632749 | controller | Monday 16 February 2026 21:05:31 +0000 (0:00:00.030) 0:00:00.030 ******* 2026-02-16 21:05:32.812545 | 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:05:32.820283 | controller | ok: [controller] 2026-02-16 21:05:32.820304 | controller | 2026-02-16 21:05:32.820309 | controller | TASK [Install Debian packages] ************************************************* 2026-02-16 21:05:32.820313 | controller | Monday 16 February 2026 21:05:32 +0000 (0:00:01.185) 0:00:01.215 ******* 2026-02-16 21:05:37.440901 | controller | ok: [controller] 2026-02-16 21:05:37.441121 | controller | 2026-02-16 21:05:37.441293 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:05:37.441472 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:05:37.441624 | controller | 2026-02-16 21:05:37.441772 | controller | 2026-02-16 21:05:37.441932 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:05:37.442108 | controller | Monday 16 February 2026 21:05:37 +0000 (0:00:04.622) 0:00:05.838 ******* 2026-02-16 21:05:37.442260 | controller | =============================================================================== 2026-02-16 21:05:37.442417 | controller | Install Debian packages ------------------------------------------------- 4.62s 2026-02-16 21:05:37.442574 | controller | Gathering Facts --------------------------------------------------------- 1.19s 2026-02-16 21:05:37.522963 | controller | INFO [helm > prepare] Executed: Successful 2026-02-16 21:05:37.534225 | controller | INFO [helm > converge] Executing 2026-02-16 21:05:38.332358 | controller | 2026-02-16 21:05:38.332399 | controller | PLAY [Converge] **************************************************************** 2026-02-16 21:05:38.332409 | controller | 2026-02-16 21:05:38.332418 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:05:38.332427 | controller | Monday 16 February 2026 21:05:38 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-02-16 21:05:39.328731 | 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:05:39.328837 | controller | ok: [controller] 2026-02-16 21:05:39.328969 | controller | 2026-02-16 21:05:39.329096 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-16 21:05:39.329217 | controller | Monday 16 February 2026 21:05:39 +0000 (0:00:00.989) 0:00:01.002 ******* 2026-02-16 21:05:39.767490 | controller | ok: [controller] 2026-02-16 21:05:39.767656 | controller | 2026-02-16 21:05:39.767858 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-16 21:05:39.768095 | controller | Monday 16 February 2026 21:05:39 +0000 (0:00:00.446) 0:00:01.448 ******* 2026-02-16 21:05:40.685712 | controller | ok: [controller] 2026-02-16 21:05:40.685879 | controller | 2026-02-16 21:05:40.686078 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-16 21:05:40.686265 | controller | Monday 16 February 2026 21:05:40 +0000 (0:00:00.918) 0:00:02.366 ******* 2026-02-16 21:05:41.092010 | controller | changed: [controller] 2026-02-16 21:05:41.092080 | controller | 2026-02-16 21:05:41.092172 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:05:41.092261 | controller | Monday 16 February 2026 21:05:41 +0000 (0:00:00.406) 0:00:02.773 ******* 2026-02-16 21:05:41.140035 | controller | ok: [controller] => { 2026-02-16 21:05:41.140068 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-16 21:05:41.140074 | controller | } 2026-02-16 21:05:41.140079 | controller | 2026-02-16 21:05:41.140084 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:05:41.140089 | controller | Monday 16 February 2026 21:05:41 +0000 (0:00:00.044) 0:00:02.818 ******* 2026-02-16 21:05:42.013407 | 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:05:42.022343 | controller | changed: [controller] 2026-02-16 21:05:42.022461 | controller | 2026-02-16 21:05:42.022585 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:05:42.022706 | controller | Monday 16 February 2026 21:05:42 +0000 (0:00:00.885) 0:00:03.703 ******* 2026-02-16 21:05:43.927472 | controller | changed: [controller] 2026-02-16 21:05:43.927575 | controller | 2026-02-16 21:05:43.927711 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-16 21:05:43.927857 | controller | Monday 16 February 2026 21:05:43 +0000 (0:00:01.905) 0:00:05.608 ******* 2026-02-16 21:05:44.410681 | controller | ok: [controller] 2026-02-16 21:05:44.410718 | controller | 2026-02-16 21:05:44.410728 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-16 21:05:44.410736 | controller | Monday 16 February 2026 21:05:44 +0000 (0:00:00.476) 0:00:06.085 ******* 2026-02-16 21:05:44.780780 | controller | changed: [controller] 2026-02-16 21:05:44.780838 | controller | 2026-02-16 21:05:44.780931 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-16 21:05:44.781019 | controller | Monday 16 February 2026 21:05:44 +0000 (0:00:00.376) 0:00:06.461 ******* 2026-02-16 21:05:45.207130 | controller | ok: [controller] 2026-02-16 21:05:45.207217 | controller | 2026-02-16 21:05:45.207351 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-16 21:05:45.208143 | controller | Monday 16 February 2026 21:05:45 +0000 (0:00:00.426) 0:00:06.888 ******* 2026-02-16 21:05:45.485657 | controller | changed: [controller] 2026-02-16 21:05:45.485684 | controller | 2026-02-16 21:05:45.485690 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-16 21:05:45.485695 | controller | Monday 16 February 2026 21:05:45 +0000 (0:00:00.277) 0:00:07.166 ******* 2026-02-16 21:05:45.762567 | controller | ok: [controller] 2026-02-16 21:05:45.762745 | controller | 2026-02-16 21:05:45.762911 | controller | TASK [Install plugin] ********************************************************** 2026-02-16 21:05:45.763089 | controller | Monday 16 February 2026 21:05:45 +0000 (0:00:00.277) 0:00:07.443 ******* 2026-02-16 21:05:45.813872 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-16 21:05:45.814308 | controller | 2026-02-16 21:05:45.814990 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:05:45.815810 | controller | Monday 16 February 2026 21:05:45 +0000 (0:00:00.051) 0:00:07.494 ******* 2026-02-16 21:05:45.849150 | controller | ok: [controller] => { 2026-02-16 21:05:45.849466 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-16 21:05:45.849771 | controller | } 2026-02-16 21:05:45.850042 | controller | 2026-02-16 21:05:45.850342 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:05:45.850641 | controller | Monday 16 February 2026 21:05:45 +0000 (0:00:00.036) 0:00:07.530 ******* 2026-02-16 21:05:46.588042 | controller | changed: [controller] 2026-02-16 21:05:46.588070 | controller | 2026-02-16 21:05:46.588076 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:05:46.588081 | controller | Monday 16 February 2026 21:05:46 +0000 (0:00:00.737) 0:00:08.268 ******* 2026-02-16 21:05:48.528450 | controller | changed: [controller] 2026-02-16 21:05:48.528498 | controller | 2026-02-16 21:05:48.528569 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:05:48.528668 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:05:48.528747 | controller | 2026-02-16 21:05:48.528819 | controller | 2026-02-16 21:05:48.528913 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:05:48.528997 | controller | Monday 16 February 2026 21:05:48 +0000 (0:00:01.940) 0:00:10.209 ******* 2026-02-16 21:05:48.531081 | controller | =============================================================================== 2026-02-16 21:05:48.531105 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.94s 2026-02-16 21:05:48.531109 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.91s 2026-02-16 21:05:48.531113 | controller | Gathering Facts --------------------------------------------------------- 0.99s 2026-02-16 21:05:48.531116 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.92s 2026-02-16 21:05:48.531119 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.89s 2026-02-16 21:05:48.531123 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.74s 2026-02-16 21:05:48.531126 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s 2026-02-16 21:05:48.531129 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.45s 2026-02-16 21:05:48.531133 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s 2026-02-16 21:05:48.531137 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-02-16 21:05:48.531148 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.38s 2026-02-16 21:05:48.531153 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-02-16 21:05:48.531156 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.28s 2026-02-16 21:05:48.531160 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-02-16 21:05:48.531163 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-16 21:05:48.531166 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-16 21:05:48.619808 | controller | INFO [helm > converge] Executed: Successful 2026-02-16 21:05:48.630565 | controller | INFO [helm > idempotence] Executing 2026-02-16 21:05:49.416860 | controller | 2026-02-16 21:05:49.417167 | controller | PLAY [Converge] **************************************************************** 2026-02-16 21:05:49.417429 | controller | 2026-02-16 21:05:49.417748 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:05:49.418141 | controller | Monday 16 February 2026 21:05:49 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-02-16 21:05:50.395617 | 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:05:50.401813 | controller | ok: [controller] 2026-02-16 21:05:50.404021 | controller | 2026-02-16 21:05:50.404042 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-16 21:05:50.404051 | controller | Monday 16 February 2026 21:05:50 +0000 (0:00:00.985) 0:00:00.997 ******* 2026-02-16 21:05:50.783228 | controller | ok: [controller] 2026-02-16 21:05:50.783271 | controller | 2026-02-16 21:05:50.783369 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-16 21:05:50.783461 | controller | Monday 16 February 2026 21:05:50 +0000 (0:00:00.381) 0:00:01.378 ******* 2026-02-16 21:05:51.718928 | controller | ok: [controller] 2026-02-16 21:05:51.719023 | controller | 2026-02-16 21:05:51.719155 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-16 21:05:51.720043 | controller | Monday 16 February 2026 21:05:51 +0000 (0:00:00.935) 0:00:02.314 ******* 2026-02-16 21:05:52.123411 | controller | ok: [controller] 2026-02-16 21:05:52.123439 | controller | 2026-02-16 21:05:52.123445 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:05:52.123450 | controller | Monday 16 February 2026 21:05:52 +0000 (0:00:00.403) 0:00:02.718 ******* 2026-02-16 21:05:52.167310 | controller | ok: [controller] => { 2026-02-16 21:05:52.167506 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-16 21:05:52.167666 | controller | } 2026-02-16 21:05:52.167815 | controller | 2026-02-16 21:05:52.168034 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:05:52.168203 | controller | Monday 16 February 2026 21:05:52 +0000 (0:00:00.044) 0:00:02.762 ******* 2026-02-16 21:05:52.666187 | controller | ok: [controller] 2026-02-16 21:05:52.666368 | controller | 2026-02-16 21:05:52.666537 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:05:52.666733 | controller | Monday 16 February 2026 21:05:52 +0000 (0:00:00.498) 0:00:03.261 ******* 2026-02-16 21:05:54.232162 | controller | ok: [controller] 2026-02-16 21:05:54.232341 | controller | 2026-02-16 21:05:54.232510 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-16 21:05:54.232668 | controller | Monday 16 February 2026 21:05:54 +0000 (0:00:01.564) 0:00:04.826 ******* 2026-02-16 21:05:54.728386 | controller | ok: [controller] 2026-02-16 21:05:54.728443 | controller | 2026-02-16 21:05:54.728533 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-16 21:05:54.728621 | controller | Monday 16 February 2026 21:05:54 +0000 (0:00:00.496) 0:00:05.322 ******* 2026-02-16 21:05:55.104171 | controller | ok: [controller] 2026-02-16 21:05:55.104191 | controller | 2026-02-16 21:05:55.104196 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-16 21:05:55.104200 | controller | Monday 16 February 2026 21:05:55 +0000 (0:00:00.374) 0:00:05.696 ******* 2026-02-16 21:05:55.514980 | controller | ok: [controller] 2026-02-16 21:05:55.516526 | controller | 2026-02-16 21:05:55.516889 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-16 21:05:55.517167 | controller | Monday 16 February 2026 21:05:55 +0000 (0:00:00.413) 0:00:06.110 ******* 2026-02-16 21:05:55.796032 | controller | ok: [controller] 2026-02-16 21:05:55.796057 | controller | 2026-02-16 21:05:55.796063 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-16 21:05:55.796068 | controller | Monday 16 February 2026 21:05:55 +0000 (0:00:00.279) 0:00:06.389 ******* 2026-02-16 21:05:56.072580 | controller | ok: [controller] 2026-02-16 21:05:56.072602 | controller | 2026-02-16 21:05:56.072607 | controller | TASK [Install plugin] ********************************************************** 2026-02-16 21:05:56.072611 | controller | Monday 16 February 2026 21:05:56 +0000 (0:00:00.278) 0:00:06.667 ******* 2026-02-16 21:05:56.118686 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-16 21:05:56.118817 | controller | 2026-02-16 21:05:56.118945 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:05:56.119064 | controller | Monday 16 February 2026 21:05:56 +0000 (0:00:00.046) 0:00:06.714 ******* 2026-02-16 21:05:56.161937 | controller | ok: [controller] => { 2026-02-16 21:05:56.164473 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-16 21:05:56.164483 | controller | } 2026-02-16 21:05:56.164487 | controller | 2026-02-16 21:05:56.164491 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:05:56.164494 | controller | Monday 16 February 2026 21:05:56 +0000 (0:00:00.042) 0:00:06.757 ******* 2026-02-16 21:05:56.555261 | controller | ok: [controller] 2026-02-16 21:05:56.555399 | controller | 2026-02-16 21:05:56.557424 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:05:56.557434 | controller | Monday 16 February 2026 21:05:56 +0000 (0:00:00.393) 0:00:07.150 ******* 2026-02-16 21:05:59.093719 | controller | ok: [controller] 2026-02-16 21:05:59.093900 | controller | 2026-02-16 21:05:59.094060 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:05:59.094231 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:05:59.094379 | controller | 2026-02-16 21:05:59.094523 | controller | 2026-02-16 21:05:59.094678 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:05:59.094837 | controller | Monday 16 February 2026 21:05:59 +0000 (0:00:02.539) 0:00:09.689 ******* 2026-02-16 21:05:59.094986 | controller | =============================================================================== 2026-02-16 21:05:59.095145 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.54s 2026-02-16 21:05:59.095302 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.56s 2026-02-16 21:05:59.095457 | controller | Gathering Facts --------------------------------------------------------- 0.99s 2026-02-16 21:05:59.095643 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.94s 2026-02-16 21:05:59.095802 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.50s 2026-02-16 21:05:59.095960 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s 2026-02-16 21:05:59.096155 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.41s 2026-02-16 21:05:59.096316 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s 2026-02-16 21:05:59.096478 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.39s 2026-02-16 21:05:59.096636 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.38s 2026-02-16 21:05:59.096803 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.37s 2026-02-16 21:05:59.096970 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-02-16 21:05:59.097135 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.28s 2026-02-16 21:05:59.097308 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-02-16 21:05:59.097469 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-16 21:05:59.097625 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-16 21:05:59.177836 | controller | INFO [helm > idempotence] Executed: Successful 2026-02-16 21:05:59.186863 | controller | INFO [helm > side_effect] Executing 2026-02-16 21:05:59.187303 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:05:59.193128 | controller | INFO [helm > verify] Executing 2026-02-16 21:05:59.957387 | controller | 2026-02-16 21:05:59.957680 | controller | PLAY [Verify] ****************************************************************** 2026-02-16 21:05:59.957911 | controller | 2026-02-16 21:05:59.958155 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:05:59.958400 | controller | Monday 16 February 2026 21:05:59 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-02-16 21:06:00.897215 | 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:06:00.903621 | controller | ok: [controller] 2026-02-16 21:06:00.903806 | controller | 2026-02-16 21:06:00.904004 | controller | TASK [Get the current Helm version] ******************************************** 2026-02-16 21:06:00.904208 | controller | Monday 16 February 2026 21:06:00 +0000 (0:00:00.946) 0:00:00.955 ******* 2026-02-16 21:06:01.320540 | controller | changed: [controller] 2026-02-16 21:06:01.320825 | controller | 2026-02-16 21:06:01.321085 | controller | TASK [Assert Helm version] ***************************************************** 2026-02-16 21:06:01.321340 | controller | Monday 16 February 2026 21:06:01 +0000 (0:00:00.416) 0:00:01.371 ******* 2026-02-16 21:06:01.341752 | controller | ok: [controller] => { 2026-02-16 21:06:01.342018 | controller | "changed": false, 2026-02-16 21:06:01.342266 | controller | "msg": "All assertions passed" 2026-02-16 21:06:01.342504 | controller | } 2026-02-16 21:06:01.342732 | controller | 2026-02-16 21:06:01.342971 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:06:01.343301 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:06:01.343538 | controller | 2026-02-16 21:06:01.343761 | controller | 2026-02-16 21:06:01.343965 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:06:01.344162 | controller | Monday 16 February 2026 21:06:01 +0000 (0:00:00.022) 0:00:01.393 ******* 2026-02-16 21:06:01.344310 | controller | =============================================================================== 2026-02-16 21:06:01.344462 | controller | Gathering Facts --------------------------------------------------------- 0.95s 2026-02-16 21:06:01.344614 | controller | Get the current Helm version -------------------------------------------- 0.42s 2026-02-16 21:06:01.344764 | controller | Assert Helm version ----------------------------------------------------- 0.02s 2026-02-16 21:06:01.413879 | controller | INFO [helm > verify] Executed: Successful 2026-02-16 21:06:01.424123 | controller | INFO [helm > cleanup] Executing 2026-02-16 21:06:01.424592 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:06:01.437592 | controller | INFO [helm > destroy] Executing 2026-02-16 21:06:01.437725 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-16 21:06:01.437844 | controller | INFO [helm > destroy] Executed: Successful 2026-02-16 21:06:01.438210 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-16 21:06:01.579275 | controller | ok: Runtime: 0:00:50.653180 2026-02-16 21:06:01.584597 | 2026-02-16 21:06:01.584662 | PLAY RECAP 2026-02-16 21:06:01.584722 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:06:01.584754 | 2026-02-16 21:06:01.694878 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:06:01.696351 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:06:02.379677 | 2026-02-16 21:06:02.379813 | PLAY [all] 2026-02-16 21:06:02.390044 | 2026-02-16 21:06:02.390124 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 21:06:02.435559 | controller | skipping: Conditional result was False 2026-02-16 21:06:02.443939 | 2026-02-16 21:06:02.444056 | TASK [fetch-output : Set log path for single node] 2026-02-16 21:06:02.486589 | controller | ok 2026-02-16 21:06:02.490610 | 2026-02-16 21:06:02.490704 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 21:06:02.880800 | controller -> localhost | ok: "/var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/work/logs" 2026-02-16 21:06:03.112240 | controller -> localhost | changed: "/var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/work/artifacts" 2026-02-16 21:06:03.333890 | controller -> localhost | changed: "/var/lib/zuul/builds/76c81701ebb8445c9f50e0277cacb6cb/work/docs" 2026-02-16 21:06:03.348606 | 2026-02-16 21:06:03.348786 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 21:06:04.005969 | controller | ok 2026-02-16 21:06:04.006438 | controller | changed: All items complete 2026-02-16 21:06:04.006512 | 2026-02-16 21:06:04.479119 | controller | changed: .d..t...... ./ 2026-02-16 21:06:04.950611 | controller | changed: .d..t...... ./ 2026-02-16 21:06:04.973451 | 2026-02-16 21:06:04.973582 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 21:06:05.414131 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008813 2026-02-16 21:06:05.649007 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008042 2026-02-16 21:06:05.671454 | 2026-02-16 21:06:05.671552 | PLAY [all] 2026-02-16 21:06:05.677644 | 2026-02-16 21:06:05.677708 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 21:06:06.127705 | controller | changed 2026-02-16 21:06:06.133921 | 2026-02-16 21:06:06.133986 | PLAY RECAP 2026-02-16 21:06:06.134044 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 21:06:06.134073 | 2026-02-16 21:06:06.248847 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:06:06.250334 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 21:06:06.849715 | 2026-02-16 21:06:06.849878 | PLAY [localhost] 2026-02-16 21:06:06.860349 | 2026-02-16 21:06:06.860450 | TASK [Generate Zuul manifest] 2026-02-16 21:06:06.883639 | localhost | ok 2026-02-16 21:06:06.903934 | 2026-02-16 21:06:06.904115 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 21:06:07.219497 | localhost | changed 2026-02-16 21:06:07.231125 | 2026-02-16 21:06:07.231203 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 21:06:07.261871 | localhost | ok 2026-02-16 21:06:07.270435 | 2026-02-16 21:06:07.270519 | TASK [Upload logs] 2026-02-16 21:06:07.290962 | localhost | ok 2026-02-16 21:06:07.350937 | 2026-02-16 21:06:07.351067 | TASK [Set zuul-log-path fact] 2026-02-16 21:06:07.370173 | localhost | ok 2026-02-16 21:06:07.384000 | 2026-02-16 21:06:07.384072 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:06:07.414295 | localhost | ok 2026-02-16 21:06:07.425781 | 2026-02-16 21:06:07.425850 | TASK [upload-logs : Create log directories] 2026-02-16 21:06:07.820494 | localhost | changed 2026-02-16 21:06:07.826776 | 2026-02-16 21:06:07.826871 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 21:06:08.250391 | localhost -> localhost | ok: Runtime: 0:00:00.007470 2026-02-16 21:06:08.255312 | 2026-02-16 21:06:08.255379 | TASK [upload-logs : Upload logs to log server] 2026-02-16 21:06:08.743104 | localhost | Output suppressed because no_log was given 2026-02-16 21:06:08.749971 | 2026-02-16 21:06:08.750130 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 21:06:08.799007 | localhost | skipping: Conditional result was False 2026-02-16 21:06:08.805667 | localhost | skipping: Conditional result was False 2026-02-16 21:06:08.817647 | 2026-02-16 21:06:08.817809 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 21:06:08.864698 | localhost | skipping: Conditional result was False 2026-02-16 21:06:08.865101 | 2026-02-16 21:06:08.868376 | localhost | skipping: Conditional result was False 2026-02-16 21:06:08.884253 | 2026-02-16 21:06:08.885024 | LOOP [upload-logs : Upload console log and json output]