2026-03-06 02:02:25.764728 | Job console starting 2026-03-06 02:02:25.770713 | Updating git repos 2026-03-06 02:02:25.805296 | Cloning repos into workspace 2026-03-06 02:02:25.866774 | Restoring repo states 2026-03-06 02:02:25.878841 | Merging changes 2026-03-06 02:02:26.261167 | Checking out repos 2026-03-06 02:02:26.294333 | Preparing playbooks 2026-03-06 02:02:27.669308 | Running Ansible setup 2026-03-06 02:02:31.718401 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-06 02:02:32.271848 | 2026-03-06 02:02:32.334746 | PLAY [localhost] 2026-03-06 02:02:32.347495 | 2026-03-06 02:02:32.347565 | TASK [Gathering Facts] 2026-03-06 02:02:33.292718 | localhost | ok 2026-03-06 02:02:33.300076 | 2026-03-06 02:02:33.300152 | TASK [Setup log path fact] 2026-03-06 02:02:33.316767 | localhost | ok 2026-03-06 02:02:33.566618 | 2026-03-06 02:02:33.566705 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-06 02:02:33.595224 | localhost | ok 2026-03-06 02:02:33.603998 | 2026-03-06 02:02:33.604084 | TASK [emit-job-header : Print job information] 2026-03-06 02:02:33.643619 | # Job Information 2026-03-06 02:02:33.643816 | Ansible Version: 2.16.16 2026-03-06 02:02:33.643884 | Job: ansible-collection-kubernetes-molecule-helm 2026-03-06 02:02:33.643932 | Pipeline: check 2026-03-06 02:02:33.643977 | Executor: 0a8996d2b663 2026-03-06 02:02:33.644020 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/235 2026-03-06 02:02:33.644069 | Event ID: 62655ae0-1900-11f1-9569-19afb6ddb422 2026-03-06 02:02:33.649625 | 2026-03-06 02:02:33.649686 | LOOP [emit-job-header : Print node information] 2026-03-06 02:02:33.725912 | localhost | ok: 2026-03-06 02:02:33.726126 | localhost | # Node Information 2026-03-06 02:02:33.726162 | localhost | Inventory Hostname: controller 2026-03-06 02:02:33.726314 | localhost | Hostname: np0000158027 2026-03-06 02:02:33.726343 | localhost | Username: zuul 2026-03-06 02:02:33.726369 | localhost | Distro: Debian 13.3 2026-03-06 02:02:33.726390 | localhost | Provider: yul1 2026-03-06 02:02:33.726409 | localhost | Region: ca-ymq-1 2026-03-06 02:02:33.726428 | localhost | Label: debian-trixie 2026-03-06 02:02:33.726447 | localhost | Product Name: OpenStack Nova 2026-03-06 02:02:33.726465 | localhost | Interface IP: 199.204.45.223 2026-03-06 02:02:33.748877 | 2026-03-06 02:02:33.749124 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-06 02:02:34.413296 | localhost -> localhost | changed 2026-03-06 02:02:34.422532 | 2026-03-06 02:02:34.422667 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-06 02:02:35.410126 | localhost -> localhost | changed 2026-03-06 02:02:35.419633 | 2026-03-06 02:02:35.419746 | PLAY [all] 2026-03-06 02:02:35.431820 | 2026-03-06 02:02:35.432172 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-06 02:02:35.660312 | controller -> localhost | ok 2026-03-06 02:02:35.670343 | 2026-03-06 02:02:35.670500 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-06 02:02:35.702500 | controller | ok 2026-03-06 02:02:35.721260 | controller | included: /var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-06 02:02:35.726653 | 2026-03-06 02:02:35.726738 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-06 02:02:36.411676 | controller -> localhost | Generating public/private rsa key pair. 2026-03-06 02:02:36.411881 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/work/66c3793a35874c2daaf282e9da5043ea_id_rsa 2026-03-06 02:02:36.411931 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/work/66c3793a35874c2daaf282e9da5043ea_id_rsa.pub 2026-03-06 02:02:36.411961 | controller -> localhost | The key fingerprint is: 2026-03-06 02:02:36.411982 | controller -> localhost | SHA256:W3cpf5RGWK+i9Bf8KOfUiXn7PA3VfR1+qTU6pdtZFhw zuul-build-sshkey 2026-03-06 02:02:36.412020 | controller -> localhost | The key's randomart image is: 2026-03-06 02:02:36.412041 | controller -> localhost | +---[RSA 3072]----+ 2026-03-06 02:02:36.412069 | controller -> localhost | | . | 2026-03-06 02:02:36.412091 | controller -> localhost | | oE.| 2026-03-06 02:02:36.412141 | controller -> localhost | | .o.O| 2026-03-06 02:02:36.412170 | controller -> localhost | | ..@O| 2026-03-06 02:02:36.412191 | controller -> localhost | | S o + %+*| 2026-03-06 02:02:36.412211 | controller -> localhost | | + + X=*+| 2026-03-06 02:02:36.412230 | controller -> localhost | | . . ooX=B| 2026-03-06 02:02:36.412250 | controller -> localhost | | B.*o| 2026-03-06 02:02:36.412271 | controller -> localhost | | ..=| 2026-03-06 02:02:36.412292 | controller -> localhost | +----[SHA256]-----+ 2026-03-06 02:02:36.412341 | controller -> localhost | ok: Runtime: 0:00:00.294989 2026-03-06 02:02:36.417293 | 2026-03-06 02:02:36.417358 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-06 02:02:36.453509 | controller | ok 2026-03-06 02:02:36.463598 | controller | included: /var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-06 02:02:36.469811 | 2026-03-06 02:02:36.469871 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-06 02:02:36.493802 | controller | skipping: Conditional result was False 2026-03-06 02:02:36.504383 | 2026-03-06 02:02:36.504468 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-06 02:02:37.050186 | controller | changed 2026-03-06 02:02:37.056444 | 2026-03-06 02:02:37.056527 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-06 02:02:37.250844 | controller | ok 2026-03-06 02:02:37.254837 | 2026-03-06 02:02:37.254899 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-06 02:02:37.797871 | controller | changed 2026-03-06 02:02:37.803835 | 2026-03-06 02:02:37.803921 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-06 02:02:38.340974 | controller | changed 2026-03-06 02:02:38.346895 | 2026-03-06 02:02:38.346954 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-06 02:02:38.371019 | controller | skipping: Conditional result was False 2026-03-06 02:02:38.381967 | 2026-03-06 02:02:38.382036 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-06 02:02:38.939498 | controller -> localhost | changed 2026-03-06 02:02:38.950853 | 2026-03-06 02:02:38.950949 | TASK [add-build-sshkey : Add back temp key] 2026-03-06 02:02:39.236222 | controller -> localhost | Identity added: /var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/work/66c3793a35874c2daaf282e9da5043ea_id_rsa (zuul-build-sshkey) 2026-03-06 02:02:39.236432 | controller -> localhost | ok: Runtime: 0:00:00.012719 2026-03-06 02:02:39.241562 | 2026-03-06 02:02:39.241620 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-06 02:02:39.556392 | controller | ok 2026-03-06 02:02:39.563156 | 2026-03-06 02:02:39.563268 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-06 02:02:39.586856 | controller | skipping: Conditional result was False 2026-03-06 02:02:39.594133 | 2026-03-06 02:02:39.594193 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-06 02:02:39.944260 | controller | ok 2026-03-06 02:02:39.952066 | 2026-03-06 02:02:39.952127 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-06 02:02:41.100867 | controller | Output suppressed because no_log was given 2026-03-06 02:02:41.107995 | 2026-03-06 02:02:41.108070 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-06 02:02:41.337470 | controller | ok: "logs" 2026-03-06 02:02:41.337696 | controller | ok: All items complete 2026-03-06 02:02:41.337727 | 2026-03-06 02:02:41.522957 | controller | ok: "artifacts" 2026-03-06 02:02:41.701430 | controller | ok: "docs" 2026-03-06 02:02:41.708076 | 2026-03-06 02:02:41.708194 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-06 02:02:41.921846 | controller | changed: "logs" 2026-03-06 02:02:42.097488 | controller | changed: "artifacts" 2026-03-06 02:02:42.281117 | controller | changed: "docs" 2026-03-06 02:02:42.301263 | 2026-03-06 02:02:42.301370 | PLAY RECAP 2026-03-06 02:02:42.301418 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-06 02:02:42.301445 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-06 02:02:42.301465 | 2026-03-06 02:02:42.404928 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-06 02:02:42.407338 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-06 02:02:42.931312 | 2026-03-06 02:02:43.050074 | PLAY [all] 2026-03-06 02:02:43.069354 | 2026-03-06 02:02:43.069499 | TASK [setup-uv : Extract archive] 2026-03-06 02:02:45.600920 | controller | changed 2026-03-06 02:02:45.609188 | 2026-03-06 02:02:45.609262 | TASK [setup-uv : Print version] 2026-03-06 02:02:46.714543 | controller | uv 0.8.13 2026-03-06 02:02:46.133670 | controller | ok: Runtime: 0:00:00.014148 2026-03-06 02:02:46.138611 | 2026-03-06 02:02:46.138659 | PLAY RECAP 2026-03-06 02:02:46.138701 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-06 02:02:46.138725 | 2026-03-06 02:02:46.297828 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-06 02:02:46.313044 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-06 02:02:46.872005 | 2026-03-06 02:02:47.002163 | PLAY [all] 2026-03-06 02:02:47.017768 | 2026-03-06 02:02:47.017951 | TASK [Copy inventory file for Zuul] 2026-03-06 02:02:47.944217 | controller | changed 2026-03-06 02:02:47.948603 | 2026-03-06 02:02:47.948683 | TASK [Switch "ansible_host" to private IP] 2026-03-06 02:02:48.254742 | controller | changed: 1 replacements made 2026-03-06 02:02:48.261929 | 2026-03-06 02:02:48.262022 | TASK [Run Molecule scenario] 2026-03-06 02:02:49.489139 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-06 02:02:49.489220 | controller | Creating virtual environment at: .venv 2026-03-06 02:02:50.279260 | controller | Downloading pygments (1.2MiB) 2026-03-06 02:02:50.331451 | controller | Downloading ansible-core (2.3MiB) 2026-03-06 02:02:50.333310 | controller | Downloading cryptography (4.3MiB) 2026-03-06 02:02:50.576707 | controller | Downloading cryptography 2026-03-06 02:02:50.589893 | controller | Downloading pygments 2026-03-06 02:02:50.786136 | controller | Downloading ansible-core 2026-03-06 02:02:50.834609 | controller | Installed 36 packages in 47ms 2026-03-06 02:02:51.496651 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-06 02:02:51.496691 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-06 02:02:52.329829 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-06 02:02:52.329940 | controller | INFO default scenario not found, disabling shared state. 2026-03-06 02:02:52.330133 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-06 02:02:52.330236 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-03-06 02:03:22.245702 | controller | INFO [helm > dependency] Executing 2026-03-06 02:03:22.246221 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-03-06 02:03:22.246686 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-03-06 02:03:22.246962 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-06 02:03:22.257705 | controller | INFO [helm > cleanup] Executing 2026-03-06 02:03:22.258071 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-06 02:03:22.263342 | controller | INFO [helm > destroy] Executing 2026-03-06 02:03:22.263408 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-06 02:03:22.263479 | controller | INFO [helm > destroy] Executed: Successful 2026-03-06 02:03:22.268694 | controller | INFO [helm > syntax] Executing 2026-03-06 02:03:23.148374 | controller | 2026-03-06 02:03:23.148581 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-03-06 02:03:23.206660 | controller | INFO [helm > syntax] Executed: Successful 2026-03-06 02:03:23.216997 | controller | INFO [helm > create] Executing 2026-03-06 02:03:23.219046 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-06 02:03:23.224718 | controller | INFO [helm > prepare] Executing 2026-03-06 02:03:24.055379 | controller | 2026-03-06 02:03:24.055461 | controller | PLAY [Prepare] ***************************************************************** 2026-03-06 02:03:24.055694 | controller | 2026-03-06 02:03:24.055904 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-06 02:03:24.056116 | controller | Friday 06 March 2026 02:03:24 +0000 (0:00:00.028) 0:00:00.028 ********** 2026-03-06 02:03:25.352736 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-06 02:03:25.358897 | controller | ok: [controller] 2026-03-06 02:03:25.359085 | controller | 2026-03-06 02:03:25.359281 | controller | TASK [Install Debian packages] ************************************************* 2026-03-06 02:03:25.359481 | controller | Friday 06 March 2026 02:03:25 +0000 (0:00:01.304) 0:00:01.332 ********** 2026-03-06 02:03:30.315049 | controller | ok: [controller] 2026-03-06 02:03:30.315171 | controller | 2026-03-06 02:03:30.315355 | controller | PLAY RECAP ********************************************************************* 2026-03-06 02:03:30.315566 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-06 02:03:30.315774 | controller | 2026-03-06 02:03:30.315947 | controller | 2026-03-06 02:03:30.316120 | controller | TASKS RECAP ******************************************************************** 2026-03-06 02:03:30.316308 | controller | Friday 06 March 2026 02:03:30 +0000 (0:00:04.956) 0:00:06.289 ********** 2026-03-06 02:03:30.316478 | controller | =============================================================================== 2026-03-06 02:03:30.316663 | controller | Install Debian packages ------------------------------------------------- 4.96s 2026-03-06 02:03:30.316844 | controller | Gathering Facts --------------------------------------------------------- 1.30s 2026-03-06 02:03:30.419596 | controller | INFO [helm > prepare] Executed: Successful 2026-03-06 02:03:30.428861 | controller | INFO [helm > converge] Executing 2026-03-06 02:03:31.273962 | controller | 2026-03-06 02:03:31.274042 | controller | PLAY [Converge] **************************************************************** 2026-03-06 02:03:31.274245 | controller | 2026-03-06 02:03:31.274493 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-06 02:03:31.274728 | controller | Friday 06 March 2026 02:03:31 +0000 (0:00:00.014) 0:00:00.014 ********** 2026-03-06 02:03:32.380692 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-06 02:03:32.381944 | controller | ok: [controller] 2026-03-06 02:03:32.382149 | controller | 2026-03-06 02:03:32.382362 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-06 02:03:32.382558 | controller | Friday 06 March 2026 02:03:32 +0000 (0:00:01.108) 0:00:01.122 ********** 2026-03-06 02:03:32.850289 | controller | ok: [controller] 2026-03-06 02:03:32.850429 | controller | 2026-03-06 02:03:32.850669 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-06 02:03:32.850864 | controller | Friday 06 March 2026 02:03:32 +0000 (0:00:00.467) 0:00:01.590 ********** 2026-03-06 02:03:33.929770 | controller | ok: [controller] 2026-03-06 02:03:33.929892 | controller | 2026-03-06 02:03:33.930100 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-06 02:03:33.930291 | controller | Friday 06 March 2026 02:03:33 +0000 (0:00:01.079) 0:00:02.670 ********** 2026-03-06 02:03:34.339929 | controller | changed: [controller] 2026-03-06 02:03:34.340008 | controller | 2026-03-06 02:03:34.340224 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-06 02:03:34.340507 | controller | Friday 06 March 2026 02:03:34 +0000 (0:00:00.409) 0:00:03.080 ********** 2026-03-06 02:03:34.397513 | controller | ok: [controller] => { 2026-03-06 02:03:34.397603 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-06 02:03:34.397737 | controller | } 2026-03-06 02:03:34.397850 | controller | 2026-03-06 02:03:34.397978 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-06 02:03:34.398105 | controller | Friday 06 March 2026 02:03:34 +0000 (0:00:00.050) 0:00:03.130 ********** 2026-03-06 02:03:35.346103 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-03-06 02:03:35.355536 | controller | changed: [controller] 2026-03-06 02:03:35.355725 | controller | 2026-03-06 02:03:35.356004 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-06 02:03:35.356204 | controller | Friday 06 March 2026 02:03:35 +0000 (0:00:00.965) 0:00:04.096 ********** 2026-03-06 02:03:37.394765 | controller | changed: [controller] 2026-03-06 02:03:37.394905 | controller | 2026-03-06 02:03:37.395112 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-06 02:03:37.395302 | controller | Friday 06 March 2026 02:03:37 +0000 (0:00:02.038) 0:00:06.134 ********** 2026-03-06 02:03:37.942197 | controller | ok: [controller] 2026-03-06 02:03:37.942236 | controller | 2026-03-06 02:03:37.942247 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-06 02:03:37.942256 | controller | Friday 06 March 2026 02:03:37 +0000 (0:00:00.544) 0:00:06.679 ********** 2026-03-06 02:03:38.338648 | controller | changed: [controller] 2026-03-06 02:03:38.338923 | controller | 2026-03-06 02:03:38.339179 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-06 02:03:38.339421 | controller | Friday 06 March 2026 02:03:38 +0000 (0:00:00.399) 0:00:07.078 ********** 2026-03-06 02:03:38.799403 | controller | ok: [controller] 2026-03-06 02:03:38.799695 | controller | 2026-03-06 02:03:38.799999 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-06 02:03:38.800267 | controller | Friday 06 March 2026 02:03:38 +0000 (0:00:00.460) 0:00:07.539 ********** 2026-03-06 02:03:39.098699 | controller | changed: [controller] 2026-03-06 02:03:39.099004 | controller | 2026-03-06 02:03:39.099307 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-06 02:03:39.099578 | controller | Friday 06 March 2026 02:03:39 +0000 (0:00:00.299) 0:00:07.838 ********** 2026-03-06 02:03:39.401140 | controller | ok: [controller] 2026-03-06 02:03:39.401419 | controller | 2026-03-06 02:03:39.401681 | controller | TASK [Install plugin] ********************************************************** 2026-03-06 02:03:39.401936 | controller | Friday 06 March 2026 02:03:39 +0000 (0:00:00.302) 0:00:08.141 ********** 2026-03-06 02:03:39.458400 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-06 02:03:39.458692 | controller | 2026-03-06 02:03:39.458963 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-06 02:03:39.459217 | controller | Friday 06 March 2026 02:03:39 +0000 (0:00:00.058) 0:00:08.199 ********** 2026-03-06 02:03:39.506401 | controller | ok: [controller] => { 2026-03-06 02:03:39.506424 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-06 02:03:39.506432 | controller | } 2026-03-06 02:03:39.506441 | controller | 2026-03-06 02:03:39.506449 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-06 02:03:39.506458 | controller | Friday 06 March 2026 02:03:39 +0000 (0:00:00.046) 0:00:08.245 ********** 2026-03-06 02:03:40.296334 | controller | changed: [controller] 2026-03-06 02:03:40.296615 | controller | 2026-03-06 02:03:40.296872 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-06 02:03:40.297173 | controller | Friday 06 March 2026 02:03:40 +0000 (0:00:00.790) 0:00:09.036 ********** 2026-03-06 02:03:42.347297 | controller | changed: [controller] 2026-03-06 02:03:42.347595 | controller | 2026-03-06 02:03:42.348004 | controller | PLAY RECAP ********************************************************************* 2026-03-06 02:03:42.348309 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-06 02:03:42.348542 | controller | 2026-03-06 02:03:42.348768 | controller | 2026-03-06 02:03:42.349062 | controller | TASKS RECAP ******************************************************************** 2026-03-06 02:03:42.349331 | controller | Friday 06 March 2026 02:03:42 +0000 (0:00:02.051) 0:00:11.088 ********** 2026-03-06 02:03:42.349566 | controller | =============================================================================== 2026-03-06 02:03:42.349813 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s 2026-03-06 02:03:42.350059 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.04s 2026-03-06 02:03:42.350301 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2026-03-06 02:03:42.350548 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.08s 2026-03-06 02:03:42.350794 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.97s 2026-03-06 02:03:42.351042 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.79s 2026-03-06 02:03:42.351285 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.54s 2026-03-06 02:03:42.351531 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s 2026-03-06 02:03:42.351840 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-03-06 02:03:42.352098 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-03-06 02:03:42.352361 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s 2026-03-06 02:03:42.352621 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-03-06 02:03:42.352877 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-03-06 02:03:42.353161 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-03-06 02:03:42.353410 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-06 02:03:42.353654 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-06 02:03:42.453344 | controller | INFO [helm > converge] Executed: Successful 2026-03-06 02:03:42.462758 | controller | INFO [helm > idempotence] Executing 2026-03-06 02:03:43.302876 | controller | 2026-03-06 02:03:43.303291 | controller | PLAY [Converge] **************************************************************** 2026-03-06 02:03:43.303548 | controller | 2026-03-06 02:03:43.303808 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-06 02:03:43.304066 | controller | Friday 06 March 2026 02:03:43 +0000 (0:00:00.013) 0:00:00.013 ********** 2026-03-06 02:03:44.368988 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-06 02:03:44.375736 | controller | ok: [controller] 2026-03-06 02:03:44.375920 | controller | 2026-03-06 02:03:44.376122 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-06 02:03:44.376309 | controller | Friday 06 March 2026 02:03:44 +0000 (0:00:01.072) 0:00:01.085 ********** 2026-03-06 02:03:44.803689 | controller | ok: [controller] 2026-03-06 02:03:44.803901 | controller | 2026-03-06 02:03:44.804168 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-06 02:03:44.804416 | controller | Friday 06 March 2026 02:03:44 +0000 (0:00:00.428) 0:00:01.513 ********** 2026-03-06 02:03:45.968191 | controller | ok: [controller] 2026-03-06 02:03:45.968261 | controller | 2026-03-06 02:03:45.968481 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-06 02:03:45.968761 | controller | Friday 06 March 2026 02:03:45 +0000 (0:00:01.164) 0:00:02.678 ********** 2026-03-06 02:03:46.402702 | controller | ok: [controller] 2026-03-06 02:03:46.402863 | controller | 2026-03-06 02:03:46.403062 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-06 02:03:46.403249 | controller | Friday 06 March 2026 02:03:46 +0000 (0:00:00.434) 0:00:03.112 ********** 2026-03-06 02:03:46.454685 | controller | ok: [controller] => { 2026-03-06 02:03:46.454887 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-06 02:03:46.455069 | controller | } 2026-03-06 02:03:46.455241 | controller | 2026-03-06 02:03:46.455430 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-06 02:03:46.455615 | controller | Friday 06 March 2026 02:03:46 +0000 (0:00:00.052) 0:00:03.165 ********** 2026-03-06 02:03:47.009010 | controller | ok: [controller] 2026-03-06 02:03:47.009057 | controller | 2026-03-06 02:03:47.009066 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-06 02:03:47.009074 | controller | Friday 06 March 2026 02:03:47 +0000 (0:00:00.553) 0:00:03.718 ********** 2026-03-06 02:03:49.725514 | controller | ok: [controller] 2026-03-06 02:03:49.725583 | controller | 2026-03-06 02:03:49.725640 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-06 02:03:49.725732 | controller | Friday 06 March 2026 02:03:49 +0000 (0:00:02.715) 0:00:06.433 ********** 2026-03-06 02:03:50.239095 | controller | ok: [controller] 2026-03-06 02:03:50.239135 | controller | 2026-03-06 02:03:50.239139 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-06 02:03:50.239144 | controller | Friday 06 March 2026 02:03:50 +0000 (0:00:00.514) 0:00:06.947 ********** 2026-03-06 02:03:50.652650 | controller | ok: [controller] 2026-03-06 02:03:50.652715 | controller | 2026-03-06 02:03:50.652823 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-06 02:03:50.653020 | controller | Friday 06 March 2026 02:03:50 +0000 (0:00:00.414) 0:00:07.362 ********** 2026-03-06 02:03:51.113773 | controller | ok: [controller] 2026-03-06 02:03:51.113810 | controller | 2026-03-06 02:03:51.113815 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-06 02:03:51.113819 | controller | Friday 06 March 2026 02:03:51 +0000 (0:00:00.458) 0:00:07.821 ********** 2026-03-06 02:03:51.409470 | controller | ok: [controller] 2026-03-06 02:03:51.409531 | controller | 2026-03-06 02:03:51.409632 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-06 02:03:51.409746 | controller | Friday 06 March 2026 02:03:51 +0000 (0:00:00.296) 0:00:08.117 ********** 2026-03-06 02:03:51.704494 | controller | ok: [controller] 2026-03-06 02:03:51.704545 | controller | 2026-03-06 02:03:51.704622 | controller | TASK [Install plugin] ********************************************************** 2026-03-06 02:03:51.704730 | controller | Friday 06 March 2026 02:03:51 +0000 (0:00:00.297) 0:00:08.414 ********** 2026-03-06 02:03:51.765550 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-06 02:03:51.765593 | controller | 2026-03-06 02:03:51.765675 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-06 02:03:51.765765 | controller | Friday 06 March 2026 02:03:51 +0000 (0:00:00.061) 0:00:08.476 ********** 2026-03-06 02:03:51.817206 | controller | ok: [controller] => { 2026-03-06 02:03:51.817341 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-06 02:03:51.817449 | controller | } 2026-03-06 02:03:51.817558 | controller | 2026-03-06 02:03:51.817684 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-06 02:03:51.817849 | controller | Friday 06 March 2026 02:03:51 +0000 (0:00:00.050) 0:00:08.526 ********** 2026-03-06 02:03:52.286000 | controller | ok: [controller] 2026-03-06 02:03:52.286044 | controller | 2026-03-06 02:03:52.286050 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-06 02:03:52.286056 | controller | Friday 06 March 2026 02:03:52 +0000 (0:00:00.465) 0:00:08.991 ********** 2026-03-06 02:03:53.901070 | controller | ok: [controller] 2026-03-06 02:03:53.901491 | controller | 2026-03-06 02:03:53.901769 | controller | PLAY RECAP ********************************************************************* 2026-03-06 02:03:53.902045 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-06 02:03:53.902277 | controller | 2026-03-06 02:03:53.902502 | controller | 2026-03-06 02:03:53.902742 | controller | TASKS RECAP ******************************************************************** 2026-03-06 02:03:53.902992 | controller | Friday 06 March 2026 02:03:53 +0000 (0:00:01.620) 0:00:10.611 ********** 2026-03-06 02:03:53.903227 | controller | =============================================================================== 2026-03-06 02:03:53.903474 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.72s 2026-03-06 02:03:53.903716 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.62s 2026-03-06 02:03:53.903959 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.16s 2026-03-06 02:03:53.904199 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-03-06 02:03:53.904444 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.55s 2026-03-06 02:03:53.904692 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-03-06 02:03:53.904989 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.47s 2026-03-06 02:03:53.905270 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-03-06 02:03:53.905523 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-03-06 02:03:53.905772 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s 2026-03-06 02:03:53.906038 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s 2026-03-06 02:03:53.906300 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-03-06 02:03:53.906557 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-03-06 02:03:53.906800 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-03-06 02:03:53.907048 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-06 02:03:53.907293 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-06 02:03:54.022149 | controller | INFO [helm > idempotence] Executed: Successful 2026-03-06 02:03:54.031470 | controller | INFO [helm > side_effect] Executing 2026-03-06 02:03:54.031970 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-06 02:03:54.039536 | controller | INFO [helm > verify] Executing 2026-03-06 02:03:54.841126 | controller | 2026-03-06 02:03:54.841512 | controller | PLAY [Verify] ****************************************************************** 2026-03-06 02:03:54.841742 | controller | 2026-03-06 02:03:54.841989 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-06 02:03:54.842230 | controller | Friday 06 March 2026 02:03:54 +0000 (0:00:00.009) 0:00:00.009 ********** 2026-03-06 02:03:55.933161 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-06 02:03:55.941268 | controller | ok: [controller] 2026-03-06 02:03:55.941289 | controller | 2026-03-06 02:03:55.941298 | controller | TASK [Get the current Helm version] ******************************************** 2026-03-06 02:03:55.941306 | controller | Friday 06 March 2026 02:03:55 +0000 (0:00:01.099) 0:00:01.109 ********** 2026-03-06 02:03:56.407533 | controller | changed: [controller] 2026-03-06 02:03:56.407827 | controller | 2026-03-06 02:03:56.408081 | controller | TASK [Assert Helm version] ***************************************************** 2026-03-06 02:03:56.408328 | controller | Friday 06 March 2026 02:03:56 +0000 (0:00:00.466) 0:00:01.575 ********** 2026-03-06 02:03:56.439408 | controller | ok: [controller] => { 2026-03-06 02:03:56.439699 | controller | "changed": false, 2026-03-06 02:03:56.439943 | controller | "msg": "All assertions passed" 2026-03-06 02:03:56.440190 | controller | } 2026-03-06 02:03:56.440414 | controller | 2026-03-06 02:03:56.440659 | controller | PLAY RECAP ********************************************************************* 2026-03-06 02:03:56.440956 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-06 02:03:56.441182 | controller | 2026-03-06 02:03:56.441399 | controller | 2026-03-06 02:03:56.441634 | controller | TASKS RECAP ******************************************************************** 2026-03-06 02:03:56.441872 | controller | Friday 06 March 2026 02:03:56 +0000 (0:00:00.032) 0:00:01.608 ********** 2026-03-06 02:03:56.442098 | controller | =============================================================================== 2026-03-06 02:03:56.442333 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-03-06 02:03:56.442568 | controller | Get the current Helm version -------------------------------------------- 0.47s 2026-03-06 02:03:56.442803 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-03-06 02:03:56.561438 | controller | INFO [helm > verify] Executed: Successful 2026-03-06 02:03:56.570446 | controller | INFO [helm > cleanup] Executing 2026-03-06 02:03:56.571336 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-06 02:03:56.577939 | controller | INFO [helm > destroy] Executing 2026-03-06 02:03:56.578031 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-06 02:03:56.578142 | controller | INFO [helm > destroy] Executed: Successful 2026-03-06 02:03:56.578475 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-06 02:03:56.121232 | controller | ok: Runtime: 0:01:07.266734 2026-03-06 02:03:56.125284 | 2026-03-06 02:03:56.125337 | PLAY RECAP 2026-03-06 02:03:56.125389 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-06 02:03:56.125411 | 2026-03-06 02:03:56.283178 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-06 02:03:56.286303 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-06 02:03:56.918270 | 2026-03-06 02:03:56.918423 | PLAY [all] 2026-03-06 02:03:56.928632 | 2026-03-06 02:03:56.928713 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-06 02:03:56.976362 | controller | skipping: Conditional result was False 2026-03-06 02:03:56.983149 | 2026-03-06 02:03:56.983261 | TASK [fetch-output : Set log path for single node] 2026-03-06 02:03:57.027695 | controller | ok 2026-03-06 02:03:57.032849 | 2026-03-06 02:03:57.032941 | LOOP [fetch-output : Ensure local output dirs] 2026-03-06 02:03:57.426315 | controller -> localhost | ok: "/var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/work/logs" 2026-03-06 02:03:57.631926 | controller -> localhost | changed: "/var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/work/artifacts" 2026-03-06 02:03:57.849157 | controller -> localhost | changed: "/var/lib/zuul/builds/66c3793a35874c2daaf282e9da5043ea/work/docs" 2026-03-06 02:03:57.871689 | 2026-03-06 02:03:57.871883 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-06 02:03:58.567606 | controller | ok 2026-03-06 02:03:58.567878 | controller | changed: All items complete 2026-03-06 02:03:58.567918 | 2026-03-06 02:03:59.059716 | controller | changed: .d..t...... ./ 2026-03-06 02:03:59.560396 | controller | changed: .d..t...... ./ 2026-03-06 02:03:59.583084 | 2026-03-06 02:03:59.583278 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-06 02:04:00.073201 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007523 2026-03-06 02:04:00.305804 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009234 2026-03-06 02:04:00.318418 | 2026-03-06 02:04:00.318604 | PLAY [all] 2026-03-06 02:04:00.324816 | 2026-03-06 02:04:00.324886 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-06 02:04:00.790190 | controller | changed 2026-03-06 02:04:00.797265 | 2026-03-06 02:04:00.797796 | PLAY RECAP 2026-03-06 02:04:00.797867 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-06 02:04:00.797897 | 2026-03-06 02:04:00.977504 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-06 02:04:00.980226 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-06 02:04:01.524818 | 2026-03-06 02:04:01.525644 | PLAY [localhost] 2026-03-06 02:04:01.535364 | 2026-03-06 02:04:01.535433 | TASK [Generate Zuul manifest] 2026-03-06 02:04:01.554306 | localhost | ok 2026-03-06 02:04:01.571216 | 2026-03-06 02:04:01.571292 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-06 02:04:01.899662 | localhost | changed 2026-03-06 02:04:01.910842 | 2026-03-06 02:04:01.910913 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-06 02:04:01.952825 | localhost | ok 2026-03-06 02:04:01.962710 | 2026-03-06 02:04:01.962801 | TASK [Upload logs] 2026-03-06 02:04:01.983901 | localhost | ok 2026-03-06 02:04:02.042931 | 2026-03-06 02:04:02.043036 | TASK [Set zuul-log-path fact] 2026-03-06 02:04:02.063757 | localhost | ok 2026-03-06 02:04:02.076444 | 2026-03-06 02:04:02.076516 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-06 02:04:02.107237 | localhost | ok 2026-03-06 02:04:02.116692 | 2026-03-06 02:04:02.116798 | TASK [upload-logs : Create log directories] 2026-03-06 02:04:02.501988 | localhost | changed 2026-03-06 02:04:02.508095 | 2026-03-06 02:04:02.508187 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-06 02:04:02.925155 | localhost -> localhost | ok: Runtime: 0:00:00.004632 2026-03-06 02:04:02.932299 | 2026-03-06 02:04:02.932364 | TASK [upload-logs : Upload logs to log server] 2026-03-06 02:04:03.389666 | localhost | Output suppressed because no_log was given 2026-03-06 02:04:03.394679 | 2026-03-06 02:04:03.394745 | LOOP [upload-logs : Compress console log and json output] 2026-03-06 02:04:03.434146 | localhost | skipping: Conditional result was False 2026-03-06 02:04:03.440897 | localhost | skipping: Conditional result was False 2026-03-06 02:04:03.457934 | 2026-03-06 02:04:03.458097 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-06 02:04:03.493779 | localhost | skipping: Conditional result was False 2026-03-06 02:04:03.494248 | 2026-03-06 02:04:03.497437 | localhost | skipping: Conditional result was False 2026-03-06 02:04:03.512629 | 2026-03-06 02:04:03.512784 | LOOP [upload-logs : Upload console log and json output]