2026-02-16 21:04:54.523318 | Job console starting
2026-02-16 21:04:54.534175 | Updating git repos
2026-02-16 21:04:54.565128 | Cloning repos into workspace
2026-02-16 21:04:54.612766 | Restoring repo states
2026-02-16 21:04:54.625140 | Merging changes
2026-02-16 21:04:55.271272 | Checking out repos
2026-02-16 21:04:55.318486 | Preparing playbooks
2026-02-16 21:04:56.716179 | Running Ansible setup
2026-02-16 21:05:01.421886 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 21:05:02.039763 | 
2026-02-16 21:05:02.039955 | PLAY [localhost]
2026-02-16 21:05:02.048811 | 
2026-02-16 21:05:02.048975 | TASK [Gathering Facts]
2026-02-16 21:05:03.020243 | localhost | ok
2026-02-16 21:05:03.027049 | 
2026-02-16 21:05:03.027141 | TASK [Setup log path fact]
2026-02-16 21:05:03.045119 | localhost | ok
2026-02-16 21:05:03.056769 | 
2026-02-16 21:05:03.095633 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 21:05:03.129395 | localhost | ok
2026-02-16 21:05:03.137159 | 
2026-02-16 21:05:03.137235 | TASK [emit-job-header : Print job information]
2026-02-16 21:05:03.180550 | # Job Information
2026-02-16 21:05:03.180738 | Ansible Version: 2.16.15
2026-02-16 21:05:03.180783 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart
2026-02-16 21:05:03.180814 | Pipeline: check
2026-02-16 21:05:03.180841 | Executor: 3a2793d2bd32
2026-02-16 21:05:03.180867 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/229
2026-02-16 21:05:03.180897 | Event ID: 05f21470-0b7b-11f1-8965-6c0a04ea4243
2026-02-16 21:05:03.184781 | 
2026-02-16 21:05:03.184874 | LOOP [emit-job-header : Print node information]
2026-02-16 21:05:03.267449 | localhost | ok:
2026-02-16 21:05:03.268229 | localhost | # Node Information
2026-02-16 21:05:03.268269 | localhost | Inventory Hostname: controller
2026-02-16 21:05:03.268294 | localhost | Hostname: np0000155744
2026-02-16 21:05:03.268316 | localhost | Username: zuul
2026-02-16 21:05:03.268339 | localhost | Distro: Debian 13.3
2026-02-16 21:05:03.268360 | localhost | Provider: yul1
2026-02-16 21:05:03.268381 | localhost | Region: ca-ymq-1
2026-02-16 21:05:03.268401 | localhost | Label: debian-trixie
2026-02-16 21:05:03.268420 | localhost | Product Name: OpenStack Nova
2026-02-16 21:05:03.268440 | localhost | Interface IP: 199.204.45.156
2026-02-16 21:05:03.282841 | 
2026-02-16 21:05:03.283006 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 21:05:03.747651 | localhost -> localhost | changed
2026-02-16 21:05:03.757864 | 
2026-02-16 21:05:03.758018 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 21:05:04.777585 | localhost -> localhost | changed
2026-02-16 21:05:04.790009 | 
2026-02-16 21:05:04.790476 | PLAY [all]
2026-02-16 21:05:04.799832 | 
2026-02-16 21:05:04.799965 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 21:05:05.032858 | controller -> localhost | ok
2026-02-16 21:05:05.043916 | 
2026-02-16 21:05:05.044086 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 21:05:05.079480 | controller | ok
2026-02-16 21:05:05.106863 | controller | included: /var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 21:05:05.114844 | 
2026-02-16 21:05:05.114934 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 21:05:06.834144 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 21:05:06.834332 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/work/a5c7897db80a475b88cd1841c9f7905f_id_rsa
2026-02-16 21:05:06.834362 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/work/a5c7897db80a475b88cd1841c9f7905f_id_rsa.pub
2026-02-16 21:05:06.834385 | controller -> localhost | The key fingerprint is:
2026-02-16 21:05:06.834406 | controller -> localhost | SHA256:uOjIpFQhzxkmTIL/+StgSLYqWo3QyxudZWKvDi+hAqk zuul-build-sshkey
2026-02-16 21:05:06.834463 | controller -> localhost | The key's randomart image is:
2026-02-16 21:05:06.834484 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 21:05:06.834513 | controller -> localhost | |o.               |
2026-02-16 21:05:06.834534 | controller -> localhost | |=                |
2026-02-16 21:05:06.834555 | controller -> localhost | | = +             |
2026-02-16 21:05:06.834576 | controller -> localhost | | +B +  .         |
2026-02-16 21:05:06.834596 | controller -> localhost | |+oo*o.+ S        |
2026-02-16 21:05:06.834616 | controller -> localhost | |++=*oB .         |
2026-02-16 21:05:06.834636 | controller -> localhost | |++Oo=.o          |
2026-02-16 21:05:06.834656 | controller -> localhost | |E=.B...          |
2026-02-16 21:05:06.834678 | controller -> localhost | |* +o=...         |
2026-02-16 21:05:06.834716 | controller -> localhost | +----[SHA256]-----+
2026-02-16 21:05:06.834762 | controller -> localhost | ok: Runtime: 0:00:01.290669
2026-02-16 21:05:06.838861 | 
2026-02-16 21:05:06.838924 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 21:05:06.880727 | controller | ok
2026-02-16 21:05:06.891227 | controller | included: /var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 21:05:06.898395 | 
2026-02-16 21:05:06.898461 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 21:05:06.923001 | controller | skipping: Conditional result was False
2026-02-16 21:05:06.933513 | 
2026-02-16 21:05:06.933602 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 21:05:07.472105 | controller | changed
2026-02-16 21:05:07.484800 | 
2026-02-16 21:05:07.484892 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 21:05:07.680895 | controller | ok
2026-02-16 21:05:07.686966 | 
2026-02-16 21:05:07.687047 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 21:05:08.227796 | controller | changed
2026-02-16 21:05:08.235886 | 
2026-02-16 21:05:08.236160 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 21:05:08.724086 | controller | changed
2026-02-16 21:05:08.736332 | 
2026-02-16 21:05:08.823728 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 21:05:08.841911 | controller | skipping: Conditional result was False
2026-02-16 21:05:08.848168 | 
2026-02-16 21:05:08.848251 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 21:05:09.220822 | controller -> localhost | changed
2026-02-16 21:05:09.231311 | 
2026-02-16 21:05:09.231388 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 21:05:09.527771 | controller -> localhost | Identity added: /var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/work/a5c7897db80a475b88cd1841c9f7905f_id_rsa (zuul-build-sshkey)
2026-02-16 21:05:09.528115 | controller -> localhost | ok: Runtime: 0:00:00.013469
2026-02-16 21:05:09.534180 | 
2026-02-16 21:05:09.534266 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 21:05:09.872981 | controller | ok
2026-02-16 21:05:09.876794 | 
2026-02-16 21:05:09.876861 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 21:05:09.911550 | controller | skipping: Conditional result was False
2026-02-16 21:05:09.923622 | 
2026-02-16 21:05:09.923764 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 21:05:10.299828 | controller | ok
2026-02-16 21:05:10.342851 | 
2026-02-16 21:05:10.342987 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 21:05:11.484169 | controller | Output suppressed because no_log was given
2026-02-16 21:05:11.494089 | 
2026-02-16 21:05:11.494318 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 21:05:11.720564 | controller | ok: "logs"
2026-02-16 21:05:11.720976 | controller | ok: All items complete
2026-02-16 21:05:11.721047 | 
2026-02-16 21:05:11.895970 | controller | ok: "artifacts"
2026-02-16 21:05:12.093995 | controller | ok: "docs"
2026-02-16 21:05:12.111430 | 
2026-02-16 21:05:12.111598 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 21:05:12.327603 | controller | changed: "logs"
2026-02-16 21:05:12.526952 | controller | changed: "artifacts"
2026-02-16 21:05:12.699164 | controller | changed: "docs"
2026-02-16 21:05:12.719764 | 
2026-02-16 21:05:12.720013 | PLAY RECAP
2026-02-16 21:05:12.720093 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 21:05:12.720134 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 21:05:12.720163 | 
2026-02-16 21:05:12.873493 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 21:05:12.875078 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-16 21:05:13.510485 | 
2026-02-16 21:05:13.510665 | PLAY [all]
2026-02-16 21:05:13.522236 | 
2026-02-16 21:05:13.522335 | TASK [setup-uv : Extract archive]
2026-02-16 21:05:15.786373 | controller | changed
2026-02-16 21:05:15.791517 | 
2026-02-16 21:05:15.791600 | TASK [setup-uv : Print version]
2026-02-16 21:05:16.188947 | controller | uv 0.8.13
2026-02-16 21:05:16.333031 | controller | ok: Runtime: 0:00:00.014814
2026-02-16 21:05:16.341175 | 
2026-02-16 21:05:16.341245 | PLAY RECAP
2026-02-16 21:05:16.341306 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 21:05:16.341340 | 
2026-02-16 21:05:16.497619 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-16 21:05:16.499151 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-16 21:05:17.081120 | 
2026-02-16 21:05:17.081264 | PLAY [all]
2026-02-16 21:05:17.091051 | 
2026-02-16 21:05:17.091124 | TASK [Copy inventory file for Zuul]
2026-02-16 21:05:18.053824 | controller | changed
2026-02-16 21:05:18.060061 | 
2026-02-16 21:05:18.060315 | TASK [Switch "ansible_host" to private IP]
2026-02-16 21:05:18.352246 | controller | changed: 1 replacements made
2026-02-16 21:05:18.356892 | 
2026-02-16 21:05:18.356978 | TASK [Run Molecule scenario]
2026-02-16 21:05:18.785112 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-02-16 21:05:18.785220 | controller | Creating virtual environment at: .venv
2026-02-16 21:05:18.825046 | controller | Downloading cryptography (4.3MiB)
2026-02-16 21:05:18.825307 | controller | Downloading pygments (1.2MiB)
2026-02-16 21:05:18.836043 | controller | Downloading ansible-core (2.3MiB)
2026-02-16 21:05:19.250303 | controller |  Downloading cryptography
2026-02-16 21:05:19.296580 | controller |  Downloading pygments
2026-02-16 21:05:19.400087 | controller |  Downloading ansible-core
2026-02-16 21:05:19.455043 | controller | Installed 36 packages in 54ms
2026-02-16 21:05:20.135671 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-02-16 21:05:20.135697 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-02-16 21:05:20.918512 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-02-16 21:05:20.918669 | controller | INFO     default scenario not found, disabling shared state.
2026-02-16 21:05:20.918866 | 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:20.918978 | controller | INFO     [upload-helm-chart > prerun] Performing prerun with role_name_check=0...
2026-02-16 21:05:37.946981 | controller | INFO     [upload-helm-chart > dependency] Executing
2026-02-16 21:05:37.947386 | controller | WARNING  [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml
2026-02-16 21:05:37.947752 | controller | WARNING  [upload-helm-chart > dependency] Missing collections requirements file: collections.yml
2026-02-16 21:05:37.947956 | controller | WARNING  [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-02-16 21:05:37.954338 | controller | INFO     [upload-helm-chart > cleanup] Executing
2026-02-16 21:05:37.954734 | controller | WARNING  [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:05:37.960030 | controller | INFO     [upload-helm-chart > destroy] Executing
2026-02-16 21:05:37.960126 | controller | WARNING  [upload-helm-chart > destroy] Skipping, '--destroy=never' requested.
2026-02-16 21:05:37.960236 | controller | INFO     [upload-helm-chart > destroy] Executed: Successful
2026-02-16 21:05:37.965927 | controller | INFO     [upload-helm-chart > syntax] Executing
2026-02-16 21:05:38.843554 | controller |
2026-02-16 21:05:38.843983 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml
2026-02-16 21:05:38.905733 | controller | INFO     [upload-helm-chart > syntax] Executed: Successful
2026-02-16 21:05:38.916344 | controller | INFO     [upload-helm-chart > create] Executing
2026-02-16 21:05:38.918431 | controller | WARNING  [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:05:38.924126 | controller | INFO     [upload-helm-chart > prepare] Executing
2026-02-16 21:05:38.924449 | controller | WARNING  [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:05:38.929696 | controller | INFO     [upload-helm-chart > converge] Executing
2026-02-16 21:05:39.734532 | controller |
2026-02-16 21:05:39.734974 | controller | PLAY [Converge] ****************************************************************
2026-02-16 21:05:39.734980 | controller |
2026-02-16 21:05:39.734983 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:05:39.734988 | controller | Monday 16 February 2026  21:05:39 +0000 (0:00:00.029)       0:00:00.029 *******
2026-02-16 21:05:40.909652 | 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:40.914380 | controller | ok: [controller]
2026-02-16 21:05:40.914494 | controller |
2026-02-16 21:05:40.914740 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:40.914862 | controller | Monday 16 February 2026  21:05:40 +0000 (0:00:01.180)       0:00:01.209 *******
2026-02-16 21:05:40.983673 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-02-16 21:05:40.984015 | controller |
2026-02-16 21:05:40.984282 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-02-16 21:05:40.984556 | controller | Monday 16 February 2026  21:05:40 +0000 (0:00:00.068)       0:00:01.278 *******
2026-02-16 21:05:41.639770 | controller | changed: [controller]
2026-02-16 21:05:41.639915 | controller |
2026-02-16 21:05:41.640044 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:41.640197 | controller | Monday 16 February 2026  21:05:41 +0000 (0:00:00.656)       0:00:01.934 *******
2026-02-16 21:05:41.675320 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-02-16 21:05:41.675629 | controller |
2026-02-16 21:05:41.675888 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-02-16 21:05:41.676136 | controller | Monday 16 February 2026  21:05:41 +0000 (0:00:00.035)       0:00:01.970 *******
2026-02-16 21:05:42.038600 | controller | changed: [controller]
2026-02-16 21:05:42.038626 | controller |
2026-02-16 21:05:42.038632 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-02-16 21:05:42.038637 | controller | Monday 16 February 2026  21:05:42 +0000 (0:00:00.362)       0:00:02.333 *******
2026-02-16 21:05:43.002600 | controller | changed: [controller]
2026-02-16 21:05:43.002656 | controller |
2026-02-16 21:05:43.002753 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-02-16 21:05:43.002838 | controller | Monday 16 February 2026  21:05:42 +0000 (0:00:00.952)       0:00:03.285 *******
2026-02-16 21:05:43.358717 | controller | ok: [controller]
2026-02-16 21:05:43.358789 | controller |
2026-02-16 21:05:43.358888 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-02-16 21:05:43.358977 | controller | Monday 16 February 2026  21:05:43 +0000 (0:00:00.366)       0:00:03.652 *******
2026-02-16 21:05:43.407086 | controller | skipping: [controller]
2026-02-16 21:05:43.407336 | controller |
2026-02-16 21:05:43.407579 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:05:43.407839 | controller | controller                 : ok=7    changed=3    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-02-16 21:05:43.408062 | controller |
2026-02-16 21:05:43.408279 | controller |
2026-02-16 21:05:43.408512 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:05:43.408753 | controller | Monday 16 February 2026  21:05:43 +0000 (0:00:00.048)       0:00:03.700 *******
2026-02-16 21:05:43.408981 | controller | ===============================================================================
2026-02-16 21:05:43.409251 | controller | Gathering Facts --------------------------------------------------------- 1.18s
2026-02-16 21:05:43.409505 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 0.95s
2026-02-16 21:05:43.409749 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.66s
2026-02-16 21:05:43.410004 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.37s
2026-02-16 21:05:43.410248 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.36s
2026-02-16 21:05:43.410498 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s
2026-02-16 21:05:43.410787 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s
2026-02-16 21:05:43.411041 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s
2026-02-16 21:05:43.487341 | controller | INFO     [upload-helm-chart > converge] Executed: Successful
2026-02-16 21:05:43.496377 | controller | INFO     [upload-helm-chart > idempotence] Executing
2026-02-16 21:05:44.278815 | controller |
2026-02-16 21:05:44.279096 | controller | PLAY [Converge] ****************************************************************
2026-02-16 21:05:44.279331 | controller |
2026-02-16 21:05:44.279585 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:05:44.279856 | controller | Monday 16 February 2026  21:05:44 +0000 (0:00:00.010)       0:00:00.010 *******
2026-02-16 21:05:45.241834 | 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:45.249338 | controller | ok: [controller]
2026-02-16 21:05:45.249457 | controller |
2026-02-16 21:05:45.249583 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:45.249700 | controller | Monday 16 February 2026  21:05:45 +0000 (0:00:00.970)       0:00:00.980 *******
2026-02-16 21:05:45.323075 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-02-16 21:05:45.323348 | controller |
2026-02-16 21:05:45.323642 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-02-16 21:05:45.323901 | controller | Monday 16 February 2026  21:05:45 +0000 (0:00:00.073)       0:00:01.054 *******
2026-02-16 21:05:45.983702 | controller | ok: [controller]
2026-02-16 21:05:45.983985 | controller |
2026-02-16 21:05:45.984295 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:45.984566 | controller | Monday 16 February 2026  21:05:45 +0000 (0:00:00.660)       0:00:01.714 *******
2026-02-16 21:05:46.023557 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-02-16 21:05:46.023836 | controller |
2026-02-16 21:05:46.024147 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-02-16 21:05:46.024485 | controller | Monday 16 February 2026  21:05:46 +0000 (0:00:00.039)       0:00:01.754 *******
2026-02-16 21:05:46.410220 | controller | ok: [controller]
2026-02-16 21:05:46.410245 | controller |
2026-02-16 21:05:46.410251 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-02-16 21:05:46.410257 | controller | Monday 16 February 2026  21:05:46 +0000 (0:00:00.383)       0:00:02.138 *******
2026-02-16 21:05:47.404927 | controller | ok: [controller]
2026-02-16 21:05:47.404958 | controller |
2026-02-16 21:05:47.404968 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-02-16 21:05:47.404977 | controller | Monday 16 February 2026  21:05:47 +0000 (0:00:00.996)       0:00:03.134 *******
2026-02-16 21:05:47.781967 | controller | ok: [controller]
2026-02-16 21:05:47.784893 | controller |
2026-02-16 21:05:47.784907 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-02-16 21:05:47.784912 | controller | Monday 16 February 2026  21:05:47 +0000 (0:00:00.378)       0:00:03.513 *******
2026-02-16 21:05:47.817944 | controller | skipping: [controller]
2026-02-16 21:05:47.818109 | controller |
2026-02-16 21:05:47.818274 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:05:47.818446 | controller | controller                 : ok=7    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-02-16 21:05:47.818660 | controller |
2026-02-16 21:05:47.818810 | controller |
2026-02-16 21:05:47.818965 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:05:47.819125 | controller | Monday 16 February 2026  21:05:47 +0000 (0:00:00.036)       0:00:03.549 *******
2026-02-16 21:05:47.819316 | controller | ===============================================================================
2026-02-16 21:05:47.819493 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.00s
2026-02-16 21:05:47.819614 | controller | Gathering Facts --------------------------------------------------------- 0.97s
2026-02-16 21:05:47.819726 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.66s
2026-02-16 21:05:47.819838 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.38s
2026-02-16 21:05:47.819950 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s
2026-02-16 21:05:47.820070 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s
2026-02-16 21:05:47.820185 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s
2026-02-16 21:05:47.820302 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s
2026-02-16 21:05:47.906934 | controller | INFO     [upload-helm-chart > idempotence] Executed: Successful
2026-02-16 21:05:47.917860 | controller | INFO     [upload-helm-chart > side_effect] Executing
2026-02-16 21:05:48.693232 | controller |
2026-02-16 21:05:48.693527 | controller | PLAY [Side effect] *************************************************************
2026-02-16 21:05:48.693796 | controller |
2026-02-16 21:05:48.694044 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:05:48.694292 | controller | Monday 16 February 2026  21:05:48 +0000 (0:00:00.010)       0:00:00.010 *******
2026-02-16 21:05:49.642232 | 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:49.642709 | controller | ok: [controller]
2026-02-16 21:05:49.642884 | controller |
2026-02-16 21:05:49.643088 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:49.643278 | controller | Monday 16 February 2026  21:05:49 +0000 (0:00:00.948)       0:00:00.958 *******
2026-02-16 21:05:49.706359 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-02-16 21:05:49.706531 | controller |
2026-02-16 21:05:49.706814 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-02-16 21:05:49.707004 | controller | Monday 16 February 2026  21:05:49 +0000 (0:00:00.064)       0:00:01.023 *******
2026-02-16 21:05:50.370082 | controller | changed: [controller]
2026-02-16 21:05:50.370145 | controller |
2026-02-16 21:05:50.370243 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-02-16 21:05:50.370330 | controller | Monday 16 February 2026  21:05:50 +0000 (0:00:00.663)       0:00:01.686 *******
2026-02-16 21:05:50.405016 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-02-16 21:05:50.405078 | controller |
2026-02-16 21:05:50.405179 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-02-16 21:05:50.405266 | controller | Monday 16 February 2026  21:05:50 +0000 (0:00:00.035)       0:00:01.722 *******
2026-02-16 21:05:50.761727 | controller | ok: [controller]
2026-02-16 21:05:50.761820 | controller |
2026-02-16 21:05:50.761980 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-02-16 21:05:50.762720 | controller | Monday 16 February 2026  21:05:50 +0000 (0:00:00.356)       0:00:02.078 *******
2026-02-16 21:05:51.718051 | controller | ok: [controller]
2026-02-16 21:05:51.718075 | controller |
2026-02-16 21:05:51.718082 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-02-16 21:05:51.718087 | controller | Monday 16 February 2026  21:05:51 +0000 (0:00:00.953)       0:00:03.031 *******
2026-02-16 21:05:52.077561 | controller | ok: [controller]
2026-02-16 21:05:52.077757 | controller |
2026-02-16 21:05:52.077936 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-02-16 21:05:52.078099 | controller | Monday 16 February 2026  21:05:52 +0000 (0:00:00.360)       0:00:03.392 *******
2026-02-16 21:05:52.359056 | controller | changed: [controller] => (item=/file)
2026-02-16 21:05:52.359166 | controller |
2026-02-16 21:05:52.359299 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:05:52.359438 | controller | controller                 : ok=8    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-02-16 21:05:52.359564 | controller |
2026-02-16 21:05:52.359665 | controller |
2026-02-16 21:05:52.359813 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:05:52.359949 | controller | Monday 16 February 2026  21:05:52 +0000 (0:00:00.282)       0:00:03.674 *******
2026-02-16 21:05:52.360064 | controller | ===============================================================================
2026-02-16 21:05:52.360193 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 0.95s
2026-02-16 21:05:52.360320 | controller | Gathering Facts --------------------------------------------------------- 0.95s
2026-02-16 21:05:52.360435 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.66s
2026-02-16 21:05:52.360557 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.36s
2026-02-16 21:05:52.360685 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.36s
2026-02-16 21:05:52.360827 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.28s
2026-02-16 21:05:52.360935 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.06s
2026-02-16 21:05:52.361057 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s
2026-02-16 21:05:52.434113 | controller | INFO     [upload-helm-chart > side_effect] Executed: Successful
2026-02-16 21:05:52.445083 | controller | INFO     [upload-helm-chart > verify] Executing
2026-02-16 21:05:53.213153 | controller |
2026-02-16 21:05:53.213177 | controller | PLAY [Verify] ******************************************************************
2026-02-16 21:05:53.213181 | controller |
2026-02-16 21:05:53.213184 | controller | TASK [Gathering Facts] *********************************************************
2026-02-16 21:05:53.213188 | controller | Monday 16 February 2026  21:05:53 +0000 (0:00:00.009)       0:00:00.009 *******
2026-02-16 21:05:54.239168 | 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:54.247155 | controller | ok: [controller]
2026-02-16 21:05:54.247223 | controller |
2026-02-16 21:05:54.247309 | controller | TASK [Stat file 1] *************************************************************
2026-02-16 21:05:54.247392 | controller | Monday 16 February 2026  21:05:54 +0000 (0:00:01.035)       0:00:01.045 *******
2026-02-16 21:05:54.645647 | controller | ok: [controller]
2026-02-16 21:05:54.645705 | controller |
2026-02-16 21:05:54.645796 | controller | TASK [Stat file 2] *************************************************************
2026-02-16 21:05:54.645886 | controller | Monday 16 February 2026  21:05:54 +0000 (0:00:00.398)       0:00:01.443 *******
2026-02-16 21:05:54.925082 | controller | ok: [controller]
2026-02-16 21:05:54.925123 | controller |
2026-02-16 21:05:54.925216 | controller | TASK [Stat removed file 1] *****************************************************
2026-02-16 21:05:54.925311 | controller | Monday 16 February 2026  21:05:54 +0000 (0:00:00.279)       0:00:01.723 *******
2026-02-16 21:05:55.225630 | controller | ok: [controller]
2026-02-16 21:05:55.225666 | controller |
2026-02-16 21:05:55.225671 | controller | TASK [Stat removed file 2] *****************************************************
2026-02-16 21:05:55.225685 | controller | Monday 16 February 2026  21:05:55 +0000 (0:00:00.296)       0:00:02.020 *******
2026-02-16 21:05:55.500136 | controller | ok: [controller]
2026-02-16 21:05:55.500350 | controller |
2026-02-16 21:05:55.500562 | controller | TASK [ansible.builtin.assert] **************************************************
2026-02-16 21:05:55.500730 | controller | Monday 16 February 2026  21:05:55 +0000 (0:00:00.278)       0:00:02.298 *******
2026-02-16 21:05:55.533562 | controller | ok: [controller] => {
2026-02-16 21:05:55.533591 | controller |     "changed": false,
2026-02-16 21:05:55.533597 | controller |     "msg": "All assertions passed"
2026-02-16 21:05:55.533603 | controller | }
2026-02-16 21:05:55.533608 | controller |
2026-02-16 21:05:55.533613 | controller | PLAY RECAP *********************************************************************
2026-02-16 21:05:55.533619 | controller | controller                 : ok=6    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-02-16 21:05:55.533623 | controller |
2026-02-16 21:05:55.533637 | controller |
2026-02-16 21:05:55.533641 | controller | TASKS RECAP ********************************************************************
2026-02-16 21:05:55.533646 | controller | Monday 16 February 2026  21:05:55 +0000 (0:00:00.029)       0:00:02.327 *******
2026-02-16 21:05:55.533651 | controller | ===============================================================================
2026-02-16 21:05:55.533655 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2026-02-16 21:05:55.533660 | controller | Stat file 1 ------------------------------------------------------------- 0.40s
2026-02-16 21:05:55.533664 | controller | Stat removed file 1 ----------------------------------------------------- 0.30s
2026-02-16 21:05:55.533669 | controller | Stat file 2 ------------------------------------------------------------- 0.28s
2026-02-16 21:05:55.533673 | controller | Stat removed file 2 ----------------------------------------------------- 0.28s
2026-02-16 21:05:55.533678 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s
2026-02-16 21:05:55.621100 | controller | INFO     [upload-helm-chart > verify] Executed: Successful
2026-02-16 21:05:55.631385 | controller | INFO     [upload-helm-chart > cleanup] Executing
2026-02-16 21:05:55.631861 | controller | WARNING  [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-02-16 21:05:55.638144 | controller | INFO     [upload-helm-chart > destroy] Executing
2026-02-16 21:05:55.638258 | controller | WARNING  [upload-helm-chart > destroy] Skipping, '--destroy=never' requested.
2026-02-16 21:05:55.638344 | controller | INFO     [upload-helm-chart > destroy] Executed: Successful
2026-02-16 21:05:55.638636 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-02-16 21:05:55.913118 | controller | ok: Runtime: 0:00:36.989980
2026-02-16 21:05:55.919071 | 
2026-02-16 21:05:55.919150 | PLAY RECAP
2026-02-16 21:05:55.919212 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 21:05:55.919247 | 
2026-02-16 21:05:56.057706 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-16 21:05:56.059171 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 21:05:56.688426 | 
2026-02-16 21:05:56.688591 | PLAY [all]
2026-02-16 21:05:56.705055 | 
2026-02-16 21:05:56.705210 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 21:05:56.750380 | controller | skipping: Conditional result was False
2026-02-16 21:05:56.755742 | 
2026-02-16 21:05:56.755856 | TASK [fetch-output : Set log path for single node]
2026-02-16 21:05:56.796834 | controller | ok
2026-02-16 21:05:56.802781 | 
2026-02-16 21:05:56.802892 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 21:05:57.215324 | controller -> localhost | ok: "/var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/work/logs"
2026-02-16 21:05:57.417222 | controller -> localhost | changed: "/var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/work/artifacts"
2026-02-16 21:05:57.652179 | controller -> localhost | changed: "/var/lib/zuul/builds/a5c7897db80a475b88cd1841c9f7905f/work/docs"
2026-02-16 21:05:57.673127 | 
2026-02-16 21:05:57.673317 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 21:05:58.339721 | controller | changed: .d..t...... ./
2026-02-16 21:05:58.339961 | controller | changed: All items complete
2026-02-16 21:05:58.340000 | 
2026-02-16 21:05:58.806461 | controller | changed: .d..t...... ./
2026-02-16 21:05:59.269979 | controller | changed: .d..t...... ./
2026-02-16 21:05:59.282948 | 
2026-02-16 21:05:59.283086 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 21:05:59.702880 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006355
2026-02-16 21:05:59.916982 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006385
2026-02-16 21:05:59.934939 | 
2026-02-16 21:05:59.935065 | PLAY [all]
2026-02-16 21:05:59.940046 | 
2026-02-16 21:05:59.940125 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 21:06:00.375266 | controller | changed
2026-02-16 21:06:00.385530 | 
2026-02-16 21:06:00.385957 | PLAY RECAP
2026-02-16 21:06:00.386032 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 21:06:00.386055 | 
2026-02-16 21:06:00.550124 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 21:06:00.552872 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 21:06:01.155900 | 
2026-02-16 21:06:01.156033 | PLAY [localhost]
2026-02-16 21:06:01.165578 | 
2026-02-16 21:06:01.165652 | TASK [Generate Zuul manifest]
2026-02-16 21:06:01.182934 | localhost | ok
2026-02-16 21:06:01.196641 | 
2026-02-16 21:06:01.196724 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 21:06:01.505846 | localhost | changed
2026-02-16 21:06:01.516743 | 
2026-02-16 21:06:01.516829 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 21:06:01.547013 | localhost | ok
2026-02-16 21:06:01.553643 | 
2026-02-16 21:06:01.553711 | TASK [Upload logs]
2026-02-16 21:06:01.572310 | localhost | ok
2026-02-16 21:06:01.633005 | 
2026-02-16 21:06:01.633135 | TASK [Set zuul-log-path fact]
2026-02-16 21:06:01.654820 | localhost | ok
2026-02-16 21:06:01.670697 | 
2026-02-16 21:06:01.670822 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 21:06:01.703180 | localhost | ok
2026-02-16 21:06:01.711149 | 
2026-02-16 21:06:01.711247 | TASK [upload-logs : Create log directories]
2026-02-16 21:06:02.146900 | localhost | changed
2026-02-16 21:06:02.154028 | 
2026-02-16 21:06:02.154110 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 21:06:02.579324 | localhost -> localhost | ok: Runtime: 0:00:00.005148
2026-02-16 21:06:02.586880 | 
2026-02-16 21:06:02.587027 | TASK [upload-logs : Upload logs to log server]
2026-02-16 21:06:03.054105 | localhost | Output suppressed because no_log was given
2026-02-16 21:06:03.058106 | 
2026-02-16 21:06:03.058170 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 21:06:03.106932 | localhost | skipping: Conditional result was False
2026-02-16 21:06:03.116576 | localhost | skipping: Conditional result was False
2026-02-16 21:06:03.131251 | 
2026-02-16 21:06:03.131412 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 21:06:03.177691 | localhost | skipping: Conditional result was False
2026-02-16 21:06:03.178200 | 
2026-02-16 21:06:03.181101 | localhost | skipping: Conditional result was False
2026-02-16 21:06:03.197488 | 
2026-02-16 21:06:03.197696 | LOOP [upload-logs : Upload console log and json output]