2026-03-11 16:45:35.042893 | Job console starting 2026-03-11 16:45:35.128762 | Updating git repos 2026-03-11 16:45:35.163980 | Cloning repos into workspace 2026-03-11 16:45:35.515896 | Restoring repo states 2026-03-11 16:45:35.564498 | Merging changes 2026-03-11 16:45:36.225517 | Checking out repos 2026-03-11 16:45:36.389336 | Preparing playbooks 2026-03-11 16:45:42.873054 | Running Ansible setup 2026-03-11 16:45:47.951127 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 16:45:48.543034 | 2026-03-11 16:45:48.543164 | PLAY [localhost] 2026-03-11 16:45:48.550407 | 2026-03-11 16:45:48.550476 | TASK [Gathering Facts] 2026-03-11 16:45:49.449830 | localhost | ok 2026-03-11 16:45:49.457669 | 2026-03-11 16:45:49.457776 | TASK [Setup log path fact] 2026-03-11 16:45:49.477460 | localhost | ok 2026-03-11 16:45:49.516807 | 2026-03-11 16:45:49.516934 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 16:45:49.546102 | localhost | ok 2026-03-11 16:45:49.566724 | 2026-03-11 16:45:49.566831 | TASK [emit-job-header : Print job information] 2026-03-11 16:45:49.607210 | # Job Information 2026-03-11 16:45:49.607454 | Ansible Version: 2.16.16 2026-03-11 16:45:49.607502 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-11 16:45:49.607535 | Pipeline: check 2026-03-11 16:45:49.607563 | Executor: 0a8996d2b663 2026-03-11 16:45:49.607590 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/236 2026-03-11 16:45:49.607622 | Event ID: 7fb891d0-1d69-11f1-914a-d4c45fee44e9 2026-03-11 16:45:49.611634 | 2026-03-11 16:45:49.611729 | LOOP [emit-job-header : Print node information] 2026-03-11 16:45:49.693711 | localhost | ok: 2026-03-11 16:45:49.715842 | localhost | # Node Information 2026-03-11 16:45:49.715944 | localhost | Inventory Hostname: controller 2026-03-11 16:45:49.716001 | localhost | Hostname: np0000158406 2026-03-11 16:45:49.716046 | localhost | Username: zuul 2026-03-11 16:45:49.716095 | localhost | Distro: Debian 13.3 2026-03-11 16:45:49.716139 | localhost | Provider: yul1 2026-03-11 16:45:49.716181 | localhost | Region: ca-ymq-1 2026-03-11 16:45:49.716223 | localhost | Label: debian-trixie 2026-03-11 16:45:49.716265 | localhost | Product Name: OpenStack Nova 2026-03-11 16:45:49.716307 | localhost | Interface IP: 199.204.45.3 2026-03-11 16:45:49.730998 | 2026-03-11 16:45:49.731181 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 16:45:50.173978 | localhost -> localhost | changed 2026-03-11 16:45:50.184874 | 2026-03-11 16:45:50.185124 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 16:45:51.646329 | localhost -> localhost | changed 2026-03-11 16:45:51.652851 | 2026-03-11 16:45:51.652911 | PLAY [all] 2026-03-11 16:45:51.665217 | 2026-03-11 16:45:51.665306 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 16:45:52.024757 | controller -> localhost | ok 2026-03-11 16:45:52.032458 | 2026-03-11 16:45:52.032584 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 16:45:52.054849 | controller | ok 2026-03-11 16:45:52.071321 | controller | included: /var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 16:45:52.076077 | 2026-03-11 16:45:52.076140 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 16:45:53.365775 | controller -> localhost | Generating public/private rsa key pair. 2026-03-11 16:45:53.366012 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/work/7477b945cdea4b0bbafa28b706633b81_id_rsa 2026-03-11 16:45:53.366075 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/work/7477b945cdea4b0bbafa28b706633b81_id_rsa.pub 2026-03-11 16:45:53.366124 | controller -> localhost | The key fingerprint is: 2026-03-11 16:45:53.366170 | controller -> localhost | SHA256:lw+0efmfBWEKe3tb62U4voPtPwtAmDWgT9Hq30AFVK8 zuul-build-sshkey 2026-03-11 16:45:53.366235 | controller -> localhost | The key's randomart image is: 2026-03-11 16:45:53.366282 | controller -> localhost | +---[RSA 3072]----+ 2026-03-11 16:45:53.366331 | controller -> localhost | | o+=o. | 2026-03-11 16:45:53.366432 | controller -> localhost | | . =.... | 2026-03-11 16:45:53.366488 | controller -> localhost | | . ++.. o. | 2026-03-11 16:45:53.366532 | controller -> localhost | | oo.B +.. | 2026-03-11 16:45:53.366576 | controller -> localhost | | S.O.=E. | 2026-03-11 16:45:53.366619 | controller -> localhost | | o *.o o | 2026-03-11 16:45:53.366662 | controller -> localhost | | . =+= =| 2026-03-11 16:45:53.366705 | controller -> localhost | | ..++B=| 2026-03-11 16:45:53.366754 | controller -> localhost | | .*O=| 2026-03-11 16:45:53.366798 | controller -> localhost | +----[SHA256]-----+ 2026-03-11 16:45:53.366889 | controller -> localhost | ok: Runtime: 0:00:00.860184 2026-03-11 16:45:53.374502 | 2026-03-11 16:45:53.374574 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 16:45:53.406213 | controller | ok 2026-03-11 16:45:53.415072 | controller | included: /var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 16:45:53.421824 | 2026-03-11 16:45:53.421885 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 16:45:53.445901 | controller | skipping: Conditional result was False 2026-03-11 16:45:53.454174 | 2026-03-11 16:45:53.454260 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 16:45:53.944446 | controller | changed 2026-03-11 16:45:53.949482 | 2026-03-11 16:45:53.949662 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 16:45:54.156610 | controller | ok 2026-03-11 16:45:54.193458 | 2026-03-11 16:45:54.193542 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 16:45:54.745395 | controller | changed 2026-03-11 16:45:54.901769 | 2026-03-11 16:45:54.901923 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 16:45:55.444232 | controller | changed 2026-03-11 16:45:55.451317 | 2026-03-11 16:45:55.451389 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 16:45:55.478489 | controller | skipping: Conditional result was False 2026-03-11 16:45:55.488888 | 2026-03-11 16:45:55.489126 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 16:45:55.851215 | controller -> localhost | changed 2026-03-11 16:45:55.863282 | 2026-03-11 16:45:55.863364 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 16:45:56.139252 | controller -> localhost | Identity added: /var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/work/7477b945cdea4b0bbafa28b706633b81_id_rsa (zuul-build-sshkey) 2026-03-11 16:45:56.139536 | controller -> localhost | ok: Runtime: 0:00:00.014044 2026-03-11 16:45:56.145777 | 2026-03-11 16:45:56.145862 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 16:45:56.474951 | controller | ok 2026-03-11 16:45:56.566930 | 2026-03-11 16:45:56.567087 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 16:45:56.592999 | controller | skipping: Conditional result was False 2026-03-11 16:45:56.605694 | 2026-03-11 16:45:56.605784 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 16:45:56.968460 | controller | ok 2026-03-11 16:45:56.974155 | 2026-03-11 16:45:56.974220 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 16:45:58.048721 | controller | Output suppressed because no_log was given 2026-03-11 16:45:58.056554 | 2026-03-11 16:45:58.056615 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 16:45:58.288075 | controller | ok: "logs" 2026-03-11 16:45:58.288284 | controller | ok: All items complete 2026-03-11 16:45:58.288322 | 2026-03-11 16:45:58.475966 | controller | ok: "artifacts" 2026-03-11 16:45:58.663667 | controller | ok: "docs" 2026-03-11 16:45:58.687869 | 2026-03-11 16:45:58.688018 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 16:45:58.907860 | controller | changed: "logs" 2026-03-11 16:45:59.087872 | controller | changed: "artifacts" 2026-03-11 16:45:59.275147 | controller | changed: "docs" 2026-03-11 16:45:59.293922 | 2026-03-11 16:45:59.294039 | PLAY RECAP 2026-03-11 16:45:59.294101 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 16:45:59.294139 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 16:45:59.294169 | 2026-03-11 16:45:59.429986 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 16:45:59.432948 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 16:46:00.042784 | 2026-03-11 16:46:00.042935 | PLAY [all] 2026-03-11 16:46:00.053629 | 2026-03-11 16:46:00.053709 | TASK [setup-uv : Extract archive] 2026-03-11 16:46:02.393552 | controller | changed 2026-03-11 16:46:02.403997 | 2026-03-11 16:46:02.404156 | TASK [setup-uv : Print version] 2026-03-11 16:46:02.820184 | controller | uv 0.8.13 2026-03-11 16:46:02.948246 | controller | ok: Runtime: 0:00:00.014329 2026-03-11 16:46:02.956511 | 2026-03-11 16:46:02.956580 | PLAY RECAP 2026-03-11 16:46:02.956625 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 16:46:02.956649 | 2026-03-11 16:46:03.084790 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 16:46:03.086460 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 16:46:03.679068 | 2026-03-11 16:46:03.679193 | PLAY [all] 2026-03-11 16:46:03.689025 | 2026-03-11 16:46:03.689101 | TASK [Copy inventory file for Zuul] 2026-03-11 16:46:04.665548 | controller | changed 2026-03-11 16:46:04.670399 | 2026-03-11 16:46:04.670483 | TASK [Switch "ansible_host" to private IP] 2026-03-11 16:46:04.977859 | controller | changed: 1 replacements made 2026-03-11 16:46:04.984897 | 2026-03-11 16:46:04.984991 | TASK [Run Molecule scenario] 2026-03-11 16:46:05.406002 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-11 16:46:05.406128 | controller | Creating virtual environment at: .venv 2026-03-11 16:46:05.698650 | controller | Downloading pygments (1.2MiB) 2026-03-11 16:46:05.700054 | controller | Downloading cryptography (4.3MiB) 2026-03-11 16:46:05.700266 | controller | Downloading ansible-core (2.3MiB) 2026-03-11 16:46:05.848498 | controller | Downloading cryptography 2026-03-11 16:46:05.904521 | controller | Downloading pygments 2026-03-11 16:46:06.110688 | controller | Downloading ansible-core 2026-03-11 16:46:06.159674 | controller | Installed 36 packages in 48ms 2026-03-11 16:46:06.807409 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-11 16:46:06.807491 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-11 16:46:07.659634 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-11 16:46:07.659709 | controller | INFO default scenario not found, disabling shared state. 2026-03-11 16:46:07.659732 | 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 16:46:07.659786 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-11 16:46:23.848707 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-11 16:46:23.848786 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-11 16:46:23.848797 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-11 16:46:23.848821 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-11 16:46:23.853573 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 16:46:23.853919 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 16:46:23.859369 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 16:46:23.859398 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 16:46:23.859528 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 16:46:23.864822 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-11 16:46:24.751644 | controller | 2026-03-11 16:46:24.751850 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-11 16:46:24.834385 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-11 16:46:24.845161 | controller | INFO [upload-helm-chart > create] Executing 2026-03-11 16:46:24.847279 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 16:46:24.854249 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-11 16:46:24.854538 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 16:46:24.860084 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-11 16:46:25.725459 | controller | 2026-03-11 16:46:25.725534 | controller | PLAY [Converge] **************************************************************** 2026-03-11 16:46:25.725793 | controller | 2026-03-11 16:46:25.725950 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 16:46:25.726204 | controller | Wednesday 11 March 2026 16:46:25 +0000 (0:00:00.033) 0:00:00.033 ******* 2026-03-11 16:46:27.039173 | 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 16:46:27.045483 | controller | ok: [controller] 2026-03-11 16:46:27.045651 | controller | 2026-03-11 16:46:27.045860 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 16:46:27.046083 | controller | Wednesday 11 March 2026 16:46:27 +0000 (0:00:01.320) 0:00:01.353 ******* 2026-03-11 16:46:27.124390 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 16:46:27.124533 | controller | 2026-03-11 16:46:27.124737 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 16:46:27.124931 | controller | Wednesday 11 March 2026 16:46:27 +0000 (0:00:00.078) 0:00:01.432 ******* 2026-03-11 16:46:27.876657 | controller | changed: [controller] 2026-03-11 16:46:27.876729 | controller | 2026-03-11 16:46:27.876740 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 16:46:27.876838 | controller | Wednesday 11 March 2026 16:46:27 +0000 (0:00:00.750) 0:00:02.183 ******* 2026-03-11 16:46:27.915154 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 16:46:27.915221 | controller | 2026-03-11 16:46:27.915313 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 16:46:27.915401 | controller | Wednesday 11 March 2026 16:46:27 +0000 (0:00:00.040) 0:00:02.223 ******* 2026-03-11 16:46:28.315657 | controller | changed: [controller] 2026-03-11 16:46:28.315715 | controller | 2026-03-11 16:46:28.315847 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 16:46:28.315975 | controller | Wednesday 11 March 2026 16:46:28 +0000 (0:00:00.400) 0:00:02.623 ******* 2026-03-11 16:46:29.437027 | controller | changed: [controller] 2026-03-11 16:46:29.437117 | controller | 2026-03-11 16:46:29.437184 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 16:46:29.440136 | controller | Wednesday 11 March 2026 16:46:29 +0000 (0:00:01.120) 0:00:03.744 ******* 2026-03-11 16:46:29.839178 | controller | ok: [controller] 2026-03-11 16:46:29.839293 | controller | 2026-03-11 16:46:29.839509 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 16:46:29.839705 | controller | Wednesday 11 March 2026 16:46:29 +0000 (0:00:00.402) 0:00:04.146 ******* 2026-03-11 16:46:29.900709 | controller | skipping: [controller] 2026-03-11 16:46:29.900899 | controller | 2026-03-11 16:46:29.901106 | controller | PLAY RECAP ********************************************************************* 2026-03-11 16:46:29.901338 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 16:46:29.901518 | controller | 2026-03-11 16:46:29.901690 | controller | 2026-03-11 16:46:29.901896 | controller | TASKS RECAP ******************************************************************** 2026-03-11 16:46:29.902092 | controller | Wednesday 11 March 2026 16:46:29 +0000 (0:00:00.062) 0:00:04.209 ******* 2026-03-11 16:46:29.902280 | controller | =============================================================================== 2026-03-11 16:46:29.902477 | controller | Gathering Facts --------------------------------------------------------- 1.32s 2026-03-11 16:46:29.902689 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.12s 2026-03-11 16:46:29.902894 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.75s 2026-03-11 16:46:29.903106 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-11 16:46:29.903296 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-03-11 16:46:29.903502 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-11 16:46:29.903713 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.06s 2026-03-11 16:46:29.903922 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 16:46:30.015588 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-11 16:46:30.025840 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-11 16:46:30.858600 | controller | 2026-03-11 16:46:30.858800 | controller | PLAY [Converge] **************************************************************** 2026-03-11 16:46:30.858992 | controller | 2026-03-11 16:46:30.859200 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 16:46:30.859414 | controller | Wednesday 11 March 2026 16:46:30 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-03-11 16:46:31.933210 | 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 16:46:31.937254 | controller | ok: [controller] 2026-03-11 16:46:31.937434 | controller | 2026-03-11 16:46:31.937622 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 16:46:31.937842 | controller | Wednesday 11 March 2026 16:46:31 +0000 (0:00:01.079) 0:00:01.092 ******* 2026-03-11 16:46:32.015920 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 16:46:32.016129 | controller | 2026-03-11 16:46:32.016389 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 16:46:32.016621 | controller | Wednesday 11 March 2026 16:46:32 +0000 (0:00:00.078) 0:00:01.170 ******* 2026-03-11 16:46:32.770165 | controller | ok: [controller] 2026-03-11 16:46:32.770308 | controller | 2026-03-11 16:46:32.770543 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 16:46:32.770739 | controller | Wednesday 11 March 2026 16:46:32 +0000 (0:00:00.753) 0:00:01.924 ******* 2026-03-11 16:46:32.817600 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 16:46:32.817754 | controller | 2026-03-11 16:46:32.817950 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 16:46:32.818142 | controller | Wednesday 11 March 2026 16:46:32 +0000 (0:00:00.047) 0:00:01.972 ******* 2026-03-11 16:46:33.267578 | controller | ok: [controller] 2026-03-11 16:46:33.267724 | controller | 2026-03-11 16:46:33.267933 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 16:46:33.268177 | controller | Wednesday 11 March 2026 16:46:33 +0000 (0:00:00.449) 0:00:02.421 ******* 2026-03-11 16:46:34.429866 | controller | ok: [controller] 2026-03-11 16:46:34.430011 | controller | 2026-03-11 16:46:34.430216 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 16:46:34.430410 | controller | Wednesday 11 March 2026 16:46:34 +0000 (0:00:01.156) 0:00:03.578 ******* 2026-03-11 16:46:34.847571 | controller | ok: [controller] 2026-03-11 16:46:34.847608 | controller | 2026-03-11 16:46:34.847615 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 16:46:34.847620 | controller | Wednesday 11 March 2026 16:46:34 +0000 (0:00:00.421) 0:00:03.999 ******* 2026-03-11 16:46:34.891585 | controller | skipping: [controller] 2026-03-11 16:46:34.891752 | controller | 2026-03-11 16:46:34.891934 | controller | PLAY RECAP ********************************************************************* 2026-03-11 16:46:34.892189 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 16:46:34.892348 | controller | 2026-03-11 16:46:34.892513 | controller | 2026-03-11 16:46:34.892694 | controller | TASKS RECAP ******************************************************************** 2026-03-11 16:46:34.892883 | controller | Wednesday 11 March 2026 16:46:34 +0000 (0:00:00.048) 0:00:04.047 ******* 2026-03-11 16:46:34.893052 | controller | =============================================================================== 2026-03-11 16:46:34.893256 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.16s 2026-03-11 16:46:34.893431 | controller | Gathering Facts --------------------------------------------------------- 1.08s 2026-03-11 16:46:34.893615 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.75s 2026-03-11 16:46:34.893799 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.45s 2026-03-11 16:46:34.894038 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.42s 2026-03-11 16:46:34.894231 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-11 16:46:34.894424 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-11 16:46:34.894615 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-11 16:46:35.025401 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-11 16:46:35.036145 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-11 16:46:35.856155 | controller | 2026-03-11 16:46:35.856257 | controller | PLAY [Side effect] ************************************************************* 2026-03-11 16:46:35.856382 | controller | 2026-03-11 16:46:35.856509 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 16:46:35.856631 | controller | Wednesday 11 March 2026 16:46:35 +0000 (0:00:00.011) 0:00:00.011 ******* 2026-03-11 16:46:36.981025 | 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 16:46:36.987207 | controller | ok: [controller] 2026-03-11 16:46:36.987356 | controller | 2026-03-11 16:46:36.987578 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 16:46:36.987784 | controller | Wednesday 11 March 2026 16:46:36 +0000 (0:00:01.131) 0:00:01.142 ******* 2026-03-11 16:46:37.070444 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 16:46:37.070565 | controller | 2026-03-11 16:46:37.070764 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 16:46:37.070958 | controller | Wednesday 11 March 2026 16:46:37 +0000 (0:00:00.082) 0:00:01.225 ******* 2026-03-11 16:46:37.799535 | controller | changed: [controller] 2026-03-11 16:46:37.799569 | controller | 2026-03-11 16:46:37.799575 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 16:46:37.799581 | controller | Wednesday 11 March 2026 16:46:37 +0000 (0:00:00.726) 0:00:01.952 ******* 2026-03-11 16:46:37.842633 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 16:46:37.842800 | controller | 2026-03-11 16:46:37.843006 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 16:46:37.843202 | controller | Wednesday 11 March 2026 16:46:37 +0000 (0:00:00.046) 0:00:01.998 ******* 2026-03-11 16:46:38.244947 | controller | ok: [controller] 2026-03-11 16:46:38.245122 | controller | 2026-03-11 16:46:38.245375 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 16:46:38.245635 | controller | Wednesday 11 March 2026 16:46:38 +0000 (0:00:00.401) 0:00:02.399 ******* 2026-03-11 16:46:40.353384 | controller | ok: [controller] 2026-03-11 16:46:40.353429 | controller | 2026-03-11 16:46:40.353440 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 16:46:40.353448 | controller | Wednesday 11 March 2026 16:46:40 +0000 (0:00:02.107) 0:00:04.507 ******* 2026-03-11 16:46:40.752880 | controller | ok: [controller] 2026-03-11 16:46:40.753292 | controller | 2026-03-11 16:46:40.753756 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 16:46:40.754107 | controller | Wednesday 11 March 2026 16:46:40 +0000 (0:00:00.399) 0:00:04.907 ******* 2026-03-11 16:46:41.094700 | controller | changed: [controller] => (item=/file) 2026-03-11 16:46:41.094983 | controller | 2026-03-11 16:46:41.095238 | controller | PLAY RECAP ********************************************************************* 2026-03-11 16:46:41.095505 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 16:46:41.095731 | controller | 2026-03-11 16:46:41.095953 | controller | 2026-03-11 16:46:41.096236 | controller | TASKS RECAP ******************************************************************** 2026-03-11 16:46:41.096501 | controller | Wednesday 11 March 2026 16:46:41 +0000 (0:00:00.343) 0:00:05.250 ******* 2026-03-11 16:46:41.096731 | controller | =============================================================================== 2026-03-11 16:46:41.097053 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 2.11s 2026-03-11 16:46:41.097313 | controller | Gathering Facts --------------------------------------------------------- 1.13s 2026-03-11 16:46:41.097565 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.73s 2026-03-11 16:46:41.097816 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-03-11 16:46:41.098031 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-11 16:46:41.098220 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.34s 2026-03-11 16:46:41.098394 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-11 16:46:41.098564 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-11 16:46:41.198092 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-11 16:46:41.213059 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-11 16:46:42.091797 | controller | 2026-03-11 16:46:42.091887 | controller | PLAY [Verify] ****************************************************************** 2026-03-11 16:46:42.092261 | controller | 2026-03-11 16:46:42.092315 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 16:46:42.092520 | controller | Wednesday 11 March 2026 16:46:42 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-03-11 16:46:43.184713 | 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 16:46:43.186374 | controller | ok: [controller] 2026-03-11 16:46:43.186462 | controller | 2026-03-11 16:46:43.186741 | controller | TASK [Stat file 1] ************************************************************* 2026-03-11 16:46:43.186837 | controller | Wednesday 11 March 2026 16:46:43 +0000 (0:00:01.094) 0:00:01.104 ******* 2026-03-11 16:46:43.586993 | controller | ok: [controller] 2026-03-11 16:46:43.587117 | controller | 2026-03-11 16:46:43.587306 | controller | TASK [Stat file 2] ************************************************************* 2026-03-11 16:46:43.587499 | controller | Wednesday 11 March 2026 16:46:43 +0000 (0:00:00.399) 0:00:01.504 ******* 2026-03-11 16:46:43.885681 | controller | ok: [controller] 2026-03-11 16:46:43.885720 | controller | 2026-03-11 16:46:43.885727 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-11 16:46:43.885734 | controller | Wednesday 11 March 2026 16:46:43 +0000 (0:00:00.297) 0:00:01.801 ******* 2026-03-11 16:46:44.168170 | controller | ok: [controller] 2026-03-11 16:46:44.168225 | controller | 2026-03-11 16:46:44.168234 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-11 16:46:44.168266 | controller | Wednesday 11 March 2026 16:46:44 +0000 (0:00:00.282) 0:00:02.084 ******* 2026-03-11 16:46:44.449806 | controller | ok: [controller] 2026-03-11 16:46:44.450119 | controller | 2026-03-11 16:46:44.450445 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-11 16:46:44.450741 | controller | Wednesday 11 March 2026 16:46:44 +0000 (0:00:00.282) 0:00:02.367 ******* 2026-03-11 16:46:44.480910 | controller | ok: [controller] => { 2026-03-11 16:46:44.481008 | controller | "changed": false, 2026-03-11 16:46:44.481065 | controller | "msg": "All assertions passed" 2026-03-11 16:46:44.481186 | controller | } 2026-03-11 16:46:44.481302 | controller | 2026-03-11 16:46:44.481430 | controller | PLAY RECAP ********************************************************************* 2026-03-11 16:46:44.481575 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 16:46:44.481692 | controller | 2026-03-11 16:46:44.481787 | controller | 2026-03-11 16:46:44.481921 | controller | TASKS RECAP ******************************************************************** 2026-03-11 16:46:44.482039 | controller | Wednesday 11 March 2026 16:46:44 +0000 (0:00:00.028) 0:00:02.395 ******* 2026-03-11 16:46:44.482158 | controller | =============================================================================== 2026-03-11 16:46:44.482285 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-03-11 16:46:44.482974 | controller | Stat file 1 ------------------------------------------------------------- 0.40s 2026-03-11 16:46:44.483010 | controller | Stat file 2 ------------------------------------------------------------- 0.30s 2026-03-11 16:46:44.483017 | controller | Stat removed file 1 ----------------------------------------------------- 0.28s 2026-03-11 16:46:44.483022 | controller | Stat removed file 2 ----------------------------------------------------- 0.28s 2026-03-11 16:46:44.483026 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-03-11 16:46:44.668855 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-11 16:46:44.678495 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 16:46:44.678995 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 16:46:44.685912 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 16:46:44.686008 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 16:46:44.686119 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 16:46:44.686448 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-11 16:46:45.042542 | controller | ok: Runtime: 0:00:39.448028 2026-03-11 16:46:45.045741 | 2026-03-11 16:46:45.046523 | PLAY RECAP 2026-03-11 16:46:45.046574 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 16:46:45.046596 | 2026-03-11 16:46:45.146319 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 16:46:45.147560 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 16:46:45.770162 | 2026-03-11 16:46:45.770311 | PLAY [all] 2026-03-11 16:46:45.781483 | 2026-03-11 16:46:45.781557 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 16:46:45.826217 | controller | skipping: Conditional result was False 2026-03-11 16:46:45.830372 | 2026-03-11 16:46:45.830452 | TASK [fetch-output : Set log path for single node] 2026-03-11 16:46:45.873618 | controller | ok 2026-03-11 16:46:45.877747 | 2026-03-11 16:46:45.877816 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 16:46:46.339989 | controller -> localhost | ok: "/var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/work/logs" 2026-03-11 16:46:46.546763 | controller -> localhost | changed: "/var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/work/artifacts" 2026-03-11 16:46:46.937081 | controller -> localhost | changed: "/var/lib/zuul/builds/7477b945cdea4b0bbafa28b706633b81/work/docs" 2026-03-11 16:46:46.958202 | 2026-03-11 16:46:46.958326 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 16:46:47.673871 | controller | changed: .d..t...... ./ 2026-03-11 16:46:47.674181 | controller | changed: All items complete 2026-03-11 16:46:47.674220 | 2026-03-11 16:46:48.216133 | controller | changed: .d..t...... ./ 2026-03-11 16:46:48.719060 | controller | changed: .d..t...... ./ 2026-03-11 16:46:48.745637 | 2026-03-11 16:46:48.745778 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 16:46:49.193659 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011576 2026-03-11 16:46:49.438752 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006597 2026-03-11 16:46:49.460953 | 2026-03-11 16:46:49.461150 | PLAY [all] 2026-03-11 16:46:49.469642 | 2026-03-11 16:46:49.469707 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 16:46:49.983451 | controller | changed 2026-03-11 16:46:49.987891 | 2026-03-11 16:46:49.987941 | PLAY RECAP 2026-03-11 16:46:49.987985 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 16:46:49.988007 | 2026-03-11 16:46:50.087078 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 16:46:50.089681 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 16:46:50.659780 | 2026-03-11 16:46:50.659910 | PLAY [localhost] 2026-03-11 16:46:50.669607 | 2026-03-11 16:46:50.669678 | TASK [Generate Zuul manifest] 2026-03-11 16:46:50.700549 | localhost | ok 2026-03-11 16:46:50.723107 | 2026-03-11 16:46:50.723301 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 16:46:51.069024 | localhost | changed 2026-03-11 16:46:51.081412 | 2026-03-11 16:46:51.081513 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 16:46:51.115437 | localhost | ok 2026-03-11 16:46:51.121920 | 2026-03-11 16:46:51.121990 | TASK [Upload logs] 2026-03-11 16:46:51.140670 | localhost | ok 2026-03-11 16:46:51.198601 | 2026-03-11 16:46:51.198715 | TASK [Set zuul-log-path fact] 2026-03-11 16:46:51.239797 | localhost | ok 2026-03-11 16:46:51.254301 | 2026-03-11 16:46:51.254401 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 16:46:51.288151 | localhost | ok 2026-03-11 16:46:51.296182 | 2026-03-11 16:46:51.296248 | TASK [upload-logs : Create log directories] 2026-03-11 16:46:51.718121 | localhost | changed 2026-03-11 16:46:51.724810 | 2026-03-11 16:46:51.724883 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 16:46:52.232995 | localhost -> localhost | ok: Runtime: 0:00:00.005473 2026-03-11 16:46:52.239627 | 2026-03-11 16:46:52.239692 | TASK [upload-logs : Upload logs to log server] 2026-03-11 16:46:52.709410 | localhost | Output suppressed because no_log was given 2026-03-11 16:46:52.713726 | 2026-03-11 16:46:52.713792 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 16:46:52.765535 | localhost | skipping: Conditional result was False 2026-03-11 16:46:52.773281 | localhost | skipping: Conditional result was False 2026-03-11 16:46:52.786457 | 2026-03-11 16:46:52.786615 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 16:46:52.833309 | localhost | skipping: Conditional result was False 2026-03-11 16:46:52.833684 | 2026-03-11 16:46:52.838044 | localhost | skipping: Conditional result was False 2026-03-11 16:46:52.852091 | 2026-03-11 16:46:52.852257 | LOOP [upload-logs : Upload console log and json output]