2026-03-17 19:59:27.333969 | Job console starting
2026-03-17 19:59:27.382314 | Updating git repos
2026-03-17 19:59:27.440856 | Cloning repos into workspace
2026-03-17 19:59:27.935703 | Restoring repo states
2026-03-17 19:59:27.950383 | Merging changes
2026-03-17 19:59:28.538135 | Checking out repos
2026-03-17 19:59:28.787926 | Preparing playbooks
2026-03-17 19:59:30.446733 | Running Ansible setup
2026-03-17 19:59:33.880646 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-17 19:59:34.442381 | 
2026-03-17 19:59:34.442823 | PLAY [localhost]
2026-03-17 19:59:34.450063 | 
2026-03-17 19:59:34.450130 | TASK [Gathering Facts]
2026-03-17 19:59:35.254847 | localhost | ok
2026-03-17 19:59:35.264762 | 
2026-03-17 19:59:35.264858 | TASK [Setup log path fact]
2026-03-17 19:59:35.284162 | localhost | ok
2026-03-17 19:59:35.297953 | 
2026-03-17 19:59:35.298038 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 19:59:35.326351 | localhost | ok
2026-03-17 19:59:35.333924 | 
2026-03-17 19:59:35.334009 | TASK [emit-job-header : Print job information]
2026-03-17 19:59:35.373700 | # Job Information
2026-03-17 19:59:35.373909 | Ansible Version: 2.16.16
2026-03-17 19:59:35.373979 | Job: ansible-collection-kubernetes-molecule-helm
2026-03-17 19:59:35.374029 | Pipeline: check
2026-03-17 19:59:35.374075 | Executor: 0a8996d2b663
2026-03-17 19:59:35.374118 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/240
2026-03-17 19:59:35.374152 | Event ID: 9474bf50-223b-11f1-9aa3-3704d885a9a9
2026-03-17 19:59:35.377732 | 
2026-03-17 19:59:35.377814 | LOOP [emit-job-header : Print node information]
2026-03-17 19:59:35.465116 | localhost | ok:
2026-03-17 19:59:35.465540 | localhost | # Node Information
2026-03-17 19:59:35.465639 | localhost | Inventory Hostname: controller
2026-03-17 19:59:35.465702 | localhost | Hostname: np0000159589
2026-03-17 19:59:35.465755 | localhost | Username: zuul
2026-03-17 19:59:35.465812 | localhost | Distro: Debian 13.3
2026-03-17 19:59:35.465863 | localhost | Provider: yul1
2026-03-17 19:59:35.465912 | localhost | Region: ca-ymq-1
2026-03-17 19:59:35.465960 | localhost | Label: debian-trixie
2026-03-17 19:59:35.466008 | localhost | Product Name: OpenStack Nova
2026-03-17 19:59:35.466055 | localhost | Interface IP: 199.19.213.171
2026-03-17 19:59:35.476881 | 
2026-03-17 19:59:35.477018 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-17 19:59:35.927412 | localhost -> localhost | changed
2026-03-17 19:59:35.935742 | 
2026-03-17 19:59:35.935807 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-17 19:59:36.767851 | localhost -> localhost | changed
2026-03-17 19:59:36.775737 | 
2026-03-17 19:59:36.775812 | PLAY [all]
2026-03-17 19:59:36.782980 | 
2026-03-17 19:59:36.783042 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-17 19:59:37.029488 | controller -> localhost | ok
2026-03-17 19:59:37.037582 | 
2026-03-17 19:59:37.040015 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-17 19:59:37.074435 | controller | ok
2026-03-17 19:59:37.087125 | controller | included: /var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-17 19:59:37.442221 | 
2026-03-17 19:59:37.442368 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-17 19:59:38.333652 | controller -> localhost | Generating public/private rsa key pair.
2026-03-17 19:59:38.333841 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/work/8e803a5005e84ca48e98181841d8251f_id_rsa
2026-03-17 19:59:38.333884 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/work/8e803a5005e84ca48e98181841d8251f_id_rsa.pub
2026-03-17 19:59:38.333916 | controller -> localhost | The key fingerprint is:
2026-03-17 19:59:38.333945 | controller -> localhost | SHA256:HsiQ/neK34yHYdtxKA0O5rtIuDdHpgyWCeUd7alvZT0 zuul-build-sshkey
2026-03-17 19:59:38.333986 | controller -> localhost | The key's randomart image is:
2026-03-17 19:59:38.334017 | controller -> localhost | +---[RSA 3072]----+
2026-03-17 19:59:38.334055 | controller -> localhost | |     .           |
2026-03-17 19:59:38.334086 | controller -> localhost | |  . ...          |
2026-03-17 19:59:38.334114 | controller -> localhost | | o .oo .         |
2026-03-17 19:59:38.334142 | controller -> localhost | |. ...o=..        |
2026-03-17 19:59:38.334170 | controller -> localhost | | . o.+ooS+ .     |
2026-03-17 19:59:38.334197 | controller -> localhost | |  =...+.B.E .    |
2026-03-17 19:59:38.334224 | controller -> localhost | | ..o.=.=o*.+     |
2026-03-17 19:59:38.334252 | controller -> localhost | |   o=.=oo*o      |
2026-03-17 19:59:38.334283 | controller -> localhost | |  ...+oo+.o      |
2026-03-17 19:59:38.334311 | controller -> localhost | +----[SHA256]-----+
2026-03-17 19:59:38.334374 | controller -> localhost | ok: Runtime: 0:00:00.453736
2026-03-17 19:59:38.340137 | 
2026-03-17 19:59:38.340201 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-17 19:59:38.372093 | controller | ok
2026-03-17 19:59:38.382036 | controller | included: /var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-17 19:59:38.389623 | 
2026-03-17 19:59:38.389683 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-17 19:59:38.414254 | controller | skipping: Conditional result was False
2026-03-17 19:59:38.569782 | 
2026-03-17 19:59:38.569951 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-17 19:59:39.080516 | controller | changed
2026-03-17 19:59:39.087331 | 
2026-03-17 19:59:39.087400 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-17 19:59:39.301232 | controller | ok
2026-03-17 19:59:39.306801 | 
2026-03-17 19:59:39.306868 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-17 19:59:39.831449 | controller | changed
2026-03-17 19:59:39.894101 | 
2026-03-17 19:59:39.894241 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-17 19:59:40.407961 | controller | changed
2026-03-17 19:59:40.413754 | 
2026-03-17 19:59:40.413875 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-17 19:59:40.437669 | controller | skipping: Conditional result was False
2026-03-17 19:59:40.446071 | 
2026-03-17 19:59:40.446186 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-17 19:59:40.845254 | controller -> localhost | changed
2026-03-17 19:59:40.861015 | 
2026-03-17 19:59:40.861082 | TASK [add-build-sshkey : Add back temp key]
2026-03-17 19:59:41.135601 | controller -> localhost | Identity added: /var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/work/8e803a5005e84ca48e98181841d8251f_id_rsa (zuul-build-sshkey)
2026-03-17 19:59:41.135816 | controller -> localhost | ok: Runtime: 0:00:00.013266
2026-03-17 19:59:41.141131 | 
2026-03-17 19:59:41.141214 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-17 19:59:41.465609 | controller | ok
2026-03-17 19:59:41.470489 | 
2026-03-17 19:59:41.470572 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-17 19:59:41.494747 | controller | skipping: Conditional result was False
2026-03-17 19:59:41.506351 | 
2026-03-17 19:59:41.507820 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-17 19:59:41.862037 | controller | ok
2026-03-17 19:59:41.868527 | 
2026-03-17 19:59:41.868635 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-17 19:59:42.919201 | controller | Output suppressed because no_log was given
2026-03-17 19:59:42.926309 | 
2026-03-17 19:59:42.926374 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-17 19:59:43.133551 | controller | ok: "logs"
2026-03-17 19:59:43.133889 | controller | ok: All items complete
2026-03-17 19:59:43.133938 | 
2026-03-17 19:59:43.308524 | controller | ok: "artifacts"
2026-03-17 19:59:43.496516 | controller | ok: "docs"
2026-03-17 19:59:43.507292 | 
2026-03-17 19:59:43.507539 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-17 19:59:43.743324 | controller | changed: "logs"
2026-03-17 19:59:43.930823 | controller | changed: "artifacts"
2026-03-17 19:59:44.113935 | controller | changed: "docs"
2026-03-17 19:59:44.133974 | 
2026-03-17 19:59:44.134131 | PLAY RECAP
2026-03-17 19:59:44.134203 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-17 19:59:44.134246 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 19:59:44.134277 | 
2026-03-17 19:59:44.251825 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-17 19:59:44.254822 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-17 19:59:44.796927 | 
2026-03-17 19:59:44.797431 | PLAY [all]
2026-03-17 19:59:44.807795 | 
2026-03-17 19:59:44.807895 | TASK [setup-uv : Extract archive]
2026-03-17 19:59:47.101827 | controller | changed
2026-03-17 19:59:47.110922 | 
2026-03-17 19:59:47.111002 | TASK [setup-uv : Print version]
2026-03-17 19:59:47.698354 | controller | uv 0.8.13
2026-03-17 19:59:47.653573 | controller | ok: Runtime: 0:00:00.014337
2026-03-17 19:59:47.660584 | 
2026-03-17 19:59:47.660635 | PLAY RECAP
2026-03-17 19:59:47.660684 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 19:59:47.660715 | 
2026-03-17 19:59:47.761443 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-17 19:59:47.764229 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-03-17 19:59:48.346119 | 
2026-03-17 19:59:48.346265 | PLAY [all]
2026-03-17 19:59:48.356741 | 
2026-03-17 19:59:48.356828 | TASK [Update apt cache]
2026-03-17 19:59:52.745381 | controller | ok
2026-03-17 19:59:52.751320 | 
2026-03-17 19:59:52.751397 | TASK [Install "jq" for log collection]
2026-03-17 19:59:54.869680 | controller | changed
2026-03-17 19:59:54.872320 | 
2026-03-17 19:59:54.872387 | PLAY RECAP
2026-03-17 19:59:54.872449 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 19:59:54.872478 | 
2026-03-17 19:59:54.986502 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-03-17 19:59:54.989274 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-17 19:59:55.531683 | 
2026-03-17 19:59:55.531840 | PLAY [all]
2026-03-17 19:59:55.542383 | 
2026-03-17 19:59:55.542465 | TASK [Copy inventory file for Zuul]
2026-03-17 19:59:57.535327 | controller | changed
2026-03-17 19:59:57.542029 | 
2026-03-17 19:59:57.542138 | TASK [Switch "ansible_host" to private IP]
2026-03-17 19:59:57.855585 | controller | changed: 1 replacements made
2026-03-17 19:59:57.861858 | 
2026-03-17 19:59:57.861954 | TASK [Run Molecule scenario]
2026-03-17 19:59:58.350958 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-03-17 19:59:58.351065 | controller | Creating virtual environment at: .venv
2026-03-17 19:59:58.607769 | controller | Downloading cryptography (4.3MiB)
2026-03-17 19:59:58.609028 | controller | Downloading pygments (1.2MiB)
2026-03-17 19:59:58.609406 | controller | Downloading ansible-core (2.3MiB)
2026-03-17 19:59:58.790377 | controller |  Downloading cryptography
2026-03-17 19:59:58.850295 | controller |  Downloading pygments
2026-03-17 19:59:59.033203 | controller |  Downloading ansible-core
2026-03-17 19:59:59.078470 | controller | Installed 36 packages in 44ms
2026-03-17 19:59:59.750227 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-03-17 19:59:59.750273 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-03-17 20:00:00.594843 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-03-17 20:00:00.594882 | controller | INFO     default scenario not found, disabling shared state.
2026-03-17 20:00:00.595058 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-03-17 20:00:00.595134 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-03-17 20:00:46.591307 | controller | INFO     [helm > dependency] Executing
2026-03-17 20:00:46.591469 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-03-17 20:00:46.591653 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-03-17 20:00:46.591772 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-03-17 20:00:46.597236 | controller | INFO     [helm > cleanup] Executing
2026-03-17 20:00:46.597542 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-17 20:00:46.602684 | controller | INFO     [helm > destroy] Executing
2026-03-17 20:00:46.602747 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-03-17 20:00:46.602802 | controller | INFO     [helm > destroy] Executed: Successful
2026-03-17 20:00:46.607944 | controller | INFO     [helm > syntax] Executing
2026-03-17 20:00:47.484587 | controller |
2026-03-17 20:00:47.484628 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-03-17 20:00:47.541022 | controller | INFO     [helm > syntax] Executed: Successful
2026-03-17 20:00:47.551705 | controller | INFO     [helm > create] Executing
2026-03-17 20:00:47.553129 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-17 20:00:47.558312 | controller | INFO     [helm > prepare] Executing
2026-03-17 20:00:48.383326 | controller |
2026-03-17 20:00:48.383349 | controller | PLAY [Prepare] *****************************************************************
2026-03-17 20:00:48.383353 | controller |
2026-03-17 20:00:48.383356 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 20:00:48.383361 | controller | Tuesday 17 March 2026  20:00:48 +0000 (0:00:00.028)       0:00:00.028 *********
2026-03-17 20:00:49.620958 | 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-17 20:00:49.626855 | controller | ok: [controller]
2026-03-17 20:00:49.627021 | controller |
2026-03-17 20:00:49.627215 | controller | TASK [Install Debian packages] *************************************************
2026-03-17 20:00:49.627411 | controller | Tuesday 17 March 2026  20:00:49 +0000 (0:00:01.246)       0:00:01.275 *********
2026-03-17 20:00:50.953764 | controller | ok: [controller]
2026-03-17 20:00:50.953862 | controller |
2026-03-17 20:00:50.954080 | controller | PLAY RECAP *********************************************************************
2026-03-17 20:00:50.954305 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-03-17 20:00:50.954483 | controller |
2026-03-17 20:00:50.954660 | controller |
2026-03-17 20:00:50.954857 | controller | TASKS RECAP ********************************************************************
2026-03-17 20:00:50.955052 | controller | Tuesday 17 March 2026  20:00:50 +0000 (0:00:01.327)       0:00:02.602 *********
2026-03-17 20:00:50.955271 | controller | ===============================================================================
2026-03-17 20:00:50.955478 | controller | Install Debian packages ------------------------------------------------- 1.33s
2026-03-17 20:00:50.955693 | controller | Gathering Facts --------------------------------------------------------- 1.25s
2026-03-17 20:00:51.032567 | controller | INFO     [helm > prepare] Executed: Successful
2026-03-17 20:00:51.042060 | controller | INFO     [helm > converge] Executing
2026-03-17 20:00:51.872536 | controller |
2026-03-17 20:00:51.872765 | controller | PLAY [Converge] ****************************************************************
2026-03-17 20:00:51.872894 | controller |
2026-03-17 20:00:51.873138 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 20:00:51.873275 | controller | Tuesday 17 March 2026  20:00:51 +0000 (0:00:00.012)       0:00:00.012 *********
2026-03-17 20:00:52.909068 | 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-17 20:00:52.915069 | controller | ok: [controller]
2026-03-17 20:00:52.915229 | controller |
2026-03-17 20:00:52.915423 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-03-17 20:00:52.915610 | controller | Tuesday 17 March 2026  20:00:52 +0000 (0:00:01.042)       0:00:01.055 *********
2026-03-17 20:00:53.374336 | controller | ok: [controller]
2026-03-17 20:00:53.374433 | controller |
2026-03-17 20:00:53.374580 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-03-17 20:00:53.374767 | controller | Tuesday 17 March 2026  20:00:53 +0000 (0:00:00.458)       0:00:01.514 *********
2026-03-17 20:00:54.385209 | controller | ok: [controller]
2026-03-17 20:00:54.385343 | controller |
2026-03-17 20:00:54.385572 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-03-17 20:00:54.385762 | controller | Tuesday 17 March 2026  20:00:54 +0000 (0:00:01.010)       0:00:02.525 *********
2026-03-17 20:00:54.799645 | controller | changed: [controller]
2026-03-17 20:00:54.799763 | controller |
2026-03-17 20:00:54.799963 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-03-17 20:00:54.800158 | controller | Tuesday 17 March 2026  20:00:54 +0000 (0:00:00.412)       0:00:02.937 *********
2026-03-17 20:00:54.839626 | controller | ok: [controller] => {
2026-03-17 20:00:54.839835 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-03-17 20:00:54.840023 | controller | }
2026-03-17 20:00:54.840196 | controller |
2026-03-17 20:00:54.840409 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-03-17 20:00:54.840649 | controller | Tuesday 17 March 2026  20:00:54 +0000 (0:00:00.042)       0:00:02.979 *********
2026-03-17 20:00:55.798578 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually
2026-03-17 20:00:55.807119 | controller | changed: [controller]
2026-03-17 20:00:55.807294 | controller |
2026-03-17 20:00:55.807504 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-03-17 20:00:55.807698 | controller | Tuesday 17 March 2026  20:00:55 +0000 (0:00:00.967)       0:00:03.947 *********
2026-03-17 20:00:57.779129 | controller | changed: [controller]
2026-03-17 20:00:57.779166 | controller |
2026-03-17 20:00:57.779175 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-03-17 20:00:57.779183 | controller | Tuesday 17 March 2026  20:00:57 +0000 (0:00:01.970)       0:00:05.918 *********
2026-03-17 20:00:58.261990 | controller | ok: [controller]
2026-03-17 20:00:58.262030 | controller |
2026-03-17 20:00:58.262040 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-03-17 20:00:58.262048 | controller | Tuesday 17 March 2026  20:00:58 +0000 (0:00:00.483)       0:00:06.401 *********
2026-03-17 20:00:58.652968 | controller | changed: [controller]
2026-03-17 20:00:58.653233 | controller |
2026-03-17 20:00:58.653486 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-03-17 20:00:58.653725 | controller | Tuesday 17 March 2026  20:00:58 +0000 (0:00:00.391)       0:00:06.792 *********
2026-03-17 20:00:59.083878 | controller | ok: [controller]
2026-03-17 20:00:59.084943 | controller |
2026-03-17 20:00:59.085174 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-03-17 20:00:59.085365 | controller | Tuesday 17 March 2026  20:00:59 +0000 (0:00:00.431)       0:00:07.223 *********
2026-03-17 20:00:59.372226 | controller | changed: [controller]
2026-03-17 20:00:59.372399 | controller |
2026-03-17 20:00:59.372667 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-03-17 20:00:59.372858 | controller | Tuesday 17 March 2026  20:00:59 +0000 (0:00:00.287)       0:00:07.511 *********
2026-03-17 20:00:59.664118 | controller | ok: [controller]
2026-03-17 20:00:59.664293 | controller |
2026-03-17 20:00:59.664610 | controller | TASK [Install plugin] **********************************************************
2026-03-17 20:00:59.664802 | controller | Tuesday 17 March 2026  20:00:59 +0000 (0:00:00.292)       0:00:07.803 *********
2026-03-17 20:00:59.715413 | controller | included: vexxhost.containers.download_artifact for controller
2026-03-17 20:00:59.715598 | controller |
2026-03-17 20:00:59.715798 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-03-17 20:00:59.715983 | controller | Tuesday 17 March 2026  20:00:59 +0000 (0:00:00.051)       0:00:07.855 *********
2026-03-17 20:00:59.758506 | controller | ok: [controller] => {
2026-03-17 20:00:59.758728 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-03-17 20:00:59.758910 | controller | }
2026-03-17 20:00:59.759080 | controller |
2026-03-17 20:00:59.759275 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-03-17 20:00:59.759466 | controller | Tuesday 17 March 2026  20:00:59 +0000 (0:00:00.042)       0:00:07.898 *********
2026-03-17 20:01:00.516404 | controller | changed: [controller]
2026-03-17 20:01:00.516523 | controller |
2026-03-17 20:01:00.517587 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-03-17 20:01:00.517605 | controller | Tuesday 17 March 2026  20:01:00 +0000 (0:00:00.755)       0:00:08.654 *********
2026-03-17 20:01:02.499999 | controller | changed: [controller]
2026-03-17 20:01:02.500183 | controller |
2026-03-17 20:01:02.500341 | controller | PLAY RECAP *********************************************************************
2026-03-17 20:01:02.500543 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-03-17 20:01:02.500687 | controller |
2026-03-17 20:01:02.500824 | controller |
2026-03-17 20:01:02.500969 | controller | TASKS RECAP ********************************************************************
2026-03-17 20:01:02.501121 | controller | Tuesday 17 March 2026  20:01:02 +0000 (0:00:01.986)       0:00:10.640 *********
2026-03-17 20:01:02.501263 | controller | ===============================================================================
2026-03-17 20:01:02.501414 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.99s
2026-03-17 20:01:02.501599 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.97s
2026-03-17 20:01:02.501774 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2026-03-17 20:01:02.501926 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.01s
2026-03-17 20:01:02.502074 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.97s
2026-03-17 20:01:02.502222 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.76s
2026-03-17 20:01:02.502369 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s
2026-03-17 20:01:02.502516 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.46s
2026-03-17 20:01:02.502664 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s
2026-03-17 20:01:02.502813 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s
2026-03-17 20:01:02.502970 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-03-17 20:01:02.503125 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-03-17 20:01:02.503278 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s
2026-03-17 20:01:02.503423 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-03-17 20:01:02.503570 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-03-17 20:01:02.503716 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-03-17 20:01:02.596576 | controller | INFO     [helm > converge] Executed: Successful
2026-03-17 20:01:02.606927 | controller | INFO     [helm > idempotence] Executing
2026-03-17 20:01:03.427022 | controller |
2026-03-17 20:01:03.427378 | controller | PLAY [Converge] ****************************************************************
2026-03-17 20:01:03.427611 | controller |
2026-03-17 20:01:03.427852 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 20:01:03.428092 | controller | Tuesday 17 March 2026  20:01:03 +0000 (0:00:00.012)       0:00:00.012 *********
2026-03-17 20:01:04.460871 | 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-17 20:01:04.469258 | controller | ok: [controller]
2026-03-17 20:01:04.469278 | controller |
2026-03-17 20:01:04.469287 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-03-17 20:01:04.469295 | controller | Tuesday 17 March 2026  20:01:04 +0000 (0:00:01.041)       0:00:01.053 *********
2026-03-17 20:01:04.892845 | controller | ok: [controller]
2026-03-17 20:01:04.893105 | controller |
2026-03-17 20:01:04.893365 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-03-17 20:01:04.893614 | controller | Tuesday 17 March 2026  20:01:04 +0000 (0:00:00.424)       0:00:01.478 *********
2026-03-17 20:01:05.855515 | controller | ok: [controller]
2026-03-17 20:01:05.855797 | controller |
2026-03-17 20:01:05.856067 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-03-17 20:01:05.856323 | controller | Tuesday 17 March 2026  20:01:05 +0000 (0:00:00.962)       0:00:02.440 *********
2026-03-17 20:01:06.276948 | controller | ok: [controller]
2026-03-17 20:01:06.277208 | controller |
2026-03-17 20:01:06.277465 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-03-17 20:01:06.277712 | controller | Tuesday 17 March 2026  20:01:06 +0000 (0:00:00.421)       0:00:02.862 *********
2026-03-17 20:01:06.321386 | controller | ok: [controller] => {
2026-03-17 20:01:06.321664 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-03-17 20:01:06.321903 | controller | }
2026-03-17 20:01:06.322126 | controller |
2026-03-17 20:01:06.322370 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-03-17 20:01:06.322609 | controller | Tuesday 17 March 2026  20:01:06 +0000 (0:00:00.044)       0:00:02.906 *********
2026-03-17 20:01:06.855449 | controller | ok: [controller]
2026-03-17 20:01:06.855689 | controller |
2026-03-17 20:01:06.855867 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-03-17 20:01:06.855979 | controller | Tuesday 17 March 2026  20:01:06 +0000 (0:00:00.533)       0:00:03.440 *********
2026-03-17 20:01:08.514605 | controller | ok: [controller]
2026-03-17 20:01:08.514898 | controller |
2026-03-17 20:01:08.515164 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-03-17 20:01:08.515412 | controller | Tuesday 17 March 2026  20:01:08 +0000 (0:00:01.659)       0:00:05.099 *********
2026-03-17 20:01:09.040081 | controller | ok: [controller]
2026-03-17 20:01:09.040190 | controller |
2026-03-17 20:01:09.040201 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-03-17 20:01:09.041566 | controller | Tuesday 17 March 2026  20:01:09 +0000 (0:00:00.523)       0:00:05.623 *********
2026-03-17 20:01:09.464870 | controller | ok: [controller]
2026-03-17 20:01:09.465005 | controller |
2026-03-17 20:01:09.465124 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-03-17 20:01:09.465237 | controller | Tuesday 17 March 2026  20:01:09 +0000 (0:00:00.425)       0:00:06.048 *********
2026-03-17 20:01:09.916156 | controller | ok: [controller]
2026-03-17 20:01:09.919394 | controller |
2026-03-17 20:01:09.919404 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-03-17 20:01:09.919409 | controller | Tuesday 17 March 2026  20:01:09 +0000 (0:00:00.452)       0:00:06.500 *********
2026-03-17 20:01:10.201892 | controller | ok: [controller]
2026-03-17 20:01:10.201972 | controller |
2026-03-17 20:01:10.202107 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-03-17 20:01:10.202226 | controller | Tuesday 17 March 2026  20:01:10 +0000 (0:00:00.286)       0:00:06.787 *********
2026-03-17 20:01:10.482241 | controller | ok: [controller]
2026-03-17 20:01:10.482334 | controller |
2026-03-17 20:01:10.482456 | controller | TASK [Install plugin] **********************************************************
2026-03-17 20:01:10.482576 | controller | Tuesday 17 March 2026  20:01:10 +0000 (0:00:00.280)       0:00:07.067 *********
2026-03-17 20:01:10.535696 | controller | included: vexxhost.containers.download_artifact for controller
2026-03-17 20:01:10.535755 | controller |
2026-03-17 20:01:10.535858 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-03-17 20:01:10.535944 | controller | Tuesday 17 March 2026  20:01:10 +0000 (0:00:00.052)       0:00:07.119 *********
2026-03-17 20:01:10.575764 | controller | ok: [controller] => {
2026-03-17 20:01:10.575986 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-03-17 20:01:10.576583 | controller | }
2026-03-17 20:01:10.576608 | controller |
2026-03-17 20:01:10.576617 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-03-17 20:01:10.576800 | controller | Tuesday 17 March 2026  20:01:10 +0000 (0:00:00.040)       0:00:07.160 *********
2026-03-17 20:01:10.980773 | controller | ok: [controller]
2026-03-17 20:01:10.980809 | controller |
2026-03-17 20:01:10.980819 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-03-17 20:01:10.980827 | controller | Tuesday 17 March 2026  20:01:10 +0000 (0:00:00.404)       0:00:07.564 *********
2026-03-17 20:01:12.590528 | controller | ok: [controller]
2026-03-17 20:01:12.590803 | controller |
2026-03-17 20:01:12.591048 | controller | PLAY RECAP *********************************************************************
2026-03-17 20:01:12.591309 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-03-17 20:01:12.591533 | controller |
2026-03-17 20:01:12.591750 | controller |
2026-03-17 20:01:12.591983 | controller | TASKS RECAP ********************************************************************
2026-03-17 20:01:12.592225 | controller | Tuesday 17 March 2026  20:01:12 +0000 (0:00:01.611)       0:00:09.176 *********
2026-03-17 20:01:12.592498 | controller | ===============================================================================
2026-03-17 20:01:12.592777 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.66s
2026-03-17 20:01:12.593019 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.61s
2026-03-17 20:01:12.593254 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2026-03-17 20:01:12.593494 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.96s
2026-03-17 20:01:12.593734 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.53s
2026-03-17 20:01:12.593974 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s
2026-03-17 20:01:12.594257 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s
2026-03-17 20:01:12.594513 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.43s
2026-03-17 20:01:12.594752 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.42s
2026-03-17 20:01:12.594998 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s
2026-03-17 20:01:12.595227 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.40s
2026-03-17 20:01:12.595384 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s
2026-03-17 20:01:12.595541 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.28s
2026-03-17 20:01:12.595689 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-03-17 20:01:12.595840 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-03-17 20:01:12.595988 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-03-17 20:01:12.676193 | controller | INFO     [helm > idempotence] Executed: Successful
2026-03-17 20:01:12.687806 | controller | INFO     [helm > side_effect] Executing
2026-03-17 20:01:12.688064 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-17 20:01:12.693252 | controller | INFO     [helm > verify] Executing
2026-03-17 20:01:13.479151 | controller |
2026-03-17 20:01:13.479286 | controller | PLAY [Verify] ******************************************************************
2026-03-17 20:01:13.479496 | controller |
2026-03-17 20:01:13.479636 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 20:01:13.479818 | controller | Tuesday 17 March 2026  20:01:13 +0000 (0:00:00.009)       0:00:00.009 *********
2026-03-17 20:01:15.567555 | 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-17 20:01:15.573378 | controller | ok: [controller]
2026-03-17 20:01:15.573566 | controller |
2026-03-17 20:01:15.573775 | controller | TASK [Get the current Helm version] ********************************************
2026-03-17 20:01:15.573981 | controller | Tuesday 17 March 2026  20:01:15 +0000 (0:00:02.094)       0:00:02.103 *********
2026-03-17 20:01:16.007737 | controller | changed: [controller]
2026-03-17 20:01:16.007911 | controller |
2026-03-17 20:01:16.008108 | controller | TASK [Assert Helm version] *****************************************************
2026-03-17 20:01:16.008308 | controller | Tuesday 17 March 2026  20:01:16 +0000 (0:00:00.433)       0:00:02.537 *********
2026-03-17 20:01:16.039547 | controller | ok: [controller] => {
2026-03-17 20:01:16.039761 | controller |     "changed": false,
2026-03-17 20:01:16.039952 | controller |     "msg": "All assertions passed"
2026-03-17 20:01:16.040130 | controller | }
2026-03-17 20:01:16.040314 | controller |
2026-03-17 20:01:16.040528 | controller | PLAY RECAP *********************************************************************
2026-03-17 20:01:16.040747 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-03-17 20:01:16.040905 | controller |
2026-03-17 20:01:16.041064 | controller |
2026-03-17 20:01:16.041243 | controller | TASKS RECAP ********************************************************************
2026-03-17 20:01:16.041429 | controller | Tuesday 17 March 2026  20:01:16 +0000 (0:00:00.032)       0:00:02.570 *********
2026-03-17 20:01:16.041599 | controller | ===============================================================================
2026-03-17 20:01:16.041777 | controller | Gathering Facts --------------------------------------------------------- 2.09s
2026-03-17 20:01:16.041959 | controller | Get the current Helm version -------------------------------------------- 0.43s
2026-03-17 20:01:16.042137 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-03-17 20:01:16.172650 | controller | INFO     [helm > verify] Executed: Successful
2026-03-17 20:01:16.184787 | controller | INFO     [helm > cleanup] Executing
2026-03-17 20:01:16.185378 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-17 20:01:16.192140 | controller | INFO     [helm > destroy] Executing
2026-03-17 20:01:16.192165 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-03-17 20:01:16.192178 | controller | INFO     [helm > destroy] Executed: Successful
2026-03-17 20:01:16.192525 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-03-17 20:01:16.491723 | controller | ok: Runtime: 0:01:17.997586
2026-03-17 20:01:16.496582 | 
2026-03-17 20:01:16.496628 | PLAY RECAP
2026-03-17 20:01:16.496670 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 20:01:16.496692 | 
2026-03-17 20:01:16.591766 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-17 20:01:16.593644 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-03-17 20:01:17.145074 | 
2026-03-17 20:01:17.145182 | PLAY [all]
2026-03-17 20:01:17.155861 | 
2026-03-17 20:01:17.155946 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-03-17 20:01:17.537951 | controller | changed
2026-03-17 20:01:17.542347 | 
2026-03-17 20:01:17.542438 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-03-17 20:01:17.910700 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-17 20:01:17.910852 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-17 20:01:17.918059 | controller | environment: line 1: kubectl: command not found
2026-03-17 20:01:17.919652 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-17 20:01:17.920556 | controller | environment: line 1: kubectl: command not found
2026-03-17 20:01:17.923026 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-17 20:01:17.923219 | controller | environment: line 1: kubectl: command not found
2026-03-17 20:01:17.925379 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-17 20:01:17.926652 | controller | environment: line 1: kubectl: command not found
2026-03-17 20:01:17.928164 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-17 20:01:17.929949 | controller | environment: line 1: kubectl: command not found
2026-03-17 20:01:17.931322 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-17 20:01:18.070947 | controller | ok: Runtime: 0:00:00.034812
2026-03-17 20:01:18.078138 | 
2026-03-17 20:01:18.078203 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-03-17 20:01:18.301867 | controller | changed
2026-03-17 20:01:18.308085 | 
2026-03-17 20:01:18.308182 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-03-17 20:01:18.542560 | controller | environment: line 5: kubectl: command not found
2026-03-17 20:01:18.543911 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-17 20:01:18.544433 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-17 20:01:18.544810 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-17 20:01:18.839203 | controller | ok: Runtime: 0:00:00.011895
2026-03-17 20:01:18.848994 | 
2026-03-17 20:01:18.849067 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-03-17 20:01:19.510864 | controller | changed:
2026-03-17 20:01:19.511057 | controller | created directory /var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/work/logs/controller
2026-03-17 20:01:19.511101 | controller | cd+++++++++ objects/
2026-03-17 20:01:19.511134 | controller | cd+++++++++ objects/cluster/
2026-03-17 20:01:19.511167 | controller | cd+++++++++ objects/namespaced/
2026-03-17 20:01:19.521267 | 
2026-03-17 20:01:19.521368 | TASK [gather-pod-logs : creating directory for pod logs]
2026-03-17 20:01:19.767037 | controller | changed
2026-03-17 20:01:19.772861 | 
2026-03-17 20:01:19.772928 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-03-17 20:01:20.008379 | controller | changed
2026-03-17 20:01:20.014326 | 
2026-03-17 20:01:20.014411 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-03-17 20:01:20.249161 | controller | environment: line 3: kubectl: command not found
2026-03-17 20:01:20.542762 | controller | ok: Runtime: 0:00:00.011607
2026-03-17 20:01:20.550346 | 
2026-03-17 20:01:20.550410 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-03-17 20:01:21.077643 | controller | changed:
2026-03-17 20:01:21.077883 | controller | cd+++++++++ pod-logs/
2026-03-17 20:01:21.077942 | controller | cd+++++++++ pod-logs/failed-pods/
2026-03-17 20:01:21.086073 | 
2026-03-17 20:01:21.086131 | LOOP [helm-release-status : creating directory for helm release status]
2026-03-17 20:01:21.317816 | controller | changed: "values"
2026-03-17 20:01:21.517853 | controller | changed: "releases"
2026-03-17 20:01:21.527605 | 
2026-03-17 20:01:21.527752 | TASK [helm-release-status : Gather get release status for helm charts]
2026-03-17 20:01:21.758627 | controller | /bin/bash: line 3: kubectl: command not found
2026-03-17 20:01:22.060943 | controller | ok: Runtime: 0:00:00.008710
2026-03-17 20:01:22.066881 | 
2026-03-17 20:01:22.066978 | TASK [helm-release-status : Downloads logs to executor]
2026-03-17 20:01:22.594792 | controller | changed:
2026-03-17 20:01:22.594950 | controller | cd+++++++++ helm/
2026-03-17 20:01:22.594976 | controller | cd+++++++++ helm/releases/
2026-03-17 20:01:22.594999 | controller | cd+++++++++ helm/values/
2026-03-17 20:01:22.599472 | 
2026-03-17 20:01:22.599520 | PLAY RECAP
2026-03-17 20:01:22.599562 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 20:01:22.599584 | 
2026-03-17 20:01:22.704012 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-03-17 20:01:22.706795 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-17 20:01:23.297997 | 
2026-03-17 20:01:23.298109 | PLAY [all]
2026-03-17 20:01:23.307844 | 
2026-03-17 20:01:23.307962 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-17 20:01:23.353019 | controller | skipping: Conditional result was False
2026-03-17 20:01:23.363237 | 
2026-03-17 20:01:23.363377 | TASK [fetch-output : Set log path for single node]
2026-03-17 20:01:23.407798 | controller | ok
2026-03-17 20:01:23.413543 | 
2026-03-17 20:01:23.413618 | LOOP [fetch-output : Ensure local output dirs]
2026-03-17 20:01:23.789895 | controller -> localhost | ok: "/var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/work/logs"
2026-03-17 20:01:24.017461 | controller -> localhost | changed: "/var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/work/artifacts"
2026-03-17 20:01:24.219015 | controller -> localhost | changed: "/var/lib/zuul/builds/8e803a5005e84ca48e98181841d8251f/work/docs"
2026-03-17 20:01:24.231154 | 
2026-03-17 20:01:24.231309 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-17 20:01:24.917649 | controller | changed: .d..t...... ./
2026-03-17 20:01:24.917885 | controller | changed: All items complete
2026-03-17 20:01:24.917925 | 
2026-03-17 20:01:25.395824 | controller | changed: .d..t...... ./
2026-03-17 20:01:25.884615 | controller | changed: .d..t...... ./
2026-03-17 20:01:25.905155 | 
2026-03-17 20:01:25.905286 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-17 20:01:26.299645 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007040
2026-03-17 20:01:26.528211 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008918
2026-03-17 20:01:26.542151 | 
2026-03-17 20:01:26.542299 | PLAY [all]
2026-03-17 20:01:26.547668 | 
2026-03-17 20:01:26.547752 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-17 20:01:26.998964 | controller | changed
2026-03-17 20:01:27.003761 | 
2026-03-17 20:01:27.003815 | PLAY RECAP
2026-03-17 20:01:27.003862 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-17 20:01:27.003885 | 
2026-03-17 20:01:27.150787 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-17 20:01:27.153832 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-17 20:01:27.715746 | 
2026-03-17 20:01:27.715858 | PLAY [localhost]
2026-03-17 20:01:27.725463 | 
2026-03-17 20:01:27.725555 | TASK [Generate Zuul manifest]
2026-03-17 20:01:27.743522 | localhost | ok
2026-03-17 20:01:27.759012 | 
2026-03-17 20:01:27.759146 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-17 20:01:28.068882 | localhost | changed
2026-03-17 20:01:28.078582 | 
2026-03-17 20:01:28.078654 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-17 20:01:28.107835 | localhost | ok
2026-03-17 20:01:28.116011 | 
2026-03-17 20:01:28.116089 | TASK [Upload logs]
2026-03-17 20:01:28.133691 | localhost | ok
2026-03-17 20:01:28.191000 | 
2026-03-17 20:01:28.191093 | TASK [Set zuul-log-path fact]
2026-03-17 20:01:28.209666 | localhost | ok
2026-03-17 20:01:28.223538 | 
2026-03-17 20:01:28.223604 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 20:01:28.254936 | localhost | ok
2026-03-17 20:01:28.263049 | 
2026-03-17 20:01:28.263113 | TASK [upload-logs : Create log directories]
2026-03-17 20:01:28.679919 | localhost | changed
2026-03-17 20:01:28.686131 | 
2026-03-17 20:01:28.686198 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-17 20:01:29.069748 | localhost -> localhost | ok: Runtime: 0:00:00.004082
2026-03-17 20:01:29.074962 | 
2026-03-17 20:01:29.075026 | TASK [upload-logs : Upload logs to log server]
2026-03-17 20:01:29.498530 | localhost | Output suppressed because no_log was given
2026-03-17 20:01:29.503518 | 
2026-03-17 20:01:29.503608 | LOOP [upload-logs : Compress console log and json output]
2026-03-17 20:01:29.543109 | localhost | skipping: Conditional result was False
2026-03-17 20:01:29.550101 | localhost | skipping: Conditional result was False
2026-03-17 20:01:29.567461 | 
2026-03-17 20:01:29.567597 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-17 20:01:29.602594 | localhost | skipping: Conditional result was False
2026-03-17 20:01:29.603041 | 
2026-03-17 20:01:29.606974 | localhost | skipping: Conditional result was False
2026-03-17 20:01:29.622783 | 
2026-03-17 20:01:29.622976 | LOOP [upload-logs : Upload console log and json output]