2026-01-22 21:46:19.623082 | Job console starting 2026-01-22 21:46:19.636511 | Updating git repos 2026-01-22 21:46:19.676889 | Cloning repos into workspace 2026-01-22 21:46:19.762208 | Restoring repo states 2026-01-22 21:46:19.780765 | Merging changes 2026-01-22 21:46:20.295999 | Checking out repos 2026-01-22 21:46:20.408442 | Preparing playbooks 2026-01-22 21:46:22.096995 | Running Ansible setup 2026-01-22 21:46:25.773466 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-22 21:46:26.350412 | 2026-01-22 21:46:26.350559 | PLAY [localhost] 2026-01-22 21:46:26.358472 | 2026-01-22 21:46:26.358542 | TASK [Gathering Facts] 2026-01-22 21:46:27.247434 | localhost | ok 2026-01-22 21:46:27.256458 | 2026-01-22 21:46:27.256565 | TASK [Setup log path fact] 2026-01-22 21:46:27.276354 | localhost | ok 2026-01-22 21:46:27.290440 | 2026-01-22 21:46:27.290545 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-22 21:46:27.322534 | localhost | ok 2026-01-22 21:46:27.330910 | 2026-01-22 21:46:27.330991 | TASK [emit-job-header : Print job information] 2026-01-22 21:46:27.371971 | # Job Information 2026-01-22 21:46:27.372218 | Ansible Version: 2.16.15 2026-01-22 21:46:27.372270 | Job: ansible-collection-kubernetes-molecule-helm 2026-01-22 21:46:27.372302 | Pipeline: check 2026-01-22 21:46:27.372332 | Executor: 3a2793d2bd32 2026-01-22 21:46:27.372361 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/229 2026-01-22 21:46:27.372397 | Event ID: a4636040-f7db-11f0-909d-aaab0d2ecdc5 2026-01-22 21:46:27.376583 | 2026-01-22 21:46:27.376680 | LOOP [emit-job-header : Print node information] 2026-01-22 21:46:27.464858 | localhost | ok: 2026-01-22 21:46:27.465217 | localhost | # Node Information 2026-01-22 21:46:27.465297 | localhost | Inventory Hostname: controller 2026-01-22 21:46:27.465355 | localhost | Hostname: np0000154418 2026-01-22 21:46:27.465405 | localhost | Username: zuul 2026-01-22 21:46:27.465461 | localhost | Distro: Debian 13.3 2026-01-22 21:46:27.465512 | localhost | Provider: yul1 2026-01-22 21:46:27.465560 | localhost | Region: ca-ymq-1 2026-01-22 21:46:27.465608 | localhost | Label: debian-trixie 2026-01-22 21:46:27.465655 | localhost | Product Name: OpenStack Nova 2026-01-22 21:46:27.465701 | localhost | Interface IP: 199.19.213.152 2026-01-22 21:46:27.487978 | 2026-01-22 21:46:27.488152 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-22 21:46:27.904423 | localhost -> localhost | changed 2026-01-22 21:46:27.912208 | 2026-01-22 21:46:27.912437 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-22 21:46:28.763545 | localhost -> localhost | changed 2026-01-22 21:46:28.771136 | 2026-01-22 21:46:28.771204 | PLAY [all] 2026-01-22 21:46:28.778144 | 2026-01-22 21:46:28.778216 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-22 21:46:29.007577 | controller -> localhost | ok 2026-01-22 21:46:29.017009 | 2026-01-22 21:46:29.017109 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-22 21:46:29.049386 | controller | ok 2026-01-22 21:46:29.064312 | controller | included: /var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-22 21:46:29.070231 | 2026-01-22 21:46:29.070297 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-22 21:46:30.445401 | controller -> localhost | Generating public/private rsa key pair. 2026-01-22 21:46:30.445701 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/work/543f368646f94d18b13993aeddf7f958_id_rsa 2026-01-22 21:46:30.445769 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/work/543f368646f94d18b13993aeddf7f958_id_rsa.pub 2026-01-22 21:46:30.445823 | controller -> localhost | The key fingerprint is: 2026-01-22 21:46:30.445873 | controller -> localhost | SHA256:j9aAtHbo968QBVh772g+YfXmNrX99FLax2VrHfYiCwc zuul-build-sshkey 2026-01-22 21:46:30.445942 | controller -> localhost | The key's randomart image is: 2026-01-22 21:46:30.445992 | controller -> localhost | +---[RSA 3072]----+ 2026-01-22 21:46:30.446044 | controller -> localhost | | oo | 2026-01-22 21:46:30.446093 | controller -> localhost | | . o | 2026-01-22 21:46:30.446140 | controller -> localhost | | . . o | 2026-01-22 21:46:30.446186 | controller -> localhost | | . + o .. | 2026-01-22 21:46:30.446233 | controller -> localhost | | = S E... | 2026-01-22 21:46:30.446279 | controller -> localhost | | o . *o+ oo=| 2026-01-22 21:46:30.446325 | controller -> localhost | | . =.*.oo.BX| 2026-01-22 21:46:30.446371 | controller -> localhost | | o =.o. B=O| 2026-01-22 21:46:30.446425 | controller -> localhost | | ++o+.+=| 2026-01-22 21:46:30.446472 | controller -> localhost | +----[SHA256]-----+ 2026-01-22 21:46:30.446572 | controller -> localhost | ok: Runtime: 0:00:00.949014 2026-01-22 21:46:30.452458 | 2026-01-22 21:46:30.452532 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-22 21:46:30.486036 | controller | ok 2026-01-22 21:46:30.493998 | controller | included: /var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-22 21:46:30.500407 | 2026-01-22 21:46:30.500472 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-22 21:46:30.525858 | controller | skipping: Conditional result was False 2026-01-22 21:46:30.535918 | 2026-01-22 21:46:30.536037 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-22 21:46:31.134722 | controller | changed 2026-01-22 21:46:31.139809 | 2026-01-22 21:46:31.139897 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-22 21:46:31.352932 | controller | ok 2026-01-22 21:46:31.358655 | 2026-01-22 21:46:31.358745 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-22 21:46:31.885946 | controller | changed 2026-01-22 21:46:31.891634 | 2026-01-22 21:46:31.891740 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-22 21:46:32.431262 | controller | changed 2026-01-22 21:46:32.435660 | 2026-01-22 21:46:32.435724 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-22 21:46:32.460574 | controller | skipping: Conditional result was False 2026-01-22 21:46:32.469929 | 2026-01-22 21:46:32.470066 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-22 21:46:32.870373 | controller -> localhost | changed 2026-01-22 21:46:32.884261 | 2026-01-22 21:46:32.884331 | TASK [add-build-sshkey : Add back temp key] 2026-01-22 21:46:33.183658 | controller -> localhost | Identity added: /var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/work/543f368646f94d18b13993aeddf7f958_id_rsa (zuul-build-sshkey) 2026-01-22 21:46:33.183921 | controller -> localhost | ok: Runtime: 0:00:00.022628 2026-01-22 21:46:33.189404 | 2026-01-22 21:46:33.189492 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-22 21:46:33.547576 | controller | ok 2026-01-22 21:46:33.553451 | 2026-01-22 21:46:33.553516 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-22 21:46:33.578191 | controller | skipping: Conditional result was False 2026-01-22 21:46:33.591031 | 2026-01-22 21:46:33.591092 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-22 21:46:33.976695 | controller | ok 2026-01-22 21:46:33.981933 | 2026-01-22 21:46:33.982003 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-22 21:46:35.025631 | controller | Output suppressed because no_log was given 2026-01-22 21:46:35.032601 | 2026-01-22 21:46:35.032715 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-22 21:46:35.261916 | controller | ok: "logs" 2026-01-22 21:46:35.262169 | controller | ok: All items complete 2026-01-22 21:46:35.262203 | 2026-01-22 21:46:35.451309 | controller | ok: "artifacts" 2026-01-22 21:46:35.638482 | controller | ok: "docs" 2026-01-22 21:46:35.655181 | 2026-01-22 21:46:35.655327 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-22 21:46:35.864569 | controller | changed: "logs" 2026-01-22 21:46:36.057761 | controller | changed: "artifacts" 2026-01-22 21:46:36.243252 | controller | changed: "docs" 2026-01-22 21:46:36.263811 | 2026-01-22 21:46:36.263893 | PLAY RECAP 2026-01-22 21:46:36.263937 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-01-22 21:46:36.263966 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-22 21:46:36.263988 | 2026-01-22 21:46:36.383232 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-22 21:46:36.385821 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-22 21:46:36.998070 | 2026-01-22 21:46:36.998227 | PLAY [all] 2026-01-22 21:46:37.008702 | 2026-01-22 21:46:37.008800 | TASK [setup-uv : Extract archive] 2026-01-22 21:46:39.298639 | controller | changed 2026-01-22 21:46:39.303947 | 2026-01-22 21:46:39.304026 | TASK [setup-uv : Print version] 2026-01-22 21:46:39.673749 | controller | uv 0.8.13 2026-01-22 21:46:39.830657 | controller | ok: Runtime: 0:00:00.009168 2026-01-22 21:46:39.836235 | 2026-01-22 21:46:39.836286 | PLAY RECAP 2026-01-22 21:46:39.836331 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-22 21:46:39.836355 | 2026-01-22 21:46:39.952593 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-22 21:46:39.954170 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-22 21:46:40.538940 | 2026-01-22 21:46:40.539068 | PLAY [all] 2026-01-22 21:46:40.548863 | 2026-01-22 21:46:40.548935 | TASK [Copy inventory file for Zuul] 2026-01-22 21:46:41.474698 | controller | changed 2026-01-22 21:46:41.479242 | 2026-01-22 21:46:41.479316 | TASK [Switch "ansible_host" to private IP] 2026-01-22 21:46:41.781089 | controller | changed: 1 replacements made 2026-01-22 21:46:41.787124 | 2026-01-22 21:46:41.787194 | TASK [Run Molecule scenario] 2026-01-22 21:46:42.294522 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-01-22 21:46:42.294618 | controller | Creating virtual environment at: .venv 2026-01-22 21:46:42.344127 | controller | Downloading pygments (1.2MiB) 2026-01-22 21:46:42.349473 | controller | Downloading ansible-core (2.3MiB) 2026-01-22 21:46:42.352061 | controller | Downloading cryptography (4.3MiB) 2026-01-22 21:46:42.532857 | controller | Downloading cryptography 2026-01-22 21:46:42.583540 | controller | Downloading pygments 2026-01-22 21:46:42.703200 | controller | Downloading ansible-core 2026-01-22 21:46:42.801948 | controller | Installed 36 packages in 98ms 2026-01-22 21:46:43.479657 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-01-22 21:46:43.479733 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-01-22 21:46:44.249307 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-01-22 21:46:44.249332 | controller | INFO default scenario not found, disabling shared state. 2026-01-22 21:46:44.249336 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-01-22 21:46:44.249340 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-01-22 21:47:01.669783 | controller | INFO [helm > dependency] Executing 2026-01-22 21:47:01.670011 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-01-22 21:47:01.670291 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-01-22 21:47:01.670446 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-01-22 21:47:01.678717 | controller | INFO [helm > cleanup] Executing 2026-01-22 21:47:01.679024 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-22 21:47:01.684318 | controller | INFO [helm > destroy] Executing 2026-01-22 21:47:01.684346 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-01-22 21:47:01.684443 | controller | INFO [helm > destroy] Executed: Successful 2026-01-22 21:47:01.689884 | controller | INFO [helm > syntax] Executing 2026-01-22 21:47:02.610844 | controller | 2026-01-22 21:47:02.610877 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-01-22 21:47:02.669321 | controller | INFO [helm > syntax] Executed: Successful 2026-01-22 21:47:02.677665 | controller | INFO [helm > create] Executing 2026-01-22 21:47:02.679148 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-22 21:47:02.684650 | controller | INFO [helm > prepare] Executing 2026-01-22 21:47:03.542247 | controller | 2026-01-22 21:47:03.542304 | controller | PLAY [Prepare] ***************************************************************** 2026-01-22 21:47:03.542314 | controller | 2026-01-22 21:47:03.542322 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-22 21:47:03.542333 | controller | Thursday 22 January 2026 21:47:03 +0000 (0:00:00.030) 0:00:00.030 ****** 2026-01-22 21:47:04.759985 | 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-01-22 21:47:04.766842 | controller | ok: [controller] 2026-01-22 21:47:04.766894 | controller | 2026-01-22 21:47:04.766899 | controller | TASK [Install Debian packages] ************************************************* 2026-01-22 21:47:04.766905 | controller | Thursday 22 January 2026 21:47:04 +0000 (0:00:01.226) 0:00:01.257 ****** 2026-01-22 21:47:09.752118 | controller | ok: [controller] 2026-01-22 21:47:09.752150 | controller | 2026-01-22 21:47:09.752156 | controller | PLAY RECAP ********************************************************************* 2026-01-22 21:47:09.752162 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-22 21:47:09.752167 | controller | 2026-01-22 21:47:09.752172 | controller | 2026-01-22 21:47:09.752177 | controller | TASKS RECAP ******************************************************************** 2026-01-22 21:47:09.752192 | controller | Thursday 22 January 2026 21:47:09 +0000 (0:00:04.984) 0:00:06.241 ****** 2026-01-22 21:47:09.752196 | controller | =============================================================================== 2026-01-22 21:47:09.752201 | controller | Install Debian packages ------------------------------------------------- 4.98s 2026-01-22 21:47:09.752206 | controller | Gathering Facts --------------------------------------------------------- 1.23s 2026-01-22 21:47:09.842274 | controller | INFO [helm > prepare] Executed: Successful 2026-01-22 21:47:09.848461 | controller | INFO [helm > converge] Executing 2026-01-22 21:47:10.711530 | controller | 2026-01-22 21:47:10.711549 | controller | PLAY [Converge] **************************************************************** 2026-01-22 21:47:10.711553 | controller | 2026-01-22 21:47:10.711557 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-22 21:47:10.711561 | controller | Thursday 22 January 2026 21:47:10 +0000 (0:00:00.015) 0:00:00.015 ****** 2026-01-22 21:47:11.701328 | 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-01-22 21:47:11.704553 | controller | ok: [controller] 2026-01-22 21:47:11.706218 | controller | 2026-01-22 21:47:11.706471 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-01-22 21:47:11.706615 | controller | Thursday 22 January 2026 21:47:11 +0000 (0:00:00.995) 0:00:01.011 ****** 2026-01-22 21:47:12.154670 | controller | ok: [controller] 2026-01-22 21:47:12.154817 | controller | 2026-01-22 21:47:12.154945 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-01-22 21:47:12.155075 | controller | Thursday 22 January 2026 21:47:12 +0000 (0:00:00.446) 0:00:01.458 ****** 2026-01-22 21:47:13.200391 | controller | ok: [controller] 2026-01-22 21:47:13.200538 | controller | 2026-01-22 21:47:13.200710 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-01-22 21:47:13.200880 | controller | Thursday 22 January 2026 21:47:13 +0000 (0:00:01.049) 0:00:02.507 ****** 2026-01-22 21:47:13.651612 | controller | changed: [controller] 2026-01-22 21:47:13.651706 | controller | 2026-01-22 21:47:13.653304 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-22 21:47:13.653319 | controller | Thursday 22 January 2026 21:47:13 +0000 (0:00:00.451) 0:00:02.958 ****** 2026-01-22 21:47:13.696730 | controller | ok: [controller] => { 2026-01-22 21:47:13.696803 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-01-22 21:47:13.696881 | controller | } 2026-01-22 21:47:13.696965 | controller | 2026-01-22 21:47:13.697054 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-22 21:47:13.697137 | controller | Thursday 22 January 2026 21:47:13 +0000 (0:00:00.044) 0:00:03.003 ****** 2026-01-22 21:47:15.345691 | 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-01-22 21:47:15.352692 | controller | changed: [controller] 2026-01-22 21:47:15.352779 | controller | 2026-01-22 21:47:15.352877 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-22 21:47:15.352970 | controller | Thursday 22 January 2026 21:47:15 +0000 (0:00:01.652) 0:00:04.656 ****** 2026-01-22 21:47:17.436911 | controller | changed: [controller] 2026-01-22 21:47:17.439061 | controller | 2026-01-22 21:47:17.439072 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-01-22 21:47:17.439076 | controller | Thursday 22 January 2026 21:47:17 +0000 (0:00:02.087) 0:00:06.744 ****** 2026-01-22 21:47:17.952397 | controller | ok: [controller] 2026-01-22 21:47:17.952466 | controller | 2026-01-22 21:47:17.952575 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-01-22 21:47:17.952668 | controller | Thursday 22 January 2026 21:47:17 +0000 (0:00:00.514) 0:00:07.258 ****** 2026-01-22 21:47:18.375167 | controller | changed: [controller] 2026-01-22 21:47:18.375790 | controller | 2026-01-22 21:47:18.375832 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-01-22 21:47:18.375840 | controller | Thursday 22 January 2026 21:47:18 +0000 (0:00:00.423) 0:00:07.681 ****** 2026-01-22 21:47:18.809323 | controller | ok: [controller] 2026-01-22 21:47:18.809364 | controller | 2026-01-22 21:47:18.809369 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-01-22 21:47:18.809373 | controller | Thursday 22 January 2026 21:47:18 +0000 (0:00:00.433) 0:00:08.115 ****** 2026-01-22 21:47:19.101917 | controller | changed: [controller] 2026-01-22 21:47:19.101942 | controller | 2026-01-22 21:47:19.101947 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-01-22 21:47:19.101950 | controller | Thursday 22 January 2026 21:47:19 +0000 (0:00:00.293) 0:00:08.408 ****** 2026-01-22 21:47:19.385192 | controller | ok: [controller] 2026-01-22 21:47:19.385408 | controller | 2026-01-22 21:47:19.385580 | controller | TASK [Install plugin] ********************************************************** 2026-01-22 21:47:19.385743 | controller | Thursday 22 January 2026 21:47:19 +0000 (0:00:00.283) 0:00:08.692 ****** 2026-01-22 21:47:19.437404 | controller | included: vexxhost.containers.download_artifact for controller 2026-01-22 21:47:19.437692 | controller | 2026-01-22 21:47:19.437967 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-22 21:47:19.438230 | controller | Thursday 22 January 2026 21:47:19 +0000 (0:00:00.052) 0:00:08.744 ****** 2026-01-22 21:47:19.481495 | controller | ok: [controller] => { 2026-01-22 21:47:19.481517 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-01-22 21:47:19.481521 | controller | } 2026-01-22 21:47:19.481525 | controller | 2026-01-22 21:47:19.481529 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-22 21:47:19.481533 | controller | Thursday 22 January 2026 21:47:19 +0000 (0:00:00.041) 0:00:08.786 ****** 2026-01-22 21:47:20.688241 | controller | changed: [controller] 2026-01-22 21:47:20.688270 | controller | 2026-01-22 21:47:20.688276 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-22 21:47:20.688282 | controller | Thursday 22 January 2026 21:47:20 +0000 (0:00:01.208) 0:00:09.994 ****** 2026-01-22 21:47:22.736225 | controller | changed: [controller] 2026-01-22 21:47:22.736507 | controller | 2026-01-22 21:47:22.736773 | controller | PLAY RECAP ********************************************************************* 2026-01-22 21:47:22.737050 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-22 21:47:22.737348 | controller | 2026-01-22 21:47:22.737586 | controller | 2026-01-22 21:47:22.737834 | controller | TASKS RECAP ******************************************************************** 2026-01-22 21:47:22.738087 | controller | Thursday 22 January 2026 21:47:22 +0000 (0:00:02.049) 0:00:12.043 ****** 2026-01-22 21:47:22.738323 | controller | =============================================================================== 2026-01-22 21:47:22.738579 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.09s 2026-01-22 21:47:22.738955 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s 2026-01-22 21:47:22.739223 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.65s 2026-01-22 21:47:22.739471 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.21s 2026-01-22 21:47:22.739713 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.05s 2026-01-22 21:47:22.739954 | controller | Gathering Facts --------------------------------------------------------- 1.00s 2026-01-22 21:47:22.740196 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-01-22 21:47:22.740441 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s 2026-01-22 21:47:22.740683 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.45s 2026-01-22 21:47:22.740928 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s 2026-01-22 21:47:22.741184 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s 2026-01-22 21:47:22.741497 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-01-22 21:47:22.741758 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.28s 2026-01-22 21:47:22.741998 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-01-22 21:47:22.742243 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-01-22 21:47:22.742513 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-01-22 21:47:22.821643 | controller | INFO [helm > converge] Executed: Successful 2026-01-22 21:47:22.830250 | controller | INFO [helm > idempotence] Executing 2026-01-22 21:47:23.640941 | controller | 2026-01-22 21:47:23.641108 | controller | PLAY [Converge] **************************************************************** 2026-01-22 21:47:23.641236 | controller | 2026-01-22 21:47:23.641440 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-22 21:47:23.641580 | controller | Thursday 22 January 2026 21:47:23 +0000 (0:00:00.013) 0:00:00.013 ****** 2026-01-22 21:47:24.637405 | 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-01-22 21:47:24.643082 | controller | ok: [controller] 2026-01-22 21:47:24.643094 | controller | 2026-01-22 21:47:24.643098 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-01-22 21:47:24.643102 | controller | Thursday 22 January 2026 21:47:24 +0000 (0:00:00.998) 0:00:01.011 ****** 2026-01-22 21:47:25.038945 | controller | ok: [controller] 2026-01-22 21:47:25.039096 | controller | 2026-01-22 21:47:25.039220 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-01-22 21:47:25.039345 | controller | Thursday 22 January 2026 21:47:25 +0000 (0:00:00.399) 0:00:01.410 ****** 2026-01-22 21:47:26.026870 | controller | ok: [controller] 2026-01-22 21:47:26.027059 | controller | 2026-01-22 21:47:26.027298 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-01-22 21:47:26.027481 | controller | Thursday 22 January 2026 21:47:26 +0000 (0:00:00.988) 0:00:02.398 ****** 2026-01-22 21:47:26.441476 | controller | ok: [controller] 2026-01-22 21:47:26.441663 | controller | 2026-01-22 21:47:26.441838 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-22 21:47:26.442001 | controller | Thursday 22 January 2026 21:47:26 +0000 (0:00:00.414) 0:00:02.813 ****** 2026-01-22 21:47:26.490522 | controller | ok: [controller] => { 2026-01-22 21:47:26.490725 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-01-22 21:47:26.490889 | controller | } 2026-01-22 21:47:26.491039 | controller | 2026-01-22 21:47:26.491203 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-22 21:47:26.491362 | controller | Thursday 22 January 2026 21:47:26 +0000 (0:00:00.048) 0:00:02.862 ****** 2026-01-22 21:47:26.991347 | controller | ok: [controller] 2026-01-22 21:47:26.993654 | controller | 2026-01-22 21:47:26.993664 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-22 21:47:26.993668 | controller | Thursday 22 January 2026 21:47:26 +0000 (0:00:00.501) 0:00:03.363 ****** 2026-01-22 21:47:28.603014 | controller | ok: [controller] 2026-01-22 21:47:28.603209 | controller | 2026-01-22 21:47:28.603394 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-01-22 21:47:28.603570 | controller | Thursday 22 January 2026 21:47:28 +0000 (0:00:01.611) 0:00:04.974 ****** 2026-01-22 21:47:29.117096 | controller | ok: [controller] 2026-01-22 21:47:29.117123 | controller | 2026-01-22 21:47:29.117129 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-01-22 21:47:29.117135 | controller | Thursday 22 January 2026 21:47:29 +0000 (0:00:00.510) 0:00:05.485 ****** 2026-01-22 21:47:29.500721 | controller | ok: [controller] 2026-01-22 21:47:29.500912 | controller | 2026-01-22 21:47:29.501095 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-01-22 21:47:29.501300 | controller | Thursday 22 January 2026 21:47:29 +0000 (0:00:00.386) 0:00:05.872 ****** 2026-01-22 21:47:29.936556 | controller | ok: [controller] 2026-01-22 21:47:29.936652 | controller | 2026-01-22 21:47:29.936955 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-01-22 21:47:29.936981 | controller | Thursday 22 January 2026 21:47:29 +0000 (0:00:00.435) 0:00:06.308 ****** 2026-01-22 21:47:30.213346 | controller | ok: [controller] 2026-01-22 21:47:30.213369 | controller | 2026-01-22 21:47:30.213374 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-01-22 21:47:30.213377 | controller | Thursday 22 January 2026 21:47:30 +0000 (0:00:00.275) 0:00:06.583 ****** 2026-01-22 21:47:30.500877 | controller | ok: [controller] 2026-01-22 21:47:30.500898 | controller | 2026-01-22 21:47:30.500902 | controller | TASK [Install plugin] ********************************************************** 2026-01-22 21:47:30.500906 | controller | Thursday 22 January 2026 21:47:30 +0000 (0:00:00.286) 0:00:06.870 ****** 2026-01-22 21:47:30.546933 | controller | included: vexxhost.containers.download_artifact for controller 2026-01-22 21:47:30.546992 | controller | 2026-01-22 21:47:30.547139 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-01-22 21:47:30.547266 | controller | Thursday 22 January 2026 21:47:30 +0000 (0:00:00.049) 0:00:06.919 ****** 2026-01-22 21:47:30.589567 | controller | ok: [controller] => { 2026-01-22 21:47:30.589601 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-01-22 21:47:30.589608 | controller | } 2026-01-22 21:47:30.589614 | controller | 2026-01-22 21:47:30.589619 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-01-22 21:47:30.589624 | controller | Thursday 22 January 2026 21:47:30 +0000 (0:00:00.039) 0:00:06.958 ****** 2026-01-22 21:47:30.989800 | controller | ok: [controller] 2026-01-22 21:47:30.989994 | controller | 2026-01-22 21:47:30.990176 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-01-22 21:47:30.990350 | controller | Thursday 22 January 2026 21:47:30 +0000 (0:00:00.403) 0:00:07.361 ****** 2026-01-22 21:47:32.541325 | controller | ok: [controller] 2026-01-22 21:47:32.541523 | controller | 2026-01-22 21:47:32.541732 | controller | PLAY RECAP ********************************************************************* 2026-01-22 21:47:32.541928 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-22 21:47:32.542084 | controller | 2026-01-22 21:47:32.542237 | controller | 2026-01-22 21:47:32.542400 | controller | TASKS RECAP ******************************************************************** 2026-01-22 21:47:32.542576 | controller | Thursday 22 January 2026 21:47:32 +0000 (0:00:01.552) 0:00:08.913 ****** 2026-01-22 21:47:32.542734 | controller | =============================================================================== 2026-01-22 21:47:32.542907 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.61s 2026-01-22 21:47:32.543065 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.55s 2026-01-22 21:47:32.543291 | controller | Gathering Facts --------------------------------------------------------- 1.00s 2026-01-22 21:47:32.543517 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.99s 2026-01-22 21:47:32.543742 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-01-22 21:47:32.543913 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.50s 2026-01-22 21:47:32.544072 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s 2026-01-22 21:47:32.544230 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-01-22 21:47:32.544386 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.40s 2026-01-22 21:47:32.544544 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.40s 2026-01-22 21:47:32.544712 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-01-22 21:47:32.544880 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-01-22 21:47:32.545043 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-01-22 21:47:32.545196 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-01-22 21:47:32.545503 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-01-22 21:47:32.545717 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-01-22 21:47:32.639340 | controller | INFO [helm > idempotence] Executed: Successful 2026-01-22 21:47:32.649456 | controller | INFO [helm > side_effect] Executing 2026-01-22 21:47:32.649729 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-22 21:47:32.655637 | controller | INFO [helm > verify] Executing 2026-01-22 21:47:33.452373 | controller | 2026-01-22 21:47:33.452619 | controller | PLAY [Verify] ****************************************************************** 2026-01-22 21:47:33.452802 | controller | 2026-01-22 21:47:33.452994 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-22 21:47:33.453183 | controller | Thursday 22 January 2026 21:47:33 +0000 (0:00:00.009) 0:00:00.009 ****** 2026-01-22 21:47:34.436619 | 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-01-22 21:47:34.444781 | controller | ok: [controller] 2026-01-22 21:47:34.446274 | controller | 2026-01-22 21:47:34.446284 | controller | TASK [Get the current Helm version] ******************************************** 2026-01-22 21:47:34.446289 | controller | Thursday 22 January 2026 21:47:34 +0000 (0:00:00.990) 0:00:01.000 ****** 2026-01-22 21:47:34.887379 | controller | changed: [controller] 2026-01-22 21:47:34.887443 | controller | 2026-01-22 21:47:34.887534 | controller | TASK [Assert Helm version] ***************************************************** 2026-01-22 21:47:34.887624 | controller | Thursday 22 January 2026 21:47:34 +0000 (0:00:00.444) 0:00:01.444 ****** 2026-01-22 21:47:34.913412 | controller | ok: [controller] => { 2026-01-22 21:47:34.913497 | controller | "changed": false, 2026-01-22 21:47:34.913593 | controller | "msg": "All assertions passed" 2026-01-22 21:47:34.913686 | controller | } 2026-01-22 21:47:34.913756 | controller | 2026-01-22 21:47:34.913839 | controller | PLAY RECAP ********************************************************************* 2026-01-22 21:47:34.913935 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-22 21:47:34.914001 | controller | 2026-01-22 21:47:34.914078 | controller | 2026-01-22 21:47:34.914159 | controller | TASKS RECAP ******************************************************************** 2026-01-22 21:47:34.914255 | controller | Thursday 22 January 2026 21:47:34 +0000 (0:00:00.026) 0:00:01.471 ****** 2026-01-22 21:47:34.914349 | controller | =============================================================================== 2026-01-22 21:47:34.914433 | controller | Gathering Facts --------------------------------------------------------- 0.99s 2026-01-22 21:47:34.914516 | controller | Get the current Helm version -------------------------------------------- 0.44s 2026-01-22 21:47:34.914600 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-01-22 21:47:34.997765 | controller | INFO [helm > verify] Executed: Successful 2026-01-22 21:47:35.006861 | controller | INFO [helm > cleanup] Executing 2026-01-22 21:47:35.007263 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-22 21:47:35.013119 | controller | INFO [helm > destroy] Executing 2026-01-22 21:47:35.013188 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-01-22 21:47:35.013295 | controller | INFO [helm > destroy] Executed: Successful 2026-01-22 21:47:35.013540 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-01-22 21:47:35.359074 | controller | ok: Runtime: 0:00:52.940988 2026-01-22 21:47:35.364340 | 2026-01-22 21:47:35.364413 | PLAY RECAP 2026-01-22 21:47:35.364476 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-22 21:47:35.364509 | 2026-01-22 21:47:35.484645 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-22 21:47:35.487495 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-22 21:47:36.077064 | 2026-01-22 21:47:36.077188 | PLAY [all] 2026-01-22 21:47:36.087190 | 2026-01-22 21:47:36.087265 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-22 21:47:36.132567 | controller | skipping: Conditional result was False 2026-01-22 21:47:36.141029 | 2026-01-22 21:47:36.141194 | TASK [fetch-output : Set log path for single node] 2026-01-22 21:47:36.174827 | controller | ok 2026-01-22 21:47:36.180352 | 2026-01-22 21:47:36.180418 | LOOP [fetch-output : Ensure local output dirs] 2026-01-22 21:47:36.588726 | controller -> localhost | ok: "/var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/work/logs" 2026-01-22 21:47:36.799698 | controller -> localhost | changed: "/var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/work/artifacts" 2026-01-22 21:47:37.003410 | controller -> localhost | changed: "/var/lib/zuul/builds/543f368646f94d18b13993aeddf7f958/work/docs" 2026-01-22 21:47:37.017122 | 2026-01-22 21:47:37.017207 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-22 21:47:37.647251 | controller | changed: .d..t...... ./ 2026-01-22 21:47:37.647468 | controller | changed: All items complete 2026-01-22 21:47:37.647496 | 2026-01-22 21:47:38.140023 | controller | changed: .d..t...... ./ 2026-01-22 21:47:38.617892 | controller | changed: .d..t...... ./ 2026-01-22 21:47:38.638328 | 2026-01-22 21:47:38.638470 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-22 21:47:39.066468 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007092 2026-01-22 21:47:39.304343 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008172 2026-01-22 21:47:39.314892 | 2026-01-22 21:47:39.315041 | PLAY [all] 2026-01-22 21:47:39.322220 | 2026-01-22 21:47:39.322291 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-01-22 21:47:39.800637 | controller | changed 2026-01-22 21:47:39.806357 | 2026-01-22 21:47:39.806418 | PLAY RECAP 2026-01-22 21:47:39.806479 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-01-22 21:47:39.806502 | 2026-01-22 21:47:39.939895 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-22 21:47:39.942577 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-01-22 21:47:40.534967 | 2026-01-22 21:47:40.535725 | PLAY [localhost] 2026-01-22 21:47:40.545862 | 2026-01-22 21:47:40.545933 | TASK [Generate Zuul manifest] 2026-01-22 21:47:40.567410 | localhost | ok 2026-01-22 21:47:40.583294 | 2026-01-22 21:47:40.583385 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-22 21:47:40.890687 | localhost | changed 2026-01-22 21:47:40.903661 | 2026-01-22 21:47:40.903744 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-22 21:47:40.935563 | localhost | ok 2026-01-22 21:47:40.943375 | 2026-01-22 21:47:40.943435 | TASK [Upload logs] 2026-01-22 21:47:40.963059 | localhost | ok 2026-01-22 21:47:41.023135 | 2026-01-22 21:47:41.023280 | TASK [Set zuul-log-path fact] 2026-01-22 21:47:41.045679 | localhost | ok 2026-01-22 21:47:41.059035 | 2026-01-22 21:47:41.059104 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-22 21:47:41.089528 | localhost | ok 2026-01-22 21:47:41.098110 | 2026-01-22 21:47:41.098172 | TASK [upload-logs : Create log directories] 2026-01-22 21:47:41.507648 | localhost | changed 2026-01-22 21:47:41.513189 | 2026-01-22 21:47:41.513266 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-01-22 21:47:41.922597 | localhost -> localhost | ok: Runtime: 0:00:00.005673 2026-01-22 21:47:41.928642 | 2026-01-22 21:47:41.928711 | TASK [upload-logs : Upload logs to log server] 2026-01-22 21:47:42.387165 | localhost | Output suppressed because no_log was given 2026-01-22 21:47:42.392012 | 2026-01-22 21:47:42.392098 | LOOP [upload-logs : Compress console log and json output] 2026-01-22 21:47:42.437314 | localhost | skipping: Conditional result was False 2026-01-22 21:47:42.443609 | localhost | skipping: Conditional result was False 2026-01-22 21:47:42.453747 | 2026-01-22 21:47:42.453846 | LOOP [upload-logs : Upload compressed console log and json output] 2026-01-22 21:47:42.494870 | localhost | skipping: Conditional result was False 2026-01-22 21:47:42.495260 | 2026-01-22 21:47:42.498365 | localhost | skipping: Conditional result was False 2026-01-22 21:47:42.508432 | 2026-01-22 21:47:42.508590 | LOOP [upload-logs : Upload console log and json output]