2026-03-25 09:42:44.948721 | Job console starting
2026-03-25 09:42:44.967122 | Updating git repos
2026-03-25 09:42:45.009521 | Cloning repos into workspace
2026-03-25 09:42:45.067747 | Restoring repo states
2026-03-25 09:42:45.078535 | Merging changes
2026-03-25 09:42:45.624308 | Checking out repos
2026-03-25 09:42:45.896017 | Preparing playbooks
2026-03-25 09:42:51.628190 | Running Ansible setup
2026-03-25 09:42:55.126851 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-25 09:42:55.699756 | 
2026-03-25 09:42:55.699872 | PLAY [localhost]
2026-03-25 09:42:55.707085 | 
2026-03-25 09:42:55.707151 | TASK [Gathering Facts]
2026-03-25 09:42:56.534238 | localhost | ok
2026-03-25 09:42:56.546110 | 
2026-03-25 09:42:56.546233 | TASK [Setup log path fact]
2026-03-25 09:42:56.564028 | localhost | ok
2026-03-25 09:42:56.591095 | 
2026-03-25 09:42:56.591209 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-25 09:42:56.617975 | localhost | ok
2026-03-25 09:42:56.624402 | 
2026-03-25 09:42:56.624495 | TASK [emit-job-header : Print job information]
2026-03-25 09:42:56.665727 | # Job Information
2026-03-25 09:42:56.665955 | Ansible Version: 2.16.16
2026-03-25 09:42:56.666025 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart
2026-03-25 09:42:56.666076 | Pipeline: check
2026-03-25 09:42:56.666121 | Executor: 0a8996d2b663
2026-03-25 09:42:56.666165 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/243
2026-03-25 09:42:56.666213 | Event ID: af0541c0-282e-11f1-87bc-eda92f783d0b
2026-03-25 09:42:56.671009 | 
2026-03-25 09:42:56.671079 | LOOP [emit-job-header : Print node information]
2026-03-25 09:42:56.751041 | localhost | ok:
2026-03-25 09:42:56.751743 | localhost | # Node Information
2026-03-25 09:42:56.751811 | localhost | Inventory Hostname: controller
2026-03-25 09:42:56.751861 | localhost | Hostname: np0000160503
2026-03-25 09:42:56.751907 | localhost | Username: zuul
2026-03-25 09:42:56.751960 | localhost | Distro: Debian 13.4
2026-03-25 09:42:56.752006 | localhost | Provider: yul1
2026-03-25 09:42:56.752050 | localhost | Region: ca-ymq-1
2026-03-25 09:42:56.752092 | localhost | Label: debian-trixie
2026-03-25 09:42:56.752134 | localhost | Product Name: OpenStack Nova
2026-03-25 09:42:56.752176 | localhost | Interface IP: 199.19.213.122
2026-03-25 09:42:56.767030 | 
2026-03-25 09:42:56.767156 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-25 09:42:57.124880 | localhost -> localhost | changed
2026-03-25 09:42:57.133844 | 
2026-03-25 09:42:57.133981 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-25 09:42:57.971151 | localhost -> localhost | changed
2026-03-25 09:42:57.980583 | 
2026-03-25 09:42:57.980666 | PLAY [all]
2026-03-25 09:42:57.988094 | 
2026-03-25 09:42:57.988169 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-25 09:42:58.222689 | controller -> localhost | ok
2026-03-25 09:42:58.231185 | 
2026-03-25 09:42:58.231268 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-25 09:42:58.265005 | controller | ok
2026-03-25 09:42:58.279439 | controller | included: /var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-25 09:42:58.283898 | 
2026-03-25 09:42:58.283957 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-25 09:42:59.868713 | controller -> localhost | Generating public/private rsa key pair.
2026-03-25 09:42:59.869020 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/work/ffdccfc22ea54d018a873d62f2adfc1f_id_rsa
2026-03-25 09:42:59.869097 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/work/ffdccfc22ea54d018a873d62f2adfc1f_id_rsa.pub
2026-03-25 09:42:59.869153 | controller -> localhost | The key fingerprint is:
2026-03-25 09:42:59.869207 | controller -> localhost | SHA256:AOLhDuPOE+byUMCBVW6UyJ16yAYQejw11XfmqMWreoA zuul-build-sshkey
2026-03-25 09:42:59.869279 | controller -> localhost | The key's randomart image is:
2026-03-25 09:42:59.869333 | controller -> localhost | +---[RSA 3072]----+
2026-03-25 09:42:59.869393 | controller -> localhost | |==++*+..         |
2026-03-25 09:42:59.869447 | controller -> localhost | |=++*+o  . . o    |
2026-03-25 09:42:59.869552 | controller -> localhost | |=+*oo .  o =     |
2026-03-25 09:42:59.869608 | controller -> localhost | |.*=o.  .  + .    |
2026-03-25 09:42:59.869658 | controller -> localhost | | =o. .  So .     |
2026-03-25 09:42:59.869706 | controller -> localhost | |=.. E . . .      |
2026-03-25 09:42:59.869755 | controller -> localhost | |o=     . .       |
2026-03-25 09:42:59.869803 | controller -> localhost | |.o.     o        |
2026-03-25 09:42:59.869858 | controller -> localhost | |  .   .o         |
2026-03-25 09:42:59.869907 | controller -> localhost | +----[SHA256]-----+
2026-03-25 09:42:59.870011 | controller -> localhost | ok: Runtime: 0:00:01.164955
2026-03-25 09:42:59.874366 | 
2026-03-25 09:42:59.874429 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-25 09:42:59.905663 | controller | ok
2026-03-25 09:42:59.913269 | controller | included: /var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-25 09:42:59.920138 | 
2026-03-25 09:42:59.920200 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-25 09:42:59.944001 | controller | skipping: Conditional result was False
2026-03-25 09:42:59.952794 | 
2026-03-25 09:42:59.952947 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-25 09:43:00.509189 | controller | changed
2026-03-25 09:43:00.514310 | 
2026-03-25 09:43:00.514411 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-25 09:43:00.727492 | controller | ok
2026-03-25 09:43:00.731603 | 
2026-03-25 09:43:00.731666 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-25 09:43:01.259570 | controller | changed
2026-03-25 09:43:01.265242 | 
2026-03-25 09:43:01.265332 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-25 09:43:01.815966 | controller | changed
2026-03-25 09:43:01.822221 | 
2026-03-25 09:43:01.822288 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-25 09:43:01.845016 | controller | skipping: Conditional result was False
2026-03-25 09:43:01.853819 | 
2026-03-25 09:43:01.853958 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-25 09:43:02.193484 | controller -> localhost | changed
2026-03-25 09:43:02.204712 | 
2026-03-25 09:43:02.204777 | TASK [add-build-sshkey : Add back temp key]
2026-03-25 09:43:02.466904 | controller -> localhost | Identity added: /var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/work/ffdccfc22ea54d018a873d62f2adfc1f_id_rsa (zuul-build-sshkey)
2026-03-25 09:43:02.467099 | controller -> localhost | ok: Runtime: 0:00:00.012698
2026-03-25 09:43:02.471984 | 
2026-03-25 09:43:02.472062 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-25 09:43:02.801431 | controller | ok
2026-03-25 09:43:02.808081 | 
2026-03-25 09:43:02.808146 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-25 09:43:02.831215 | controller | skipping: Conditional result was False
2026-03-25 09:43:02.845424 | 
2026-03-25 09:43:02.845542 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-25 09:43:03.214268 | controller | ok
2026-03-25 09:43:03.219445 | 
2026-03-25 09:43:03.219527 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-25 09:43:04.327663 | controller | Output suppressed because no_log was given
2026-03-25 09:43:04.337929 | 
2026-03-25 09:43:04.338007 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-25 09:43:04.545082 | controller | ok: "logs"
2026-03-25 09:43:04.545742 | controller | ok: All items complete
2026-03-25 09:43:04.545804 | 
2026-03-25 09:43:04.751279 | controller | ok: "artifacts"
2026-03-25 09:43:04.937554 | controller | ok: "docs"
2026-03-25 09:43:04.953838 | 
2026-03-25 09:43:04.953999 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-25 09:43:05.175766 | controller | changed: "logs"
2026-03-25 09:43:05.364301 | controller | changed: "artifacts"
2026-03-25 09:43:05.554646 | controller | changed: "docs"
2026-03-25 09:43:05.565717 | 
2026-03-25 09:43:05.565860 | PLAY RECAP
2026-03-25 09:43:05.565920 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-25 09:43:05.565958 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-25 09:43:05.565988 | 
2026-03-25 09:43:05.692420 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-25 09:43:05.694785 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-25 09:43:06.237215 | 
2026-03-25 09:43:06.237327 | PLAY [all]
2026-03-25 09:43:06.246888 | 
2026-03-25 09:43:06.246960 | TASK [setup-uv : Extract archive]
2026-03-25 09:43:09.657569 | controller | changed
2026-03-25 09:43:09.665606 | 
2026-03-25 09:43:09.665682 | TASK [setup-uv : Print version]
2026-03-25 09:43:10.115204 | controller | uv 0.8.13
2026-03-25 09:43:10.197085 | controller | ok: Runtime: 0:00:00.012836
2026-03-25 09:43:10.203210 | 
2026-03-25 09:43:10.203258 | PLAY RECAP
2026-03-25 09:43:10.203299 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-25 09:43:10.203323 | 
2026-03-25 09:43:10.301077 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-25 09:43:10.303721 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-03-25 09:43:10.887918 | 
2026-03-25 09:43:10.897440 | PLAY [all]
2026-03-25 09:43:10.915031 | 
2026-03-25 09:43:10.915408 | TASK [Update apt cache]
2026-03-25 09:43:15.763221 | controller | ok
2026-03-25 09:43:15.770103 | 
2026-03-25 09:43:15.770265 | TASK [Disable firewalld]
2026-03-25 09:43:15.794859 | controller | skipping: Conditional result was False
2026-03-25 09:43:15.803302 | 
2026-03-25 09:43:15.803428 | TASK [Install "jq" for log collection]
2026-03-25 09:43:18.415059 | controller | changed
2026-03-25 09:43:18.417827 | 
2026-03-25 09:43:18.417906 | PLAY RECAP
2026-03-25 09:43:18.417987 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-25 09:43:18.418051 | 
2026-03-25 09:43:18.519772 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-03-25 09:43:18.522334 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-25 09:43:19.078483 | 
2026-03-25 09:43:19.078612 | PLAY [all]
2026-03-25 09:43:19.088366 | 
2026-03-25 09:43:19.088445 | TASK [Copy inventory file for Zuul]
2026-03-25 09:43:20.045305 | controller | changed
2026-03-25 09:43:20.052277 | 
2026-03-25 09:43:20.052382 | TASK [Switch "ansible_host" to private IP]
2026-03-25 09:43:20.368300 | controller | changed: 1 replacements made
2026-03-25 09:43:20.374275 | 
2026-03-25 09:43:20.374361 | TASK [Run Molecule scenario]
2026-03-25 09:43:20.826950 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-03-25 09:43:20.827093 | controller | Creating virtual environment at: .venv
2026-03-25 09:43:21.096445 | controller | Downloading pygments (1.2MiB)
2026-03-25 09:43:21.097846 | controller | Downloading cryptography (4.3MiB)
2026-03-25 09:43:21.098062 | controller | Downloading ansible-core (2.3MiB)
2026-03-25 09:43:21.268019 | controller |  Downloading cryptography
2026-03-25 09:43:21.329769 | controller |  Downloading pygments
2026-03-25 09:43:21.540625 | controller |  Downloading ansible-core
2026-03-25 09:43:21.589627 | controller | Installed 36 packages in 48ms
2026-03-25 09:43:22.279896 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-03-25 09:43:22.279957 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-03-25 09:43:23.202511 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-03-25 09:43:23.202580 | controller | INFO     default scenario not found, disabling shared state.
2026-03-25 09:43:23.202710 | controller | INFO     [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-03-25 09:43:23.202816 | controller | INFO     [upload-helm-chart > prerun] Performing prerun with role_name_check=0...
2026-03-25 09:43:39.587638 | controller | INFO     [upload-helm-chart > dependency] Executing
2026-03-25 09:43:39.587711 | controller | WARNING  [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml
2026-03-25 09:43:39.587722 | controller | WARNING  [upload-helm-chart > dependency] Missing collections requirements file: collections.yml
2026-03-25 09:43:39.587751 | controller | WARNING  [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-03-25 09:43:39.595038 | controller | INFO     [upload-helm-chart > cleanup] Executing
2026-03-25 09:43:39.595420 | controller | WARNING  [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-25 09:43:39.600851 | controller | INFO     [upload-helm-chart > destroy] Executing
2026-03-25 09:43:39.600886 | controller | WARNING  [upload-helm-chart > destroy] Skipping, '--destroy=never' requested.
2026-03-25 09:43:39.600971 | controller | INFO     [upload-helm-chart > destroy] Executed: Successful
2026-03-25 09:43:39.606291 | controller | INFO     [upload-helm-chart > syntax] Executing
2026-03-25 09:43:40.505942 | controller |
2026-03-25 09:43:40.506189 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml
2026-03-25 09:43:40.585813 | controller | INFO     [upload-helm-chart > syntax] Executed: Successful
2026-03-25 09:43:40.596819 | controller | INFO     [upload-helm-chart > create] Executing
2026-03-25 09:43:40.598264 | controller | WARNING  [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-25 09:43:40.603585 | controller | INFO     [upload-helm-chart > prepare] Executing
2026-03-25 09:43:40.603830 | controller | WARNING  [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-25 09:43:40.609367 | controller | INFO     [upload-helm-chart > converge] Executing
2026-03-25 09:43:41.465669 | controller |
2026-03-25 09:43:41.465848 | controller | PLAY [Converge] ****************************************************************
2026-03-25 09:43:41.466277 | controller |
2026-03-25 09:43:41.466299 | controller | TASK [Gathering Facts] *********************************************************
2026-03-25 09:43:41.466461 | controller | Wednesday 25 March 2026  09:43:41 +0000 (0:00:00.033)       0:00:00.033 *******
2026-03-25 09:43:42.761800 | 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-25 09:43:42.767524 | controller | ok: [controller]
2026-03-25 09:43:42.767665 | controller |
2026-03-25 09:43:42.767870 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-03-25 09:43:42.768069 | controller | Wednesday 25 March 2026  09:43:42 +0000 (0:00:01.300)       0:00:01.334 *******
2026-03-25 09:43:42.844404 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-03-25 09:43:42.844542 | controller |
2026-03-25 09:43:42.844747 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-03-25 09:43:42.844939 | controller | Wednesday 25 March 2026  09:43:42 +0000 (0:00:00.078)       0:00:01.412 *******
2026-03-25 09:43:43.577600 | controller | changed: [controller]
2026-03-25 09:43:43.577678 | controller |
2026-03-25 09:43:43.577926 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-03-25 09:43:43.578218 | controller | Wednesday 25 March 2026  09:43:43 +0000 (0:00:00.732)       0:00:02.145 *******
2026-03-25 09:43:43.625851 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-03-25 09:43:43.625937 | controller |
2026-03-25 09:43:43.626170 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-03-25 09:43:43.626487 | controller | Wednesday 25 March 2026  09:43:43 +0000 (0:00:00.048)       0:00:02.194 *******
2026-03-25 09:43:44.055798 | controller | changed: [controller]
2026-03-25 09:43:44.055873 | controller |
2026-03-25 09:43:44.056118 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-03-25 09:43:44.056334 | controller | Wednesday 25 March 2026  09:43:44 +0000 (0:00:00.429)       0:00:02.623 *******
2026-03-25 09:43:45.203327 | controller | changed: [controller]
2026-03-25 09:43:45.203380 | controller |
2026-03-25 09:43:45.203535 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-03-25 09:43:45.203668 | controller | Wednesday 25 March 2026  09:43:45 +0000 (0:00:01.147)       0:00:03.771 *******
2026-03-25 09:43:45.633095 | controller | ok: [controller]
2026-03-25 09:43:45.633189 | controller |
2026-03-25 09:43:45.633283 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-03-25 09:43:45.633412 | controller | Wednesday 25 March 2026  09:43:45 +0000 (0:00:00.429)       0:00:04.201 *******
2026-03-25 09:43:45.689483 | controller | skipping: [controller]
2026-03-25 09:43:45.689590 | controller |
2026-03-25 09:43:45.689811 | controller | PLAY RECAP *********************************************************************
2026-03-25 09:43:45.690035 | controller | controller                 : ok=7    changed=3    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-03-25 09:43:45.690232 | controller |
2026-03-25 09:43:45.690388 | controller |
2026-03-25 09:43:45.690650 | controller | TASKS RECAP ********************************************************************
2026-03-25 09:43:45.690824 | controller | Wednesday 25 March 2026  09:43:45 +0000 (0:00:00.057)       0:00:04.258 *******
2026-03-25 09:43:45.691000 | controller | ===============================================================================
2026-03-25 09:43:45.691197 | controller | Gathering Facts --------------------------------------------------------- 1.30s
2026-03-25 09:43:45.691388 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.15s
2026-03-25 09:43:45.691576 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.73s
2026-03-25 09:43:45.691819 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.43s
2026-03-25 09:43:45.691961 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s
2026-03-25 09:43:45.692158 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s
2026-03-25 09:43:45.692351 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.06s
2026-03-25 09:43:45.692547 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s
2026-03-25 09:43:45.793815 | controller | INFO     [upload-helm-chart > converge] Executed: Successful
2026-03-25 09:43:45.802060 | controller | INFO     [upload-helm-chart > idempotence] Executing
2026-03-25 09:43:46.685522 | controller |
2026-03-25 09:43:46.685803 | controller | PLAY [Converge] ****************************************************************
2026-03-25 09:43:46.686018 | controller |
2026-03-25 09:43:46.686231 | controller | TASK [Gathering Facts] *********************************************************
2026-03-25 09:43:46.686559 | controller | Wednesday 25 March 2026  09:43:46 +0000 (0:00:00.013)       0:00:00.013 *******
2026-03-25 09:43:48.748224 | 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-25 09:43:48.753926 | controller | ok: [controller]
2026-03-25 09:43:48.754139 | controller |
2026-03-25 09:43:48.754384 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-03-25 09:43:48.754674 | controller | Wednesday 25 March 2026  09:43:48 +0000 (0:00:02.068)       0:00:02.081 *******
2026-03-25 09:43:48.829553 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-03-25 09:43:48.829744 | controller |
2026-03-25 09:43:48.829953 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-03-25 09:43:48.830151 | controller | Wednesday 25 March 2026  09:43:48 +0000 (0:00:00.075)       0:00:02.157 *******
2026-03-25 09:43:49.573365 | controller | ok: [controller]
2026-03-25 09:43:49.573552 | controller |
2026-03-25 09:43:49.573890 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-03-25 09:43:49.574197 | controller | Wednesday 25 March 2026  09:43:49 +0000 (0:00:00.743)       0:00:02.900 *******
2026-03-25 09:43:49.617851 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-03-25 09:43:49.617945 | controller |
2026-03-25 09:43:49.618124 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-03-25 09:43:49.618315 | controller | Wednesday 25 March 2026  09:43:49 +0000 (0:00:00.045)       0:00:02.946 *******
2026-03-25 09:43:50.043563 | controller | ok: [controller]
2026-03-25 09:43:50.043725 | controller |
2026-03-25 09:43:50.044005 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-03-25 09:43:50.044276 | controller | Wednesday 25 March 2026  09:43:50 +0000 (0:00:00.424)       0:00:03.370 *******
2026-03-25 09:43:51.193629 | controller | ok: [controller]
2026-03-25 09:43:51.193885 | controller |
2026-03-25 09:43:51.194162 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-03-25 09:43:51.194504 | controller | Wednesday 25 March 2026  09:43:51 +0000 (0:00:01.150)       0:00:04.521 *******
2026-03-25 09:43:51.645158 | controller | ok: [controller]
2026-03-25 09:43:51.645345 | controller |
2026-03-25 09:43:51.645623 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-03-25 09:43:51.645851 | controller | Wednesday 25 March 2026  09:43:51 +0000 (0:00:00.451)       0:00:04.972 *******
2026-03-25 09:43:51.687324 | controller | skipping: [controller]
2026-03-25 09:43:51.687512 | controller |
2026-03-25 09:43:51.687766 | controller | PLAY RECAP *********************************************************************
2026-03-25 09:43:51.688011 | controller | controller                 : ok=7    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-03-25 09:43:51.688203 | controller |
2026-03-25 09:43:51.688931 | controller |
2026-03-25 09:43:51.689192 | controller | TASKS RECAP ********************************************************************
2026-03-25 09:43:51.689420 | controller | Wednesday 25 March 2026  09:43:51 +0000 (0:00:00.043)       0:00:05.015 *******
2026-03-25 09:43:51.689596 | controller | ===============================================================================
2026-03-25 09:43:51.689793 | controller | Gathering Facts --------------------------------------------------------- 2.07s
2026-03-25 09:43:51.690012 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.15s
2026-03-25 09:43:51.690207 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s
2026-03-25 09:43:51.690432 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.45s
2026-03-25 09:43:51.690715 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.42s
2026-03-25 09:43:51.691061 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s
2026-03-25 09:43:51.691257 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s
2026-03-25 09:43:51.691451 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s
2026-03-25 09:43:51.816659 | controller | INFO     [upload-helm-chart > idempotence] Executed: Successful
2026-03-25 09:43:51.828051 | controller | INFO     [upload-helm-chart > side_effect] Executing
2026-03-25 09:43:52.760737 | controller |
2026-03-25 09:43:52.760961 | controller | PLAY [Side effect] *************************************************************
2026-03-25 09:43:52.761195 | controller |
2026-03-25 09:43:52.761384 | controller | TASK [Gathering Facts] *********************************************************
2026-03-25 09:43:52.761584 | controller | Wednesday 25 March 2026  09:43:52 +0000 (0:00:00.012)       0:00:00.012 *******
2026-03-25 09:43:53.866046 | 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-25 09:43:53.872049 | controller | ok: [controller]
2026-03-25 09:43:53.872346 | controller |
2026-03-25 09:43:53.872614 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-03-25 09:43:53.872843 | controller | Wednesday 25 March 2026  09:43:53 +0000 (0:00:01.111)       0:00:01.123 *******
2026-03-25 09:43:53.951554 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-03-25 09:43:53.951749 | controller |
2026-03-25 09:43:53.951986 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-03-25 09:43:53.952199 | controller | Wednesday 25 March 2026  09:43:53 +0000 (0:00:00.079)       0:00:01.202 *******
2026-03-25 09:43:54.737248 | controller | changed: [controller]
2026-03-25 09:43:54.737693 | controller |
2026-03-25 09:43:54.737705 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-03-25 09:43:54.737966 | controller | Wednesday 25 March 2026  09:43:54 +0000 (0:00:00.785)       0:00:01.988 *******
2026-03-25 09:43:54.787082 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller
2026-03-25 09:43:54.787224 | controller |
2026-03-25 09:43:54.787477 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] **********
2026-03-25 09:43:54.787996 | controller | Wednesday 25 March 2026  09:43:54 +0000 (0:00:00.049)       0:00:02.038 *******
2026-03-25 09:43:55.249959 | controller | ok: [controller]
2026-03-25 09:43:55.250016 | controller |
2026-03-25 09:43:55.250030 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] ***
2026-03-25 09:43:55.250042 | controller | Wednesday 25 March 2026  09:43:55 +0000 (0:00:00.461)       0:00:02.500 *******
2026-03-25 09:43:56.463038 | controller | ok: [controller]
2026-03-25 09:43:56.463189 | controller |
2026-03-25 09:43:56.463314 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] **********
2026-03-25 09:43:56.463433 | controller | Wednesday 25 March 2026  09:43:56 +0000 (0:00:01.209)       0:00:03.710 *******
2026-03-25 09:43:56.922846 | controller | ok: [controller]
2026-03-25 09:43:56.922881 | controller |
2026-03-25 09:43:56.922885 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] ***
2026-03-25 09:43:56.922889 | controller | Wednesday 25 March 2026  09:43:56 +0000 (0:00:00.460)       0:00:04.171 *******
2026-03-25 09:43:57.265291 | controller | changed: [controller] => (item=/file)
2026-03-25 09:43:57.265343 | controller |
2026-03-25 09:43:57.265352 | controller | PLAY RECAP *********************************************************************
2026-03-25 09:43:57.265360 | controller | controller                 : ok=8    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-03-25 09:43:57.265366 | controller |
2026-03-25 09:43:57.265373 | controller |
2026-03-25 09:43:57.265379 | controller | TASKS RECAP ********************************************************************
2026-03-25 09:43:57.265398 | controller | Wednesday 25 March 2026  09:43:57 +0000 (0:00:00.343)       0:00:04.514 *******
2026-03-25 09:43:57.265405 | controller | ===============================================================================
2026-03-25 09:43:57.265411 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.21s
2026-03-25 09:43:57.265416 | controller | Gathering Facts --------------------------------------------------------- 1.11s
2026-03-25 09:43:57.265422 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.79s
2026-03-25 09:43:57.265427 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.46s
2026-03-25 09:43:57.265432 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.46s
2026-03-25 09:43:57.265439 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.34s
2026-03-25 09:43:57.265445 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s
2026-03-25 09:43:57.265451 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s
2026-03-25 09:43:57.388528 | controller | INFO     [upload-helm-chart > side_effect] Executed: Successful
2026-03-25 09:43:57.398945 | controller | INFO     [upload-helm-chart > verify] Executing
2026-03-25 09:43:58.279051 | controller |
2026-03-25 09:43:58.279368 | controller | PLAY [Verify] ******************************************************************
2026-03-25 09:43:58.279613 | controller |
2026-03-25 09:43:58.279869 | controller | TASK [Gathering Facts] *********************************************************
2026-03-25 09:43:58.280124 | controller | Wednesday 25 March 2026  09:43:58 +0000 (0:00:00.009)       0:00:00.009 *******
2026-03-25 09:43:59.392956 | 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-25 09:43:59.397674 | controller | ok: [controller]
2026-03-25 09:43:59.397953 | controller |
2026-03-25 09:43:59.398236 | controller | TASK [Stat file 1] *************************************************************
2026-03-25 09:43:59.398575 | controller | Wednesday 25 March 2026  09:43:59 +0000 (0:00:01.118)       0:00:01.128 *******
2026-03-25 09:43:59.818912 | controller | ok: [controller]
2026-03-25 09:43:59.823191 | controller |
2026-03-25 09:43:59.823384 | controller | TASK [Stat file 2] *************************************************************
2026-03-25 09:43:59.823584 | controller | Wednesday 25 March 2026  09:43:59 +0000 (0:00:00.420)       0:00:01.548 *******
2026-03-25 09:44:00.146261 | controller | ok: [controller]
2026-03-25 09:44:00.146323 | controller |
2026-03-25 09:44:00.146527 | controller | TASK [Stat removed file 1] *****************************************************
2026-03-25 09:44:00.146739 | controller | Wednesday 25 March 2026  09:44:00 +0000 (0:00:00.327)       0:00:01.876 *******
2026-03-25 09:44:00.454642 | controller | ok: [controller]
2026-03-25 09:44:00.454701 | controller |
2026-03-25 09:44:00.454715 | controller | TASK [Stat removed file 2] *****************************************************
2026-03-25 09:44:00.454746 | controller | Wednesday 25 March 2026  09:44:00 +0000 (0:00:00.307)       0:00:02.183 *******
2026-03-25 09:44:00.758818 | controller | ok: [controller]
2026-03-25 09:44:00.758901 | controller |
2026-03-25 09:44:00.759201 | controller | TASK [ansible.builtin.assert] **************************************************
2026-03-25 09:44:00.759216 | controller | Wednesday 25 March 2026  09:44:00 +0000 (0:00:00.305)       0:00:02.489 *******
2026-03-25 09:44:00.795074 | controller | ok: [controller] => {
2026-03-25 09:44:00.795244 | controller |     "changed": false,
2026-03-25 09:44:00.795363 | controller |     "msg": "All assertions passed"
2026-03-25 09:44:00.795476 | controller | }
2026-03-25 09:44:00.795581 | controller |
2026-03-25 09:44:00.795694 | controller | PLAY RECAP *********************************************************************
2026-03-25 09:44:00.795818 | controller | controller                 : ok=6    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-03-25 09:44:00.795922 | controller |
2026-03-25 09:44:00.796037 | controller |
2026-03-25 09:44:00.796146 | controller | TASKS RECAP ********************************************************************
2026-03-25 09:44:00.796258 | controller | Wednesday 25 March 2026  09:44:00 +0000 (0:00:00.036)       0:00:02.525 *******
2026-03-25 09:44:00.796366 | controller | ===============================================================================
2026-03-25 09:44:00.796476 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2026-03-25 09:44:00.796585 | controller | Stat file 1 ------------------------------------------------------------- 0.42s
2026-03-25 09:44:00.796692 | controller | Stat file 2 ------------------------------------------------------------- 0.33s
2026-03-25 09:44:00.796804 | controller | Stat removed file 1 ----------------------------------------------------- 0.31s
2026-03-25 09:44:00.796917 | controller | Stat removed file 2 ----------------------------------------------------- 0.31s
2026-03-25 09:44:00.797026 | controller | ansible.builtin.assert -------------------------------------------------- 0.04s
2026-03-25 09:44:00.903890 | controller | INFO     [upload-helm-chart > verify] Executed: Successful
2026-03-25 09:44:00.916068 | controller | INFO     [upload-helm-chart > cleanup] Executing
2026-03-25 09:44:00.916300 | controller | WARNING  [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-03-25 09:44:00.921931 | controller | INFO     [upload-helm-chart > destroy] Executing
2026-03-25 09:44:00.922029 | controller | WARNING  [upload-helm-chart > destroy] Skipping, '--destroy=never' requested.
2026-03-25 09:44:00.922147 | controller | INFO     [upload-helm-chart > destroy] Executed: Successful
2026-03-25 09:44:00.922527 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-03-25 09:44:01.459280 | controller | ok: Runtime: 0:00:40.311471
2026-03-25 09:44:01.463053 | 
2026-03-25 09:44:01.463105 | PLAY RECAP
2026-03-25 09:44:01.463146 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-25 09:44:01.463168 | 
2026-03-25 09:44:01.553611 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-25 09:44:01.556135 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-03-25 09:44:02.180303 | 
2026-03-25 09:44:02.180499 | PLAY [all]
2026-03-25 09:44:02.193537 | 
2026-03-25 09:44:02.193736 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-03-25 09:44:02.573701 | controller | changed
2026-03-25 09:44:02.578547 | 
2026-03-25 09:44:02.578628 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-03-25 09:44:02.975999 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-25 09:44:02.976099 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-25 09:44:02.983596 | controller | environment: line 1: kubectl: command not found
2026-03-25 09:44:02.985594 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-25 09:44:02.987178 | controller | environment: line 1: kubectl: command not found
2026-03-25 09:44:02.988857 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-25 09:44:02.988970 | controller | environment: line 1: kubectl: command not found
2026-03-25 09:44:02.990843 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-25 09:44:02.992727 | controller | environment: line 1: kubectl: command not found
2026-03-25 09:44:02.994264 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-25 09:44:02.996078 | controller | environment: line 1: kubectl: command not found
2026-03-25 09:44:02.997646 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-03-25 09:44:03.107539 | controller | ok: Runtime: 0:00:00.037515
2026-03-25 09:44:03.113262 | 
2026-03-25 09:44:03.113362 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-03-25 09:44:03.367523 | controller | changed
2026-03-25 09:44:03.373106 | 
2026-03-25 09:44:03.373224 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-03-25 09:44:03.615911 | controller | environment: line 5: kubectl: command not found
2026-03-25 09:44:03.617040 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-25 09:44:03.617326 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-25 09:44:03.618051 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-03-25 09:44:03.898573 | controller | ok: Runtime: 0:00:00.016028
2026-03-25 09:44:03.906310 | 
2026-03-25 09:44:03.906432 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-03-25 09:44:04.588707 | controller | changed:
2026-03-25 09:44:04.588928 | controller | created directory /var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/work/logs/controller
2026-03-25 09:44:04.589009 | controller | cd+++++++++ objects/
2026-03-25 09:44:04.589055 | controller | cd+++++++++ objects/cluster/
2026-03-25 09:44:04.589095 | controller | cd+++++++++ objects/namespaced/
2026-03-25 09:44:04.598442 | 
2026-03-25 09:44:04.598530 | TASK [gather-pod-logs : creating directory for pod logs]
2026-03-25 09:44:04.842819 | controller | changed
2026-03-25 09:44:04.849557 | 
2026-03-25 09:44:04.849623 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-03-25 09:44:05.113349 | controller | changed
2026-03-25 09:44:05.117669 | 
2026-03-25 09:44:05.117740 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-03-25 09:44:05.357635 | controller | environment: line 3: kubectl: command not found
2026-03-25 09:44:05.647351 | controller | ok: Runtime: 0:00:00.013712
2026-03-25 09:44:05.654053 | 
2026-03-25 09:44:05.654137 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-03-25 09:44:06.181298 | controller | changed:
2026-03-25 09:44:06.181485 | controller | cd+++++++++ pod-logs/
2026-03-25 09:44:06.181535 | controller | cd+++++++++ pod-logs/failed-pods/
2026-03-25 09:44:06.191213 | 
2026-03-25 09:44:06.191273 | LOOP [helm-release-status : creating directory for helm release status]
2026-03-25 09:44:06.421611 | controller | changed: "values"
2026-03-25 09:44:06.633761 | controller | changed: "releases"
2026-03-25 09:44:06.643714 | 
2026-03-25 09:44:06.643862 | TASK [helm-release-status : Gather get release status for helm charts]
2026-03-25 09:44:06.916007 | controller | /bin/bash: line 3: kubectl: command not found
2026-03-25 09:44:07.174356 | controller | ok: Runtime: 0:00:00.009707
2026-03-25 09:44:07.180591 | 
2026-03-25 09:44:07.180654 | TASK [helm-release-status : Downloads logs to executor]
2026-03-25 09:44:07.698499 | controller | changed:
2026-03-25 09:44:07.698693 | controller | cd+++++++++ helm/
2026-03-25 09:44:07.698731 | controller | cd+++++++++ helm/releases/
2026-03-25 09:44:07.698764 | controller | cd+++++++++ helm/values/
2026-03-25 09:44:07.705076 | 
2026-03-25 09:44:07.705152 | PLAY RECAP
2026-03-25 09:44:07.705211 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-25 09:44:07.705241 | 
2026-03-25 09:44:07.818239 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-03-25 09:44:07.821070 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-25 09:44:08.577594 | 
2026-03-25 09:44:08.577723 | PLAY [all]
2026-03-25 09:44:08.587781 | 
2026-03-25 09:44:08.587871 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-25 09:44:08.632403 | controller | skipping: Conditional result was False
2026-03-25 09:44:08.642166 | 
2026-03-25 09:44:08.642314 | TASK [fetch-output : Set log path for single node]
2026-03-25 09:44:08.683486 | controller | ok
2026-03-25 09:44:08.689554 | 
2026-03-25 09:44:08.689681 | LOOP [fetch-output : Ensure local output dirs]
2026-03-25 09:44:09.085819 | controller -> localhost | ok: "/var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/work/logs"
2026-03-25 09:44:09.320935 | controller -> localhost | changed: "/var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/work/artifacts"
2026-03-25 09:44:09.565759 | controller -> localhost | changed: "/var/lib/zuul/builds/ffdccfc22ea54d018a873d62f2adfc1f/work/docs"
2026-03-25 09:44:09.589778 | 
2026-03-25 09:44:09.589905 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-25 09:44:10.223910 | controller | changed: .d..t...... ./
2026-03-25 09:44:10.224208 | controller | changed: All items complete
2026-03-25 09:44:10.224247 | 
2026-03-25 09:44:10.714777 | controller | changed: .d..t...... ./
2026-03-25 09:44:11.234850 | controller | changed: .d..t...... ./
2026-03-25 09:44:11.248834 | 
2026-03-25 09:44:11.249070 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-25 09:44:11.665478 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006751
2026-03-25 09:44:11.897243 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006557
2026-03-25 09:44:11.907846 | 
2026-03-25 09:44:11.907998 | PLAY [all]
2026-03-25 09:44:11.915520 | 
2026-03-25 09:44:11.915594 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-25 09:44:12.373514 | controller | changed
2026-03-25 09:44:12.380395 | 
2026-03-25 09:44:12.380482 | PLAY RECAP
2026-03-25 09:44:12.380543 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-25 09:44:12.380575 | 
2026-03-25 09:44:12.493922 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-25 09:44:12.495603 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-25 09:44:13.048497 | 
2026-03-25 09:44:13.048647 | PLAY [localhost]
2026-03-25 09:44:13.059597 | 
2026-03-25 09:44:13.059716 | TASK [Generate Zuul manifest]
2026-03-25 09:44:13.079427 | localhost | ok
2026-03-25 09:44:13.100718 | 
2026-03-25 09:44:13.100829 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-25 09:44:13.414036 | localhost | changed
2026-03-25 09:44:13.426238 | 
2026-03-25 09:44:13.426318 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-25 09:44:13.455579 | localhost | ok
2026-03-25 09:44:13.463982 | 
2026-03-25 09:44:13.464060 | TASK [Upload logs]
2026-03-25 09:44:13.484098 | localhost | ok
2026-03-25 09:44:13.545532 | 
2026-03-25 09:44:13.545672 | TASK [Set zuul-log-path fact]
2026-03-25 09:44:13.565815 | localhost | ok
2026-03-25 09:44:13.580065 | 
2026-03-25 09:44:13.580171 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-25 09:44:13.612041 | localhost | ok
2026-03-25 09:44:13.620554 | 
2026-03-25 09:44:13.620655 | TASK [upload-logs : Create log directories]
2026-03-25 09:44:14.026976 | localhost | changed
2026-03-25 09:44:14.033289 | 
2026-03-25 09:44:14.033381 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-25 09:44:14.452412 | localhost -> localhost | ok: Runtime: 0:00:00.002801
2026-03-25 09:44:14.459285 | 
2026-03-25 09:44:14.459379 | TASK [upload-logs : Upload logs to log server]
2026-03-25 09:44:14.925839 | localhost | Output suppressed because no_log was given
2026-03-25 09:44:14.931156 | 
2026-03-25 09:44:14.931244 | LOOP [upload-logs : Compress console log and json output]
2026-03-25 09:44:14.977885 | localhost | skipping: Conditional result was False
2026-03-25 09:44:14.984634 | localhost | skipping: Conditional result was False
2026-03-25 09:44:14.999755 | 
2026-03-25 09:44:14.999956 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-25 09:44:15.070662 | localhost | skipping: Conditional result was False
2026-03-25 09:44:15.071057 | 
2026-03-25 09:44:15.076482 | localhost | skipping: Conditional result was False
2026-03-25 09:44:15.095626 | 
2026-03-25 09:44:15.095781 | LOOP [upload-logs : Upload console log and json output]