2026-02-16 21:05:08.985145 | Job console starting
2026-02-16 21:05:08.995217 | Updating git repos
2026-02-16 21:05:09.031741 | Cloning repos into workspace
2026-02-16 21:05:09.087009 | Restoring repo states
2026-02-16 21:05:09.101076 | Merging changes
2026-02-16 21:05:09.613297 | Checking out repos
2026-02-16 21:05:09.854289 | Preparing playbooks
2026-02-16 21:05:11.030043 | Running Ansible setup
2026-02-16 21:05:14.815691 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 21:05:15.446547 | 
2026-02-16 21:05:15.446730 | PLAY [localhost]
2026-02-16 21:05:15.455107 | 
2026-02-16 21:05:15.455195 | TASK [Gathering Facts]
2026-02-16 21:05:16.349360 | localhost | ok
2026-02-16 21:05:16.359738 | 
2026-02-16 21:05:16.359846 | TASK [Setup log path fact]
2026-02-16 21:05:16.380754 | localhost | ok
2026-02-16 21:05:16.396768 | 
2026-02-16 21:05:16.396838 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 21:05:16.430805 | localhost | ok
2026-02-16 21:05:16.442705 | 
2026-02-16 21:05:16.442775 | TASK [emit-job-header : Print job information]
2026-02-16 21:05:16.484810 | # Job Information
2026-02-16 21:05:16.485117 | Ansible Version: 2.16.15
2026-02-16 21:05:16.485195 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart
2026-02-16 21:05:16.485249 | Pipeline: check
2026-02-16 21:05:16.485297 | Executor: 3a2793d2bd32
2026-02-16 21:05:16.485343 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/222
2026-02-16 21:05:16.485395 | Event ID: 092ea1d0-0b7b-11f1-85c6-b1e29916fa8a
2026-02-16 21:05:16.490045 | 
2026-02-16 21:05:16.490136 | LOOP [emit-job-header : Print node information]
2026-02-16 21:05:16.574442 | localhost | ok:
2026-02-16 21:05:16.574659 | localhost | # Node Information
2026-02-16 21:05:16.574687 | localhost | Inventory Hostname: controller
2026-02-16 21:05:16.574708 | localhost | Hostname: np0000155778
2026-02-16 21:05:16.574728 | localhost | Username: zuul
2026-02-16 21:05:16.574750 | localhost | Distro: Debian 13.3
2026-02-16 21:05:16.574770 | localhost | Provider: yul1
2026-02-16 21:05:16.574788 | localhost | Region: ca-ymq-1
2026-02-16 21:05:16.574807 | localhost | Label: debian-trixie
2026-02-16 21:05:16.574825 | localhost | Product Name: OpenStack Nova
2026-02-16 21:05:16.574843 | localhost | Interface IP: 199.204.45.6
2026-02-16 21:05:16.585814 | 
2026-02-16 21:05:16.586027 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 21:05:16.964750 | localhost -> localhost | changed
2026-02-16 21:05:16.970051 | 
2026-02-16 21:05:16.970121 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 21:05:17.826957 | localhost -> localhost | changed
2026-02-16 21:05:17.836432 | 
2026-02-16 21:05:17.836532 | PLAY [all]
2026-02-16 21:05:17.850767 | 
2026-02-16 21:05:17.850899 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 21:05:18.069373 | controller -> localhost | ok
2026-02-16 21:05:18.074906 | 
2026-02-16 21:05:18.075056 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 21:05:18.107950 | controller | ok
2026-02-16 21:05:18.150925 | controller | included: /var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 21:05:18.155601 | 
2026-02-16 21:05:18.155665 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 21:05:19.410011 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 21:05:19.410216 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/work/0eb798a3c8e7473195a219a1c0f0cc65_id_rsa
2026-02-16 21:05:19.410248 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/work/0eb798a3c8e7473195a219a1c0f0cc65_id_rsa.pub
2026-02-16 21:05:19.410271 | controller -> localhost | The key fingerprint is:
2026-02-16 21:05:19.410294 | controller -> localhost | SHA256:1qUnmapmU02DZVUAbya7lOqAXlQ56lejptd0Cmsyljo zuul-build-sshkey
2026-02-16 21:05:19.410326 | controller -> localhost | The key's randomart image is:
2026-02-16 21:05:19.410348 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 21:05:19.410375 | controller -> localhost | |          .ooo.  |
2026-02-16 21:05:19.410397 | controller -> localhost | |         .o.     |
2026-02-16 21:05:19.410418 | controller -> localhost | |        ++. =    |
2026-02-16 21:05:19.410438 | controller -> localhost | |       o.oo@     |
2026-02-16 21:05:19.410458 | controller -> localhost | |      o So%..    |
2026-02-16 21:05:19.410478 | controller -> localhost | |     + .o*o=.    |
2026-02-16 21:05:19.410497 | controller -> localhost | |    . +o*=.o     |
2026-02-16 21:05:19.410517 | controller -> localhost | |   .E.OB+ o      |
2026-02-16 21:05:19.410540 | controller -> localhost | |    o*o*.        |
2026-02-16 21:05:19.410560 | controller -> localhost | +----[SHA256]-----+
2026-02-16 21:05:19.410613 | controller -> localhost | ok: Runtime: 0:00:00.821672
2026-02-16 21:05:19.415003 | 
2026-02-16 21:05:19.415069 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 21:05:19.448133 | controller | ok
2026-02-16 21:05:19.459602 | controller | included: /var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 21:05:19.466649 | 
2026-02-16 21:05:19.466715 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 21:05:19.491847 | controller | skipping: Conditional result was False
2026-02-16 21:05:19.499782 | 
2026-02-16 21:05:19.499872 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 21:05:19.989245 | controller | changed
2026-02-16 21:05:19.999054 | 
2026-02-16 21:05:19.999144 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 21:05:20.191481 | controller | ok
2026-02-16 21:05:20.197132 | 
2026-02-16 21:05:20.197209 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 21:05:20.733965 | controller | changed
2026-02-16 21:05:20.739906 | 
2026-02-16 21:05:20.739995 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 21:05:21.233412 | controller | changed
2026-02-16 21:05:21.239072 | 
2026-02-16 21:05:21.239162 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 21:05:21.266079 | controller | skipping: Conditional result was False
2026-02-16 21:05:21.272306 | 
2026-02-16 21:05:21.272383 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 21:05:21.653206 | controller -> localhost | changed
2026-02-16 21:05:21.668892 | 
2026-02-16 21:05:21.669127 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 21:05:21.957362 | controller -> localhost | Identity added: /var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/work/0eb798a3c8e7473195a219a1c0f0cc65_id_rsa (zuul-build-sshkey)
2026-02-16 21:05:21.957590 | controller -> localhost | ok: Runtime: 0:00:00.013946
2026-02-16 21:05:21.962910 | 
2026-02-16 21:05:21.962993 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 21:05:22.318384 | controller | ok
2026-02-16 21:05:22.325300 | 
2026-02-16 21:05:22.325438 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 21:05:22.350736 | controller | skipping: Conditional result was False
2026-02-16 21:05:22.365796 | 
2026-02-16 21:05:22.366038 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 21:05:22.743935 | controller | ok
2026-02-16 21:05:22.750554 | 
2026-02-16 21:05:22.750659 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 21:05:23.797811 | controller | Output suppressed because no_log was given
2026-02-16 21:05:23.809476 | 
2026-02-16 21:05:23.809621 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 21:05:24.009500 | controller | ok: "logs"
2026-02-16 21:05:24.009806 | controller | ok: All items complete
2026-02-16 21:05:24.009866 | 
2026-02-16 21:05:24.186514 | controller | ok: "artifacts"
2026-02-16 21:05:24.359822 | controller | ok: "docs"
2026-02-16 21:05:24.377076 | 
2026-02-16 21:05:24.377348 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 21:05:24.591438 | controller | changed: "logs"
2026-02-16 21:05:24.770280 | controller | changed: "artifacts"
2026-02-16 21:05:24.950306 | controller | changed: "docs"
2026-02-16 21:05:24.966571 | 
2026-02-16 21:05:24.966729 | PLAY RECAP
2026-02-16 21:05:24.966795 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 21:05:24.966835 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 21:05:24.966867 | 
2026-02-16 21:05:25.111878 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 21:05:25.114680 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-16 21:05:25.734763 | 
2026-02-16 21:05:25.734894 | PLAY [all]
2026-02-16 21:05:25.746588 | 
2026-02-16 21:05:25.746675 | TASK [setup-uv : Extract archive]
2026-02-16 21:05:28.087147 | controller | changed
2026-02-16 21:05:28.093625 | 
2026-02-16 21:05:28.093728 | TASK [setup-uv : Print version]
2026-02-16 21:05:28.504133 | controller | uv 0.8.13
2026-02-16 21:05:28.622530 | controller | ok: Runtime: 0:00:00.013426
2026-02-16 21:05:28.629502 | 
2026-02-16 21:05:28.629597 | PLAY RECAP
2026-02-16 21:05:28.629661 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 21:05:28.629697 | 
2026-02-16 21:05:28.775404 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-16 21:05:28.777183 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-16 21:05:29.425049 | 
2026-02-16 21:05:29.425175 | PLAY [all]
2026-02-16 21:05:29.435154 | 
2026-02-16 21:05:29.435232 | TASK [Copy inventory file for Zuul]
2026-02-16 21:05:30.469664 | controller | changed
2026-02-16 21:05:30.476835 | 
2026-02-16 21:05:30.476938 | TASK [Switch "ansible_host" to private IP]
2026-02-16 21:05:30.782975 | controller | changed: 1 replacements made
2026-02-16 21:05:30.791864 | 
2026-02-16 21:05:30.791937 | TASK [Run Molecule scenario]
2026-02-16 21:05:31.236449 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-02-16 21:05:31.236622 | controller | Creating virtual environment at: .venv
2026-02-16 21:05:31.510187 | controller | Downloading pygments (1.2MiB)
2026-02-16 21:05:31.511808 | controller | Downloading ansible-core (2.3MiB)
2026-02-16 21:05:31.512339 | controller | Downloading cryptography (4.3MiB)
2026-02-16 21:05:31.737707 | controller |  Downloading pygments
2026-02-16 21:05:31.828372 | controller |  Downloading cryptography
2026-02-16 21:05:31.864317 | controller |  Downloading ansible-core
2026-02-16 21:05:31.918899 | controller | Installed 36 packages in 54ms
2026-02-16 21:05:32.577975 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-02-16 21:05:32.578002 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-02-16 21:05:33.340081 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-02-16 21:05:33.340195 | controller | INFO     default scenario not found, disabling shared state.
2026-02-16 21:05:33.340397 | controller | INFO     [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-02-16 21:05:33.340510 | controller | INFO     [upload-helm-chart > prerun] Performing prerun with role_name_check=0...
2026-02-16 21:05:50.727790 | controller | INFO     [upload-helm-chart > dependency] Executing
2026-02-16 21:05:50.727850 | controller | WARNING  [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml
2026-02-16 21:05:50.727860 | controller | WARNING  [upload-helm-chart > dependency] Missing collections requirements file: collections.yml
2026-02-16 21:05:50.727885 | controller | WARNING  [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-02-16 21:05:50.733428 | controller | INFO     [upload-helm-chart > cleanup] Executing
2026-02-16 21:05:50.733736 | controller | WARNING  [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:05:50.739266 | controller | INFO     [upload-helm-chart > destroy] Executing
2026-02-16 21:05:50.739293 | controller | WARNING  [upload-helm-chart > destroy] Skipping, '--destroy=never' requested.
2026-02-16 21:05:50.739330 | controller | INFO     [upload-helm-chart > destroy] Executed: Successful
2026-02-16 21:05:50.744749 | controller | INFO     [upload-helm-chart > syntax] Executing
2026-02-16 21:05:51.615606 | controller |
2026-02-16 21:05:51.615922 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml
2026-02-16 21:05:51.671143 | controller | INFO     [upload-helm-chart > syntax] Executed: Successful
2026-02-16 21:05:51.680737 | controller | INFO     [upload-helm-chart > create] Executing
2026-02-16 21:05:51.682980 | controller | WARNING  [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:05:51.690459 | controller | INFO     [upload-helm-chart > prepare] Executing
2026-02-16 21:05:51.691303 | controller | WARNING  [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:05:51.701884 | controller | INFO     [upload-helm-chart > converge] Executing
2026-02-16 21:05:52.560358 | controller |
2026-02-16 21:05:52.560380 | controller | PLAY [Converge] ****************************************************************
2026-02-16 21:05:52.560385 | controller |
2026-02-16 21:05:52.560388 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:05:52.560392 | controller | Monday 16 February 2026  21:05:52 +0000 (0:00:00.041)       0:00:00.041 *******
2026-02-16 21:05:53.836159 | 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-02-16 21:05:53.840988 | controller | ok: [controller]
2026-02-16 21:05:53.841104 | controller |
2026-02-16 21:05:53.841267 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:53.841397 | controller | Monday 16 February 2026  21:05:53 +0000 (0:00:01.288)       0:00:01.330 *******
2026-02-16 21:05:53.913950 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-02-16 21:05:53.914067 | controller |
2026-02-16 21:05:53.914185 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-02-16 21:05:53.914294 | controller | Monday 16 February 2026  21:05:53 +0000 (0:00:00.072)       0:00:01.403 *******
2026-02-16 21:05:54.548787 | controller | changed: [controller]
2026-02-16 21:05:54.548845 | controller |
2026-02-16 21:05:54.548960 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:54.549051 | controller | Monday 16 February 2026  21:05:54 +0000 (0:00:00.633)       0:00:02.036 *******
2026-02-16 21:05:54.581157 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-02-16 21:05:54.581204 | controller |
2026-02-16 21:05:54.581291 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-02-16 21:05:54.581401 | controller | Monday 16 February 2026  21:05:54 +0000 (0:00:00.033)       0:00:02.070 *******
2026-02-16 21:05:54.969683 | controller | changed: [controller]
2026-02-16 21:05:54.969741 | controller |
2026-02-16 21:05:54.969847 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-02-16 21:05:54.971711 | controller | Monday 16 February 2026  21:05:54 +0000 (0:00:00.388)       0:00:02.458 *******
2026-02-16 21:05:56.010544 | controller | changed: [controller]
2026-02-16 21:05:56.010623 | controller |
2026-02-16 21:05:56.010726 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-02-16 21:05:56.010817 | controller | Monday 16 February 2026  21:05:56 +0000 (0:00:01.040)       0:00:03.499 *******
2026-02-16 21:05:56.374187 | controller | ok: [controller]
2026-02-16 21:05:56.374252 | controller |
2026-02-16 21:05:56.374355 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-02-16 21:05:56.374446 | controller | Monday 16 February 2026  21:05:56 +0000 (0:00:00.362)       0:00:03.862 *******
2026-02-16 21:05:56.423983 | controller | skipping: [controller]
2026-02-16 21:05:56.424080 | controller |
2026-02-16 21:05:56.424185 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:05:56.424291 | controller | controller                 : ok=7    changed=3    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-02-16 21:05:56.424381 | controller |
2026-02-16 21:05:56.424476 | controller |
2026-02-16 21:05:56.424573 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:05:56.424670 | controller | Monday 16 February 2026  21:05:56 +0000 (0:00:00.051)       0:00:03.913 *******
2026-02-16 21:05:56.424752 | controller | ===============================================================================
2026-02-16 21:05:56.424835 | controller | Gathering Facts --------------------------------------------------------- 1.29s
2026-02-16 21:05:56.424927 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.04s
2026-02-16 21:05:56.425013 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.63s
2026-02-16 21:05:56.425106 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.39s
2026-02-16 21:05:56.425182 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.36s
2026-02-16 21:05:56.425269 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s
2026-02-16 21:05:56.425358 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s
2026-02-16 21:05:56.425445 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.03s
2026-02-16 21:05:56.504421 | controller | INFO     [upload-helm-chart > converge] Executed: Successful
2026-02-16 21:05:56.573994 | controller | INFO     [upload-helm-chart > idempotence] Executing
2026-02-16 21:05:57.416410 | controller |
2026-02-16 21:05:57.416695 | controller | PLAY [Converge] ****************************************************************
2026-02-16 21:05:57.416952 | controller |
2026-02-16 21:05:57.417234 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:05:57.417500 | controller | Monday 16 February 2026  21:05:57 +0000 (0:00:00.011)       0:00:00.011 *******
2026-02-16 21:05:58.385507 | 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-02-16 21:05:58.391625 | controller | ok: [controller]
2026-02-16 21:05:58.391635 | controller |
2026-02-16 21:05:58.391639 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:58.391643 | controller | Monday 16 February 2026  21:05:58 +0000 (0:00:00.974)       0:00:00.986 *******
2026-02-16 21:05:58.460017 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-02-16 21:05:58.460347 | controller |
2026-02-16 21:05:58.460647 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-02-16 21:05:58.460932 | controller | Monday 16 February 2026  21:05:58 +0000 (0:00:00.068)       0:00:01.054 *******
2026-02-16 21:05:59.162054 | controller | ok: [controller]
2026-02-16 21:05:59.162104 | controller |
2026-02-16 21:05:59.162110 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:59.162116 | controller | Monday 16 February 2026  21:05:59 +0000 (0:00:00.701)       0:00:01.756 *******
2026-02-16 21:05:59.205045 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-02-16 21:05:59.205103 | controller |
2026-02-16 21:05:59.205168 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-02-16 21:05:59.205259 | controller | Monday 16 February 2026  21:05:59 +0000 (0:00:00.043)       0:00:01.800 *******
2026-02-16 21:05:59.618055 | controller | ok: [controller]
2026-02-16 21:05:59.618079 | controller |
2026-02-16 21:05:59.618083 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-02-16 21:05:59.618088 | controller | Monday 16 February 2026  21:05:59 +0000 (0:00:00.411)       0:00:02.212 *******
2026-02-16 21:06:00.630098 | controller | ok: [controller]
2026-02-16 21:06:00.630123 | controller |
2026-02-16 21:06:00.630128 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-02-16 21:06:00.630132 | controller | Monday 16 February 2026  21:06:00 +0000 (0:00:01.009)       0:00:03.222 *******
2026-02-16 21:06:01.009887 | controller | ok: [controller]
2026-02-16 21:06:01.009938 | controller |
2026-02-16 21:06:01.009945 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-02-16 21:06:01.009950 | controller | Monday 16 February 2026  21:06:01 +0000 (0:00:00.380)       0:00:03.602 *******
2026-02-16 21:06:01.046265 | controller | skipping: [controller]
2026-02-16 21:06:01.051345 | controller |
2026-02-16 21:06:01.051361 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:06:01.051366 | controller | controller                 : ok=7    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-02-16 21:06:01.051370 | controller |
2026-02-16 21:06:01.051373 | controller |
2026-02-16 21:06:01.051376 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:06:01.051380 | controller | Monday 16 February 2026  21:06:01 +0000 (0:00:00.039)       0:00:03.641 *******
2026-02-16 21:06:01.051383 | controller | ===============================================================================
2026-02-16 21:06:01.051387 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.01s
2026-02-16 21:06:01.051391 | controller | Gathering Facts --------------------------------------------------------- 0.98s
2026-02-16 21:06:01.051394 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.70s
2026-02-16 21:06:01.051397 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s
2026-02-16 21:06:01.051401 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s
2026-02-16 21:06:01.051412 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s
2026-02-16 21:06:01.051415 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s
2026-02-16 21:06:01.051418 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s
2026-02-16 21:06:01.147259 | controller | INFO     [upload-helm-chart > idempotence] Executed: Successful
2026-02-16 21:06:01.153290 | controller | INFO     [upload-helm-chart > side_effect] Executing
2026-02-16 21:06:01.919920 | controller |
2026-02-16 21:06:01.920223 | controller | PLAY [Side effect] *************************************************************
2026-02-16 21:06:01.920459 | controller |
2026-02-16 21:06:01.920706 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:06:01.921002 | controller | Monday 16 February 2026  21:06:01 +0000 (0:00:00.010)       0:00:00.010 *******
2026-02-16 21:06:02.905211 | 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-02-16 21:06:02.909679 | controller | ok: [controller]
2026-02-16 21:06:02.909844 | controller |
2026-02-16 21:06:02.910077 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:06:02.910244 | controller | Monday 16 February 2026  21:06:02 +0000 (0:00:00.989)       0:00:01.000 *******
2026-02-16 21:06:02.985502 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-02-16 21:06:02.985523 | controller |
2026-02-16 21:06:02.985527 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-02-16 21:06:02.985531 | controller | Monday 16 February 2026  21:06:02 +0000 (0:00:00.073)       0:00:01.073 *******
2026-02-16 21:06:03.762324 | controller | changed: [controller]
2026-02-16 21:06:03.762505 | controller |
2026-02-16 21:06:03.762666 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:06:03.762810 | controller | Monday 16 February 2026  21:06:03 +0000 (0:00:00.778)       0:00:01.851 *******
2026-02-16 21:06:03.796092 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-02-16 21:06:03.796272 | controller |
2026-02-16 21:06:03.796425 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-02-16 21:06:03.796575 | controller | Monday 16 February 2026  21:06:03 +0000 (0:00:00.033)       0:00:01.885 *******
2026-02-16 21:06:04.228016 | controller | ok: [controller]
2026-02-16 21:06:04.228152 | controller |
2026-02-16 21:06:04.228285 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-02-16 21:06:04.228405 | controller | Monday 16 February 2026  21:06:04 +0000 (0:00:00.432)       0:00:02.318 *******
2026-02-16 21:06:05.222195 | controller | ok: [controller]
2026-02-16 21:06:05.222220 | controller |
2026-02-16 21:06:05.222226 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-02-16 21:06:05.222231 | controller | Monday 16 February 2026  21:06:05 +0000 (0:00:00.987)       0:00:03.305 *******
2026-02-16 21:06:05.619681 | controller | ok: [controller]
2026-02-16 21:06:05.619818 | controller |
2026-02-16 21:06:05.619948 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-02-16 21:06:05.620067 | controller | Monday 16 February 2026  21:06:05 +0000 (0:00:00.404)       0:00:03.709 *******
2026-02-16 21:06:05.907607 | controller | changed: [controller] => (item=/file)
2026-02-16 21:06:05.907746 | controller |
2026-02-16 21:06:05.907867 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:06:05.907995 | controller | controller                 : ok=8    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-02-16 21:06:05.908103 | controller |
2026-02-16 21:06:05.908207 | controller |
2026-02-16 21:06:05.908318 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:06:05.908467 | controller | Monday 16 February 2026  21:06:05 +0000 (0:00:00.288)       0:00:03.998 *******
2026-02-16 21:06:05.908589 | controller | ===============================================================================
2026-02-16 21:06:05.908701 | controller | Gathering Facts --------------------------------------------------------- 0.99s
2026-02-16 21:06:05.908819 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 0.99s
2026-02-16 21:06:05.908932 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.78s
2026-02-16 21:06:05.909045 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s
2026-02-16 21:06:05.909156 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s
2026-02-16 21:06:05.909273 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.29s
2026-02-16 21:06:05.909392 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s
2026-02-16 21:06:05.909508 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.03s
2026-02-16 21:06:05.990686 | controller | INFO     [upload-helm-chart > side_effect] Executed: Successful
2026-02-16 21:06:06.000767 | controller | INFO     [upload-helm-chart > verify] Executing
2026-02-16 21:06:06.782666 | controller |
2026-02-16 21:06:06.782951 | controller | PLAY [Verify] ******************************************************************
2026-02-16 21:06:06.783184 | controller |
2026-02-16 21:06:06.783429 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:06:06.783675 | controller | Monday 16 February 2026  21:06:06 +0000 (0:00:00.009)       0:00:00.009 *******
2026-02-16 21:06:08.770189 | 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-02-16 21:06:08.775229 | controller | ok: [controller]
2026-02-16 21:06:08.775338 | controller |
2026-02-16 21:06:08.775449 | controller | TASK [Stat file 1] *************************************************************
2026-02-16 21:06:08.775560 | controller | Monday 16 February 2026  21:06:08 +0000 (0:00:01.992)       0:00:02.001 *******
2026-02-16 21:06:09.146539 | controller | ok: [controller]
2026-02-16 21:06:09.146729 | controller |
2026-02-16 21:06:09.146933 | controller | TASK [Stat file 2] *************************************************************
2026-02-16 21:06:09.147131 | controller | Monday 16 February 2026  21:06:09 +0000 (0:00:00.370)       0:00:02.372 *******
2026-02-16 21:06:09.420677 | controller | ok: [controller]
2026-02-16 21:06:09.420787 | controller |
2026-02-16 21:06:09.420923 | controller | TASK [Stat removed file 1] *****************************************************
2026-02-16 21:06:09.421057 | controller | Monday 16 February 2026  21:06:09 +0000 (0:00:00.272)       0:00:02.645 *******
2026-02-16 21:06:09.677863 | controller | ok: [controller]
2026-02-16 21:06:09.678120 | controller |
2026-02-16 21:06:09.678253 | controller | TASK [Stat removed file 2] *****************************************************
2026-02-16 21:06:09.678381 | controller | Monday 16 February 2026  21:06:09 +0000 (0:00:00.258)       0:00:02.903 *******
2026-02-16 21:06:09.953874 | controller | ok: [controller]
2026-02-16 21:06:09.954026 | controller |
2026-02-16 21:06:09.954129 | controller | TASK [ansible.builtin.assert] **************************************************
2026-02-16 21:06:09.954225 | controller | Monday 16 February 2026  21:06:09 +0000 (0:00:00.276)       0:00:03.180 *******
2026-02-16 21:06:09.989749 | controller | ok: [controller] => {
2026-02-16 21:06:09.989784 | controller |     "changed": false,
2026-02-16 21:06:09.989794 | controller |     "msg": "All assertions passed"
2026-02-16 21:06:09.989803 | controller | }
2026-02-16 21:06:09.989812 | controller |
2026-02-16 21:06:09.989821 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:06:09.989830 | controller | controller                 : ok=6    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-02-16 21:06:09.989838 | controller |
2026-02-16 21:06:09.989857 | controller |
2026-02-16 21:06:09.989865 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:06:09.989873 | controller | Monday 16 February 2026  21:06:09 +0000 (0:00:00.029)       0:00:03.209 *******
2026-02-16 21:06:09.989881 | controller | ===============================================================================
2026-02-16 21:06:09.989889 | controller | Gathering Facts --------------------------------------------------------- 1.99s
2026-02-16 21:06:09.989897 | controller | Stat file 1 ------------------------------------------------------------- 0.37s
2026-02-16 21:06:09.989905 | controller | Stat removed file 2 ----------------------------------------------------- 0.28s
2026-02-16 21:06:09.989968 | controller | Stat file 2 ------------------------------------------------------------- 0.27s
2026-02-16 21:06:09.989978 | controller | Stat removed file 1 ----------------------------------------------------- 0.26s
2026-02-16 21:06:09.989986 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s
2026-02-16 21:06:10.061770 | controller | INFO     [upload-helm-chart > verify] Executed: Successful
2026-02-16 21:06:10.073020 | controller | INFO     [upload-helm-chart > cleanup] Executing
2026-02-16 21:06:10.073493 | controller | WARNING  [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:06:10.081249 | controller | INFO     [upload-helm-chart > destroy] Executing
2026-02-16 21:06:10.081388 | controller | WARNING  [upload-helm-chart > destroy] Skipping, '--destroy=never' requested.
2026-02-16 21:06:10.081509 | controller | INFO     [upload-helm-chart > destroy] Executed: Successful
2026-02-16 21:06:10.081838 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-02-16 21:06:10.363012 | controller | ok: Runtime: 0:00:38.993083
2026-02-16 21:06:10.369508 | 
2026-02-16 21:06:10.369557 | PLAY RECAP
2026-02-16 21:06:10.369604 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 21:06:10.369625 | 
2026-02-16 21:06:10.480733 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-16 21:06:10.483728 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 21:06:11.087388 | 
2026-02-16 21:06:11.087529 | PLAY [all]
2026-02-16 21:06:11.097625 | 
2026-02-16 21:06:11.097700 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 21:06:11.142941 | controller | skipping: Conditional result was False
2026-02-16 21:06:11.151595 | 
2026-02-16 21:06:11.151685 | TASK [fetch-output : Set log path for single node]
2026-02-16 21:06:11.184150 | controller | ok
2026-02-16 21:06:11.189528 | 
2026-02-16 21:06:11.189599 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 21:06:11.567494 | controller -> localhost | ok: "/var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/work/logs"
2026-02-16 21:06:11.766795 | controller -> localhost | changed: "/var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/work/artifacts"
2026-02-16 21:06:11.992464 | controller -> localhost | changed: "/var/lib/zuul/builds/0eb798a3c8e7473195a219a1c0f0cc65/work/docs"
2026-02-16 21:06:12.007034 | 
2026-02-16 21:06:12.007136 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 21:06:12.656939 | controller | changed: .d..t...... ./
2026-02-16 21:06:12.657375 | controller | changed: All items complete
2026-02-16 21:06:12.657435 | 
2026-02-16 21:06:13.118288 | controller | changed: .d..t...... ./
2026-02-16 21:06:13.629128 | controller | changed: .d..t...... ./
2026-02-16 21:06:13.645096 | 
2026-02-16 21:06:13.645237 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 21:06:14.067695 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006822
2026-02-16 21:06:14.335863 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.036308
2026-02-16 21:06:14.350833 | 
2026-02-16 21:06:14.350968 | PLAY [all]
2026-02-16 21:06:14.356559 | 
2026-02-16 21:06:14.356627 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 21:06:14.793802 | controller | changed
2026-02-16 21:06:14.799939 | 
2026-02-16 21:06:14.800010 | PLAY RECAP
2026-02-16 21:06:14.800068 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 21:06:14.800102 | 
2026-02-16 21:06:14.930466 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 21:06:14.933301 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 21:06:15.502958 | 
2026-02-16 21:06:15.503089 | PLAY [localhost]
2026-02-16 21:06:15.512692 | 
2026-02-16 21:06:15.512763 | TASK [Generate Zuul manifest]
2026-02-16 21:06:15.534343 | localhost | ok
2026-02-16 21:06:15.552112 | 
2026-02-16 21:06:15.552190 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 21:06:15.868311 | localhost | changed
2026-02-16 21:06:15.881441 | 
2026-02-16 21:06:15.881525 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 21:06:15.912911 | localhost | ok
2026-02-16 21:06:15.921328 | 
2026-02-16 21:06:15.921391 | TASK [Upload logs]
2026-02-16 21:06:15.942794 | localhost | ok
2026-02-16 21:06:16.005171 | 
2026-02-16 21:06:16.005332 | TASK [Set zuul-log-path fact]
2026-02-16 21:06:16.027118 | localhost | ok
2026-02-16 21:06:16.042445 | 
2026-02-16 21:06:16.042606 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 21:06:16.074549 | localhost | ok
2026-02-16 21:06:16.084746 | 
2026-02-16 21:06:16.084843 | TASK [upload-logs : Create log directories]
2026-02-16 21:06:16.511964 | localhost | changed
2026-02-16 21:06:16.518724 | 
2026-02-16 21:06:16.518796 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 21:06:16.943296 | localhost -> localhost | ok: Runtime: 0:00:00.005646
2026-02-16 21:06:16.948522 | 
2026-02-16 21:06:16.948589 | TASK [upload-logs : Upload logs to log server]
2026-02-16 21:06:17.435440 | localhost | Output suppressed because no_log was given
2026-02-16 21:06:17.440598 | 
2026-02-16 21:06:17.440691 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 21:06:17.484936 | localhost | skipping: Conditional result was False
2026-02-16 21:06:17.491333 | localhost | skipping: Conditional result was False
2026-02-16 21:06:17.502222 | 
2026-02-16 21:06:17.502400 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 21:06:17.544504 | localhost | skipping: Conditional result was False
2026-02-16 21:06:17.544879 | 
2026-02-16 21:06:17.548129 | localhost | skipping: Conditional result was False
2026-02-16 21:06:17.563432 | 
2026-02-16 21:06:17.563644 | LOOP [upload-logs : Upload console log and json output]