2026-02-16 21:11:10.638497 | Job console starting 2026-02-16 21:11:10.652689 | Updating git repos 2026-02-16 21:11:10.683255 | Cloning repos into workspace 2026-02-16 21:11:10.731327 | Restoring repo states 2026-02-16 21:11:10.747140 | Merging changes 2026-02-16 21:11:11.295942 | Checking out repos 2026-02-16 21:11:11.335391 | Preparing playbooks 2026-02-16 21:11:12.428479 | Running Ansible setup 2026-02-16 21:11:16.048364 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:11:16.702773 | 2026-02-16 21:11:16.702905 | PLAY [localhost] 2026-02-16 21:11:16.710385 | 2026-02-16 21:11:16.710455 | TASK [Gathering Facts] 2026-02-16 21:11:17.573891 | localhost | ok 2026-02-16 21:11:17.588296 | 2026-02-16 21:11:17.588405 | TASK [Setup log path fact] 2026-02-16 21:11:17.611732 | localhost | ok 2026-02-16 21:11:17.623610 | 2026-02-16 21:11:17.623678 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:11:17.657808 | localhost | ok 2026-02-16 21:11:17.666449 | 2026-02-16 21:11:17.666517 | TASK [emit-job-header : Print job information] 2026-02-16 21:11:17.715266 | # Job Information 2026-02-16 21:11:17.715421 | Ansible Version: 2.16.15 2026-02-16 21:11:17.715466 | Job: ansible-collection-kubernetes-molecule-helm 2026-02-16 21:11:17.715496 | Pipeline: check 2026-02-16 21:11:17.715524 | Executor: 3a2793d2bd32 2026-02-16 21:11:17.715551 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/229 2026-02-16 21:11:17.715581 | Event ID: cb3b7370-0b7b-11f1-9c52-05f14bb964d8 2026-02-16 21:11:17.719282 | 2026-02-16 21:11:17.719376 | LOOP [emit-job-header : Print node information] 2026-02-16 21:11:17.823709 | localhost | ok: 2026-02-16 21:11:17.902258 | localhost | # Node Information 2026-02-16 21:11:17.902437 | localhost | Inventory Hostname: controller 2026-02-16 21:11:17.902494 | localhost | Hostname: np0000155794 2026-02-16 21:11:17.902540 | localhost | Username: zuul 2026-02-16 21:11:17.902590 | localhost | Distro: Debian 13.3 2026-02-16 21:11:17.902635 | localhost | Provider: yul1 2026-02-16 21:11:17.902677 | localhost | Region: ca-ymq-1 2026-02-16 21:11:17.902718 | localhost | Label: debian-trixie 2026-02-16 21:11:17.902758 | localhost | Product Name: OpenStack Nova 2026-02-16 21:11:17.902798 | localhost | Interface IP: 199.204.45.210 2026-02-16 21:11:17.923047 | 2026-02-16 21:11:17.923213 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 21:11:18.389245 | localhost -> localhost | changed 2026-02-16 21:11:18.399651 | 2026-02-16 21:11:18.399754 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 21:11:19.288243 | localhost -> localhost | changed 2026-02-16 21:11:19.297903 | 2026-02-16 21:11:19.297978 | PLAY [all] 2026-02-16 21:11:19.306312 | 2026-02-16 21:11:19.306380 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 21:11:19.542348 | controller -> localhost | ok 2026-02-16 21:11:19.547299 | 2026-02-16 21:11:19.547373 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 21:11:19.575839 | controller | ok 2026-02-16 21:11:19.588000 | controller | included: /var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 21:11:19.593677 | 2026-02-16 21:11:19.593740 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 21:11:20.447120 | controller -> localhost | Generating public/private rsa key pair. 2026-02-16 21:11:20.447478 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/work/938096597ad34db3b4b5fdb87c4d2d93_id_rsa 2026-02-16 21:11:20.447529 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/work/938096597ad34db3b4b5fdb87c4d2d93_id_rsa.pub 2026-02-16 21:11:20.447563 | controller -> localhost | The key fingerprint is: 2026-02-16 21:11:20.447594 | controller -> localhost | SHA256:otBCv5UaNMVwSJSD0kN6BQuPji7MnwVSpLBcPsk1bpY zuul-build-sshkey 2026-02-16 21:11:20.447639 | controller -> localhost | The key's randomart image is: 2026-02-16 21:11:20.447670 | controller -> localhost | +---[RSA 3072]----+ 2026-02-16 21:11:20.447703 | controller -> localhost | |oooB=Bo | 2026-02-16 21:11:20.447735 | controller -> localhost | |+*XoBo+ | 2026-02-16 21:11:20.447764 | controller -> localhost | |++=BoE | 2026-02-16 21:11:20.447793 | controller -> localhost | |oo.++. . | 2026-02-16 21:11:20.447821 | controller -> localhost | |.oo.+ + S | 2026-02-16 21:11:20.447849 | controller -> localhost | |+ .o.* . | 2026-02-16 21:11:20.447877 | controller -> localhost | |.+ +. | 2026-02-16 21:11:20.447905 | controller -> localhost | |. . o | 2026-02-16 21:11:20.447936 | controller -> localhost | | o | 2026-02-16 21:11:20.447964 | controller -> localhost | +----[SHA256]-----+ 2026-02-16 21:11:20.448025 | controller -> localhost | ok: Runtime: 0:00:00.419956 2026-02-16 21:11:20.452994 | 2026-02-16 21:11:20.453055 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 21:11:20.485004 | controller | ok 2026-02-16 21:11:20.493927 | controller | included: /var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 21:11:20.500108 | 2026-02-16 21:11:20.500170 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 21:11:20.524480 | controller | skipping: Conditional result was False 2026-02-16 21:11:20.533092 | 2026-02-16 21:11:20.533183 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 21:11:21.080997 | controller | changed 2026-02-16 21:11:21.088657 | 2026-02-16 21:11:21.088723 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 21:11:21.303838 | controller | ok 2026-02-16 21:11:21.308127 | 2026-02-16 21:11:21.308204 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 21:11:21.817908 | controller | changed 2026-02-16 21:11:21.823446 | 2026-02-16 21:11:21.823514 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 21:11:22.333328 | controller | changed 2026-02-16 21:11:22.338698 | 2026-02-16 21:11:22.338776 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 21:11:22.364116 | controller | skipping: Conditional result was False 2026-02-16 21:11:22.371947 | 2026-02-16 21:11:22.372036 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 21:11:22.791697 | controller -> localhost | changed 2026-02-16 21:11:22.800847 | 2026-02-16 21:11:22.800919 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 21:11:23.078754 | controller -> localhost | Identity added: /var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/work/938096597ad34db3b4b5fdb87c4d2d93_id_rsa (zuul-build-sshkey) 2026-02-16 21:11:23.078992 | controller -> localhost | ok: Runtime: 0:00:00.017538 2026-02-16 21:11:23.083542 | 2026-02-16 21:11:23.083646 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 21:11:23.408992 | controller | ok 2026-02-16 21:11:23.412779 | 2026-02-16 21:11:23.412865 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 21:11:23.437870 | controller | skipping: Conditional result was False 2026-02-16 21:11:23.449303 | 2026-02-16 21:11:23.449372 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 21:11:23.832192 | controller | ok 2026-02-16 21:11:23.837204 | 2026-02-16 21:11:23.837271 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 21:11:24.846118 | controller | Output suppressed because no_log was given 2026-02-16 21:11:24.855790 | 2026-02-16 21:11:24.855887 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 21:11:25.091255 | controller | ok: "logs" 2026-02-16 21:11:25.091614 | controller | ok: All items complete 2026-02-16 21:11:25.091661 | 2026-02-16 21:11:25.278514 | controller | ok: "artifacts" 2026-02-16 21:11:25.458058 | controller | ok: "docs" 2026-02-16 21:11:25.466651 | 2026-02-16 21:11:25.466763 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 21:11:25.668731 | controller | changed: "logs" 2026-02-16 21:11:25.863173 | controller | changed: "artifacts" 2026-02-16 21:11:26.051160 | controller | changed: "docs" 2026-02-16 21:11:26.086842 | 2026-02-16 21:11:26.086993 | PLAY RECAP 2026-02-16 21:11:26.087073 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 21:11:26.087123 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:11:26.087161 | 2026-02-16 21:11:26.232612 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:11:26.235344 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:11:26.796803 | 2026-02-16 21:11:26.797071 | PLAY [all] 2026-02-16 21:11:26.807478 | 2026-02-16 21:11:26.807553 | TASK [setup-uv : Extract archive] 2026-02-16 21:11:29.402099 | controller | changed 2026-02-16 21:11:29.408845 | 2026-02-16 21:11:29.408928 | TASK [setup-uv : Print version] 2026-02-16 21:11:29.734549 | controller | uv 0.8.13 2026-02-16 21:11:29.935664 | controller | ok: Runtime: 0:00:00.008771 2026-02-16 21:11:29.942787 | 2026-02-16 21:11:29.942837 | PLAY RECAP 2026-02-16 21:11:29.942886 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:11:29.942910 | 2026-02-16 21:11:30.070226 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:11:30.072081 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:11:30.654395 | 2026-02-16 21:11:30.654528 | PLAY [all] 2026-02-16 21:11:30.664475 | 2026-02-16 21:11:30.664549 | TASK [Copy inventory file for Zuul] 2026-02-16 21:11:31.580775 | controller | changed 2026-02-16 21:11:31.586604 | 2026-02-16 21:11:31.586706 | TASK [Switch "ansible_host" to private IP] 2026-02-16 21:11:31.906113 | controller | changed: 1 replacements made 2026-02-16 21:11:31.910916 | 2026-02-16 21:11:31.911000 | TASK [Run Molecule scenario] 2026-02-16 21:11:32.314269 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-16 21:11:32.314339 | controller | Creating virtual environment at: .venv 2026-02-16 21:11:32.580931 | controller | Downloading pygments (1.2MiB) 2026-02-16 21:11:32.580960 | controller | Downloading cryptography (4.3MiB) 2026-02-16 21:11:32.656338 | controller | Downloading ansible-core (2.3MiB) 2026-02-16 21:11:32.765202 | controller | Downloading cryptography 2026-02-16 21:11:32.803200 | controller | Downloading pygments 2026-02-16 21:11:32.887523 | controller | Downloading ansible-core 2026-02-16 21:11:32.935441 | controller | Installed 36 packages in 46ms 2026-02-16 21:11:33.596911 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-16 21:11:33.596960 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-16 21:11:34.419426 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-16 21:11:34.419480 | controller | INFO default scenario not found, disabling shared state. 2026-02-16 21:11:34.419629 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-16 21:11:34.419711 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-02-16 21:11:51.078691 | controller | INFO [helm > dependency] Executing 2026-02-16 21:11:51.078971 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-02-16 21:11:51.079304 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-02-16 21:11:51.079534 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-16 21:11:51.087211 | controller | INFO [helm > cleanup] Executing 2026-02-16 21:11:51.087601 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:11:51.093223 | controller | INFO [helm > destroy] Executing 2026-02-16 21:11:51.093246 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-16 21:11:51.093342 | controller | INFO [helm > destroy] Executed: Successful 2026-02-16 21:11:51.098936 | controller | INFO [helm > syntax] Executing 2026-02-16 21:11:52.010793 | controller | 2026-02-16 21:11:52.011078 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-02-16 21:11:52.079121 | controller | INFO [helm > syntax] Executed: Successful 2026-02-16 21:11:52.090044 | controller | INFO [helm > create] Executing 2026-02-16 21:11:52.094669 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:11:52.100915 | controller | INFO [helm > prepare] Executing 2026-02-16 21:11:53.002947 | controller | 2026-02-16 21:11:53.002970 | controller | PLAY [Prepare] ***************************************************************** 2026-02-16 21:11:53.002974 | controller | 2026-02-16 21:11:53.002978 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:11:53.002982 | controller | Monday 16 February 2026 21:11:52 +0000 (0:00:00.030) 0:00:00.030 ******* 2026-02-16 21:11:54.248026 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:11:54.252177 | controller | ok: [controller] 2026-02-16 21:11:54.252256 | controller | 2026-02-16 21:11:54.252355 | controller | TASK [Install Debian packages] ************************************************* 2026-02-16 21:11:54.252453 | controller | Monday 16 February 2026 21:11:54 +0000 (0:00:01.251) 0:00:01.282 ******* 2026-02-16 21:11:59.767798 | controller | ok: [controller] 2026-02-16 21:11:59.767897 | controller | 2026-02-16 21:11:59.768032 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:11:59.768196 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:11:59.768317 | controller | 2026-02-16 21:11:59.768431 | controller | 2026-02-16 21:11:59.768553 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:11:59.768680 | controller | Monday 16 February 2026 21:11:59 +0000 (0:00:05.514) 0:00:06.796 ******* 2026-02-16 21:11:59.768796 | controller | =============================================================================== 2026-02-16 21:11:59.771526 | controller | Install Debian packages ------------------------------------------------- 5.51s 2026-02-16 21:11:59.771534 | controller | Gathering Facts --------------------------------------------------------- 1.25s 2026-02-16 21:11:59.853965 | controller | INFO [helm > prepare] Executed: Successful 2026-02-16 21:11:59.862560 | controller | INFO [helm > converge] Executing 2026-02-16 21:12:00.718909 | controller | 2026-02-16 21:12:00.719166 | controller | PLAY [Converge] **************************************************************** 2026-02-16 21:12:00.719352 | controller | 2026-02-16 21:12:00.719560 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:12:00.719761 | controller | Monday 16 February 2026 21:12:00 +0000 (0:00:00.015) 0:00:00.015 ******* 2026-02-16 21:12:01.737305 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:12:01.745652 | controller | ok: [controller] 2026-02-16 21:12:01.745764 | controller | 2026-02-16 21:12:01.745903 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-16 21:12:01.746033 | controller | Monday 16 February 2026 21:12:01 +0000 (0:00:01.026) 0:00:01.042 ******* 2026-02-16 21:12:02.219930 | controller | ok: [controller] 2026-02-16 21:12:02.220220 | controller | 2026-02-16 21:12:02.220556 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-16 21:12:02.222540 | controller | Monday 16 February 2026 21:12:02 +0000 (0:00:00.473) 0:00:01.515 ******* 2026-02-16 21:12:03.300557 | controller | ok: [controller] 2026-02-16 21:12:03.300593 | controller | 2026-02-16 21:12:03.300703 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-16 21:12:03.300797 | controller | Monday 16 February 2026 21:12:03 +0000 (0:00:01.081) 0:00:02.596 ******* 2026-02-16 21:12:03.699896 | controller | changed: [controller] 2026-02-16 21:12:03.699918 | controller | 2026-02-16 21:12:03.699923 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:12:03.699927 | controller | Monday 16 February 2026 21:12:03 +0000 (0:00:00.397) 0:00:02.994 ******* 2026-02-16 21:12:03.755151 | controller | ok: [controller] => { 2026-02-16 21:12:03.755408 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-16 21:12:03.755647 | controller | } 2026-02-16 21:12:03.755873 | controller | 2026-02-16 21:12:03.756154 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:12:03.756398 | controller | Monday 16 February 2026 21:12:03 +0000 (0:00:00.050) 0:00:03.045 ******* 2026-02-16 21:12:04.549302 | 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:12:04.552620 | controller | changed: [controller] 2026-02-16 21:12:04.552681 | controller | 2026-02-16 21:12:04.552782 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:12:04.552980 | controller | Monday 16 February 2026 21:12:04 +0000 (0:00:00.804) 0:00:03.849 ******* 2026-02-16 21:12:06.555428 | controller | changed: [controller] 2026-02-16 21:12:06.555658 | controller | 2026-02-16 21:12:06.555892 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-16 21:12:06.556087 | controller | Monday 16 February 2026 21:12:06 +0000 (0:00:02.002) 0:00:05.852 ******* 2026-02-16 21:12:07.032441 | controller | ok: [controller] 2026-02-16 21:12:07.032888 | controller | 2026-02-16 21:12:07.032899 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-16 21:12:07.032905 | controller | Monday 16 February 2026 21:12:07 +0000 (0:00:00.473) 0:00:06.325 ******* 2026-02-16 21:12:07.416391 | controller | changed: [controller] 2026-02-16 21:12:07.416431 | controller | 2026-02-16 21:12:07.416537 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-16 21:12:07.416632 | controller | Monday 16 February 2026 21:12:07 +0000 (0:00:00.387) 0:00:06.713 ******* 2026-02-16 21:12:07.876630 | controller | ok: [controller] 2026-02-16 21:12:07.876742 | controller | 2026-02-16 21:12:07.876920 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-16 21:12:07.877055 | controller | Monday 16 February 2026 21:12:07 +0000 (0:00:00.459) 0:00:07.172 ******* 2026-02-16 21:12:08.156982 | controller | changed: [controller] 2026-02-16 21:12:08.157015 | controller | 2026-02-16 21:12:08.157023 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-16 21:12:08.157030 | controller | Monday 16 February 2026 21:12:08 +0000 (0:00:00.277) 0:00:07.450 ******* 2026-02-16 21:12:08.444631 | controller | ok: [controller] 2026-02-16 21:12:08.444733 | controller | 2026-02-16 21:12:08.446937 | controller | TASK [Install plugin] ********************************************************** 2026-02-16 21:12:08.446950 | controller | Monday 16 February 2026 21:12:08 +0000 (0:00:00.290) 0:00:07.741 ******* 2026-02-16 21:12:08.495272 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-16 21:12:08.495441 | controller | 2026-02-16 21:12:08.495651 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:12:08.495849 | controller | Monday 16 February 2026 21:12:08 +0000 (0:00:00.050) 0:00:07.791 ******* 2026-02-16 21:12:08.541276 | controller | ok: [controller] => { 2026-02-16 21:12:08.541498 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-16 21:12:08.541688 | controller | } 2026-02-16 21:12:08.541872 | controller | 2026-02-16 21:12:08.542108 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:12:08.542312 | controller | Monday 16 February 2026 21:12:08 +0000 (0:00:00.045) 0:00:07.837 ******* 2026-02-16 21:12:09.291908 | controller | changed: [controller] 2026-02-16 21:12:09.291999 | controller | 2026-02-16 21:12:09.292136 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:12:09.292268 | controller | Monday 16 February 2026 21:12:09 +0000 (0:00:00.751) 0:00:08.588 ******* 2026-02-16 21:12:11.263460 | controller | changed: [controller] 2026-02-16 21:12:11.263482 | controller | 2026-02-16 21:12:11.263486 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:12:11.263491 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:12:11.263494 | controller | 2026-02-16 21:12:11.263498 | controller | 2026-02-16 21:12:11.263501 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:12:11.263505 | controller | Monday 16 February 2026 21:12:11 +0000 (0:00:01.966) 0:00:10.554 ******* 2026-02-16 21:12:11.263508 | controller | =============================================================================== 2026-02-16 21:12:11.263511 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.00s 2026-02-16 21:12:11.263515 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.97s 2026-02-16 21:12:11.263518 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.08s 2026-02-16 21:12:11.263521 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-02-16 21:12:11.263524 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.80s 2026-02-16 21:12:11.263528 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.75s 2026-02-16 21:12:11.263531 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.47s 2026-02-16 21:12:11.263534 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s 2026-02-16 21:12:11.263537 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-02-16 21:12:11.263540 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s 2026-02-16 21:12:11.263550 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-02-16 21:12:11.263554 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-02-16 21:12:11.263557 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-02-16 21:12:11.263560 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-02-16 21:12:11.263564 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-16 21:12:11.263567 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-16 21:12:11.348895 | controller | INFO [helm > converge] Executed: Successful 2026-02-16 21:12:11.354893 | controller | INFO [helm > idempotence] Executing 2026-02-16 21:12:12.160110 | controller | 2026-02-16 21:12:12.160417 | controller | PLAY [Converge] **************************************************************** 2026-02-16 21:12:12.160657 | controller | 2026-02-16 21:12:12.160941 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:12:12.161195 | controller | Monday 16 February 2026 21:12:12 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-02-16 21:12:13.184538 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:12:13.190619 | controller | ok: [controller] 2026-02-16 21:12:13.190630 | controller | 2026-02-16 21:12:13.190634 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-02-16 21:12:13.190638 | controller | Monday 16 February 2026 21:12:13 +0000 (0:00:01.030) 0:00:01.043 ******* 2026-02-16 21:12:13.579727 | controller | ok: [controller] 2026-02-16 21:12:13.581859 | controller | 2026-02-16 21:12:13.581869 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-02-16 21:12:13.581874 | controller | Monday 16 February 2026 21:12:13 +0000 (0:00:00.389) 0:00:01.432 ******* 2026-02-16 21:12:14.687742 | controller | ok: [controller] 2026-02-16 21:12:14.691075 | controller | 2026-02-16 21:12:14.691084 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-02-16 21:12:14.691088 | controller | Monday 16 February 2026 21:12:14 +0000 (0:00:01.107) 0:00:02.540 ******* 2026-02-16 21:12:15.142688 | controller | ok: [controller] 2026-02-16 21:12:15.142874 | controller | 2026-02-16 21:12:15.143050 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:12:15.143215 | controller | Monday 16 February 2026 21:12:15 +0000 (0:00:00.451) 0:00:02.992 ******* 2026-02-16 21:12:15.194170 | controller | ok: [controller] => { 2026-02-16 21:12:15.194321 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-02-16 21:12:15.194435 | controller | } 2026-02-16 21:12:15.194542 | controller | 2026-02-16 21:12:15.194659 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:12:15.194774 | controller | Monday 16 February 2026 21:12:15 +0000 (0:00:00.054) 0:00:03.046 ******* 2026-02-16 21:12:15.733042 | controller | ok: [controller] 2026-02-16 21:12:15.736495 | controller | 2026-02-16 21:12:15.736507 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:12:15.736513 | controller | Monday 16 February 2026 21:12:15 +0000 (0:00:00.539) 0:00:03.585 ******* 2026-02-16 21:12:17.442072 | controller | ok: [controller] 2026-02-16 21:12:17.442203 | controller | 2026-02-16 21:12:17.442325 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-02-16 21:12:17.442443 | controller | Monday 16 February 2026 21:12:17 +0000 (0:00:01.709) 0:00:05.294 ******* 2026-02-16 21:12:17.954774 | controller | ok: [controller] 2026-02-16 21:12:17.957204 | controller | 2026-02-16 21:12:17.957214 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-02-16 21:12:17.957218 | controller | Monday 16 February 2026 21:12:17 +0000 (0:00:00.511) 0:00:05.806 ******* 2026-02-16 21:12:18.376779 | controller | ok: [controller] 2026-02-16 21:12:18.379639 | controller | 2026-02-16 21:12:18.379650 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-02-16 21:12:18.379654 | controller | Monday 16 February 2026 21:12:18 +0000 (0:00:00.423) 0:00:06.229 ******* 2026-02-16 21:12:18.826686 | controller | ok: [controller] 2026-02-16 21:12:18.826886 | controller | 2026-02-16 21:12:18.829271 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-02-16 21:12:18.829289 | controller | Monday 16 February 2026 21:12:18 +0000 (0:00:00.449) 0:00:06.679 ******* 2026-02-16 21:12:19.123330 | controller | ok: [controller] 2026-02-16 21:12:19.125445 | controller | 2026-02-16 21:12:19.125873 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-02-16 21:12:19.126220 | controller | Monday 16 February 2026 21:12:19 +0000 (0:00:00.296) 0:00:06.975 ******* 2026-02-16 21:12:19.408360 | controller | ok: [controller] 2026-02-16 21:12:19.408382 | controller | 2026-02-16 21:12:19.408386 | controller | TASK [Install plugin] ********************************************************** 2026-02-16 21:12:19.408390 | controller | Monday 16 February 2026 21:12:19 +0000 (0:00:00.282) 0:00:07.257 ******* 2026-02-16 21:12:19.456591 | controller | included: vexxhost.containers.download_artifact for controller 2026-02-16 21:12:19.456635 | controller | 2026-02-16 21:12:19.456748 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-02-16 21:12:19.456832 | controller | Monday 16 February 2026 21:12:19 +0000 (0:00:00.049) 0:00:07.307 ******* 2026-02-16 21:12:19.497070 | controller | ok: [controller] => { 2026-02-16 21:12:19.497198 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-02-16 21:12:19.497327 | controller | } 2026-02-16 21:12:19.497442 | controller | 2026-02-16 21:12:19.497584 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-02-16 21:12:19.497841 | controller | Monday 16 February 2026 21:12:19 +0000 (0:00:00.042) 0:00:07.349 ******* 2026-02-16 21:12:19.901035 | controller | ok: [controller] 2026-02-16 21:12:19.901079 | controller | 2026-02-16 21:12:19.901194 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-02-16 21:12:19.901280 | controller | Monday 16 February 2026 21:12:19 +0000 (0:00:00.403) 0:00:07.752 ******* 2026-02-16 21:12:21.520900 | controller | ok: [controller] 2026-02-16 21:12:21.520926 | controller | 2026-02-16 21:12:21.520932 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:12:21.520937 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:12:21.520942 | controller | 2026-02-16 21:12:21.520946 | controller | 2026-02-16 21:12:21.520950 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:12:21.520955 | controller | Monday 16 February 2026 21:12:21 +0000 (0:00:01.618) 0:00:09.371 ******* 2026-02-16 21:12:21.520959 | controller | =============================================================================== 2026-02-16 21:12:21.520963 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.71s 2026-02-16 21:12:21.520982 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.62s 2026-02-16 21:12:21.520987 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.11s 2026-02-16 21:12:21.520991 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-02-16 21:12:21.520995 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s 2026-02-16 21:12:21.521000 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-02-16 21:12:21.521004 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s 2026-02-16 21:12:21.521008 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s 2026-02-16 21:12:21.521013 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s 2026-02-16 21:12:21.521017 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.40s 2026-02-16 21:12:21.521028 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.39s 2026-02-16 21:12:21.521103 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-02-16 21:12:21.521261 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.28s 2026-02-16 21:12:21.521408 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-02-16 21:12:21.521555 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-02-16 21:12:21.521699 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-02-16 21:12:21.615879 | controller | INFO [helm > idempotence] Executed: Successful 2026-02-16 21:12:21.628167 | controller | INFO [helm > side_effect] Executing 2026-02-16 21:12:21.628849 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:12:21.635657 | controller | INFO [helm > verify] Executing 2026-02-16 21:12:22.464491 | controller | 2026-02-16 21:12:22.464814 | controller | PLAY [Verify] ****************************************************************** 2026-02-16 21:12:22.465132 | controller | 2026-02-16 21:12:22.465408 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-16 21:12:22.465677 | controller | Monday 16 February 2026 21:12:22 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-02-16 21:12:23.491208 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-16 21:12:23.498553 | controller | ok: [controller] 2026-02-16 21:12:23.498624 | controller | 2026-02-16 21:12:23.498707 | controller | TASK [Get the current Helm version] ******************************************** 2026-02-16 21:12:23.498796 | controller | Monday 16 February 2026 21:12:23 +0000 (0:00:01.033) 0:00:01.044 ******* 2026-02-16 21:12:23.923425 | controller | changed: [controller] 2026-02-16 21:12:23.923503 | controller | 2026-02-16 21:12:23.923601 | controller | TASK [Assert Helm version] ***************************************************** 2026-02-16 21:12:23.923698 | controller | Monday 16 February 2026 21:12:23 +0000 (0:00:00.425) 0:00:01.469 ******* 2026-02-16 21:12:23.952412 | controller | ok: [controller] => { 2026-02-16 21:12:23.952497 | controller | "changed": false, 2026-02-16 21:12:23.952588 | controller | "msg": "All assertions passed" 2026-02-16 21:12:23.952677 | controller | } 2026-02-16 21:12:23.952761 | controller | 2026-02-16 21:12:23.952849 | controller | PLAY RECAP ********************************************************************* 2026-02-16 21:12:23.952970 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-16 21:12:23.953052 | controller | 2026-02-16 21:12:23.953128 | controller | 2026-02-16 21:12:23.953226 | controller | TASKS RECAP ******************************************************************** 2026-02-16 21:12:23.953329 | controller | Monday 16 February 2026 21:12:23 +0000 (0:00:00.029) 0:00:01.499 ******* 2026-02-16 21:12:23.953423 | controller | =============================================================================== 2026-02-16 21:12:23.953479 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-02-16 21:12:23.953614 | controller | Get the current Helm version -------------------------------------------- 0.43s 2026-02-16 21:12:23.953700 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-02-16 21:12:24.048678 | controller | INFO [helm > verify] Executed: Successful 2026-02-16 21:12:24.054546 | controller | INFO [helm > cleanup] Executing 2026-02-16 21:12:24.054837 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-16 21:12:24.061303 | controller | INFO [helm > destroy] Executing 2026-02-16 21:12:24.061378 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-02-16 21:12:24.061468 | controller | INFO [helm > destroy] Executed: Successful 2026-02-16 21:12:24.061781 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-16 21:12:24.444238 | controller | ok: Runtime: 0:00:51.894151 2026-02-16 21:12:24.448927 | 2026-02-16 21:12:24.449016 | PLAY RECAP 2026-02-16 21:12:24.449079 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:12:24.449108 | 2026-02-16 21:12:24.564285 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:12:24.567082 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:12:25.176301 | 2026-02-16 21:12:25.176467 | PLAY [all] 2026-02-16 21:12:25.186908 | 2026-02-16 21:12:25.186981 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 21:12:25.233003 | controller | skipping: Conditional result was False 2026-02-16 21:12:25.241496 | 2026-02-16 21:12:25.241581 | TASK [fetch-output : Set log path for single node] 2026-02-16 21:12:25.273559 | controller | ok 2026-02-16 21:12:25.279348 | 2026-02-16 21:12:25.281236 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 21:12:25.656863 | controller -> localhost | ok: "/var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/work/logs" 2026-02-16 21:12:25.877635 | controller -> localhost | changed: "/var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/work/artifacts" 2026-02-16 21:12:26.096465 | controller -> localhost | changed: "/var/lib/zuul/builds/938096597ad34db3b4b5fdb87c4d2d93/work/docs" 2026-02-16 21:12:26.114115 | 2026-02-16 21:12:26.114291 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 21:12:26.777558 | controller | changed: .d..t...... ./ 2026-02-16 21:12:26.777821 | controller | changed: All items complete 2026-02-16 21:12:26.777860 | 2026-02-16 21:12:27.258806 | controller | changed: .d..t...... ./ 2026-02-16 21:12:27.731074 | controller | changed: .d..t...... ./ 2026-02-16 21:12:27.742974 | 2026-02-16 21:12:27.743042 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 21:12:28.152352 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006123 2026-02-16 21:12:28.381044 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008347 2026-02-16 21:12:28.399363 | 2026-02-16 21:12:28.399427 | PLAY [all] 2026-02-16 21:12:28.404187 | 2026-02-16 21:12:28.404248 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 21:12:28.895239 | controller | changed 2026-02-16 21:12:28.899926 | 2026-02-16 21:12:28.899974 | PLAY RECAP 2026-02-16 21:12:28.900020 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 21:12:28.900040 | 2026-02-16 21:12:29.020938 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:12:29.023590 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 21:12:29.638197 | 2026-02-16 21:12:29.638367 | PLAY [localhost] 2026-02-16 21:12:29.648950 | 2026-02-16 21:12:29.649065 | TASK [Generate Zuul manifest] 2026-02-16 21:12:29.671022 | localhost | ok 2026-02-16 21:12:29.686844 | 2026-02-16 21:12:29.686931 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 21:12:30.013632 | localhost | changed 2026-02-16 21:12:30.025330 | 2026-02-16 21:12:30.025404 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 21:12:30.056537 | localhost | ok 2026-02-16 21:12:30.065754 | 2026-02-16 21:12:30.065814 | TASK [Upload logs] 2026-02-16 21:12:30.087301 | localhost | ok 2026-02-16 21:12:30.149695 | 2026-02-16 21:12:30.149851 | TASK [Set zuul-log-path fact] 2026-02-16 21:12:30.171433 | localhost | ok 2026-02-16 21:12:30.183891 | 2026-02-16 21:12:30.183963 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:12:30.216560 | localhost | ok 2026-02-16 21:12:30.224321 | 2026-02-16 21:12:30.224383 | TASK [upload-logs : Create log directories] 2026-02-16 21:12:30.620749 | localhost | changed 2026-02-16 21:12:30.627553 | 2026-02-16 21:12:30.627647 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 21:12:31.045454 | localhost -> localhost | ok: Runtime: 0:00:00.005675 2026-02-16 21:12:31.052393 | 2026-02-16 21:12:31.052470 | TASK [upload-logs : Upload logs to log server] 2026-02-16 21:12:31.497127 | localhost | Output suppressed because no_log was given 2026-02-16 21:12:31.500790 | 2026-02-16 21:12:31.500852 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 21:12:31.541710 | localhost | skipping: Conditional result was False 2026-02-16 21:12:31.548414 | localhost | skipping: Conditional result was False 2026-02-16 21:12:31.561802 | 2026-02-16 21:12:31.562017 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 21:12:31.604844 | localhost | skipping: Conditional result was False 2026-02-16 21:12:31.605303 | 2026-02-16 21:12:31.608236 | localhost | skipping: Conditional result was False 2026-02-16 21:12:31.624655 | 2026-02-16 21:12:31.624813 | LOOP [upload-logs : Upload console log and json output]