2026-03-11 20:28:22.680267 | Job console starting 2026-03-11 20:28:22.689316 | Updating git repos 2026-03-11 20:28:22.729508 | Cloning repos into workspace 2026-03-11 20:28:22.797344 | Restoring repo states 2026-03-11 20:28:22.808483 | Merging changes 2026-03-11 20:28:23.310028 | Checking out repos 2026-03-11 20:28:23.386966 | Preparing playbooks 2026-03-11 20:28:24.475159 | Running Ansible setup 2026-03-11 20:28:29.260625 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:28:29.891361 | 2026-03-11 20:28:29.891515 | PLAY [localhost] 2026-03-11 20:28:29.899746 | 2026-03-11 20:28:29.899823 | TASK [Gathering Facts] 2026-03-11 20:28:30.809712 | localhost | ok 2026-03-11 20:28:30.823455 | 2026-03-11 20:28:30.823571 | TASK [Setup log path fact] 2026-03-11 20:28:30.844780 | localhost | ok 2026-03-11 20:28:30.861062 | 2026-03-11 20:28:30.861134 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:28:30.894351 | localhost | ok 2026-03-11 20:28:30.902913 | 2026-03-11 20:28:30.903003 | TASK [emit-job-header : Print job information] 2026-03-11 20:28:30.943925 | # Job Information 2026-03-11 20:28:30.944185 | Ansible Version: 2.16.16 2026-03-11 20:28:30.944259 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-11 20:28:30.944310 | Pipeline: check 2026-03-11 20:28:30.944357 | Executor: 0a8996d2b663 2026-03-11 20:28:30.944400 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-11 20:28:30.944449 | Event ID: b64d8f10-1d88-11f1-9be5-cc8dbe2903ae 2026-03-11 20:28:30.950199 | 2026-03-11 20:28:30.950448 | LOOP [emit-job-header : Print node information] 2026-03-11 20:28:31.041140 | localhost | ok: 2026-03-11 20:28:31.041383 | localhost | # Node Information 2026-03-11 20:28:31.041422 | localhost | Inventory Hostname: controller 2026-03-11 20:28:31.041453 | localhost | Hostname: np0000158459 2026-03-11 20:28:31.041481 | localhost | Username: zuul 2026-03-11 20:28:31.041512 | localhost | Distro: Debian 13.3 2026-03-11 20:28:31.041540 | localhost | Provider: yul1 2026-03-11 20:28:31.041567 | localhost | Region: ca-ymq-1 2026-03-11 20:28:31.041593 | localhost | Label: debian-trixie 2026-03-11 20:28:31.041619 | localhost | Product Name: OpenStack Nova 2026-03-11 20:28:31.041645 | localhost | Interface IP: 199.204.45.248 2026-03-11 20:28:31.056034 | 2026-03-11 20:28:31.056218 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 20:28:31.438440 | localhost -> localhost | changed 2026-03-11 20:28:31.447340 | 2026-03-11 20:28:31.447433 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 20:28:32.310949 | localhost -> localhost | changed 2026-03-11 20:28:32.322097 | 2026-03-11 20:28:32.322202 | PLAY [all] 2026-03-11 20:28:32.332567 | 2026-03-11 20:28:32.332718 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 20:28:32.576499 | controller -> localhost | ok 2026-03-11 20:28:32.586316 | 2026-03-11 20:28:32.586442 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 20:28:32.617600 | controller | ok 2026-03-11 20:28:32.634572 | controller | included: /var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 20:28:32.642025 | 2026-03-11 20:28:32.642090 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 20:28:33.384522 | controller -> localhost | Generating public/private rsa key pair. 2026-03-11 20:28:33.384718 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/work/6b6f479762384b7887dad56e2bad235d_id_rsa 2026-03-11 20:28:33.384760 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/work/6b6f479762384b7887dad56e2bad235d_id_rsa.pub 2026-03-11 20:28:33.384792 | controller -> localhost | The key fingerprint is: 2026-03-11 20:28:33.384822 | controller -> localhost | SHA256:rwuuJYJmNEBepNie27c4UuDaGD62c7037rBc9ohGSOo zuul-build-sshkey 2026-03-11 20:28:33.384862 | controller -> localhost | The key's randomart image is: 2026-03-11 20:28:33.384891 | controller -> localhost | +---[RSA 3072]----+ 2026-03-11 20:28:33.384927 | controller -> localhost | | ..o | 2026-03-11 20:28:33.384974 | controller -> localhost | |+.o | 2026-03-11 20:28:33.385007 | controller -> localhost | |oo. | 2026-03-11 20:28:33.385036 | controller -> localhost | |...o | 2026-03-11 20:28:33.385063 | controller -> localhost | | +=.. S | 2026-03-11 20:28:33.385090 | controller -> localhost | |ooo+.. . | 2026-03-11 20:28:33.385117 | controller -> localhost | |+Bo.=.+o . | 2026-03-11 20:28:33.385144 | controller -> localhost | |=EoooO*=o. | 2026-03-11 20:28:33.385175 | controller -> localhost | |..=.+BB++o | 2026-03-11 20:28:33.385203 | controller -> localhost | +----[SHA256]-----+ 2026-03-11 20:28:33.385267 | controller -> localhost | ok: Runtime: 0:00:00.286304 2026-03-11 20:28:33.390830 | 2026-03-11 20:28:33.390912 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 20:28:33.423670 | controller | ok 2026-03-11 20:28:33.433684 | controller | included: /var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 20:28:33.440338 | 2026-03-11 20:28:33.440396 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 20:28:33.462754 | controller | skipping: Conditional result was False 2026-03-11 20:28:33.471526 | 2026-03-11 20:28:33.471616 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 20:28:34.006878 | controller | changed 2026-03-11 20:28:34.012702 | 2026-03-11 20:28:34.012793 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 20:28:34.250078 | controller | ok 2026-03-11 20:28:34.256927 | 2026-03-11 20:28:34.257020 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 20:28:34.855142 | controller | changed 2026-03-11 20:28:34.863932 | 2026-03-11 20:28:34.864269 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 20:28:35.390670 | controller | changed 2026-03-11 20:28:35.394840 | 2026-03-11 20:28:35.394908 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 20:28:35.419141 | controller | skipping: Conditional result was False 2026-03-11 20:28:35.429339 | 2026-03-11 20:28:35.429429 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 20:28:35.763571 | controller -> localhost | changed 2026-03-11 20:28:35.784046 | 2026-03-11 20:28:35.784135 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 20:28:36.057855 | controller -> localhost | Identity added: /var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/work/6b6f479762384b7887dad56e2bad235d_id_rsa (zuul-build-sshkey) 2026-03-11 20:28:36.058073 | controller -> localhost | ok: Runtime: 0:00:00.014389 2026-03-11 20:28:36.063436 | 2026-03-11 20:28:36.063497 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 20:28:36.448431 | controller | ok 2026-03-11 20:28:36.452126 | 2026-03-11 20:28:36.452185 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 20:28:36.475908 | controller | skipping: Conditional result was False 2026-03-11 20:28:36.486667 | 2026-03-11 20:28:36.486750 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 20:28:36.837367 | controller | ok 2026-03-11 20:28:36.846233 | 2026-03-11 20:28:36.857364 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 20:28:37.942154 | controller | Output suppressed because no_log was given 2026-03-11 20:28:37.951189 | 2026-03-11 20:28:37.951271 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 20:28:38.158914 | controller | ok: "logs" 2026-03-11 20:28:38.159178 | controller | ok: All items complete 2026-03-11 20:28:38.159216 | 2026-03-11 20:28:38.330468 | controller | ok: "artifacts" 2026-03-11 20:28:38.529975 | controller | ok: "docs" 2026-03-11 20:28:38.603960 | 2026-03-11 20:28:38.604177 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 20:28:38.823992 | controller | changed: "logs" 2026-03-11 20:28:39.006729 | controller | changed: "artifacts" 2026-03-11 20:28:39.188152 | controller | changed: "docs" 2026-03-11 20:28:39.201137 | 2026-03-11 20:28:39.201376 | PLAY RECAP 2026-03-11 20:28:39.201490 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 20:28:39.201552 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:28:39.201595 | 2026-03-11 20:28:39.350616 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:28:39.351974 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:28:39.991337 | 2026-03-11 20:28:39.991493 | PLAY [all] 2026-03-11 20:28:40.002861 | 2026-03-11 20:28:40.003023 | TASK [setup-uv : Extract archive] 2026-03-11 20:28:42.400242 | controller | changed 2026-03-11 20:28:42.412341 | 2026-03-11 20:28:42.412427 | TASK [setup-uv : Print version] 2026-03-11 20:28:43.563699 | controller | uv 0.8.13 2026-03-11 20:28:42.955979 | controller | ok: Runtime: 0:00:00.015882 2026-03-11 20:28:42.965221 | 2026-03-11 20:28:42.965273 | PLAY RECAP 2026-03-11 20:28:42.965319 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:28:42.965344 | 2026-03-11 20:28:43.087464 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:28:43.092867 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:28:43.649885 | 2026-03-11 20:28:43.650591 | PLAY [all] 2026-03-11 20:28:43.660187 | 2026-03-11 20:28:43.660261 | TASK [Copy inventory file for Zuul] 2026-03-11 20:28:44.563073 | controller | changed 2026-03-11 20:28:44.568904 | 2026-03-11 20:28:44.569035 | TASK [Switch "ansible_host" to private IP] 2026-03-11 20:28:44.877785 | controller | changed: 1 replacements made 2026-03-11 20:28:44.887705 | 2026-03-11 20:28:44.887786 | TASK [Run Molecule scenario] 2026-03-11 20:28:46.077435 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-11 20:28:46.077548 | controller | Creating virtual environment at: .venv 2026-03-11 20:28:47.275997 | controller | Downloading pygments (1.2MiB) 2026-03-11 20:28:47.280788 | controller | Downloading ansible-core (2.3MiB) 2026-03-11 20:28:47.281148 | controller | Downloading cryptography (4.3MiB) 2026-03-11 20:28:47.441079 | controller | Downloading cryptography 2026-03-11 20:28:47.491555 | controller | Downloading pygments 2026-03-11 20:28:47.652813 | controller | Downloading ansible-core 2026-03-11 20:28:47.700635 | controller | Installed 36 packages in 47ms 2026-03-11 20:28:48.382155 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-11 20:28:48.382234 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-11 20:28:49.277600 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-11 20:28:49.277677 | controller | INFO default scenario not found, disabling shared state. 2026-03-11 20:28:49.277714 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-11 20:28:49.277784 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-11 20:35:21.441298 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-11 20:35:21.441377 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-11 20:35:21.441609 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-11 20:35:21.441750 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-11 20:35:21.447287 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 20:35:21.447640 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:35:21.453265 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 20:35:21.453293 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 20:35:21.453384 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 20:35:21.458749 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-11 20:35:22.281315 | controller | 2026-03-11 20:35:22.281590 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-11 20:35:22.335411 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-11 20:35:22.345808 | controller | INFO [upload-helm-chart > create] Executing 2026-03-11 20:35:22.348134 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:35:22.353624 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-11 20:35:22.353898 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:35:22.359210 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-11 20:35:23.171618 | controller | 2026-03-11 20:35:23.171818 | controller | PLAY [Converge] **************************************************************** 2026-03-11 20:35:23.172002 | controller | 2026-03-11 20:35:23.172282 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:35:23.172491 | controller | Wednesday 11 March 2026 20:35:23 +0000 (0:00:00.029) 0:00:00.029 ******* 2026-03-11 20:35:24.450598 | 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-11 20:35:24.455720 | controller | ok: [controller] 2026-03-11 20:35:24.455905 | controller | 2026-03-11 20:35:24.456154 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:35:24.456358 | controller | Wednesday 11 March 2026 20:35:24 +0000 (0:00:01.284) 0:00:01.313 ******* 2026-03-11 20:35:24.533228 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:35:24.533377 | controller | 2026-03-11 20:35:24.533662 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:35:24.533866 | controller | Wednesday 11 March 2026 20:35:24 +0000 (0:00:00.077) 0:00:01.391 ******* 2026-03-11 20:35:25.234850 | controller | changed: [controller] 2026-03-11 20:35:25.234899 | controller | 2026-03-11 20:35:25.234990 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:35:25.235076 | controller | Wednesday 11 March 2026 20:35:25 +0000 (0:00:00.698) 0:00:02.090 ******* 2026-03-11 20:35:25.275234 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:35:25.275306 | controller | 2026-03-11 20:35:25.275398 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:35:25.275483 | controller | Wednesday 11 March 2026 20:35:25 +0000 (0:00:00.043) 0:00:02.133 ******* 2026-03-11 20:35:25.704560 | controller | changed: [controller] 2026-03-11 20:35:25.704605 | controller | 2026-03-11 20:35:25.704704 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:35:25.704787 | controller | Wednesday 11 March 2026 20:35:25 +0000 (0:00:00.428) 0:00:02.562 ******* 2026-03-11 20:35:26.750184 | controller | changed: [controller] 2026-03-11 20:35:26.750223 | controller | 2026-03-11 20:35:26.750352 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:35:26.750436 | controller | Wednesday 11 March 2026 20:35:26 +0000 (0:00:01.043) 0:00:03.605 ******* 2026-03-11 20:35:27.118144 | controller | ok: [controller] 2026-03-11 20:35:27.118293 | controller | 2026-03-11 20:35:27.118520 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:35:27.118707 | controller | Wednesday 11 March 2026 20:35:27 +0000 (0:00:00.370) 0:00:03.975 ******* 2026-03-11 20:35:27.169219 | controller | skipping: [controller] 2026-03-11 20:35:27.169389 | controller | 2026-03-11 20:35:27.169585 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:35:27.169796 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 20:35:27.169970 | controller | 2026-03-11 20:35:27.170139 | controller | 2026-03-11 20:35:27.170351 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:35:27.170540 | controller | Wednesday 11 March 2026 20:35:27 +0000 (0:00:00.051) 0:00:04.027 ******* 2026-03-11 20:35:27.170717 | controller | =============================================================================== 2026-03-11 20:35:27.170936 | controller | Gathering Facts --------------------------------------------------------- 1.28s 2026-03-11 20:35:27.171183 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.04s 2026-03-11 20:35:27.171365 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.70s 2026-03-11 20:35:27.171561 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-03-11 20:35:27.171737 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.37s 2026-03-11 20:35:27.171926 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-11 20:35:27.172120 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-11 20:35:27.172303 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 20:35:27.258229 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-11 20:35:27.269097 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-11 20:35:28.049015 | controller | 2026-03-11 20:35:28.049186 | controller | PLAY [Converge] **************************************************************** 2026-03-11 20:35:28.049354 | controller | 2026-03-11 20:35:28.049540 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:35:28.049732 | controller | Wednesday 11 March 2026 20:35:28 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-03-11 20:35:29.034313 | 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-11 20:35:29.036305 | controller | ok: [controller] 2026-03-11 20:35:29.036406 | controller | 2026-03-11 20:35:29.036521 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:35:29.036626 | controller | Wednesday 11 March 2026 20:35:29 +0000 (0:00:00.987) 0:00:00.998 ******* 2026-03-11 20:35:29.107859 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:35:29.108020 | controller | 2026-03-11 20:35:29.108248 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:35:29.108438 | controller | Wednesday 11 March 2026 20:35:29 +0000 (0:00:00.071) 0:00:01.069 ******* 2026-03-11 20:35:29.797365 | controller | ok: [controller] 2026-03-11 20:35:29.797408 | controller | 2026-03-11 20:35:29.797489 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:35:29.797570 | controller | Wednesday 11 March 2026 20:35:29 +0000 (0:00:00.686) 0:00:01.756 ******* 2026-03-11 20:35:29.836527 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:35:29.836610 | controller | 2026-03-11 20:35:29.836727 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:35:29.836816 | controller | Wednesday 11 March 2026 20:35:29 +0000 (0:00:00.042) 0:00:01.798 ******* 2026-03-11 20:35:30.241373 | controller | ok: [controller] 2026-03-11 20:35:30.241456 | controller | 2026-03-11 20:35:30.241466 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:35:30.241574 | controller | Wednesday 11 March 2026 20:35:30 +0000 (0:00:00.397) 0:00:02.196 ******* 2026-03-11 20:35:31.303788 | controller | ok: [controller] 2026-03-11 20:35:31.303934 | controller | 2026-03-11 20:35:31.304133 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:35:31.304326 | controller | Wednesday 11 March 2026 20:35:31 +0000 (0:00:01.068) 0:00:03.265 ******* 2026-03-11 20:35:31.682001 | controller | ok: [controller] 2026-03-11 20:35:31.682027 | controller | 2026-03-11 20:35:31.682033 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:35:31.682037 | controller | Wednesday 11 March 2026 20:35:31 +0000 (0:00:00.376) 0:00:03.641 ******* 2026-03-11 20:35:31.720904 | controller | skipping: [controller] 2026-03-11 20:35:31.721098 | controller | 2026-03-11 20:35:31.721277 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:35:31.721483 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 20:35:31.721643 | controller | 2026-03-11 20:35:31.721804 | controller | 2026-03-11 20:35:31.721983 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:35:31.722253 | controller | Wednesday 11 March 2026 20:35:31 +0000 (0:00:00.041) 0:00:03.683 ******* 2026-03-11 20:35:31.722477 | controller | =============================================================================== 2026-03-11 20:35:31.722679 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.07s 2026-03-11 20:35:31.722864 | controller | Gathering Facts --------------------------------------------------------- 0.99s 2026-03-11 20:35:31.723054 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.69s 2026-03-11 20:35:31.723244 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-03-11 20:35:31.723435 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s 2026-03-11 20:35:31.723632 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 20:35:31.723827 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 20:35:31.724026 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-03-11 20:35:31.804028 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-11 20:35:31.813126 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-11 20:35:32.611665 | controller | 2026-03-11 20:35:32.611812 | controller | PLAY [Side effect] ************************************************************* 2026-03-11 20:35:32.611976 | controller | 2026-03-11 20:35:32.612162 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:35:32.612396 | controller | Wednesday 11 March 2026 20:35:32 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-03-11 20:35:33.604362 | 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-11 20:35:33.609637 | controller | ok: [controller] 2026-03-11 20:35:33.609806 | controller | 2026-03-11 20:35:33.610007 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:35:33.610193 | controller | Wednesday 11 March 2026 20:35:33 +0000 (0:00:00.997) 0:00:01.008 ******* 2026-03-11 20:35:33.679843 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:35:33.679876 | controller | 2026-03-11 20:35:33.679886 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:35:33.679894 | controller | Wednesday 11 March 2026 20:35:33 +0000 (0:00:00.069) 0:00:01.078 ******* 2026-03-11 20:35:34.370430 | controller | changed: [controller] 2026-03-11 20:35:34.370471 | controller | 2026-03-11 20:35:34.370487 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:35:34.370591 | controller | Wednesday 11 March 2026 20:35:34 +0000 (0:00:00.688) 0:00:01.766 ******* 2026-03-11 20:35:34.409705 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:35:34.409770 | controller | 2026-03-11 20:35:34.409866 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:35:34.409957 | controller | Wednesday 11 March 2026 20:35:34 +0000 (0:00:00.042) 0:00:01.808 ******* 2026-03-11 20:35:34.809943 | controller | ok: [controller] 2026-03-11 20:35:34.810017 | controller | 2026-03-11 20:35:34.810160 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:35:34.810320 | controller | Wednesday 11 March 2026 20:35:34 +0000 (0:00:00.399) 0:00:02.208 ******* 2026-03-11 20:35:35.858734 | controller | ok: [controller] 2026-03-11 20:35:35.858808 | controller | 2026-03-11 20:35:35.858943 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:35:35.859066 | controller | Wednesday 11 March 2026 20:35:35 +0000 (0:00:01.047) 0:00:03.255 ******* 2026-03-11 20:35:36.224221 | controller | ok: [controller] 2026-03-11 20:35:36.224274 | controller | 2026-03-11 20:35:36.224554 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:35:36.224581 | controller | Wednesday 11 March 2026 20:35:36 +0000 (0:00:00.367) 0:00:03.622 ******* 2026-03-11 20:35:36.538764 | controller | changed: [controller] => (item=/file) 2026-03-11 20:35:36.538856 | controller | 2026-03-11 20:35:36.538866 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:35:36.542362 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 20:35:36.542402 | controller | 2026-03-11 20:35:36.542407 | controller | 2026-03-11 20:35:36.542411 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:35:36.542422 | controller | Wednesday 11 March 2026 20:35:36 +0000 (0:00:00.311) 0:00:03.934 ******* 2026-03-11 20:35:36.542426 | controller | =============================================================================== 2026-03-11 20:35:36.542429 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.05s 2026-03-11 20:35:36.542432 | controller | Gathering Facts --------------------------------------------------------- 1.00s 2026-03-11 20:35:36.542436 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.69s 2026-03-11 20:35:36.542439 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-03-11 20:35:36.542442 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.37s 2026-03-11 20:35:36.542447 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.31s 2026-03-11 20:35:36.542450 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 20:35:36.542453 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 20:35:36.678376 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-11 20:35:36.687291 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-11 20:35:37.455707 | controller | 2026-03-11 20:35:37.455810 | controller | PLAY [Verify] ****************************************************************** 2026-03-11 20:35:37.456024 | controller | 2026-03-11 20:35:37.456250 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:35:37.456457 | controller | Wednesday 11 March 2026 20:35:37 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-03-11 20:35:38.473173 | 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-11 20:35:38.480015 | controller | ok: [controller] 2026-03-11 20:35:38.480186 | controller | 2026-03-11 20:35:38.480379 | controller | TASK [Stat file 1] ************************************************************* 2026-03-11 20:35:38.480571 | controller | Wednesday 11 March 2026 20:35:38 +0000 (0:00:01.020) 0:00:01.030 ******* 2026-03-11 20:35:38.873160 | controller | ok: [controller] 2026-03-11 20:35:38.873258 | controller | 2026-03-11 20:35:38.873453 | controller | TASK [Stat file 2] ************************************************************* 2026-03-11 20:35:38.873682 | controller | Wednesday 11 March 2026 20:35:38 +0000 (0:00:00.395) 0:00:01.426 ******* 2026-03-11 20:35:39.165936 | controller | ok: [controller] 2026-03-11 20:35:39.166012 | controller | 2026-03-11 20:35:39.166105 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-11 20:35:39.166249 | controller | Wednesday 11 March 2026 20:35:39 +0000 (0:00:00.293) 0:00:01.719 ******* 2026-03-11 20:35:39.446534 | controller | ok: [controller] 2026-03-11 20:35:39.446634 | controller | 2026-03-11 20:35:39.446644 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-11 20:35:39.446687 | controller | Wednesday 11 March 2026 20:35:39 +0000 (0:00:00.280) 0:00:02.000 ******* 2026-03-11 20:35:39.734301 | controller | ok: [controller] 2026-03-11 20:35:39.734340 | controller | 2026-03-11 20:35:39.734456 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-11 20:35:39.734555 | controller | Wednesday 11 March 2026 20:35:39 +0000 (0:00:00.287) 0:00:02.288 ******* 2026-03-11 20:35:39.766600 | controller | ok: [controller] => { 2026-03-11 20:35:39.766713 | controller | "changed": false, 2026-03-11 20:35:39.766831 | controller | "msg": "All assertions passed" 2026-03-11 20:35:39.766942 | controller | } 2026-03-11 20:35:39.767047 | controller | 2026-03-11 20:35:39.767167 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:35:39.767289 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 20:35:39.767392 | controller | 2026-03-11 20:35:39.767494 | controller | 2026-03-11 20:35:39.768527 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:35:39.768563 | controller | Wednesday 11 March 2026 20:35:39 +0000 (0:00:00.029) 0:00:02.317 ******* 2026-03-11 20:35:39.768569 | controller | =============================================================================== 2026-03-11 20:35:39.768574 | controller | Gathering Facts --------------------------------------------------------- 1.02s 2026-03-11 20:35:39.768579 | controller | Stat file 1 ------------------------------------------------------------- 0.40s 2026-03-11 20:35:39.768583 | controller | Stat file 2 ------------------------------------------------------------- 0.29s 2026-03-11 20:35:39.768588 | controller | Stat removed file 2 ----------------------------------------------------- 0.29s 2026-03-11 20:35:39.768593 | controller | Stat removed file 1 ----------------------------------------------------- 0.28s 2026-03-11 20:35:39.768597 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-03-11 20:35:39.915905 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-11 20:35:39.927184 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 20:35:39.927516 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:35:39.932745 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 20:35:39.932859 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 20:35:39.932941 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 20:35:39.933243 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-11 20:35:40.485006 | controller | ok: Runtime: 0:06:54.009275 2026-03-11 20:35:40.488841 | 2026-03-11 20:35:40.488893 | PLAY RECAP 2026-03-11 20:35:40.488946 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:35:40.488990 | 2026-03-11 20:35:40.642743 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:35:40.646023 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:35:41.216261 | 2026-03-11 20:35:41.216383 | PLAY [all] 2026-03-11 20:35:41.226190 | 2026-03-11 20:35:41.226263 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 20:35:41.271659 | controller | skipping: Conditional result was False 2026-03-11 20:35:41.280095 | 2026-03-11 20:35:41.280320 | TASK [fetch-output : Set log path for single node] 2026-03-11 20:35:41.321839 | controller | ok 2026-03-11 20:35:41.326890 | 2026-03-11 20:35:41.326971 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 20:35:41.719884 | controller -> localhost | ok: "/var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/work/logs" 2026-03-11 20:35:41.931588 | controller -> localhost | changed: "/var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/work/artifacts" 2026-03-11 20:35:42.137635 | controller -> localhost | changed: "/var/lib/zuul/builds/6b6f479762384b7887dad56e2bad235d/work/docs" 2026-03-11 20:35:42.152282 | 2026-03-11 20:35:42.152473 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 20:35:42.813573 | controller | ok 2026-03-11 20:35:42.813817 | controller | changed: All items complete 2026-03-11 20:35:42.813850 | 2026-03-11 20:35:43.288785 | controller | changed: .d..t...... ./ 2026-03-11 20:35:43.781604 | controller | changed: .d..t...... ./ 2026-03-11 20:35:43.804343 | 2026-03-11 20:35:43.804465 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 20:35:44.199216 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007917 2026-03-11 20:35:44.420209 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007314 2026-03-11 20:35:44.432410 | 2026-03-11 20:35:44.432542 | PLAY [all] 2026-03-11 20:35:44.438682 | 2026-03-11 20:35:44.438751 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 20:35:44.874946 | controller | changed 2026-03-11 20:35:44.882010 | 2026-03-11 20:35:44.882077 | PLAY RECAP 2026-03-11 20:35:44.882151 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 20:35:44.882183 | 2026-03-11 20:35:44.995896 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:35:44.998515 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 20:35:45.607658 | 2026-03-11 20:35:45.607822 | PLAY [localhost] 2026-03-11 20:35:45.618368 | 2026-03-11 20:35:45.618441 | TASK [Generate Zuul manifest] 2026-03-11 20:35:45.640677 | localhost | ok 2026-03-11 20:35:45.657055 | 2026-03-11 20:35:45.657134 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 20:35:45.965880 | localhost | changed 2026-03-11 20:35:45.977764 | 2026-03-11 20:35:45.977868 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 20:35:46.010327 | localhost | ok 2026-03-11 20:35:46.019763 | 2026-03-11 20:35:46.019869 | TASK [Upload logs] 2026-03-11 20:35:46.043228 | localhost | ok 2026-03-11 20:35:46.130012 | 2026-03-11 20:35:46.130225 | TASK [Set zuul-log-path fact] 2026-03-11 20:35:46.152894 | localhost | ok 2026-03-11 20:35:46.165986 | 2026-03-11 20:35:46.166052 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:35:46.197988 | localhost | ok 2026-03-11 20:35:46.206601 | 2026-03-11 20:35:46.206663 | TASK [upload-logs : Create log directories] 2026-03-11 20:35:46.592074 | localhost | changed 2026-03-11 20:35:46.597888 | 2026-03-11 20:35:46.597958 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 20:35:46.995007 | localhost -> localhost | ok: Runtime: 0:00:00.003465 2026-03-11 20:35:47.001912 | 2026-03-11 20:35:47.002003 | TASK [upload-logs : Upload logs to log server] 2026-03-11 20:35:47.439585 | localhost | Output suppressed because no_log was given 2026-03-11 20:35:47.444471 | 2026-03-11 20:35:47.444563 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 20:35:47.495286 | localhost | skipping: Conditional result was False 2026-03-11 20:35:47.502613 | localhost | skipping: Conditional result was False 2026-03-11 20:35:47.518857 | 2026-03-11 20:35:47.519025 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 20:35:47.562371 | localhost | skipping: Conditional result was False 2026-03-11 20:35:47.562736 | 2026-03-11 20:35:47.566713 | localhost | skipping: Conditional result was False 2026-03-11 20:35:47.583103 | 2026-03-11 20:35:47.583275 | LOOP [upload-logs : Upload console log and json output]