2026-02-28 03:50:22.967763 | Job console starting 2026-02-28 03:50:23.322054 | Updating git repos 2026-02-28 03:50:23.362322 | Cloning repos into workspace 2026-02-28 03:50:26.524611 | Restoring repo states 2026-02-28 03:50:26.537911 | Merging changes 2026-02-28 03:50:27.861691 | Checking out repos 2026-02-28 03:50:28.655794 | Preparing playbooks 2026-02-28 03:50:38.321632 | Running Ansible setup 2026-02-28 03:50:42.386149 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 03:50:43.011797 | 2026-02-28 03:50:43.011931 | PLAY [localhost] 2026-02-28 03:50:43.026153 | 2026-02-28 03:50:43.026276 | TASK [Gathering Facts] 2026-02-28 03:50:44.024865 | localhost | ok 2026-02-28 03:50:44.033545 | 2026-02-28 03:50:44.033625 | TASK [Setup log path fact] 2026-02-28 03:50:44.053295 | localhost | ok 2026-02-28 03:50:44.068225 | 2026-02-28 03:50:44.068299 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 03:50:44.099932 | localhost | ok 2026-02-28 03:50:44.108705 | 2026-02-28 03:50:44.108775 | TASK [emit-job-header : Print job information] 2026-02-28 03:50:44.158942 | # Job Information 2026-02-28 03:50:44.159191 | Ansible Version: 2.16.16 2026-02-28 03:50:44.159233 | Job: ansible-collection-kubernetes-molecule-helm 2026-02-28 03:50:44.159275 | Pipeline: check 2026-02-28 03:50:44.159322 | Executor: 0a8996d2b663 2026-02-28 03:50:44.159359 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/228 2026-02-28 03:50:44.159568 | Event ID: 6fc3b350-1458-11f1-8c58-29f65cadad4c 2026-02-28 03:50:44.165225 | 2026-02-28 03:50:44.165329 | LOOP [emit-job-header : Print node information] 2026-02-28 03:50:44.261983 | localhost | ok: 2026-02-28 03:50:44.262215 | localhost | # Node Information 2026-02-28 03:50:44.262248 | localhost | Inventory Hostname: controller 2026-02-28 03:50:44.262273 | localhost | Hostname: np0000157472 2026-02-28 03:50:44.262297 | localhost | Username: zuul 2026-02-28 03:50:44.262324 | localhost | Distro: Debian 13.3 2026-02-28 03:50:44.262346 | localhost | Provider: yul1 2026-02-28 03:50:44.262368 | localhost | Region: ca-ymq-1 2026-02-28 03:50:44.262390 | localhost | Label: debian-trixie 2026-02-28 03:50:44.262411 | localhost | Product Name: OpenStack Nova 2026-02-28 03:50:44.262433 | localhost | Interface IP: 199.204.45.74 2026-02-28 03:50:44.274915 | 2026-02-28 03:50:44.275059 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-28 03:50:44.706261 | localhost -> localhost | changed 2026-02-28 03:50:44.711356 | 2026-02-28 03:50:44.711421 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-28 03:50:45.890335 | localhost -> localhost | changed 2026-02-28 03:50:45.902204 | 2026-02-28 03:50:45.902323 | PLAY [all] 2026-02-28 03:50:45.927095 | 2026-02-28 03:50:45.927169 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-28 03:50:46.180357 | controller -> localhost | ok 2026-02-28 03:50:46.187702 | 2026-02-28 03:50:46.187896 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-28 03:50:46.219797 | controller | ok 2026-02-28 03:50:46.234730 | controller | included: /var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-28 03:50:46.239099 | 2026-02-28 03:50:46.239156 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-28 03:50:47.156151 | controller -> localhost | Generating public/private rsa key pair. 2026-02-28 03:50:47.156331 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/work/ff47e59b824e45e484d0172fde2c51c6_id_rsa 2026-02-28 03:50:47.156371 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/work/ff47e59b824e45e484d0172fde2c51c6_id_rsa.pub 2026-02-28 03:50:47.156402 | controller -> localhost | The key fingerprint is: 2026-02-28 03:50:47.156432 | controller -> localhost | SHA256:La5ZVtJLFu/D5hegv34YoHPc2SwJXi8ppOlUHg26duQ zuul-build-sshkey 2026-02-28 03:50:47.156475 | controller -> localhost | The key's randomart image is: 2026-02-28 03:50:47.156505 | controller -> localhost | +---[RSA 3072]----+ 2026-02-28 03:50:47.156539 | controller -> localhost | | | 2026-02-28 03:50:47.156569 | controller -> localhost | | . | 2026-02-28 03:50:47.156599 | controller -> localhost | | ..o | 2026-02-28 03:50:47.156627 | controller -> localhost | | .oBo+ | 2026-02-28 03:50:47.156655 | controller -> localhost | | S&*B.O | 2026-02-28 03:50:47.156684 | controller -> localhost | | .O*E+O = | 2026-02-28 03:50:47.156713 | controller -> localhost | | +++.o== . | 2026-02-28 03:50:47.156766 | controller -> localhost | | =. oo.o | 2026-02-28 03:50:47.156825 | controller -> localhost | | o .++ | 2026-02-28 03:50:47.156863 | controller -> localhost | +----[SHA256]-----+ 2026-02-28 03:50:47.156953 | controller -> localhost | ok: Runtime: 0:00:00.499954 2026-02-28 03:50:47.164093 | 2026-02-28 03:50:47.164189 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-28 03:50:47.194012 | controller | ok 2026-02-28 03:50:47.203884 | controller | included: /var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-28 03:50:47.211068 | 2026-02-28 03:50:47.211128 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-28 03:50:47.235809 | controller | skipping: Conditional result was False 2026-02-28 03:50:47.242714 | 2026-02-28 03:50:47.242787 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-28 03:50:47.810878 | controller | changed 2026-02-28 03:50:47.815858 | 2026-02-28 03:50:47.815919 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-28 03:50:48.030373 | controller | ok 2026-02-28 03:50:48.034604 | 2026-02-28 03:50:48.034665 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-28 03:50:48.568423 | controller | changed 2026-02-28 03:50:48.572524 | 2026-02-28 03:50:48.572587 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-28 03:50:49.119223 | controller | changed 2026-02-28 03:50:49.123635 | 2026-02-28 03:50:49.123698 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-28 03:50:49.150424 | controller | skipping: Conditional result was False 2026-02-28 03:50:49.158005 | 2026-02-28 03:50:49.158077 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-28 03:50:49.509704 | controller -> localhost | changed 2026-02-28 03:50:49.519258 | 2026-02-28 03:50:49.519322 | TASK [add-build-sshkey : Add back temp key] 2026-02-28 03:50:49.784397 | controller -> localhost | Identity added: /var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/work/ff47e59b824e45e484d0172fde2c51c6_id_rsa (zuul-build-sshkey) 2026-02-28 03:50:49.784595 | controller -> localhost | ok: Runtime: 0:00:00.014406 2026-02-28 03:50:49.788649 | 2026-02-28 03:50:49.788710 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-28 03:50:50.157709 | controller | ok 2026-02-28 03:50:50.161865 | 2026-02-28 03:50:50.161940 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-28 03:50:50.187975 | controller | skipping: Conditional result was False 2026-02-28 03:50:50.198859 | 2026-02-28 03:50:50.198929 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-28 03:50:50.529560 | controller | ok 2026-02-28 03:50:50.535755 | 2026-02-28 03:50:50.535842 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-28 03:50:51.654019 | controller | Output suppressed because no_log was given 2026-02-28 03:50:51.662437 | 2026-02-28 03:50:51.662525 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-28 03:50:51.879112 | controller | ok: "logs" 2026-02-28 03:50:51.879419 | controller | ok: All items complete 2026-02-28 03:50:51.879449 | 2026-02-28 03:50:52.061976 | controller | ok: "artifacts" 2026-02-28 03:50:52.236816 | controller | ok: "docs" 2026-02-28 03:50:52.256682 | 2026-02-28 03:50:52.256829 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-28 03:50:52.462053 | controller | changed: "logs" 2026-02-28 03:50:52.644745 | controller | changed: "artifacts" 2026-02-28 03:50:52.840093 | controller | changed: "docs" 2026-02-28 03:50:52.853431 | 2026-02-28 03:50:52.853600 | PLAY RECAP 2026-02-28 03:50:52.853744 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-28 03:50:52.855700 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:50:52.855748 | 2026-02-28 03:50:53.011245 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 03:50:53.012379 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 03:50:53.609256 | 2026-02-28 03:50:53.609377 | PLAY [all] 2026-02-28 03:50:53.619242 | 2026-02-28 03:50:53.619313 | TASK [setup-uv : Extract archive] 2026-02-28 03:50:56.990975 | controller | changed 2026-02-28 03:50:56.996393 | 2026-02-28 03:50:56.996476 | TASK [setup-uv : Print version] 2026-02-28 03:50:57.423792 | controller | uv 0.8.13 2026-02-28 03:50:57.523544 | controller | ok: Runtime: 0:00:00.013153 2026-02-28 03:50:57.536604 | 2026-02-28 03:50:57.536764 | PLAY RECAP 2026-02-28 03:50:57.536818 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:50:57.536846 | 2026-02-28 03:50:57.734900 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 03:50:57.738460 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 03:50:58.354292 | 2026-02-28 03:50:58.369555 | PLAY [all] 2026-02-28 03:50:58.384578 | 2026-02-28 03:50:58.384677 | TASK [Copy inventory file for Zuul] 2026-02-28 03:50:59.384428 | controller | changed 2026-02-28 03:50:59.390032 | 2026-02-28 03:50:59.390132 | TASK [Switch "ansible_host" to private IP] 2026-02-28 03:50:59.681420 | controller | changed: 1 replacements made 2026-02-28 03:50:59.686825 | 2026-02-28 03:50:59.686893 | TASK [Run Molecule scenario] 2026-02-28 03:51:00.173124 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-28 03:51:00.173218 | controller | Creating virtual environment at: .venv 2026-02-28 03:51:00.451570 | controller | Downloading cryptography (4.3MiB) 2026-02-28 03:51:00.451710 | controller | Downloading ansible-core (2.3MiB) 2026-02-28 03:51:00.451923 | controller | Downloading pygments (1.2MiB) 2026-02-28 03:51:00.636901 | controller | Downloading cryptography 2026-02-28 03:51:00.680863 | controller | Downloading pygments 2026-02-28 03:51:00.895877 | controller | Downloading ansible-core 2026-02-28 03:51:00.944306 | controller | Installed 36 packages in 47ms 2026-02-28 03:51:01.600063 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-28 03:51:01.600146 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-28 03:51:02.439158 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-28 03:51:02.439221 | controller | INFO default scenario not found, disabling shared state. 2026-02-28 03:51:02.439243 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-28 03:51:02.439322 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-02-28 03:51:19.690089 | controller | INFO [helm > dependency] Executing 2026-02-28 03:51:19.690270 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-02-28 03:51:19.690574 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-02-28 03:51:19.690735 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-28 03:51:19.698187 | controller | INFO [helm > cleanup] Executing 2026-02-28 03:51:19.698619 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:19.704193 | controller | INFO [helm > destroy] Executing 2026-02-28 03:51:19.704250 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-28 03:51:19.704323 | controller | INFO [helm > destroy] Executed: Successful 2026-02-28 03:51:19.709549 | controller | INFO [helm > syntax] Executing 2026-02-28 03:51:20.577415 | controller | 2026-02-28 03:51:20.577643 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-02-28 03:51:20.637364 | controller | INFO [helm > syntax] Executed: Successful 2026-02-28 03:51:20.648466 | controller | INFO [helm > create] Executing 2026-02-28 03:51:20.649899 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:20.655140 | controller | INFO [helm > prepare] Executing 2026-02-28 03:51:21.470307 | controller | 2026-02-28 03:51:21.470401 | controller | PLAY [Prepare] ***************************************************************** 2026-02-28 03:51:21.470563 | controller | 2026-02-28 03:51:21.470770 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:21.470799 | controller | Saturday 28 February 2026 03:51:21 +0000 (0:00:00.028) 0:00:00.028 ***** 2026-02-28 03:51:23.764599 | 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-28 03:51:23.771416 | controller | ok: [controller] 2026-02-28 03:51:23.771591 | controller | 2026-02-28 03:51:23.771793 | controller | TASK [Install Debian packages] ************************************************* 2026-02-28 03:51:23.772037 | controller | Saturday 28 February 2026 03:51:23 +0000 (0:00:02.301) 0:00:02.329 ***** 2026-02-28 03:51:29.180825 | controller | ok: [controller] 2026-02-28 03:51:29.180893 | controller | 2026-02-28 03:51:29.181015 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:29.181151 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:29.181258 | controller | 2026-02-28 03:51:29.181364 | controller | 2026-02-28 03:51:29.181474 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:29.181594 | controller | Saturday 28 February 2026 03:51:29 +0000 (0:00:05.409) 0:00:07.739 ***** 2026-02-28 03:51:29.181718 | controller | =============================================================================== 2026-02-28 03:51:29.181828 | controller | Install Debian packages ------------------------------------------------- 5.41s 2026-02-28 03:51:29.181945 | controller | Gathering Facts --------------------------------------------------------- 2.30s 2026-02-28 03:51:29.313908 | controller | INFO [helm > prepare] Executed: Successful 2026-02-28 03:51:29.323364 | controller | INFO [helm > converge] Executing 2026-02-28 03:51:30.265984 | controller | 2026-02-28 03:51:30.266129 | controller | PLAY [Converge] **************************************************************** 2026-02-28 03:51:30.266318 | controller | 2026-02-28 03:51:30.266536 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:30.266749 | controller | Saturday 28 February 2026 03:51:30 +0000 (0:00:00.013) 0:00:00.013 ***** 2026-02-28 03:51:31.388893 | 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-28 03:51:31.391208 | controller | ok: [controller] 2026-02-28 03:51:31.391391 | controller | 2026-02-28 03:51:31.391598 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-28 03:51:31.391811 | controller | Saturday 28 February 2026 03:51:31 +0000 (0:00:01.125) 0:00:01.138 ***** 2026-02-28 03:51:31.870050 | controller | ok: [controller] 2026-02-28 03:51:31.870150 | controller | 2026-02-28 03:51:31.870377 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-28 03:51:31.870627 | controller | Saturday 28 February 2026 03:51:31 +0000 (0:00:00.478) 0:00:01.617 ***** 2026-02-28 03:51:32.932032 | controller | ok: [controller] 2026-02-28 03:51:32.932095 | controller | 2026-02-28 03:51:32.932104 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-28 03:51:32.932115 | controller | Saturday 28 February 2026 03:51:32 +0000 (0:00:01.058) 0:00:02.675 ***** 2026-02-28 03:51:33.368849 | controller | changed: [controller] 2026-02-28 03:51:33.369052 | controller | 2026-02-28 03:51:33.369278 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:33.369478 | controller | Saturday 28 February 2026 03:51:33 +0000 (0:00:00.440) 0:00:03.115 ***** 2026-02-28 03:51:33.427918 | controller | ok: [controller] => { 2026-02-28 03:51:33.428168 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-28 03:51:33.428472 | controller | } 2026-02-28 03:51:33.428732 | controller | 2026-02-28 03:51:33.428990 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:33.429175 | controller | Saturday 28 February 2026 03:51:33 +0000 (0:00:00.059) 0:00:03.174 ***** 2026-02-28 03:51:34.205236 | 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-28 03:51:34.215897 | controller | changed: [controller] 2026-02-28 03:51:34.216148 | controller | 2026-02-28 03:51:34.216355 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:34.216551 | controller | Saturday 28 February 2026 03:51:34 +0000 (0:00:00.784) 0:00:03.959 ***** 2026-02-28 03:51:36.304492 | controller | changed: [controller] 2026-02-28 03:51:36.304561 | controller | 2026-02-28 03:51:36.304643 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-28 03:51:36.308037 | controller | Saturday 28 February 2026 03:51:36 +0000 (0:00:02.090) 0:00:06.049 ***** 2026-02-28 03:51:36.824444 | controller | ok: [controller] 2026-02-28 03:51:36.824573 | controller | 2026-02-28 03:51:36.824777 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-28 03:51:36.824981 | controller | Saturday 28 February 2026 03:51:36 +0000 (0:00:00.521) 0:00:06.571 ***** 2026-02-28 03:51:37.227097 | controller | changed: [controller] 2026-02-28 03:51:37.227252 | controller | 2026-02-28 03:51:37.227469 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-28 03:51:37.227676 | controller | Saturday 28 February 2026 03:51:37 +0000 (0:00:00.402) 0:00:06.974 ***** 2026-02-28 03:51:37.691818 | controller | ok: [controller] 2026-02-28 03:51:37.691908 | controller | 2026-02-28 03:51:37.692171 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-28 03:51:37.692353 | controller | Saturday 28 February 2026 03:51:37 +0000 (0:00:00.464) 0:00:07.438 ***** 2026-02-28 03:51:37.988136 | controller | changed: [controller] 2026-02-28 03:51:37.988298 | controller | 2026-02-28 03:51:37.988520 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-28 03:51:37.988719 | controller | Saturday 28 February 2026 03:51:37 +0000 (0:00:00.296) 0:00:07.735 ***** 2026-02-28 03:51:38.297649 | controller | ok: [controller] 2026-02-28 03:51:38.297728 | controller | 2026-02-28 03:51:38.297892 | controller | TASK [Install plugin] ********************************************************** 2026-02-28 03:51:38.298036 | controller | Saturday 28 February 2026 03:51:38 +0000 (0:00:00.309) 0:00:08.044 ***** 2026-02-28 03:51:38.356617 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-28 03:51:38.356781 | controller | 2026-02-28 03:51:38.356981 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:38.357171 | controller | Saturday 28 February 2026 03:51:38 +0000 (0:00:00.059) 0:00:08.103 ***** 2026-02-28 03:51:38.407355 | controller | ok: [controller] => { 2026-02-28 03:51:38.407514 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-28 03:51:38.407700 | controller | } 2026-02-28 03:51:38.407882 | controller | 2026-02-28 03:51:38.408166 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:38.408325 | controller | Saturday 28 February 2026 03:51:38 +0000 (0:00:00.050) 0:00:08.154 ***** 2026-02-28 03:51:39.237104 | controller | changed: [controller] 2026-02-28 03:51:39.237262 | controller | 2026-02-28 03:51:39.237460 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:39.237650 | controller | Saturday 28 February 2026 03:51:39 +0000 (0:00:00.829) 0:00:08.983 ***** 2026-02-28 03:51:41.363486 | controller | changed: [controller] 2026-02-28 03:51:41.363540 | controller | 2026-02-28 03:51:41.363552 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:41.363564 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:41.363574 | controller | 2026-02-28 03:51:41.363584 | controller | 2026-02-28 03:51:41.363593 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:41.363603 | controller | Saturday 28 February 2026 03:51:41 +0000 (0:00:02.122) 0:00:11.106 ***** 2026-02-28 03:51:41.363613 | controller | =============================================================================== 2026-02-28 03:51:41.363622 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.12s 2026-02-28 03:51:41.363632 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.09s 2026-02-28 03:51:41.363641 | controller | Gathering Facts --------------------------------------------------------- 1.13s 2026-02-28 03:51:41.363651 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.06s 2026-02-28 03:51:41.363660 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.83s 2026-02-28 03:51:41.363669 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.78s 2026-02-28 03:51:41.363679 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s 2026-02-28 03:51:41.363688 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.48s 2026-02-28 03:51:41.363698 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-02-28 03:51:41.363708 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s 2026-02-28 03:51:41.363733 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s 2026-02-28 03:51:41.363744 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-02-28 03:51:41.363753 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-02-28 03:51:41.363763 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-02-28 03:51:41.363771 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-02-28 03:51:41.363778 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-28 03:51:41.482204 | controller | INFO [helm > converge] Executed: Successful 2026-02-28 03:51:41.491827 | controller | INFO [helm > idempotence] Executing 2026-02-28 03:51:42.342340 | controller | 2026-02-28 03:51:42.342640 | controller | PLAY [Converge] **************************************************************** 2026-02-28 03:51:42.342870 | controller | 2026-02-28 03:51:42.343112 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:42.343355 | controller | Saturday 28 February 2026 03:51:42 +0000 (0:00:00.012) 0:00:00.012 ***** 2026-02-28 03:51:43.401948 | 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-28 03:51:43.407898 | controller | ok: [controller] 2026-02-28 03:51:43.408084 | controller | 2026-02-28 03:51:43.408252 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-28 03:51:43.408408 | controller | Saturday 28 February 2026 03:51:43 +0000 (0:00:01.065) 0:00:01.078 ***** 2026-02-28 03:51:43.824649 | controller | ok: [controller] 2026-02-28 03:51:43.824930 | controller | 2026-02-28 03:51:43.825194 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-28 03:51:43.825449 | controller | Saturday 28 February 2026 03:51:43 +0000 (0:00:00.416) 0:00:01.494 ***** 2026-02-28 03:51:44.972490 | controller | ok: [controller] 2026-02-28 03:51:44.972778 | controller | 2026-02-28 03:51:44.973040 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-28 03:51:44.973293 | controller | Saturday 28 February 2026 03:51:44 +0000 (0:00:01.147) 0:00:02.641 ***** 2026-02-28 03:51:45.393165 | controller | ok: [controller] 2026-02-28 03:51:45.393455 | controller | 2026-02-28 03:51:45.393719 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:45.393971 | controller | Saturday 28 February 2026 03:51:45 +0000 (0:00:00.420) 0:00:03.062 ***** 2026-02-28 03:51:45.448534 | controller | ok: [controller] => { 2026-02-28 03:51:45.448813 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-28 03:51:45.449114 | controller | } 2026-02-28 03:51:45.449384 | controller | 2026-02-28 03:51:45.449643 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:45.449889 | controller | Saturday 28 February 2026 03:51:45 +0000 (0:00:00.055) 0:00:03.117 ***** 2026-02-28 03:51:46.004854 | controller | ok: [controller] 2026-02-28 03:51:46.005133 | controller | 2026-02-28 03:51:46.005413 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:46.005731 | controller | Saturday 28 February 2026 03:51:45 +0000 (0:00:00.556) 0:00:03.674 ***** 2026-02-28 03:51:47.736525 | controller | ok: [controller] 2026-02-28 03:51:47.736591 | controller | 2026-02-28 03:51:47.736708 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-28 03:51:47.736851 | controller | Saturday 28 February 2026 03:51:47 +0000 (0:00:01.730) 0:00:05.404 ***** 2026-02-28 03:51:48.317804 | controller | ok: [controller] 2026-02-28 03:51:48.317878 | controller | 2026-02-28 03:51:48.318184 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-28 03:51:48.318225 | controller | Saturday 28 February 2026 03:51:48 +0000 (0:00:00.582) 0:00:05.987 ***** 2026-02-28 03:51:48.764316 | controller | ok: [controller] 2026-02-28 03:51:48.764382 | controller | 2026-02-28 03:51:48.764560 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-28 03:51:48.764709 | controller | Saturday 28 February 2026 03:51:48 +0000 (0:00:00.446) 0:00:06.433 ***** 2026-02-28 03:51:49.204521 | controller | ok: [controller] 2026-02-28 03:51:49.204574 | controller | 2026-02-28 03:51:49.204877 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-28 03:51:49.204891 | controller | Saturday 28 February 2026 03:51:49 +0000 (0:00:00.440) 0:00:06.874 ***** 2026-02-28 03:51:49.508172 | controller | ok: [controller] 2026-02-28 03:51:49.508224 | controller | 2026-02-28 03:51:49.508246 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-28 03:51:49.508255 | controller | Saturday 28 February 2026 03:51:49 +0000 (0:00:00.300) 0:00:07.175 ***** 2026-02-28 03:51:49.797390 | controller | ok: [controller] 2026-02-28 03:51:49.797461 | controller | 2026-02-28 03:51:49.797471 | controller | TASK [Install plugin] ********************************************************** 2026-02-28 03:51:49.797568 | controller | Saturday 28 February 2026 03:51:49 +0000 (0:00:00.291) 0:00:07.466 ***** 2026-02-28 03:51:49.860361 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-28 03:51:49.860441 | controller | 2026-02-28 03:51:49.860451 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-28 03:51:49.860538 | controller | Saturday 28 February 2026 03:51:49 +0000 (0:00:00.063) 0:00:07.530 ***** 2026-02-28 03:51:49.909257 | controller | ok: [controller] => { 2026-02-28 03:51:49.909475 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-28 03:51:49.909625 | controller | } 2026-02-28 03:51:49.910229 | controller | 2026-02-28 03:51:49.910274 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-28 03:51:49.910285 | controller | Saturday 28 February 2026 03:51:49 +0000 (0:00:00.048) 0:00:07.578 ***** 2026-02-28 03:51:50.364017 | controller | ok: [controller] 2026-02-28 03:51:50.364087 | controller | 2026-02-28 03:51:50.364604 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-28 03:51:50.364644 | controller | Saturday 28 February 2026 03:51:50 +0000 (0:00:00.454) 0:00:08.033 ***** 2026-02-28 03:51:52.033257 | controller | ok: [controller] 2026-02-28 03:51:52.033700 | controller | 2026-02-28 03:51:52.033957 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:52.034224 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:52.034451 | controller | 2026-02-28 03:51:52.034671 | controller | 2026-02-28 03:51:52.034913 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:52.035160 | controller | Saturday 28 February 2026 03:51:52 +0000 (0:00:01.666) 0:00:09.699 ***** 2026-02-28 03:51:52.035395 | controller | =============================================================================== 2026-02-28 03:51:52.035641 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.73s 2026-02-28 03:51:52.035884 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.67s 2026-02-28 03:51:52.036162 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.15s 2026-02-28 03:51:52.036406 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-02-28 03:51:52.036651 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.58s 2026-02-28 03:51:52.036897 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.56s 2026-02-28 03:51:52.037163 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.45s 2026-02-28 03:51:52.037410 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.45s 2026-02-28 03:51:52.037652 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s 2026-02-28 03:51:52.037897 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s 2026-02-28 03:51:52.038157 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.42s 2026-02-28 03:51:52.038413 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-02-28 03:51:52.038670 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-02-28 03:51:52.038911 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-02-28 03:51:52.039155 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-02-28 03:51:52.039397 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-28 03:51:52.206278 | controller | INFO [helm > idempotence] Executed: Successful 2026-02-28 03:51:52.218092 | controller | INFO [helm > side_effect] Executing 2026-02-28 03:51:52.218447 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:52.223711 | controller | INFO [helm > verify] Executing 2026-02-28 03:51:53.079157 | controller | 2026-02-28 03:51:53.079493 | controller | PLAY [Verify] ****************************************************************** 2026-02-28 03:51:53.079739 | controller | 2026-02-28 03:51:53.080110 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:53.080446 | controller | Saturday 28 February 2026 03:51:53 +0000 (0:00:00.009) 0:00:00.009 ***** 2026-02-28 03:51:54.168546 | 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-28 03:51:54.175888 | controller | ok: [controller] 2026-02-28 03:51:54.175916 | controller | 2026-02-28 03:51:54.175971 | controller | TASK [Get the current Helm version] ******************************************** 2026-02-28 03:51:54.175984 | controller | Saturday 28 February 2026 03:51:54 +0000 (0:00:01.096) 0:00:01.106 ***** 2026-02-28 03:51:54.672898 | controller | changed: [controller] 2026-02-28 03:51:54.673192 | controller | 2026-02-28 03:51:54.673450 | controller | TASK [Assert Helm version] ***************************************************** 2026-02-28 03:51:54.673707 | controller | Saturday 28 February 2026 03:51:54 +0000 (0:00:00.497) 0:00:01.603 ***** 2026-02-28 03:51:54.707537 | controller | ok: [controller] => { 2026-02-28 03:51:54.707805 | controller | "changed": false, 2026-02-28 03:51:54.708098 | controller | "msg": "All assertions passed" 2026-02-28 03:51:54.708344 | controller | } 2026-02-28 03:51:54.708570 | controller | 2026-02-28 03:51:54.708811 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:54.709078 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:54.709303 | controller | 2026-02-28 03:51:54.709524 | controller | 2026-02-28 03:51:54.709763 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:54.710012 | controller | Saturday 28 February 2026 03:51:54 +0000 (0:00:00.035) 0:00:01.639 ***** 2026-02-28 03:51:54.710267 | controller | =============================================================================== 2026-02-28 03:51:54.710559 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-02-28 03:51:54.710806 | controller | Get the current Helm version -------------------------------------------- 0.50s 2026-02-28 03:51:54.711048 | controller | Assert Helm version ----------------------------------------------------- 0.04s 2026-02-28 03:51:54.836073 | controller | INFO [helm > verify] Executed: Successful 2026-02-28 03:51:54.846637 | controller | INFO [helm > cleanup] Executing 2026-02-28 03:51:54.847078 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:54.855024 | controller | INFO [helm > destroy] Executing 2026-02-28 03:51:54.855149 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-28 03:51:54.855241 | controller | INFO [helm > destroy] Executed: Successful 2026-02-28 03:51:54.855573 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-28 03:51:55.258226 | controller | ok: Runtime: 0:00:54.863101 2026-02-28 03:51:55.262737 | 2026-02-28 03:51:55.262789 | PLAY RECAP 2026-02-28 03:51:55.262833 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:51:55.262854 | 2026-02-28 03:51:55.442051 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 03:51:55.445155 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 03:51:56.003379 | 2026-02-28 03:51:56.003511 | PLAY [all] 2026-02-28 03:51:56.013329 | 2026-02-28 03:51:56.013403 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-28 03:51:56.058382 | controller | skipping: Conditional result was False 2026-02-28 03:51:56.062525 | 2026-02-28 03:51:56.062605 | TASK [fetch-output : Set log path for single node] 2026-02-28 03:51:56.099957 | controller | ok 2026-02-28 03:51:56.103494 | 2026-02-28 03:51:56.103559 | LOOP [fetch-output : Ensure local output dirs] 2026-02-28 03:51:56.535062 | controller -> localhost | ok: "/var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/work/logs" 2026-02-28 03:51:56.792442 | controller -> localhost | changed: "/var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/work/artifacts" 2026-02-28 03:51:56.999839 | controller -> localhost | changed: "/var/lib/zuul/builds/ff47e59b824e45e484d0172fde2c51c6/work/docs" 2026-02-28 03:51:57.010301 | 2026-02-28 03:51:57.010425 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-28 03:51:57.651294 | controller | ok 2026-02-28 03:51:57.651731 | controller | changed: All items complete 2026-02-28 03:51:57.651795 | 2026-02-28 03:51:58.141623 | controller | changed: .d..t...... ./ 2026-02-28 03:51:58.657193 | controller | changed: .d..t...... ./ 2026-02-28 03:51:58.675536 | 2026-02-28 03:51:58.675688 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-28 03:51:59.094236 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008725 2026-02-28 03:51:59.333307 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008843 2026-02-28 03:51:59.352237 | 2026-02-28 03:51:59.352910 | PLAY [all] 2026-02-28 03:51:59.358682 | 2026-02-28 03:51:59.358751 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-28 03:51:59.867277 | controller | changed 2026-02-28 03:51:59.871771 | 2026-02-28 03:51:59.871822 | PLAY RECAP 2026-02-28 03:51:59.871869 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-28 03:51:59.871891 | 2026-02-28 03:51:59.988106 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 03:51:59.990995 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-28 03:52:00.524474 | 2026-02-28 03:52:00.524591 | PLAY [localhost] 2026-02-28 03:52:00.534107 | 2026-02-28 03:52:00.534175 | TASK [Generate Zuul manifest] 2026-02-28 03:52:00.555321 | localhost | ok 2026-02-28 03:52:00.571177 | 2026-02-28 03:52:00.571253 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-28 03:52:00.903170 | localhost | changed 2026-02-28 03:52:00.913240 | 2026-02-28 03:52:00.913311 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-28 03:52:00.944727 | localhost | ok 2026-02-28 03:52:00.953180 | 2026-02-28 03:52:00.953265 | TASK [Upload logs] 2026-02-28 03:52:00.973259 | localhost | ok 2026-02-28 03:52:01.030639 | 2026-02-28 03:52:01.030750 | TASK [Set zuul-log-path fact] 2026-02-28 03:52:01.050666 | localhost | ok 2026-02-28 03:52:01.063668 | 2026-02-28 03:52:01.063733 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 03:52:01.093131 | localhost | ok 2026-02-28 03:52:01.101092 | 2026-02-28 03:52:01.101164 | TASK [upload-logs : Create log directories] 2026-02-28 03:52:01.547727 | localhost | changed 2026-02-28 03:52:01.555389 | 2026-02-28 03:52:01.555485 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-28 03:52:01.992260 | localhost -> localhost | ok: Runtime: 0:00:00.005682 2026-02-28 03:52:01.998618 | 2026-02-28 03:52:01.998703 | TASK [upload-logs : Upload logs to log server] 2026-02-28 03:52:02.506005 | localhost | Output suppressed because no_log was given 2026-02-28 03:52:02.510978 | 2026-02-28 03:52:02.511065 | LOOP [upload-logs : Compress console log and json output] 2026-02-28 03:52:02.561372 | localhost | skipping: Conditional result was False 2026-02-28 03:52:02.568291 | localhost | skipping: Conditional result was False 2026-02-28 03:52:02.585994 | 2026-02-28 03:52:02.586179 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-28 03:52:02.627754 | localhost | skipping: Conditional result was False 2026-02-28 03:52:02.628462 | 2026-02-28 03:52:02.630828 | localhost | skipping: Conditional result was False 2026-02-28 03:52:02.641588 | 2026-02-28 03:52:02.641824 | LOOP [upload-logs : Upload console log and json output]