2026-03-11 20:34:44.365812 | Job console starting 2026-03-11 20:34:44.381632 | Updating git repos 2026-03-11 20:34:44.520939 | Cloning repos into workspace 2026-03-11 20:34:44.587538 | Restoring repo states 2026-03-11 20:34:44.602363 | Merging changes 2026-03-11 20:34:45.300498 | Checking out repos 2026-03-11 20:34:45.399562 | Preparing playbooks 2026-03-11 20:34:46.940913 | Running Ansible setup 2026-03-11 20:34:50.649659 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:34:51.276805 | 2026-03-11 20:34:51.276943 | PLAY [localhost] 2026-03-11 20:34:51.285371 | 2026-03-11 20:34:51.285440 | TASK [Gathering Facts] 2026-03-11 20:34:52.165904 | localhost | ok 2026-03-11 20:34:52.174903 | 2026-03-11 20:34:52.174977 | TASK [Setup log path fact] 2026-03-11 20:34:52.195340 | localhost | ok 2026-03-11 20:34:52.206210 | 2026-03-11 20:34:52.206275 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:34:52.261990 | localhost | ok 2026-03-11 20:34:52.269457 | 2026-03-11 20:34:52.269551 | TASK [emit-job-header : Print job information] 2026-03-11 20:34:52.307089 | # Job Information 2026-03-11 20:34:52.307197 | Ansible Version: 2.16.16 2026-03-11 20:34:52.307233 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-11 20:34:52.307274 | Pipeline: check 2026-03-11 20:34:52.307297 | Executor: 0a8996d2b663 2026-03-11 20:34:52.307318 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/235 2026-03-11 20:34:52.307341 | Event ID: 86d4bcd0-1d89-11f1-9c28-fe1984ae5bcd 2026-03-11 20:34:52.309943 | 2026-03-11 20:34:52.310009 | LOOP [emit-job-header : Print node information] 2026-03-11 20:34:52.406328 | localhost | ok: 2026-03-11 20:34:52.413087 | localhost | # Node Information 2026-03-11 20:34:52.413254 | localhost | Inventory Hostname: controller 2026-03-11 20:34:52.413290 | localhost | Hostname: np0000158481 2026-03-11 20:34:52.413320 | localhost | Username: zuul 2026-03-11 20:34:52.413353 | localhost | Distro: Debian 13.3 2026-03-11 20:34:52.413382 | localhost | Provider: yul1 2026-03-11 20:34:52.413409 | localhost | Region: ca-ymq-1 2026-03-11 20:34:52.413435 | localhost | Label: debian-trixie 2026-03-11 20:34:52.413461 | localhost | Product Name: OpenStack Nova 2026-03-11 20:34:52.413487 | localhost | Interface IP: 199.204.45.242 2026-03-11 20:34:52.422986 | 2026-03-11 20:34:52.423099 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 20:34:52.821643 | localhost -> localhost | changed 2026-03-11 20:34:52.828113 | 2026-03-11 20:34:52.828196 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 20:34:53.683957 | localhost -> localhost | changed 2026-03-11 20:34:53.691769 | 2026-03-11 20:34:53.691834 | PLAY [all] 2026-03-11 20:34:53.702567 | 2026-03-11 20:34:53.702671 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 20:34:53.955242 | controller -> localhost | ok 2026-03-11 20:34:53.964078 | 2026-03-11 20:34:53.964181 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 20:34:53.995775 | controller | ok 2026-03-11 20:34:54.010336 | controller | included: /var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 20:34:54.014805 | 2026-03-11 20:34:54.014866 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 20:34:55.609549 | controller -> localhost | Generating public/private rsa key pair. 2026-03-11 20:34:55.609717 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/work/aa5b65e59a4b4b5098c0a5900ce26f76_id_rsa 2026-03-11 20:34:55.609748 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/work/aa5b65e59a4b4b5098c0a5900ce26f76_id_rsa.pub 2026-03-11 20:34:55.609771 | controller -> localhost | The key fingerprint is: 2026-03-11 20:34:55.609793 | controller -> localhost | SHA256:VNlhtOuIyxO6/KLfw0Dg6N+aV2hFw+orf1cXAm3svsg zuul-build-sshkey 2026-03-11 20:34:55.609830 | controller -> localhost | The key's randomart image is: 2026-03-11 20:34:55.609851 | controller -> localhost | +---[RSA 3072]----+ 2026-03-11 20:34:55.609878 | controller -> localhost | | . .*+. | 2026-03-11 20:34:55.609900 | controller -> localhost | | . +.o.=. | 2026-03-11 20:34:55.609921 | controller -> localhost | | o . o.. +. | 2026-03-11 20:34:55.609941 | controller -> localhost | | . . o.. o.. | 2026-03-11 20:34:55.609962 | controller -> localhost | | . o oS ... . | 2026-03-11 20:34:55.609983 | controller -> localhost | | . = o. oo . | 2026-03-11 20:34:55.610003 | controller -> localhost | | . o *.o.o.o | 2026-03-11 20:34:55.610023 | controller -> localhost | | oo*o=.E . | 2026-03-11 20:34:55.610045 | controller -> localhost | | +B===+ | 2026-03-11 20:34:55.610066 | controller -> localhost | +----[SHA256]-----+ 2026-03-11 20:34:55.610113 | controller -> localhost | ok: Runtime: 0:00:01.122477 2026-03-11 20:34:55.614131 | 2026-03-11 20:34:55.614194 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 20:34:55.645575 | controller | ok 2026-03-11 20:34:55.655508 | controller | included: /var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 20:34:55.661979 | 2026-03-11 20:34:55.662044 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 20:34:55.686449 | controller | skipping: Conditional result was False 2026-03-11 20:34:55.698672 | 2026-03-11 20:34:55.698769 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 20:34:56.293177 | controller | changed 2026-03-11 20:34:56.297281 | 2026-03-11 20:34:56.297347 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 20:34:56.513507 | controller | ok 2026-03-11 20:34:56.518336 | 2026-03-11 20:34:56.518399 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 20:34:57.061957 | controller | changed 2026-03-11 20:34:57.067652 | 2026-03-11 20:34:57.067721 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 20:34:57.599080 | controller | changed 2026-03-11 20:34:57.603057 | 2026-03-11 20:34:57.603120 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 20:34:57.627110 | controller | skipping: Conditional result was False 2026-03-11 20:34:57.637409 | 2026-03-11 20:34:57.637497 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 20:34:58.038121 | controller -> localhost | changed 2026-03-11 20:34:58.051392 | 2026-03-11 20:34:58.051660 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 20:34:58.356162 | controller -> localhost | Identity added: /var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/work/aa5b65e59a4b4b5098c0a5900ce26f76_id_rsa (zuul-build-sshkey) 2026-03-11 20:34:58.356462 | controller -> localhost | ok: Runtime: 0:00:00.014750 2026-03-11 20:34:58.362302 | 2026-03-11 20:34:58.362419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 20:34:58.734019 | controller | ok 2026-03-11 20:34:58.740760 | 2026-03-11 20:34:58.740833 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 20:34:58.776259 | controller | skipping: Conditional result was False 2026-03-11 20:34:58.790130 | 2026-03-11 20:34:58.790304 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 20:34:59.187681 | controller | ok 2026-03-11 20:34:59.197393 | 2026-03-11 20:34:59.197489 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 20:35:00.266546 | controller | Output suppressed because no_log was given 2026-03-11 20:35:00.277476 | 2026-03-11 20:35:00.277587 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 20:35:00.487513 | controller | ok: "logs" 2026-03-11 20:35:00.488025 | controller | ok: All items complete 2026-03-11 20:35:00.488083 | 2026-03-11 20:35:00.682029 | controller | ok: "artifacts" 2026-03-11 20:35:00.877651 | controller | ok: "docs" 2026-03-11 20:35:00.894357 | 2026-03-11 20:35:00.894553 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 20:35:01.114828 | controller | changed: "logs" 2026-03-11 20:35:01.290125 | controller | changed: "artifacts" 2026-03-11 20:35:01.469859 | controller | changed: "docs" 2026-03-11 20:35:01.493144 | 2026-03-11 20:35:01.493384 | PLAY RECAP 2026-03-11 20:35:01.493496 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 20:35:01.493558 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:35:01.493603 | 2026-03-11 20:35:01.674210 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:35:01.677107 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:35:02.231652 | 2026-03-11 20:35:02.231782 | PLAY [all] 2026-03-11 20:35:02.241717 | 2026-03-11 20:35:02.241789 | TASK [setup-uv : Extract archive] 2026-03-11 20:35:04.721987 | controller | changed 2026-03-11 20:35:04.726812 | 2026-03-11 20:35:04.726891 | TASK [setup-uv : Print version] 2026-03-11 20:35:04.243145 | controller | uv 0.8.13 2026-03-11 20:35:05.255667 | controller | ok: Runtime: 0:00:00.014553 2026-03-11 20:35:05.262614 | 2026-03-11 20:35:05.262663 | PLAY RECAP 2026-03-11 20:35:05.262710 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:35:05.262733 | 2026-03-11 20:35:05.402266 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:35:05.441367 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:35:06.038683 | 2026-03-11 20:35:06.038827 | PLAY [all] 2026-03-11 20:35:06.049315 | 2026-03-11 20:35:06.049387 | TASK [Copy inventory file for Zuul] 2026-03-11 20:35:06.987128 | controller | changed 2026-03-11 20:35:06.991578 | 2026-03-11 20:35:06.991659 | TASK [Switch "ansible_host" to private IP] 2026-03-11 20:35:07.298540 | controller | changed: 1 replacements made 2026-03-11 20:35:07.306446 | 2026-03-11 20:35:07.306516 | TASK [Run Molecule scenario] 2026-03-11 20:35:06.851828 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-11 20:35:06.851956 | controller | Creating virtual environment at: .venv 2026-03-11 20:35:08.080567 | controller | Downloading pygments (1.2MiB) 2026-03-11 20:35:08.082069 | controller | Downloading ansible-core (2.3MiB) 2026-03-11 20:35:08.082559 | controller | Downloading cryptography (4.3MiB) 2026-03-11 20:35:08.232290 | controller | Downloading cryptography 2026-03-11 20:35:08.273911 | controller | Downloading pygments 2026-03-11 20:35:08.445384 | controller | Downloading ansible-core 2026-03-11 20:35:08.496641 | controller | Installed 36 packages in 50ms 2026-03-11 20:35:09.190220 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-11 20:35:09.190284 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-11 20:35:10.016867 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-11 20:35:10.016928 | controller | INFO default scenario not found, disabling shared state. 2026-03-11 20:35:10.017066 | 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:35:10.017147 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-11 20:36:50.805024 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-11 20:36:50.805129 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-11 20:36:50.805327 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-11 20:36:50.805448 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-11 20:36:50.810809 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 20:36:50.811168 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:36:50.816411 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 20:36:50.816437 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 20:36:50.816542 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 20:36:50.821763 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-11 20:36:51.650247 | controller | 2026-03-11 20:36:51.650516 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-11 20:36:51.703621 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-11 20:36:51.714274 | controller | INFO [upload-helm-chart > create] Executing 2026-03-11 20:36:51.715733 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:36:51.720997 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-11 20:36:51.721284 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:36:51.726613 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-11 20:36:52.574181 | controller | 2026-03-11 20:36:52.574307 | controller | PLAY [Converge] **************************************************************** 2026-03-11 20:36:52.574482 | controller | 2026-03-11 20:36:52.574678 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:36:52.574872 | controller | Wednesday 11 March 2026 20:36:52 +0000 (0:00:00.064) 0:00:00.064 ******* 2026-03-11 20:36:53.936273 | 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:36:53.942587 | controller | ok: [controller] 2026-03-11 20:36:53.942767 | controller | 2026-03-11 20:36:53.942970 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:36:53.943172 | controller | Wednesday 11 March 2026 20:36:53 +0000 (0:00:01.369) 0:00:01.433 ******* 2026-03-11 20:36:54.012686 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:36:54.012839 | controller | 2026-03-11 20:36:54.013036 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:36:54.013225 | controller | Wednesday 11 March 2026 20:36:54 +0000 (0:00:00.070) 0:00:01.503 ******* 2026-03-11 20:36:54.702248 | controller | changed: [controller] 2026-03-11 20:36:54.702407 | controller | 2026-03-11 20:36:54.702633 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:36:54.702843 | controller | Wednesday 11 March 2026 20:36:54 +0000 (0:00:00.689) 0:00:02.192 ******* 2026-03-11 20:36:54.743113 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:36:54.743291 | controller | 2026-03-11 20:36:54.743555 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:36:54.743761 | controller | Wednesday 11 March 2026 20:36:54 +0000 (0:00:00.041) 0:00:02.234 ******* 2026-03-11 20:36:55.132135 | controller | changed: [controller] 2026-03-11 20:36:55.132278 | controller | 2026-03-11 20:36:55.132548 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:36:55.132697 | controller | Wednesday 11 March 2026 20:36:55 +0000 (0:00:00.388) 0:00:02.622 ******* 2026-03-11 20:36:56.199868 | controller | changed: [controller] 2026-03-11 20:36:56.199966 | controller | 2026-03-11 20:36:56.200943 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:36:56.200952 | controller | Wednesday 11 March 2026 20:36:56 +0000 (0:00:01.064) 0:00:03.687 ******* 2026-03-11 20:36:56.586026 | controller | ok: [controller] 2026-03-11 20:36:56.586188 | controller | 2026-03-11 20:36:56.586405 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:36:56.586590 | controller | Wednesday 11 March 2026 20:36:56 +0000 (0:00:00.388) 0:00:04.076 ******* 2026-03-11 20:36:56.634090 | controller | skipping: [controller] 2026-03-11 20:36:56.634301 | controller | 2026-03-11 20:36:56.634516 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:36:56.634740 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 20:36:56.634928 | controller | 2026-03-11 20:36:56.635114 | controller | 2026-03-11 20:36:56.635317 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:36:56.635611 | controller | Wednesday 11 March 2026 20:36:56 +0000 (0:00:00.048) 0:00:04.125 ******* 2026-03-11 20:36:56.635801 | controller | =============================================================================== 2026-03-11 20:36:56.635991 | controller | Gathering Facts --------------------------------------------------------- 1.37s 2026-03-11 20:36:56.636197 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.06s 2026-03-11 20:36:56.636392 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.69s 2026-03-11 20:36:56.636592 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.39s 2026-03-11 20:36:56.636784 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.39s 2026-03-11 20:36:56.636985 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 20:36:56.637187 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-11 20:36:56.637386 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 20:36:56.725376 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-11 20:36:56.733779 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-11 20:36:57.519259 | controller | 2026-03-11 20:36:57.519863 | controller | PLAY [Converge] **************************************************************** 2026-03-11 20:36:57.519875 | controller | 2026-03-11 20:36:57.519885 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:36:57.520053 | controller | Wednesday 11 March 2026 20:36:57 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-03-11 20:36:58.628473 | 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:36:58.635125 | controller | ok: [controller] 2026-03-11 20:36:58.635204 | controller | 2026-03-11 20:36:58.635302 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:36:58.635464 | controller | Wednesday 11 March 2026 20:36:58 +0000 (0:00:01.116) 0:00:01.126 ******* 2026-03-11 20:36:58.708558 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:36:58.708743 | controller | 2026-03-11 20:36:58.708962 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:36:58.709166 | controller | Wednesday 11 March 2026 20:36:58 +0000 (0:00:00.073) 0:00:01.199 ******* 2026-03-11 20:36:59.404821 | controller | ok: [controller] 2026-03-11 20:36:59.405026 | controller | 2026-03-11 20:36:59.405292 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:36:59.405530 | controller | Wednesday 11 March 2026 20:36:59 +0000 (0:00:00.694) 0:00:01.894 ******* 2026-03-11 20:36:59.446189 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:36:59.446311 | controller | 2026-03-11 20:36:59.446454 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:36:59.446610 | controller | Wednesday 11 March 2026 20:36:59 +0000 (0:00:00.043) 0:00:01.937 ******* 2026-03-11 20:36:59.831652 | controller | ok: [controller] 2026-03-11 20:36:59.831803 | controller | 2026-03-11 20:36:59.832014 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:36:59.832209 | controller | Wednesday 11 March 2026 20:36:59 +0000 (0:00:00.384) 0:00:02.322 ******* 2026-03-11 20:37:00.898515 | controller | ok: [controller] 2026-03-11 20:37:00.898550 | controller | 2026-03-11 20:37:00.898651 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:37:00.898743 | controller | Wednesday 11 March 2026 20:37:00 +0000 (0:00:01.064) 0:00:03.386 ******* 2026-03-11 20:37:01.276061 | controller | ok: [controller] 2026-03-11 20:37:01.276104 | controller | 2026-03-11 20:37:01.276221 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:37:01.276323 | controller | Wednesday 11 March 2026 20:37:01 +0000 (0:00:00.376) 0:00:03.763 ******* 2026-03-11 20:37:01.311369 | controller | skipping: [controller] 2026-03-11 20:37:01.311539 | controller | 2026-03-11 20:37:01.311707 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:37:01.311803 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 20:37:01.311874 | controller | 2026-03-11 20:37:01.311955 | controller | 2026-03-11 20:37:01.312032 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:37:01.312120 | controller | Wednesday 11 March 2026 20:37:01 +0000 (0:00:00.039) 0:00:03.803 ******* 2026-03-11 20:37:01.312201 | controller | =============================================================================== 2026-03-11 20:37:01.312303 | controller | Gathering Facts --------------------------------------------------------- 1.12s 2026-03-11 20:37:01.312392 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.06s 2026-03-11 20:37:01.312477 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.69s 2026-03-11 20:37:01.312562 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.38s 2026-03-11 20:37:01.312648 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s 2026-03-11 20:37:01.312736 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 20:37:01.312823 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 20:37:01.312911 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-03-11 20:37:01.393974 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-11 20:37:01.405576 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-11 20:37:02.202609 | controller | 2026-03-11 20:37:02.202760 | controller | PLAY [Side effect] ************************************************************* 2026-03-11 20:37:02.202931 | controller | 2026-03-11 20:37:02.203119 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:37:02.203326 | controller | Wednesday 11 March 2026 20:37:02 +0000 (0:00:00.011) 0:00:00.011 ******* 2026-03-11 20:37:03.264200 | 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:37:03.269442 | controller | ok: [controller] 2026-03-11 20:37:03.269625 | controller | 2026-03-11 20:37:03.269851 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:37:03.270081 | controller | Wednesday 11 March 2026 20:37:03 +0000 (0:00:01.066) 0:00:01.078 ******* 2026-03-11 20:37:03.336297 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:37:03.336442 | controller | 2026-03-11 20:37:03.336613 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:37:03.336803 | controller | Wednesday 11 March 2026 20:37:03 +0000 (0:00:00.066) 0:00:01.145 ******* 2026-03-11 20:37:04.033724 | controller | changed: [controller] 2026-03-11 20:37:04.033883 | controller | 2026-03-11 20:37:04.034119 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:37:04.034324 | controller | Wednesday 11 March 2026 20:37:04 +0000 (0:00:00.697) 0:00:01.842 ******* 2026-03-11 20:37:04.067671 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:37:04.067842 | controller | 2026-03-11 20:37:04.068054 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:37:04.068248 | controller | Wednesday 11 March 2026 20:37:04 +0000 (0:00:00.034) 0:00:01.877 ******* 2026-03-11 20:37:04.476933 | controller | ok: [controller] 2026-03-11 20:37:04.477095 | controller | 2026-03-11 20:37:04.477310 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:37:04.477509 | controller | Wednesday 11 March 2026 20:37:04 +0000 (0:00:00.408) 0:00:02.285 ******* 2026-03-11 20:37:05.586899 | controller | ok: [controller] 2026-03-11 20:37:05.586929 | controller | 2026-03-11 20:37:05.586935 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:37:05.586940 | controller | Wednesday 11 March 2026 20:37:05 +0000 (0:00:01.109) 0:00:03.395 ******* 2026-03-11 20:37:05.987127 | controller | ok: [controller] 2026-03-11 20:37:05.987277 | controller | 2026-03-11 20:37:05.987782 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:37:05.987807 | controller | Wednesday 11 March 2026 20:37:05 +0000 (0:00:00.400) 0:00:03.795 ******* 2026-03-11 20:37:06.309925 | controller | changed: [controller] => (item=/file) 2026-03-11 20:37:06.310200 | controller | 2026-03-11 20:37:06.310461 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:37:06.310769 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 20:37:06.311013 | controller | 2026-03-11 20:37:06.311243 | controller | 2026-03-11 20:37:06.311579 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:37:06.311912 | controller | Wednesday 11 March 2026 20:37:06 +0000 (0:00:00.324) 0:00:04.119 ******* 2026-03-11 20:37:06.312159 | controller | =============================================================================== 2026-03-11 20:37:06.312451 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.11s 2026-03-11 20:37:06.312710 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-03-11 20:37:06.312963 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.70s 2026-03-11 20:37:06.313283 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-03-11 20:37:06.313546 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-11 20:37:06.313807 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.32s 2026-03-11 20:37:06.314074 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-11 20:37:06.314354 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.03s 2026-03-11 20:37:06.406332 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-11 20:37:06.417256 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-11 20:37:07.263078 | controller | 2026-03-11 20:37:07.263431 | controller | PLAY [Verify] ****************************************************************** 2026-03-11 20:37:07.263669 | controller | 2026-03-11 20:37:07.263914 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:37:07.264160 | controller | Wednesday 11 March 2026 20:37:07 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-03-11 20:37:08.292182 | 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:37:08.299249 | controller | ok: [controller] 2026-03-11 20:37:08.299271 | controller | 2026-03-11 20:37:08.299275 | controller | TASK [Stat file 1] ************************************************************* 2026-03-11 20:37:08.299280 | controller | Wednesday 11 March 2026 20:37:08 +0000 (0:00:01.033) 0:00:01.043 ******* 2026-03-11 20:37:08.674260 | controller | ok: [controller] 2026-03-11 20:37:08.674339 | controller | 2026-03-11 20:37:08.674463 | controller | TASK [Stat file 2] ************************************************************* 2026-03-11 20:37:08.674589 | controller | Wednesday 11 March 2026 20:37:08 +0000 (0:00:00.377) 0:00:01.420 ******* 2026-03-11 20:37:08.968425 | controller | ok: [controller] 2026-03-11 20:37:08.968474 | controller | 2026-03-11 20:37:08.968644 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-11 20:37:08.968799 | controller | Wednesday 11 March 2026 20:37:08 +0000 (0:00:00.294) 0:00:01.714 ******* 2026-03-11 20:37:09.247764 | controller | ok: [controller] 2026-03-11 20:37:09.247810 | controller | 2026-03-11 20:37:09.247897 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-11 20:37:09.248006 | controller | Wednesday 11 March 2026 20:37:09 +0000 (0:00:00.277) 0:00:01.991 ******* 2026-03-11 20:37:09.531800 | controller | ok: [controller] 2026-03-11 20:37:09.531892 | controller | 2026-03-11 20:37:09.532025 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-11 20:37:09.532155 | controller | Wednesday 11 March 2026 20:37:09 +0000 (0:00:00.286) 0:00:02.278 ******* 2026-03-11 20:37:09.561665 | controller | ok: [controller] => { 2026-03-11 20:37:09.561753 | controller | "changed": false, 2026-03-11 20:37:09.561863 | controller | "msg": "All assertions passed" 2026-03-11 20:37:09.561963 | controller | } 2026-03-11 20:37:09.562062 | controller | 2026-03-11 20:37:09.562159 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:37:09.562288 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 20:37:09.562379 | controller | 2026-03-11 20:37:09.562473 | controller | 2026-03-11 20:37:09.562581 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:37:09.562688 | controller | Wednesday 11 March 2026 20:37:09 +0000 (0:00:00.030) 0:00:02.308 ******* 2026-03-11 20:37:09.562790 | controller | =============================================================================== 2026-03-11 20:37:09.562901 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-03-11 20:37:09.563000 | controller | Stat file 1 ------------------------------------------------------------- 0.38s 2026-03-11 20:37:09.563107 | controller | Stat file 2 ------------------------------------------------------------- 0.29s 2026-03-11 20:37:09.563213 | controller | Stat removed file 2 ----------------------------------------------------- 0.29s 2026-03-11 20:37:09.563316 | controller | Stat removed file 1 ----------------------------------------------------- 0.28s 2026-03-11 20:37:09.563483 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-03-11 20:37:09.655411 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-11 20:37:09.718132 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 20:37:09.718855 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:37:09.790934 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 20:37:09.791204 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 20:37:09.791465 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 20:37:09.792153 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-11 20:37:10.065923 | controller | ok: Runtime: 0:02:03.121729 2026-03-11 20:37:10.070776 | 2026-03-11 20:37:10.070896 | PLAY RECAP 2026-03-11 20:37:10.070968 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:37:10.071000 | 2026-03-11 20:37:10.205653 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:37:10.210306 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:37:10.798269 | 2026-03-11 20:37:10.798406 | PLAY [all] 2026-03-11 20:37:10.809176 | 2026-03-11 20:37:10.809257 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 20:37:10.854720 | controller | skipping: Conditional result was False 2026-03-11 20:37:10.863809 | 2026-03-11 20:37:10.863972 | TASK [fetch-output : Set log path for single node] 2026-03-11 20:37:10.907770 | controller | ok 2026-03-11 20:37:10.912701 | 2026-03-11 20:37:10.912808 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 20:37:11.309706 | controller -> localhost | ok: "/var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/work/logs" 2026-03-11 20:37:11.620759 | controller -> localhost | changed: "/var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/work/artifacts" 2026-03-11 20:37:11.851699 | controller -> localhost | changed: "/var/lib/zuul/builds/aa5b65e59a4b4b5098c0a5900ce26f76/work/docs" 2026-03-11 20:37:11.866543 | 2026-03-11 20:37:11.866740 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 20:37:12.553128 | controller | changed: .d..t...... ./ 2026-03-11 20:37:12.553389 | controller | changed: All items complete 2026-03-11 20:37:12.553430 | 2026-03-11 20:37:13.018360 | controller | changed: .d..t...... ./ 2026-03-11 20:37:13.517335 | controller | changed: .d..t...... ./ 2026-03-11 20:37:13.533486 | 2026-03-11 20:37:13.533648 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 20:37:13.966003 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008691 2026-03-11 20:37:14.195347 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007508 2026-03-11 20:37:14.215968 | 2026-03-11 20:37:14.216110 | PLAY [all] 2026-03-11 20:37:14.221499 | 2026-03-11 20:37:14.221565 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 20:37:14.706831 | controller | changed 2026-03-11 20:37:14.713543 | 2026-03-11 20:37:14.713608 | PLAY RECAP 2026-03-11 20:37:14.713673 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 20:37:14.713704 | 2026-03-11 20:37:14.872434 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:37:14.875018 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 20:37:15.442070 | 2026-03-11 20:37:15.442206 | PLAY [localhost] 2026-03-11 20:37:15.452283 | 2026-03-11 20:37:15.452351 | TASK [Generate Zuul manifest] 2026-03-11 20:37:15.474635 | localhost | ok 2026-03-11 20:37:15.492305 | 2026-03-11 20:37:15.492386 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 20:37:15.819007 | localhost | changed 2026-03-11 20:37:15.831729 | 2026-03-11 20:37:15.831799 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 20:37:15.864841 | localhost | ok 2026-03-11 20:37:15.873359 | 2026-03-11 20:37:15.873421 | TASK [Upload logs] 2026-03-11 20:37:15.895012 | localhost | ok 2026-03-11 20:37:15.954573 | 2026-03-11 20:37:15.954656 | TASK [Set zuul-log-path fact] 2026-03-11 20:37:15.976770 | localhost | ok 2026-03-11 20:37:15.989430 | 2026-03-11 20:37:15.989495 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:37:16.020943 | localhost | ok 2026-03-11 20:37:16.029547 | 2026-03-11 20:37:16.029631 | TASK [upload-logs : Create log directories] 2026-03-11 20:37:16.452602 | localhost | changed 2026-03-11 20:37:16.458857 | 2026-03-11 20:37:16.458950 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 20:37:16.864876 | localhost -> localhost | ok: Runtime: 0:00:00.005512 2026-03-11 20:37:16.871955 | 2026-03-11 20:37:16.872046 | TASK [upload-logs : Upload logs to log server] 2026-03-11 20:37:17.359579 | localhost | Output suppressed because no_log was given 2026-03-11 20:37:17.364747 | 2026-03-11 20:37:17.364847 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 20:37:17.414498 | localhost | skipping: Conditional result was False 2026-03-11 20:37:17.422373 | localhost | skipping: Conditional result was False 2026-03-11 20:37:17.438080 | 2026-03-11 20:37:17.438210 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 20:37:17.486037 | localhost | skipping: Conditional result was False 2026-03-11 20:37:17.486485 | 2026-03-11 20:37:17.490378 | localhost | skipping: Conditional result was False 2026-03-11 20:37:17.501651 | 2026-03-11 20:37:17.501770 | LOOP [upload-logs : Upload console log and json output]