2026-01-21 17:49:51.833574 | Job console starting 2026-01-21 17:49:51.844147 | Updating git repos 2026-01-21 17:49:51.868760 | Cloning repos into workspace 2026-01-21 17:49:51.914668 | Restoring repo states 2026-01-21 17:49:51.928299 | Merging changes 2026-01-21 17:49:52.427758 | Checking out repos 2026-01-21 17:49:52.627184 | Preparing playbooks 2026-01-21 17:49:53.604927 | Running Ansible setup 2026-01-21 17:49:57.192656 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-21 17:49:57.881052 | 2026-01-21 17:49:57.881213 | PLAY [localhost] 2026-01-21 17:49:57.889307 | 2026-01-21 17:49:57.889377 | TASK [Gathering Facts] 2026-01-21 17:49:58.817826 | localhost | ok 2026-01-21 17:49:58.829589 | 2026-01-21 17:49:58.829681 | TASK [Setup log path fact] 2026-01-21 17:49:58.856111 | localhost | ok 2026-01-21 17:49:58.887587 | 2026-01-21 17:49:58.887849 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-21 17:49:58.925672 | localhost | ok 2026-01-21 17:49:58.935372 | 2026-01-21 17:49:58.935622 | TASK [emit-job-header : Print job information] 2026-01-21 17:49:58.977758 | # Job Information 2026-01-21 17:49:58.977994 | Ansible Version: 2.16.15 2026-01-21 17:49:58.978044 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-01-21 17:49:58.978078 | Pipeline: check 2026-01-21 17:49:58.978109 | Executor: 3a2793d2bd32 2026-01-21 17:49:58.978139 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/228 2026-01-21 17:49:58.978172 | Event ID: 798a3fa0-f6f1-11f0-8cda-ecf02918b158 2026-01-21 17:49:58.983075 | 2026-01-21 17:49:58.983227 | LOOP [emit-job-header : Print node information] 2026-01-21 17:49:59.089034 | localhost | ok: 2026-01-21 17:49:59.089297 | localhost | # Node Information 2026-01-21 17:49:59.089328 | localhost | Inventory Hostname: controller 2026-01-21 17:49:59.089351 | localhost | Hostname: np0000154370 2026-01-21 17:49:59.089371 | localhost | Username: zuul 2026-01-21 17:49:59.089396 | localhost | Distro: Debian 13.3 2026-01-21 17:49:59.089419 | localhost | Provider: yul1 2026-01-21 17:49:59.089438 | localhost | Region: ca-ymq-1 2026-01-21 17:49:59.089456 | localhost | Label: debian-trixie 2026-01-21 17:49:59.089475 | localhost | Product Name: OpenStack Nova 2026-01-21 17:49:59.089493 | localhost | Interface IP: 162.253.55.49 2026-01-21 17:49:59.104280 | 2026-01-21 17:49:59.104446 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-21 17:49:59.523362 | localhost -> localhost | changed 2026-01-21 17:49:59.531320 | 2026-01-21 17:49:59.531516 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-21 17:50:00.487701 | localhost -> localhost | changed 2026-01-21 17:50:00.498044 | 2026-01-21 17:50:00.498135 | PLAY [all] 2026-01-21 17:50:00.507886 | 2026-01-21 17:50:00.518961 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-21 17:50:00.817736 | controller -> localhost | ok 2026-01-21 17:50:00.825513 | 2026-01-21 17:50:00.825707 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-21 17:50:00.854055 | controller | ok 2026-01-21 17:50:00.869861 | controller | included: /var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-21 17:50:00.878730 | 2026-01-21 17:50:00.878860 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-21 17:50:02.052649 | controller -> localhost | Generating public/private rsa key pair. 2026-01-21 17:50:02.052837 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/work/b0dbd360117648f6a017a7dbf8ff3dc5_id_rsa 2026-01-21 17:50:02.052868 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/work/b0dbd360117648f6a017a7dbf8ff3dc5_id_rsa.pub 2026-01-21 17:50:02.052891 | controller -> localhost | The key fingerprint is: 2026-01-21 17:50:02.052913 | controller -> localhost | SHA256:gaczG3SqMU+YkiLruTE5js9fnRrl5rL0icX9W3DFGXY zuul-build-sshkey 2026-01-21 17:50:02.052973 | controller -> localhost | The key's randomart image is: 2026-01-21 17:50:02.052998 | controller -> localhost | +---[RSA 3072]----+ 2026-01-21 17:50:02.053023 | controller -> localhost | | oE| 2026-01-21 17:50:02.053045 | controller -> localhost | | . ..+| 2026-01-21 17:50:02.053066 | controller -> localhost | | o + .o| 2026-01-21 17:50:02.053086 | controller -> localhost | | . + = . . | 2026-01-21 17:50:02.053106 | controller -> localhost | |o o = B S . . | 2026-01-21 17:50:02.053126 | controller -> localhost | |.o.. * X o o | 2026-01-21 17:50:02.053145 | controller -> localhost | |.= . * B . . | 2026-01-21 17:50:02.053165 | controller -> localhost | |+.= o.O . . . | 2026-01-21 17:50:02.053187 | controller -> localhost | |.*+.. +o+ o. | 2026-01-21 17:50:02.053208 | controller -> localhost | +----[SHA256]-----+ 2026-01-21 17:50:02.053263 | controller -> localhost | ok: Runtime: 0:00:00.768147 2026-01-21 17:50:02.057446 | 2026-01-21 17:50:02.057514 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-21 17:50:02.078402 | controller | ok 2026-01-21 17:50:02.086252 | controller | included: /var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-21 17:50:02.092378 | 2026-01-21 17:50:02.092439 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-21 17:50:02.106377 | controller | skipping: Conditional result was False 2026-01-21 17:50:02.112025 | 2026-01-21 17:50:02.112110 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-21 17:50:02.697892 | controller | changed 2026-01-21 17:50:02.704490 | 2026-01-21 17:50:02.704553 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-21 17:50:02.915532 | controller | ok 2026-01-21 17:50:02.924197 | 2026-01-21 17:50:02.924285 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-21 17:50:03.434938 | controller | changed 2026-01-21 17:50:03.439258 | 2026-01-21 17:50:03.439324 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-21 17:50:03.950487 | controller | changed 2026-01-21 17:50:03.955776 | 2026-01-21 17:50:03.955844 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-21 17:50:03.980567 | controller | skipping: Conditional result was False 2026-01-21 17:50:03.989714 | 2026-01-21 17:50:03.989856 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-21 17:50:04.393061 | controller -> localhost | changed 2026-01-21 17:50:04.408008 | 2026-01-21 17:50:04.408095 | TASK [add-build-sshkey : Add back temp key] 2026-01-21 17:50:04.679616 | controller -> localhost | Identity added: /var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/work/b0dbd360117648f6a017a7dbf8ff3dc5_id_rsa (zuul-build-sshkey) 2026-01-21 17:50:04.679848 | controller -> localhost | ok: Runtime: 0:00:00.011199 2026-01-21 17:50:04.685007 | 2026-01-21 17:50:04.685072 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-21 17:50:05.033869 | controller | ok 2026-01-21 17:50:05.037762 | 2026-01-21 17:50:05.037859 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-21 17:50:05.062994 | controller | skipping: Conditional result was False 2026-01-21 17:50:05.077521 | 2026-01-21 17:50:05.077628 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-21 17:50:05.449632 | controller | ok 2026-01-21 17:50:05.456301 | 2026-01-21 17:50:05.456362 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-21 17:50:06.499835 | controller | Output suppressed because no_log was given 2026-01-21 17:50:06.511941 | 2026-01-21 17:50:06.512046 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-21 17:50:06.738497 | controller | ok: "logs" 2026-01-21 17:50:06.738976 | controller | ok: All items complete 2026-01-21 17:50:06.739039 | 2026-01-21 17:50:06.934011 | controller | ok: "artifacts" 2026-01-21 17:50:07.115122 | controller | ok: "docs" 2026-01-21 17:50:07.129344 | 2026-01-21 17:50:07.129512 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-21 17:50:07.333473 | controller | changed: "logs" 2026-01-21 17:50:07.514788 | controller | changed: "artifacts" 2026-01-21 17:50:07.699191 | controller | changed: "docs" 2026-01-21 17:50:07.711803 | 2026-01-21 17:50:07.711935 | PLAY RECAP 2026-01-21 17:50:07.712001 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-01-21 17:50:07.712039 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 17:50:07.712069 | 2026-01-21 17:50:07.831194 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-21 17:50:07.837509 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-21 17:50:08.446188 | 2026-01-21 17:50:08.446313 | PLAY [all] 2026-01-21 17:50:08.456282 | 2026-01-21 17:50:08.456356 | TASK [setup-uv : Extract archive] 2026-01-21 17:50:10.847230 | controller | changed 2026-01-21 17:50:10.852172 | 2026-01-21 17:50:10.852292 | TASK [setup-uv : Print version] 2026-01-21 17:50:11.191516 | controller | uv 0.8.13 2026-01-21 17:50:11.381080 | controller | ok: Runtime: 0:00:00.010116 2026-01-21 17:50:11.386283 | 2026-01-21 17:50:11.386346 | PLAY RECAP 2026-01-21 17:50:11.386394 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 17:50:11.386418 | 2026-01-21 17:50:11.527578 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-21 17:50:11.530605 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-21 17:50:12.156538 | 2026-01-21 17:50:12.156713 | PLAY [all] 2026-01-21 17:50:12.167233 | 2026-01-21 17:50:12.167345 | TASK [Copy inventory file for Zuul] 2026-01-21 17:50:13.108622 | controller | changed 2026-01-21 17:50:13.112791 | 2026-01-21 17:50:13.112883 | TASK [Switch "ansible_host" to private IP] 2026-01-21 17:50:13.420476 | controller | changed: 1 replacements made 2026-01-21 17:50:13.426321 | 2026-01-21 17:50:13.426400 | TASK [Run Molecule scenario] 2026-01-21 17:50:13.833051 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-01-21 17:50:13.833157 | controller | Creating virtual environment at: .venv 2026-01-21 17:50:13.875713 | controller | Downloading cryptography (4.3MiB) 2026-01-21 17:50:13.884540 | controller | Downloading pygments (1.2MiB) 2026-01-21 17:50:13.885698 | controller | Downloading ansible-core (2.3MiB) 2026-01-21 17:50:14.070550 | controller | Downloading cryptography 2026-01-21 17:50:14.112654 | controller | Downloading pygments 2026-01-21 17:50:14.216146 | controller | Downloading ansible-core 2026-01-21 17:50:14.277125 | controller | Installed 36 packages in 60ms 2026-01-21 17:50:14.941730 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-01-21 17:50:14.941762 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-01-21 17:50:15.691726 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-01-21 17:50:15.691853 | controller | INFO default scenario not found, disabling shared state. 2026-01-21 17:50:15.692081 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-01-21 17:50:15.692189 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-01-21 17:50:33.184689 | controller | INFO [upload-helm-chart > dependency] Executing 2026-01-21 17:50:33.184979 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-01-21 17:50:33.185231 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-01-21 17:50:33.185371 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-01-21 17:50:33.191857 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-01-21 17:50:33.192218 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:50:33.197680 | controller | INFO [upload-helm-chart > destroy] Executing 2026-01-21 17:50:33.197796 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-01-21 17:50:33.197888 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-01-21 17:50:33.203111 | controller | INFO [upload-helm-chart > syntax] Executing 2026-01-21 17:50:34.126462 | controller | 2026-01-21 17:50:34.126867 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-01-21 17:50:34.187807 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-01-21 17:50:34.198284 | controller | INFO [upload-helm-chart > create] Executing 2026-01-21 17:50:34.200368 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:50:34.206465 | controller | INFO [upload-helm-chart > prepare] Executing 2026-01-21 17:50:34.206547 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:50:34.211767 | controller | INFO [upload-helm-chart > converge] Executing 2026-01-21 17:50:35.109076 | controller | 2026-01-21 17:50:35.109134 | controller | PLAY [Converge] **************************************************************** 2026-01-21 17:50:35.109204 | controller | 2026-01-21 17:50:35.109299 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:50:35.109395 | controller | Wednesday 21 January 2026 17:50:35 +0000 (0:00:00.050) 0:00:00.051 ***** 2026-01-21 17:50:36.316163 | 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-21 17:50:36.321043 | controller | ok: [controller] 2026-01-21 17:50:36.321130 | controller | 2026-01-21 17:50:36.321228 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-01-21 17:50:36.321324 | controller | Wednesday 21 January 2026 17:50:36 +0000 (0:00:01.216) 0:00:01.267 ***** 2026-01-21 17:50:36.406600 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-01-21 17:50:36.406672 | controller | 2026-01-21 17:50:36.408387 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-01-21 17:50:36.408405 | controller | Wednesday 21 January 2026 17:50:36 +0000 (0:00:00.085) 0:00:01.352 ***** 2026-01-21 17:50:37.091774 | controller | changed: [controller] 2026-01-21 17:50:37.091863 | controller | 2026-01-21 17:50:37.092002 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-01-21 17:50:37.092126 | controller | Wednesday 21 January 2026 17:50:37 +0000 (0:00:00.684) 0:00:02.037 ***** 2026-01-21 17:50:37.136807 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-01-21 17:50:37.136985 | controller | 2026-01-21 17:50:37.137188 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-01-21 17:50:37.137384 | controller | Wednesday 21 January 2026 17:50:37 +0000 (0:00:00.045) 0:00:02.082 ***** 2026-01-21 17:50:37.540812 | controller | changed: [controller] 2026-01-21 17:50:37.540902 | controller | 2026-01-21 17:50:37.541048 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-01-21 17:50:37.541172 | controller | Wednesday 21 January 2026 17:50:37 +0000 (0:00:00.403) 0:00:02.486 ***** 2026-01-21 17:50:38.640898 | controller | changed: [controller] 2026-01-21 17:50:38.641049 | controller | 2026-01-21 17:50:38.641180 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-01-21 17:50:38.641301 | controller | Wednesday 21 January 2026 17:50:38 +0000 (0:00:01.094) 0:00:03.581 ***** 2026-01-21 17:50:39.015269 | controller | ok: [controller] 2026-01-21 17:50:39.016986 | controller | 2026-01-21 17:50:39.017130 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-01-21 17:50:39.017227 | controller | Wednesday 21 January 2026 17:50:39 +0000 (0:00:00.379) 0:00:03.961 ***** 2026-01-21 17:50:39.069301 | controller | skipping: [controller] 2026-01-21 17:50:39.069497 | controller | 2026-01-21 17:50:39.069694 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:50:39.069916 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-01-21 17:50:39.070094 | controller | 2026-01-21 17:50:39.070263 | controller | 2026-01-21 17:50:39.070449 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:50:39.070642 | controller | Wednesday 21 January 2026 17:50:39 +0000 (0:00:00.054) 0:00:04.015 ***** 2026-01-21 17:50:39.070853 | controller | =============================================================================== 2026-01-21 17:50:39.071047 | controller | Gathering Facts --------------------------------------------------------- 1.22s 2026-01-21 17:50:39.071248 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.09s 2026-01-21 17:50:39.071439 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.68s 2026-01-21 17:50:39.071640 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-01-21 17:50:39.071828 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s 2026-01-21 17:50:39.072063 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.09s 2026-01-21 17:50:39.072262 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-01-21 17:50:39.072507 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-01-21 17:50:39.182843 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-01-21 17:50:39.192770 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-01-21 17:50:39.989823 | controller | 2026-01-21 17:50:39.990001 | controller | PLAY [Converge] **************************************************************** 2026-01-21 17:50:39.990179 | controller | 2026-01-21 17:50:39.990374 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:50:39.990574 | controller | Wednesday 21 January 2026 17:50:39 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-01-21 17:50:41.093943 | 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-21 17:50:41.094188 | controller | ok: [controller] 2026-01-21 17:50:41.094271 | controller | 2026-01-21 17:50:41.094372 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-01-21 17:50:41.094459 | controller | Wednesday 21 January 2026 17:50:41 +0000 (0:00:01.104) 0:00:01.115 ***** 2026-01-21 17:50:41.167802 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-01-21 17:50:41.167980 | controller | 2026-01-21 17:50:41.168187 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-01-21 17:50:41.168430 | controller | Wednesday 21 January 2026 17:50:41 +0000 (0:00:00.073) 0:00:01.188 ***** 2026-01-21 17:50:41.868936 | controller | ok: [controller] 2026-01-21 17:50:41.869093 | controller | 2026-01-21 17:50:41.869226 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-01-21 17:50:41.869351 | controller | Wednesday 21 January 2026 17:50:41 +0000 (0:00:00.698) 0:00:01.886 ***** 2026-01-21 17:50:41.908624 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-01-21 17:50:41.908920 | controller | 2026-01-21 17:50:41.909196 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-01-21 17:50:41.909461 | controller | Wednesday 21 January 2026 17:50:41 +0000 (0:00:00.042) 0:00:01.929 ***** 2026-01-21 17:50:42.307394 | controller | ok: [controller] 2026-01-21 17:50:42.307573 | controller | 2026-01-21 17:50:42.307747 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-01-21 17:50:42.307909 | controller | Wednesday 21 January 2026 17:50:42 +0000 (0:00:00.397) 0:00:02.327 ***** 2026-01-21 17:50:43.296474 | controller | ok: [controller] 2026-01-21 17:50:43.296615 | controller | 2026-01-21 17:50:43.296742 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-01-21 17:50:43.296864 | controller | Wednesday 21 January 2026 17:50:43 +0000 (0:00:00.989) 0:00:03.316 ***** 2026-01-21 17:50:43.653172 | controller | ok: [controller] 2026-01-21 17:50:43.653307 | controller | 2026-01-21 17:50:43.653445 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-01-21 17:50:43.653564 | controller | Wednesday 21 January 2026 17:50:43 +0000 (0:00:00.357) 0:00:03.673 ***** 2026-01-21 17:50:43.689117 | controller | skipping: [controller] 2026-01-21 17:50:43.689253 | controller | 2026-01-21 17:50:43.689370 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:50:43.689494 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-01-21 17:50:43.689599 | controller | 2026-01-21 17:50:43.689700 | controller | 2026-01-21 17:50:43.689811 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:50:43.689925 | controller | Wednesday 21 January 2026 17:50:43 +0000 (0:00:00.036) 0:00:03.710 ***** 2026-01-21 17:50:43.690032 | controller | =============================================================================== 2026-01-21 17:50:43.690145 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-01-21 17:50:43.690262 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 0.99s 2026-01-21 17:50:43.690378 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.70s 2026-01-21 17:50:43.690493 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-01-21 17:50:43.690606 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.36s 2026-01-21 17:50:43.690733 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-01-21 17:50:43.690848 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-01-21 17:50:43.690963 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-01-21 17:50:43.788007 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-01-21 17:50:43.799044 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-01-21 17:50:44.619049 | controller | 2026-01-21 17:50:44.619354 | controller | PLAY [Side effect] ************************************************************* 2026-01-21 17:50:44.619589 | controller | 2026-01-21 17:50:44.619836 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:50:44.621970 | controller | Wednesday 21 January 2026 17:50:44 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-01-21 17:50:45.619852 | 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-21 17:50:45.628695 | controller | ok: [controller] 2026-01-21 17:50:45.628713 | controller | 2026-01-21 17:50:45.628719 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-01-21 17:50:45.628724 | controller | Wednesday 21 January 2026 17:50:45 +0000 (0:00:01.004) 0:00:01.015 ***** 2026-01-21 17:50:45.699090 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-01-21 17:50:45.699367 | controller | 2026-01-21 17:50:45.699634 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-01-21 17:50:45.699888 | controller | Wednesday 21 January 2026 17:50:45 +0000 (0:00:00.075) 0:00:01.090 ***** 2026-01-21 17:50:46.387751 | controller | changed: [controller] 2026-01-21 17:50:46.387775 | controller | 2026-01-21 17:50:46.387780 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-01-21 17:50:46.387784 | controller | Wednesday 21 January 2026 17:50:46 +0000 (0:00:00.684) 0:00:01.774 ***** 2026-01-21 17:50:46.419159 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-01-21 17:50:46.419441 | controller | 2026-01-21 17:50:46.419717 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-01-21 17:50:46.419978 | controller | Wednesday 21 January 2026 17:50:46 +0000 (0:00:00.035) 0:00:01.810 ***** 2026-01-21 17:50:46.800939 | controller | ok: [controller] 2026-01-21 17:50:46.800999 | controller | 2026-01-21 17:50:46.801105 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-01-21 17:50:46.801202 | controller | Wednesday 21 January 2026 17:50:46 +0000 (0:00:00.375) 0:00:02.186 ***** 2026-01-21 17:50:47.867896 | controller | ok: [controller] 2026-01-21 17:50:47.867987 | controller | 2026-01-21 17:50:47.868127 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-01-21 17:50:47.868254 | controller | Wednesday 21 January 2026 17:50:47 +0000 (0:00:01.065) 0:00:03.252 ***** 2026-01-21 17:50:48.219678 | controller | ok: [controller] 2026-01-21 17:50:48.219741 | controller | 2026-01-21 17:50:48.219839 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-01-21 17:50:48.219928 | controller | Wednesday 21 January 2026 17:50:48 +0000 (0:00:00.359) 0:00:03.611 ***** 2026-01-21 17:50:48.507044 | controller | changed: [controller] => (item=/file) 2026-01-21 17:50:48.507149 | controller | 2026-01-21 17:50:48.507273 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:50:48.507407 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-21 17:50:48.507516 | controller | 2026-01-21 17:50:48.507622 | controller | 2026-01-21 17:50:48.507736 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:50:48.507858 | controller | Wednesday 21 January 2026 17:50:48 +0000 (0:00:00.288) 0:00:03.899 ***** 2026-01-21 17:50:48.507970 | controller | =============================================================================== 2026-01-21 17:50:48.508095 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.07s 2026-01-21 17:50:48.508210 | controller | Gathering Facts --------------------------------------------------------- 1.00s 2026-01-21 17:50:48.508329 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.68s 2026-01-21 17:50:48.508482 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.38s 2026-01-21 17:50:48.508600 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.36s 2026-01-21 17:50:48.508725 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.29s 2026-01-21 17:50:48.508845 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-01-21 17:50:48.508964 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-01-21 17:50:48.582357 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-01-21 17:50:48.591990 | controller | INFO [upload-helm-chart > verify] Executing 2026-01-21 17:50:49.359931 | controller | 2026-01-21 17:50:49.360132 | controller | PLAY [Verify] ****************************************************************** 2026-01-21 17:50:49.360309 | controller | 2026-01-21 17:50:49.360548 | controller | TASK [Gathering Facts] ********************************************************* 2026-01-21 17:50:49.360744 | controller | Wednesday 21 January 2026 17:50:49 +0000 (0:00:00.009) 0:00:00.009 ***** 2026-01-21 17:50:50.355727 | 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-21 17:50:50.361466 | controller | ok: [controller] 2026-01-21 17:50:50.361488 | controller | 2026-01-21 17:50:50.361493 | controller | TASK [Stat file 1] ************************************************************* 2026-01-21 17:50:50.361497 | controller | Wednesday 21 January 2026 17:50:50 +0000 (0:00:01.000) 0:00:01.010 ***** 2026-01-21 17:50:50.732455 | controller | ok: [controller] 2026-01-21 17:50:50.732481 | controller | 2026-01-21 17:50:50.732488 | controller | TASK [Stat file 2] ************************************************************* 2026-01-21 17:50:50.732493 | controller | Wednesday 21 January 2026 17:50:50 +0000 (0:00:00.371) 0:00:01.381 ***** 2026-01-21 17:50:50.996161 | controller | ok: [controller] 2026-01-21 17:50:50.998964 | controller | 2026-01-21 17:50:50.998975 | controller | TASK [Stat removed file 1] ***************************************************** 2026-01-21 17:50:50.998981 | controller | Wednesday 21 January 2026 17:50:50 +0000 (0:00:00.264) 0:00:01.645 ***** 2026-01-21 17:50:51.270971 | controller | ok: [controller] 2026-01-21 17:50:51.270996 | controller | 2026-01-21 17:50:51.271003 | controller | TASK [Stat removed file 2] ***************************************************** 2026-01-21 17:50:51.271016 | controller | Wednesday 21 January 2026 17:50:51 +0000 (0:00:00.274) 0:00:01.919 ***** 2026-01-21 17:50:51.544468 | controller | ok: [controller] 2026-01-21 17:50:51.547208 | controller | 2026-01-21 17:50:51.547218 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-01-21 17:50:51.547222 | controller | Wednesday 21 January 2026 17:50:51 +0000 (0:00:00.273) 0:00:02.193 ***** 2026-01-21 17:50:51.573515 | controller | ok: [controller] => { 2026-01-21 17:50:51.573691 | controller | "changed": false, 2026-01-21 17:50:51.573876 | controller | "msg": "All assertions passed" 2026-01-21 17:50:51.574031 | controller | } 2026-01-21 17:50:51.574177 | controller | 2026-01-21 17:50:51.577878 | controller | PLAY RECAP ********************************************************************* 2026-01-21 17:50:51.577891 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-01-21 17:50:51.577897 | controller | 2026-01-21 17:50:51.577908 | controller | 2026-01-21 17:50:51.577913 | controller | TASKS RECAP ******************************************************************** 2026-01-21 17:50:51.577918 | controller | Wednesday 21 January 2026 17:50:51 +0000 (0:00:00.029) 0:00:02.223 ***** 2026-01-21 17:50:51.577923 | controller | =============================================================================== 2026-01-21 17:50:51.577927 | controller | Gathering Facts --------------------------------------------------------- 1.00s 2026-01-21 17:50:51.577932 | controller | Stat file 1 ------------------------------------------------------------- 0.37s 2026-01-21 17:50:51.577936 | controller | Stat removed file 1 ----------------------------------------------------- 0.27s 2026-01-21 17:50:51.577940 | controller | Stat removed file 2 ----------------------------------------------------- 0.27s 2026-01-21 17:50:51.577945 | controller | Stat file 2 ------------------------------------------------------------- 0.26s 2026-01-21 17:50:51.577949 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-01-21 17:50:51.653386 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-01-21 17:50:51.659310 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-01-21 17:50:51.659681 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-01-21 17:50:51.665008 | controller | INFO [upload-helm-chart > destroy] Executing 2026-01-21 17:50:51.665101 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-01-21 17:50:51.665187 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-01-21 17:50:51.665484 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-01-21 17:50:51.997122 | controller | ok: Runtime: 0:00:37.967096 2026-01-21 17:50:52.000717 | 2026-01-21 17:50:52.000772 | PLAY RECAP 2026-01-21 17:50:52.000820 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 17:50:52.000842 | 2026-01-21 17:50:52.167804 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-21 17:50:52.170693 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-21 17:50:52.758948 | 2026-01-21 17:50:52.759087 | PLAY [all] 2026-01-21 17:50:52.769577 | 2026-01-21 17:50:52.769650 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-21 17:50:52.814572 | controller | skipping: Conditional result was False 2026-01-21 17:50:52.821314 | 2026-01-21 17:50:52.821425 | TASK [fetch-output : Set log path for single node] 2026-01-21 17:50:52.864178 | controller | ok 2026-01-21 17:50:52.869610 | 2026-01-21 17:50:52.869701 | LOOP [fetch-output : Ensure local output dirs] 2026-01-21 17:50:53.265003 | controller -> localhost | ok: "/var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/work/logs" 2026-01-21 17:50:53.476086 | controller -> localhost | changed: "/var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/work/artifacts" 2026-01-21 17:50:53.701372 | controller -> localhost | changed: "/var/lib/zuul/builds/b0dbd360117648f6a017a7dbf8ff3dc5/work/docs" 2026-01-21 17:50:53.721261 | 2026-01-21 17:50:53.721479 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-21 17:50:54.389620 | controller | changed: .d..t...... ./ 2026-01-21 17:50:54.389908 | controller | changed: All items complete 2026-01-21 17:50:54.389949 | 2026-01-21 17:50:54.863256 | controller | changed: .d..t...... ./ 2026-01-21 17:50:55.338456 | controller | changed: .d..t...... ./ 2026-01-21 17:50:55.358429 | 2026-01-21 17:50:55.358602 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-21 17:50:55.776026 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008480 2026-01-21 17:50:56.004083 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005464 2026-01-21 17:50:56.026550 | 2026-01-21 17:50:56.026709 | PLAY [all] 2026-01-21 17:50:56.033775 | 2026-01-21 17:50:56.033871 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-01-21 17:50:56.502864 | controller | changed 2026-01-21 17:50:56.509471 | 2026-01-21 17:50:56.509523 | PLAY RECAP 2026-01-21 17:50:56.509569 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-01-21 17:50:56.509591 | 2026-01-21 17:50:56.630835 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-21 17:50:56.633791 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-01-21 17:50:57.229549 | 2026-01-21 17:50:57.229737 | PLAY [localhost] 2026-01-21 17:50:57.240618 | 2026-01-21 17:50:57.240712 | TASK [Generate Zuul manifest] 2026-01-21 17:50:57.261491 | localhost | ok 2026-01-21 17:50:57.280535 | 2026-01-21 17:50:57.280770 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-21 17:50:57.607652 | localhost | changed 2026-01-21 17:50:57.621754 | 2026-01-21 17:50:57.621855 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-21 17:50:57.653125 | localhost | ok 2026-01-21 17:50:57.662790 | 2026-01-21 17:50:57.662876 | TASK [Upload logs] 2026-01-21 17:50:57.694785 | localhost | ok 2026-01-21 17:50:57.759000 | 2026-01-21 17:50:57.759166 | TASK [Set zuul-log-path fact] 2026-01-21 17:50:57.780770 | localhost | ok 2026-01-21 17:50:57.792615 | 2026-01-21 17:50:57.792693 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-21 17:50:57.823897 | localhost | ok 2026-01-21 17:50:57.833829 | 2026-01-21 17:50:57.833956 | TASK [upload-logs : Create log directories] 2026-01-21 17:50:58.265385 | localhost | changed 2026-01-21 17:50:58.271972 | 2026-01-21 17:50:58.272067 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-01-21 17:50:58.693744 | localhost -> localhost | ok: Runtime: 0:00:00.005737 2026-01-21 17:50:58.701634 | 2026-01-21 17:50:58.701705 | TASK [upload-logs : Upload logs to log server] 2026-01-21 17:50:59.149832 | localhost | Output suppressed because no_log was given 2026-01-21 17:50:59.157751 | 2026-01-21 17:50:59.157889 | LOOP [upload-logs : Compress console log and json output] 2026-01-21 17:50:59.202252 | localhost | skipping: Conditional result was False 2026-01-21 17:50:59.209206 | localhost | skipping: Conditional result was False 2026-01-21 17:50:59.223299 | 2026-01-21 17:50:59.223489 | LOOP [upload-logs : Upload compressed console log and json output] 2026-01-21 17:50:59.267678 | localhost | skipping: Conditional result was False 2026-01-21 17:50:59.268028 | 2026-01-21 17:50:59.271080 | localhost | skipping: Conditional result was False 2026-01-21 17:50:59.291316 | 2026-01-21 17:50:59.291442 | LOOP [upload-logs : Upload console log and json output]