2026-03-17 13:10:05.283175 | Job console starting 2026-03-17 13:10:05.316518 | Updating git repos 2026-03-17 13:10:05.403093 | Cloning repos into workspace 2026-03-17 13:10:05.643724 | Restoring repo states 2026-03-17 13:10:05.660011 | Merging changes 2026-03-17 13:10:06.428551 | Checking out repos 2026-03-17 13:10:06.835013 | Preparing playbooks 2026-03-17 13:10:11.939640 | Running Ansible setup 2026-03-17 13:10:16.182842 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 13:10:16.821431 | 2026-03-17 13:10:16.846518 | PLAY [localhost] 2026-03-17 13:10:16.858346 | 2026-03-17 13:10:16.858440 | TASK [Gathering Facts] 2026-03-17 13:10:17.828562 | localhost | ok 2026-03-17 13:10:17.841253 | 2026-03-17 13:10:17.841347 | TASK [Setup log path fact] 2026-03-17 13:10:17.867617 | localhost | ok 2026-03-17 13:10:17.881373 | 2026-03-17 13:10:17.881548 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 13:10:17.912344 | localhost | ok 2026-03-17 13:10:17.921636 | 2026-03-17 13:10:17.921741 | TASK [emit-job-header : Print job information] 2026-03-17 13:10:17.961729 | # Job Information 2026-03-17 13:10:17.961913 | Ansible Version: 2.16.16 2026-03-17 13:10:17.961958 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-17 13:10:17.961989 | Pipeline: check 2026-03-17 13:10:17.962017 | Executor: 0a8996d2b663 2026-03-17 13:10:17.962045 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-17 13:10:17.962076 | Event ID: c6ddfd90-21f4-11f1-9d62-c850b40680ff 2026-03-17 13:10:17.966167 | 2026-03-17 13:10:17.966254 | LOOP [emit-job-header : Print node information] 2026-03-17 13:10:18.065483 | localhost | ok: 2026-03-17 13:10:18.066189 | localhost | # Node Information 2026-03-17 13:10:18.066242 | localhost | Inventory Hostname: controller 2026-03-17 13:10:18.066278 | localhost | Hostname: np0000159311 2026-03-17 13:10:18.066310 | localhost | Username: zuul 2026-03-17 13:10:18.066344 | localhost | Distro: Debian 13.3 2026-03-17 13:10:18.066374 | localhost | Provider: yul1 2026-03-17 13:10:18.066403 | localhost | Region: ca-ymq-1 2026-03-17 13:10:18.066430 | localhost | Label: debian-trixie 2026-03-17 13:10:18.066456 | localhost | Product Name: OpenStack Nova 2026-03-17 13:10:18.066482 | localhost | Interface IP: 199.19.213.121 2026-03-17 13:10:18.083263 | 2026-03-17 13:10:18.083405 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-17 13:10:18.958356 | localhost -> localhost | changed 2026-03-17 13:10:18.965109 | 2026-03-17 13:10:18.965221 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-17 13:10:20.517694 | localhost -> localhost | changed 2026-03-17 13:10:20.526077 | 2026-03-17 13:10:20.526149 | PLAY [all] 2026-03-17 13:10:20.533930 | 2026-03-17 13:10:20.534000 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-17 13:10:20.785200 | controller -> localhost | ok 2026-03-17 13:10:20.790734 | 2026-03-17 13:10:20.790806 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-17 13:10:20.820573 | controller | ok 2026-03-17 13:10:20.835247 | controller | included: /var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-17 13:10:20.840026 | 2026-03-17 13:10:20.840113 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-17 13:10:22.528220 | controller -> localhost | Generating public/private rsa key pair. 2026-03-17 13:10:22.528441 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/work/092e5ad8a4d6498eba151f5370619a88_id_rsa 2026-03-17 13:10:22.528475 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/work/092e5ad8a4d6498eba151f5370619a88_id_rsa.pub 2026-03-17 13:10:22.528507 | controller -> localhost | The key fingerprint is: 2026-03-17 13:10:22.528529 | controller -> localhost | SHA256:YBG6Hs4ugv+8t91nAcIfTrKtKd6JNLvIOJGTm5RnqvM zuul-build-sshkey 2026-03-17 13:10:22.528572 | controller -> localhost | The key's randomart image is: 2026-03-17 13:10:22.528595 | controller -> localhost | +---[RSA 3072]----+ 2026-03-17 13:10:22.528643 | controller -> localhost | | o. | 2026-03-17 13:10:22.528687 | controller -> localhost | | . . | 2026-03-17 13:10:22.528716 | controller -> localhost | | . o. | 2026-03-17 13:10:22.528738 | controller -> localhost | | o .+ + | 2026-03-17 13:10:22.528759 | controller -> localhost | | +o SO o | 2026-03-17 13:10:22.528779 | controller -> localhost | | B+o. . + . | 2026-03-17 13:10:22.528799 | controller -> localhost | |.. O+ o o . | 2026-03-17 13:10:22.528819 | controller -> localhost | |+ *= oo*oo o | 2026-03-17 13:10:22.528860 | controller -> localhost | |.*E+B+*++ .o | 2026-03-17 13:10:22.528886 | controller -> localhost | +----[SHA256]-----+ 2026-03-17 13:10:22.528939 | controller -> localhost | ok: Runtime: 0:00:01.229296 2026-03-17 13:10:22.535199 | 2026-03-17 13:10:22.535336 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-17 13:10:22.570396 | controller | ok 2026-03-17 13:10:22.592537 | controller | included: /var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-17 13:10:22.603047 | 2026-03-17 13:10:22.644781 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-17 13:10:22.669274 | controller | skipping: Conditional result was False 2026-03-17 13:10:22.678821 | 2026-03-17 13:10:22.678940 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-17 13:10:23.211049 | controller | changed 2026-03-17 13:10:23.217523 | 2026-03-17 13:10:23.217589 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-17 13:10:23.426498 | controller | ok 2026-03-17 13:10:23.430573 | 2026-03-17 13:10:23.430635 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-17 13:10:23.954093 | controller | changed 2026-03-17 13:10:23.960187 | 2026-03-17 13:10:23.960248 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-17 13:10:24.517174 | controller | changed 2026-03-17 13:10:24.524062 | 2026-03-17 13:10:24.524170 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-17 13:10:24.540163 | controller | skipping: Conditional result was False 2026-03-17 13:10:24.563481 | 2026-03-17 13:10:24.563609 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-17 13:10:24.922914 | controller -> localhost | changed 2026-03-17 13:10:24.936661 | 2026-03-17 13:10:24.936745 | TASK [add-build-sshkey : Add back temp key] 2026-03-17 13:10:25.249723 | controller -> localhost | Identity added: /var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/work/092e5ad8a4d6498eba151f5370619a88_id_rsa (zuul-build-sshkey) 2026-03-17 13:10:25.249991 | controller -> localhost | ok: Runtime: 0:00:00.018518 2026-03-17 13:10:25.255967 | 2026-03-17 13:10:25.256049 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-17 13:10:25.662147 | controller | ok 2026-03-17 13:10:25.752357 | 2026-03-17 13:10:25.752513 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-17 13:10:25.793439 | controller | skipping: Conditional result was False 2026-03-17 13:10:25.806530 | 2026-03-17 13:10:25.806630 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-17 13:10:26.176791 | controller | ok 2026-03-17 13:10:26.185410 | 2026-03-17 13:10:26.185493 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-17 13:10:29.384107 | controller | Output suppressed because no_log was given 2026-03-17 13:10:29.397567 | 2026-03-17 13:10:29.397644 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-17 13:10:29.615056 | controller | ok: "logs" 2026-03-17 13:10:29.615366 | controller | ok: All items complete 2026-03-17 13:10:29.615425 | 2026-03-17 13:10:29.800468 | controller | ok: "artifacts" 2026-03-17 13:10:29.986847 | controller | ok: "docs" 2026-03-17 13:10:30.003547 | 2026-03-17 13:10:30.003659 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-17 13:10:30.210248 | controller | changed: "logs" 2026-03-17 13:10:30.394793 | controller | changed: "artifacts" 2026-03-17 13:10:30.583089 | controller | changed: "docs" 2026-03-17 13:10:30.599091 | 2026-03-17 13:10:30.599232 | PLAY RECAP 2026-03-17 13:10:30.599321 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-17 13:10:30.599368 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 13:10:30.599397 | 2026-03-17 13:10:30.751325 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 13:10:30.754109 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 13:10:31.425863 | 2026-03-17 13:10:31.425995 | PLAY [all] 2026-03-17 13:10:31.436241 | 2026-03-17 13:10:31.436320 | TASK [setup-uv : Extract archive] 2026-03-17 13:10:34.173644 | controller | changed 2026-03-17 13:10:34.186947 | 2026-03-17 13:10:34.322916 | TASK [setup-uv : Print version] 2026-03-17 13:10:34.741865 | controller | uv 0.8.13 2026-03-17 13:10:34.882011 | controller | ok: Runtime: 0:00:00.013839 2026-03-17 13:10:34.889263 | 2026-03-17 13:10:34.889346 | PLAY RECAP 2026-03-17 13:10:34.889417 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 13:10:34.889455 | 2026-03-17 13:10:35.027696 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 13:10:35.030342 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 13:10:35.675474 | 2026-03-17 13:10:35.675698 | PLAY [all] 2026-03-17 13:10:35.687783 | 2026-03-17 13:10:35.687916 | TASK [Copy inventory file for Zuul] 2026-03-17 13:10:36.617131 | controller | changed 2026-03-17 13:10:36.623212 | 2026-03-17 13:10:36.623313 | TASK [Switch "ansible_host" to private IP] 2026-03-17 13:10:36.918908 | controller | changed: 1 replacements made 2026-03-17 13:10:36.925300 | 2026-03-17 13:10:36.925374 | TASK [Run Molecule scenario] 2026-03-17 13:10:37.390811 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-17 13:10:37.390974 | controller | Creating virtual environment at: .venv 2026-03-17 13:10:37.660925 | controller | Downloading ansible-core (2.3MiB) 2026-03-17 13:10:37.661693 | controller | Downloading cryptography (4.3MiB) 2026-03-17 13:10:37.662666 | controller | Downloading pygments (1.2MiB) 2026-03-17 13:10:37.826337 | controller | Downloading cryptography 2026-03-17 13:10:37.882155 | controller | Downloading pygments 2026-03-17 13:10:38.061886 | controller | Downloading ansible-core 2026-03-17 13:10:38.107559 | controller | Installed 36 packages in 44ms 2026-03-17 13:10:38.798421 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-17 13:10:38.798497 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-17 13:10:39.652983 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-17 13:10:39.653054 | controller | INFO default scenario not found, disabling shared state. 2026-03-17 13:10:39.653078 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-17 13:10:39.653139 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-17 13:10:59.019339 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-17 13:10:59.019412 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-17 13:10:59.019687 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-17 13:10:59.019768 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-17 13:10:59.025310 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-17 13:10:59.025693 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 13:10:59.031013 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-17 13:10:59.031043 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-17 13:10:59.031126 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-17 13:10:59.036423 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-17 13:10:59.940642 | controller | 2026-03-17 13:10:59.940930 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-17 13:10:59.998454 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-17 13:11:00.011128 | controller | INFO [upload-helm-chart > create] Executing 2026-03-17 13:11:00.012650 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 13:11:00.017934 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-17 13:11:00.018243 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 13:11:00.023586 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-17 13:11:00.897829 | controller | 2026-03-17 13:11:00.897923 | controller | PLAY [Converge] **************************************************************** 2026-03-17 13:11:00.898003 | controller | 2026-03-17 13:11:00.898183 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 13:11:00.898333 | controller | Tuesday 17 March 2026 13:11:00 +0000 (0:00:00.030) 0:00:00.030 ********* 2026-03-17 13:11:04.232056 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 13:11:04.238678 | controller | ok: [controller] 2026-03-17 13:11:04.238876 | controller | 2026-03-17 13:11:04.239101 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 13:11:04.239308 | controller | Tuesday 17 March 2026 13:11:04 +0000 (0:00:03.341) 0:00:03.371 ********* 2026-03-17 13:11:04.323448 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 13:11:04.323626 | controller | 2026-03-17 13:11:04.323843 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 13:11:04.324104 | controller | Tuesday 17 March 2026 13:11:04 +0000 (0:00:00.084) 0:00:03.456 ********* 2026-03-17 13:11:05.047973 | controller | changed: [controller] 2026-03-17 13:11:05.048003 | controller | 2026-03-17 13:11:05.048008 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 13:11:05.048013 | controller | Tuesday 17 March 2026 13:11:05 +0000 (0:00:00.721) 0:00:04.178 ********* 2026-03-17 13:11:05.091189 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 13:11:05.091368 | controller | 2026-03-17 13:11:05.091576 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 13:11:05.091822 | controller | Tuesday 17 March 2026 13:11:05 +0000 (0:00:00.046) 0:00:04.224 ********* 2026-03-17 13:11:05.506345 | controller | changed: [controller] 2026-03-17 13:11:05.506524 | controller | 2026-03-17 13:11:05.506777 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 13:11:05.507001 | controller | Tuesday 17 March 2026 13:11:05 +0000 (0:00:00.414) 0:00:04.638 ********* 2026-03-17 13:11:06.627979 | controller | changed: [controller] 2026-03-17 13:11:06.628159 | controller | 2026-03-17 13:11:06.628402 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 13:11:06.628596 | controller | Tuesday 17 March 2026 13:11:06 +0000 (0:00:01.121) 0:00:05.760 ********* 2026-03-17 13:11:07.050644 | controller | ok: [controller] 2026-03-17 13:11:07.050782 | controller | 2026-03-17 13:11:07.051005 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 13:11:07.051206 | controller | Tuesday 17 March 2026 13:11:07 +0000 (0:00:00.422) 0:00:06.183 ********* 2026-03-17 13:11:07.104654 | controller | skipping: [controller] 2026-03-17 13:11:07.104830 | controller | 2026-03-17 13:11:07.105024 | controller | PLAY RECAP ********************************************************************* 2026-03-17 13:11:07.105234 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-17 13:11:07.105402 | controller | 2026-03-17 13:11:07.105570 | controller | 2026-03-17 13:11:07.105749 | controller | TASKS RECAP ******************************************************************** 2026-03-17 13:11:07.105939 | controller | Tuesday 17 March 2026 13:11:07 +0000 (0:00:00.054) 0:00:06.238 ********* 2026-03-17 13:11:07.106271 | controller | =============================================================================== 2026-03-17 13:11:07.106456 | controller | Gathering Facts --------------------------------------------------------- 3.34s 2026-03-17 13:11:07.106662 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.12s 2026-03-17 13:11:07.106907 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.72s 2026-03-17 13:11:07.107138 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.42s 2026-03-17 13:11:07.107317 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-03-17 13:11:07.107513 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-17 13:11:07.107711 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-17 13:11:07.107906 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-17 13:11:07.255781 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-17 13:11:07.266568 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-17 13:11:08.134591 | controller | 2026-03-17 13:11:08.134697 | controller | PLAY [Converge] **************************************************************** 2026-03-17 13:11:08.134803 | controller | 2026-03-17 13:11:08.134983 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 13:11:08.135191 | controller | Tuesday 17 March 2026 13:11:08 +0000 (0:00:00.010) 0:00:00.010 ********* 2026-03-17 13:11:09.186750 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 13:11:09.192729 | controller | ok: [controller] 2026-03-17 13:11:09.192812 | controller | 2026-03-17 13:11:09.192908 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 13:11:09.192998 | controller | Tuesday 17 March 2026 13:11:09 +0000 (0:00:01.058) 0:00:01.069 ********* 2026-03-17 13:11:09.271609 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 13:11:09.271662 | controller | 2026-03-17 13:11:09.271722 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 13:11:09.271833 | controller | Tuesday 17 March 2026 13:11:09 +0000 (0:00:00.078) 0:00:01.147 ********* 2026-03-17 13:11:09.999452 | controller | ok: [controller] 2026-03-17 13:11:09.999515 | controller | 2026-03-17 13:11:09.999589 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 13:11:09.999669 | controller | Tuesday 17 March 2026 13:11:09 +0000 (0:00:00.727) 0:00:01.875 ********* 2026-03-17 13:11:10.047148 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 13:11:10.047204 | controller | 2026-03-17 13:11:10.047308 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 13:11:10.047400 | controller | Tuesday 17 March 2026 13:11:10 +0000 (0:00:00.048) 0:00:01.924 ********* 2026-03-17 13:11:10.447753 | controller | ok: [controller] 2026-03-17 13:11:10.447908 | controller | 2026-03-17 13:11:10.448183 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 13:11:10.448391 | controller | Tuesday 17 March 2026 13:11:10 +0000 (0:00:00.399) 0:00:02.324 ********* 2026-03-17 13:11:11.597311 | controller | ok: [controller] 2026-03-17 13:11:11.597361 | controller | 2026-03-17 13:11:11.597371 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 13:11:11.597379 | controller | Tuesday 17 March 2026 13:11:11 +0000 (0:00:01.145) 0:00:03.469 ********* 2026-03-17 13:11:11.996785 | controller | ok: [controller] 2026-03-17 13:11:11.996824 | controller | 2026-03-17 13:11:11.996829 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 13:11:11.996833 | controller | Tuesday 17 March 2026 13:11:11 +0000 (0:00:00.401) 0:00:03.870 ********* 2026-03-17 13:11:12.045233 | controller | skipping: [controller] 2026-03-17 13:11:12.045271 | controller | 2026-03-17 13:11:12.045276 | controller | PLAY RECAP ********************************************************************* 2026-03-17 13:11:12.045281 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-17 13:11:12.045284 | controller | 2026-03-17 13:11:12.045287 | controller | 2026-03-17 13:11:12.045291 | controller | TASKS RECAP ******************************************************************** 2026-03-17 13:11:12.045294 | controller | Tuesday 17 March 2026 13:11:12 +0000 (0:00:00.050) 0:00:03.921 ********* 2026-03-17 13:11:12.045298 | controller | =============================================================================== 2026-03-17 13:11:12.045302 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.15s 2026-03-17 13:11:12.045306 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-03-17 13:11:12.045309 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.73s 2026-03-17 13:11:12.045313 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-17 13:11:12.045316 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-03-17 13:11:12.045331 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-17 13:11:12.045335 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-17 13:11:12.045338 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-17 13:11:12.205178 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-17 13:11:12.214537 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-17 13:11:13.065515 | controller | 2026-03-17 13:11:13.065849 | controller | PLAY [Side effect] ************************************************************* 2026-03-17 13:11:13.066085 | controller | 2026-03-17 13:11:13.066340 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 13:11:13.066603 | controller | Tuesday 17 March 2026 13:11:13 +0000 (0:00:00.011) 0:00:00.011 ********* 2026-03-17 13:11:14.156509 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 13:11:14.162326 | controller | ok: [controller] 2026-03-17 13:11:14.162596 | controller | 2026-03-17 13:11:14.162963 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 13:11:14.163338 | controller | Tuesday 17 March 2026 13:11:14 +0000 (0:00:01.097) 0:00:01.109 ********* 2026-03-17 13:11:14.253429 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 13:11:14.253695 | controller | 2026-03-17 13:11:14.254004 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 13:11:14.254257 | controller | Tuesday 17 March 2026 13:11:14 +0000 (0:00:00.090) 0:00:01.200 ********* 2026-03-17 13:11:14.977922 | controller | changed: [controller] 2026-03-17 13:11:14.978237 | controller | 2026-03-17 13:11:14.978536 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 13:11:14.978803 | controller | Tuesday 17 March 2026 13:11:14 +0000 (0:00:00.724) 0:00:01.924 ********* 2026-03-17 13:11:15.024209 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 13:11:15.024543 | controller | 2026-03-17 13:11:15.024963 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 13:11:15.025326 | controller | Tuesday 17 March 2026 13:11:15 +0000 (0:00:00.046) 0:00:01.970 ********* 2026-03-17 13:11:15.451431 | controller | ok: [controller] 2026-03-17 13:11:15.451677 | controller | 2026-03-17 13:11:15.451885 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 13:11:15.452109 | controller | Tuesday 17 March 2026 13:11:15 +0000 (0:00:00.427) 0:00:02.397 ********* 2026-03-17 13:11:16.636988 | controller | ok: [controller] 2026-03-17 13:11:16.637027 | controller | 2026-03-17 13:11:16.637033 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 13:11:16.637038 | controller | Tuesday 17 March 2026 13:11:16 +0000 (0:00:01.182) 0:00:03.580 ********* 2026-03-17 13:11:17.079125 | controller | ok: [controller] 2026-03-17 13:11:17.079179 | controller | 2026-03-17 13:11:17.079262 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 13:11:17.079355 | controller | Tuesday 17 March 2026 13:11:17 +0000 (0:00:00.444) 0:00:04.024 ********* 2026-03-17 13:11:17.401142 | controller | changed: [controller] => (item=/file) 2026-03-17 13:11:17.401195 | controller | 2026-03-17 13:11:17.401256 | controller | PLAY RECAP ********************************************************************* 2026-03-17 13:11:17.402479 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 13:11:17.402491 | controller | 2026-03-17 13:11:17.402495 | controller | 2026-03-17 13:11:17.402498 | controller | TASKS RECAP ******************************************************************** 2026-03-17 13:11:17.402512 | controller | Tuesday 17 March 2026 13:11:17 +0000 (0:00:00.323) 0:00:04.348 ********* 2026-03-17 13:11:17.402516 | controller | =============================================================================== 2026-03-17 13:11:17.402519 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.18s 2026-03-17 13:11:17.402522 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-03-17 13:11:17.402525 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.72s 2026-03-17 13:11:17.402528 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.44s 2026-03-17 13:11:17.402531 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-03-17 13:11:17.402536 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.32s 2026-03-17 13:11:17.402539 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.09s 2026-03-17 13:11:17.402543 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-17 13:11:17.544574 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-17 13:11:17.555336 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-17 13:11:18.363006 | controller | 2026-03-17 13:11:18.363386 | controller | PLAY [Verify] ****************************************************************** 2026-03-17 13:11:18.363775 | controller | 2026-03-17 13:11:18.364115 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 13:11:18.364380 | controller | Tuesday 17 March 2026 13:11:18 +0000 (0:00:00.009) 0:00:00.009 ********* 2026-03-17 13:11:19.435213 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 13:11:19.440755 | controller | ok: [controller] 2026-03-17 13:11:19.441127 | controller | 2026-03-17 13:11:19.441457 | controller | TASK [Stat file 1] ************************************************************* 2026-03-17 13:11:19.441721 | controller | Tuesday 17 March 2026 13:11:19 +0000 (0:00:01.078) 0:00:01.087 ********* 2026-03-17 13:11:19.836484 | controller | ok: [controller] 2026-03-17 13:11:19.836768 | controller | 2026-03-17 13:11:19.837032 | controller | TASK [Stat file 2] ************************************************************* 2026-03-17 13:11:19.837341 | controller | Tuesday 17 March 2026 13:11:19 +0000 (0:00:00.395) 0:00:01.483 ********* 2026-03-17 13:11:20.133497 | controller | ok: [controller] 2026-03-17 13:11:20.133786 | controller | 2026-03-17 13:11:20.134052 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-17 13:11:20.134311 | controller | Tuesday 17 March 2026 13:11:20 +0000 (0:00:00.297) 0:00:01.781 ********* 2026-03-17 13:11:20.403036 | controller | ok: [controller] 2026-03-17 13:11:20.403299 | controller | 2026-03-17 13:11:20.403552 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-17 13:11:20.403817 | controller | Tuesday 17 March 2026 13:11:20 +0000 (0:00:00.269) 0:00:02.050 ********* 2026-03-17 13:11:20.685088 | controller | ok: [controller] 2026-03-17 13:11:20.685401 | controller | 2026-03-17 13:11:20.685809 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-17 13:11:20.686112 | controller | Tuesday 17 March 2026 13:11:20 +0000 (0:00:00.281) 0:00:02.332 ********* 2026-03-17 13:11:20.716563 | controller | ok: [controller] => { 2026-03-17 13:11:20.716760 | controller | "changed": false, 2026-03-17 13:11:20.716922 | controller | "msg": "All assertions passed" 2026-03-17 13:11:20.717075 | controller | } 2026-03-17 13:11:20.717218 | controller | 2026-03-17 13:11:20.717372 | controller | PLAY RECAP ********************************************************************* 2026-03-17 13:11:20.717588 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 13:11:20.717738 | controller | 2026-03-17 13:11:20.717889 | controller | 2026-03-17 13:11:20.718042 | controller | TASKS RECAP ******************************************************************** 2026-03-17 13:11:20.718198 | controller | Tuesday 17 March 2026 13:11:20 +0000 (0:00:00.031) 0:00:02.364 ********* 2026-03-17 13:11:20.718345 | controller | =============================================================================== 2026-03-17 13:11:20.718499 | controller | Gathering Facts --------------------------------------------------------- 1.08s 2026-03-17 13:11:20.718657 | controller | Stat file 1 ------------------------------------------------------------- 0.40s 2026-03-17 13:11:20.718808 | controller | Stat file 2 ------------------------------------------------------------- 0.30s 2026-03-17 13:11:20.718962 | controller | Stat removed file 2 ----------------------------------------------------- 0.28s 2026-03-17 13:11:20.719115 | controller | Stat removed file 1 ----------------------------------------------------- 0.27s 2026-03-17 13:11:20.719265 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-03-17 13:11:20.814991 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-17 13:11:20.826960 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-17 13:11:20.827242 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 13:11:20.832650 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-17 13:11:20.832708 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-17 13:11:20.832817 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-17 13:11:20.833073 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-17 13:11:21.010400 | controller | ok: Runtime: 0:00:43.625015 2026-03-17 13:11:21.014007 | 2026-03-17 13:11:21.014057 | PLAY RECAP 2026-03-17 13:11:21.014107 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 13:11:21.014129 | 2026-03-17 13:11:21.118324 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 13:11:21.119926 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 13:11:29.770017 | 2026-03-17 13:11:29.770220 | PLAY [all] 2026-03-17 13:11:29.781348 | 2026-03-17 13:11:29.781431 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-17 13:11:30.034900 | controller | skipping: Conditional result was False 2026-03-17 13:11:30.043948 | 2026-03-17 13:11:30.044125 | TASK [fetch-output : Set log path for single node] 2026-03-17 13:11:30.090121 | controller | ok 2026-03-17 13:11:30.094345 | 2026-03-17 13:11:30.094427 | LOOP [fetch-output : Ensure local output dirs] 2026-03-17 13:11:39.169914 | controller -> localhost | ok: "/var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/work/logs" 2026-03-17 13:11:39.399342 | controller -> localhost | changed: "/var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/work/artifacts" 2026-03-17 13:11:39.601200 | controller -> localhost | changed: "/var/lib/zuul/builds/092e5ad8a4d6498eba151f5370619a88/work/docs" 2026-03-17 13:11:39.611296 | 2026-03-17 13:11:39.611411 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-17 13:11:43.646488 | controller | ok 2026-03-17 13:11:43.646803 | controller | changed: All items complete 2026-03-17 13:11:43.646849 | 2026-03-17 13:11:44.126640 | controller | changed: .d..t...... ./ 2026-03-17 13:11:44.633385 | controller | changed: .d..t...... ./ 2026-03-17 13:11:44.659692 | 2026-03-17 13:11:44.659843 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-17 13:11:45.060112 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007522 2026-03-17 13:11:45.291629 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008202 2026-03-17 13:11:45.305466 | 2026-03-17 13:11:45.305574 | PLAY [all] 2026-03-17 13:11:45.310548 | 2026-03-17 13:11:45.310609 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-17 13:11:45.760115 | controller | changed 2026-03-17 13:11:45.821434 | 2026-03-17 13:11:45.821553 | PLAY RECAP 2026-03-17 13:11:45.821609 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-17 13:11:45.821632 | 2026-03-17 13:11:45.964523 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 13:11:45.967282 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-17 13:11:53.352591 | 2026-03-17 13:11:53.428781 | PLAY [localhost] 2026-03-17 13:11:53.444430 | 2026-03-17 13:11:53.444526 | TASK [Generate Zuul manifest] 2026-03-17 13:11:53.476292 | localhost | ok 2026-03-17 13:11:53.494255 | 2026-03-17 13:11:53.494409 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-17 13:11:53.844576 | localhost | changed 2026-03-17 13:11:53.858467 | 2026-03-17 13:11:53.858563 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-17 13:11:54.287678 | localhost | ok 2026-03-17 13:11:54.297534 | 2026-03-17 13:11:54.297645 | TASK [Upload logs] 2026-03-17 13:11:54.331463 | localhost | ok 2026-03-17 13:11:55.470302 | 2026-03-17 13:11:55.851133 | TASK [Set zuul-log-path fact] 2026-03-17 13:11:55.873478 | localhost | ok 2026-03-17 13:11:55.888285 | 2026-03-17 13:11:55.888361 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 13:11:55.920523 | localhost | ok 2026-03-17 13:11:55.929572 | 2026-03-17 13:11:55.929663 | TASK [upload-logs : Create log directories] 2026-03-17 13:11:56.305463 | localhost | changed 2026-03-17 13:11:56.311237 | 2026-03-17 13:11:56.311309 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-17 13:11:56.704601 | localhost -> localhost | ok: Runtime: 0:00:00.004721 2026-03-17 13:11:56.711909 | 2026-03-17 13:11:56.712007 | TASK [upload-logs : Upload logs to log server] 2026-03-17 13:11:57.182113 | localhost | Output suppressed because no_log was given 2026-03-17 13:11:57.186038 | 2026-03-17 13:11:57.186106 | LOOP [upload-logs : Compress console log and json output] 2026-03-17 13:11:57.229727 | localhost | skipping: Conditional result was False 2026-03-17 13:11:57.237030 | localhost | skipping: Conditional result was False 2026-03-17 13:11:57.252771 | 2026-03-17 13:11:57.252893 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-17 13:11:57.293436 | localhost | skipping: Conditional result was False 2026-03-17 13:11:57.293919 | 2026-03-17 13:11:57.297413 | localhost | skipping: Conditional result was False 2026-03-17 13:11:57.314931 | 2026-03-17 13:11:57.315046 | LOOP [upload-logs : Upload console log and json output]