2026-08-26 19:16:53.903712 | Job console starting
2026-08-26 19:16:53.921744 | Updating git repos
2026-08-26 19:16:53.964822 | Cloning repos into workspace
2026-08-26 19:16:54.023127 | Restoring repo states
2026-08-26 19:16:54.036456 | Merging changes
2026-08-26 19:16:54.709095 | Checking out repos
2026-08-26 19:16:55.008049 | Preparing playbooks
2026-08-26 19:16:59.936390 | Running Ansible setup
2026-08-26 19:17:06.431761 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 19:17:07.028205 | 
2026-08-26 19:17:07.028319 | PLAY [localhost]
2026-08-26 19:17:07.035275 | 
2026-08-26 19:17:07.035343 | TASK [Gathering Facts]
2026-08-26 19:17:07.873639 | localhost | ok
2026-08-26 19:17:07.882359 | 
2026-08-26 19:17:07.882430 | TASK [Setup log path fact]
2026-08-26 19:17:07.900640 | localhost | ok
2026-08-26 19:17:07.912712 | 
2026-08-26 19:17:07.912782 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 19:17:07.942695 | localhost | ok
2026-08-26 19:17:07.950694 | 
2026-08-26 19:17:07.950780 | TASK [emit-job-header : Print job information]
2026-08-26 19:17:07.982559 | # Job Information
2026-08-26 19:17:07.982716 | Ansible Version: 2.16.18
2026-08-26 19:17:07.982761 | Job: ansible-collection-kubernetes-molecule-cluster-upgrade
2026-08-26 19:17:07.982794 | Pipeline: check
2026-08-26 19:17:07.982823 | Executor: 2d72f0692154
2026-08-26 19:17:07.982851 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/291
2026-08-26 19:17:07.982882 | Event ID: 46f72b20-a182-11f1-8658-1c22c85d0bda
2026-08-26 19:17:07.986800 | 
2026-08-26 19:17:07.986918 | LOOP [emit-job-header : Print node information]
2026-08-26 19:17:08.068810 | localhost | ok:
2026-08-26 19:17:08.069017 | localhost | # Node Information
2026-08-26 19:17:08.069102 | localhost | Inventory Hostname: controller-1
2026-08-26 19:17:08.069141 | localhost | Hostname: np0000191172
2026-08-26 19:17:08.069165 | localhost | Username: zuul
2026-08-26 19:17:08.069189 | localhost | Distro: Debian 13.6
2026-08-26 19:17:08.069210 | localhost | Provider: yul1
2026-08-26 19:17:08.069229 | localhost | Region: ca-ymq-1
2026-08-26 19:17:08.069249 | localhost | Label: debian-trixie
2026-08-26 19:17:08.069268 | localhost | Product Name: OpenStack Nova
2026-08-26 19:17:08.069287 | localhost | Interface IP: 162.253.55.167
2026-08-26 19:17:08.105797 | localhost | ok:
2026-08-26 19:17:08.105928 | localhost | # Node Information
2026-08-26 19:17:08.106006 | localhost | Inventory Hostname: controller-2
2026-08-26 19:17:08.106076 | localhost | Hostname: np0000191173
2026-08-26 19:17:08.106143 | localhost | Username: zuul
2026-08-26 19:17:08.106209 | localhost | Distro: Debian 13.6
2026-08-26 19:17:08.106273 | localhost | Provider: yul1
2026-08-26 19:17:08.106334 | localhost | Region: ca-ymq-1
2026-08-26 19:17:08.106398 | localhost | Label: debian-trixie
2026-08-26 19:17:08.106452 | localhost | Product Name: OpenStack Nova
2026-08-26 19:17:08.106498 | localhost | Interface IP: 162.253.55.4
2026-08-26 19:17:08.139918 | localhost | ok:
2026-08-26 19:17:08.140028 | localhost | # Node Information
2026-08-26 19:17:08.140070 | localhost | Inventory Hostname: controller-3
2026-08-26 19:17:08.140105 | localhost | Hostname: np0000191174
2026-08-26 19:17:08.140139 | localhost | Username: zuul
2026-08-26 19:17:08.140171 | localhost | Distro: Debian 13.6
2026-08-26 19:17:08.140202 | localhost | Provider: yul1
2026-08-26 19:17:08.140234 | localhost | Region: ca-ymq-1
2026-08-26 19:17:08.140265 | localhost | Label: debian-trixie
2026-08-26 19:17:08.140296 | localhost | Product Name: OpenStack Nova
2026-08-26 19:17:08.140323 | localhost | Interface IP: 162.253.55.166
2026-08-26 19:17:08.150050 | 
2026-08-26 19:17:08.150165 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 19:17:08.549805 | localhost -> localhost | changed
2026-08-26 19:17:08.559161 | 
2026-08-26 19:17:08.559307 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 19:17:09.513614 | localhost -> localhost | changed
2026-08-26 19:17:09.523523 | 
2026-08-26 19:17:09.523586 | PLAY [all]
2026-08-26 19:17:09.530321 | 
2026-08-26 19:17:09.530388 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 19:17:09.746533 | controller-1 -> localhost | ok
2026-08-26 19:17:09.754978 | 
2026-08-26 19:17:09.755114 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 19:17:09.779811 | controller-1 | ok
2026-08-26 19:17:09.784245 | controller-2 | ok
2026-08-26 19:17:09.801448 | controller-3 | ok
2026-08-26 19:17:09.822830 | controller-1 | included: /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 19:17:09.822943 | controller-2 | included: /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 19:17:09.822969 | controller-3 | included: /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 19:17:09.828799 | 
2026-08-26 19:17:09.828937 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 19:17:10.990761 | controller-1 -> localhost | Generating public/private rsa key pair.
2026-08-26 19:17:10.991086 | controller-1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/5f6facb2e75748f6ab2572a45be4e29c_id_rsa
2026-08-26 19:17:10.991168 | controller-1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/5f6facb2e75748f6ab2572a45be4e29c_id_rsa.pub
2026-08-26 19:17:10.991211 | controller-1 -> localhost | The key fingerprint is:
2026-08-26 19:17:10.991275 | controller-1 -> localhost | SHA256:9tDtjWDeM8jy4zfYOYtCcG9yfqacdXuhnv/v+hA5fkk zuul-build-sshkey
2026-08-26 19:17:10.991344 | controller-1 -> localhost | The key's randomart image is:
2026-08-26 19:17:10.991394 | controller-1 -> localhost | +---[RSA 3072]----+
2026-08-26 19:17:10.991455 | controller-1 -> localhost | |                 |
2026-08-26 19:17:10.991525 | controller-1 -> localhost | |                 |
2026-08-26 19:17:10.991579 | controller-1 -> localhost | |                 |
2026-08-26 19:17:10.991616 | controller-1 -> localhost | |      . .. .   . |
2026-08-26 19:17:10.991648 | controller-1 -> localhost | |       oS.+ . +E |
2026-08-26 19:17:10.991679 | controller-1 -> localhost | |       .o*+= +.+.|
2026-08-26 19:17:10.991710 | controller-1 -> localhost | |       ..==oB.*.o|
2026-08-26 19:17:10.991741 | controller-1 -> localhost | |        .+++O=.+.|
2026-08-26 19:17:10.991772 | controller-1 -> localhost | |         oB*o*+*B|
2026-08-26 19:17:10.991803 | controller-1 -> localhost | +----[SHA256]-----+
2026-08-26 19:17:10.991878 | controller-1 -> localhost | ok: Runtime: 0:00:00.766267
2026-08-26 19:17:10.999983 | 
2026-08-26 19:17:11.000175 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 19:17:11.038300 | controller-1 | ok
2026-08-26 19:17:11.042355 | controller-2 | ok
2026-08-26 19:17:11.045238 | controller-3 | ok
2026-08-26 19:17:11.060458 | controller-1 | included: /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 19:17:11.060527 | controller-2 | included: /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 19:17:11.060552 | controller-3 | included: /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 19:17:11.072281 | 
2026-08-26 19:17:11.072359 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 19:17:11.103880 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:11.104543 | controller-2 | skipping: Conditional result was False
2026-08-26 19:17:11.114874 | controller-3 | skipping: Conditional result was False
2026-08-26 19:17:11.122409 | 
2026-08-26 19:17:11.122491 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 19:17:11.657198 | controller-2 | changed
2026-08-26 19:17:11.662173 | controller-1 | changed
2026-08-26 19:17:11.689248 | controller-3 | changed
2026-08-26 19:17:11.694463 | 
2026-08-26 19:17:11.694550 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 19:17:11.896945 | controller-1 | ok
2026-08-26 19:17:11.928328 | controller-2 | ok
2026-08-26 19:17:11.932798 | controller-3 | ok
2026-08-26 19:17:11.937062 | 
2026-08-26 19:17:11.937130 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 19:17:12.495478 | controller-1 | changed
2026-08-26 19:17:12.510982 | controller-2 | changed
2026-08-26 19:17:12.528634 | controller-3 | changed
2026-08-26 19:17:12.535731 | 
2026-08-26 19:17:12.535855 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 19:17:13.095425 | controller-2 | changed
2026-08-26 19:17:13.113004 | controller-1 | changed
2026-08-26 19:17:13.139298 | controller-3 | changed
2026-08-26 19:17:13.145238 | 
2026-08-26 19:17:13.145338 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 19:17:13.171538 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:13.183083 | controller-2 | skipping: Conditional result was False
2026-08-26 19:17:13.193679 | controller-3 | skipping: Conditional result was False
2026-08-26 19:17:13.200291 | 
2026-08-26 19:17:13.200390 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 19:17:13.514228 | controller-1 -> localhost | changed
2026-08-26 19:17:13.533575 | 
2026-08-26 19:17:13.533698 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 19:17:13.827961 | controller-1 -> localhost | Identity added: /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/5f6facb2e75748f6ab2572a45be4e29c_id_rsa (zuul-build-sshkey)
2026-08-26 19:17:13.828228 | controller-1 -> localhost | ok: Runtime: 0:00:00.014183
2026-08-26 19:17:13.834499 | 
2026-08-26 19:17:13.834592 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 19:17:14.138682 | controller-3 | ok
2026-08-26 19:17:14.144873 | controller-1 | ok
2026-08-26 19:17:14.149430 | controller-2 | ok
2026-08-26 19:17:14.153442 | 
2026-08-26 19:17:14.153511 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 19:17:14.196932 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:14.198413 | controller-2 | skipping: Conditional result was False
2026-08-26 19:17:14.219209 | controller-3 | skipping: Conditional result was False
2026-08-26 19:17:14.238707 | 
2026-08-26 19:17:14.238784 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 19:17:14.586960 | controller-1 | ok
2026-08-26 19:17:14.591280 | controller-3 | ok
2026-08-26 19:17:14.597802 | controller-2 | ok
2026-08-26 19:17:14.602569 | 
2026-08-26 19:17:14.602640 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 19:17:15.536061 | controller-2 | Output suppressed because no_log was given
2026-08-26 19:17:15.583909 | controller-1 | Output suppressed because no_log was given
2026-08-26 19:17:15.599169 | controller-3 | Output suppressed because no_log was given
2026-08-26 19:17:15.616708 | 
2026-08-26 19:17:15.616844 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 19:17:15.814073 | controller-2 | ok: "logs"
2026-08-26 19:17:15.814383 | controller-2 | ok: All items complete
2026-08-26 19:17:15.814428 | 
2026-08-26 19:17:15.837143 | controller-3 | ok: "logs"
2026-08-26 19:17:15.851460 | controller-1 | ok: "logs"
2026-08-26 19:17:15.990539 | controller-2 | ok: "artifacts"
2026-08-26 19:17:16.037381 | controller-1 | ok: "artifacts"
2026-08-26 19:17:16.047623 | controller-3 | ok: "artifacts"
2026-08-26 19:17:16.165499 | controller-2 | ok: "docs"
2026-08-26 19:17:16.212490 | controller-1 | ok: "docs"
2026-08-26 19:17:16.242862 | controller-3 | ok: "docs"
2026-08-26 19:17:16.242997 | controller-3 | ok: All items complete
2026-08-26 19:17:16.243037 | 
2026-08-26 19:17:16.255445 | 
2026-08-26 19:17:16.255615 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 19:17:16.465211 | controller-1 | changed: "logs"
2026-08-26 19:17:16.484021 | controller-2 | changed: "logs"
2026-08-26 19:17:16.494014 | controller-3 | changed: "logs"
2026-08-26 19:17:16.641045 | controller-1 | changed: "artifacts"
2026-08-26 19:17:16.689939 | controller-3 | changed: "artifacts"
2026-08-26 19:17:16.694853 | controller-2 | changed: "artifacts"
2026-08-26 19:17:16.838637 | controller-1 | changed: "docs"
2026-08-26 19:17:16.871518 | controller-3 | changed: "docs"
2026-08-26 19:17:16.871691 | controller-3 | changed: All items complete
2026-08-26 19:17:16.871759 | 
2026-08-26 19:17:16.892108 | controller-2 | changed: "docs"
2026-08-26 19:17:16.917156 | 
2026-08-26 19:17:16.917249 | PLAY RECAP
2026-08-26 19:17:16.917295 | controller-1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 19:17:16.917325 | controller-2 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 19:17:16.917351 | controller-3 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 19:17:16.917376 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:17:16.917395 | 
2026-08-26 19:17:17.021517 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 19:17:17.025578 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 19:17:17.585195 | 
2026-08-26 19:17:17.585319 | PLAY [all]
2026-08-26 19:17:17.596023 | 
2026-08-26 19:17:17.596111 | TASK [setup-uv : Extract archive]
2026-08-26 19:17:20.138507 | controller-2 | changed
2026-08-26 19:17:20.163987 | controller-1 | changed
2026-08-26 19:17:20.198414 | controller-3 | changed
2026-08-26 19:17:20.203063 | 
2026-08-26 19:17:20.203155 | TASK [setup-uv : Print version]
2026-08-26 19:17:20.603043 | controller-3 | uv 0.8.13
2026-08-26 19:17:20.597728 | controller-1 | uv 0.8.13
2026-08-26 19:17:20.600582 | controller-2 | uv 0.8.13
2026-08-26 19:17:20.764883 | controller-1 | ok: Runtime: 0:00:00.015176
2026-08-26 19:17:20.775048 | controller-3 | ok: Runtime: 0:00:00.015886
2026-08-26 19:17:20.782616 | controller-2 | ok: Runtime: 0:00:00.016219
2026-08-26 19:17:20.794967 | 
2026-08-26 19:17:20.795045 | TASK [Install Ansible collection siblings]
2026-08-26 19:17:21.161340 | controller-1 | ok:
2026-08-26 19:17:21.161505 | controller-1 | Skipping ansible.posix: no checked-out sibling
2026-08-26 19:17:21.161549 | controller-1 | Skipping community.crypto: no checked-out sibling
2026-08-26 19:17:21.161581 | controller-1 | Skipping community.general: no checked-out sibling
2026-08-26 19:17:21.161609 | controller-1 | Skipping kubernetes.core: no checked-out sibling
2026-08-26 19:17:21.161640 | controller-1 | Skipping vexxhost.containers: no checked-out sibling
2026-08-26 19:17:21.163707 | 
2026-08-26 19:17:21.163764 | PLAY RECAP
2026-08-26 19:17:21.163819 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:17:21.163856 | controller-2 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:17:21.163890 | controller-3 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:17:21.163916 | 
2026-08-26 19:17:21.257511 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 19:17:21.261755 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-26 19:17:21.858308 | 
2026-08-26 19:17:21.858437 | PLAY [all]
2026-08-26 19:17:21.869330 | 
2026-08-26 19:17:21.869422 | TASK [Update apt cache]
2026-08-26 19:17:27.770609 | controller-2 | ok
2026-08-26 19:17:27.859108 | controller-3 | ok
2026-08-26 19:17:27.947609 | controller-1 | ok
2026-08-26 19:17:27.954591 | 
2026-08-26 19:17:27.954687 | TASK [Disable firewalld]
2026-08-26 19:17:27.974589 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:27.985482 | controller-2 | skipping: Conditional result was False
2026-08-26 19:17:27.985742 | controller-3 | skipping: Conditional result was False
2026-08-26 19:17:27.992374 | 
2026-08-26 19:17:27.992507 | TASK [Install "jq" for log collection]
2026-08-26 19:17:30.679033 | controller-1 | changed
2026-08-26 19:17:30.697559 | controller-3 | changed
2026-08-26 19:17:30.735340 | controller-2 | changed
2026-08-26 19:17:30.737512 | 
2026-08-26 19:17:30.737576 | PLAY RECAP
2026-08-26 19:17:30.737638 | controller-1 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 19:17:30.737677 | controller-2 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 19:17:30.737711 | controller-3 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 19:17:30.737737 | 
2026-08-26 19:17:30.831290 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-26 19:17:30.837268 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-26 19:17:31.482022 | 
2026-08-26 19:17:31.482247 | PLAY [all]
2026-08-26 19:17:31.495266 | 
2026-08-26 19:17:31.495402 | LOOP [Check inventory directories]
2026-08-26 19:17:31.842411 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-26 19:17:32.043711 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-upgrade/inventory"
2026-08-26 19:17:32.238424 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-upgrade/inventory"
2026-08-26 19:17:32.256307 | 
2026-08-26 19:17:32.256588 | LOOP [Copy inventory file for Zuul]
2026-08-26 19:17:33.164030 | controller-1 | changed:
2026-08-26 19:17:33.164220 | controller-1 | {
2026-08-26 19:17:33.164250 | controller-1 |   "ansible_loop_var": "item",
2026-08-26 19:17:33.164273 | controller-1 |   "changed": false,
2026-08-26 19:17:33.164299 | controller-1 |   "failed": false,
2026-08-26 19:17:33.164322 | controller-1 |   "invocation": {
2026-08-26 19:17:33.164341 | controller-1 |     "module_args": {
2026-08-26 19:17:33.164359 | controller-1 |       "checksum_algorithm": "sha1",
2026-08-26 19:17:33.164377 | controller-1 |       "follow": true,
2026-08-26 19:17:33.164401 | controller-1 |       "get_attributes": true,
2026-08-26 19:17:33.164422 | controller-1 |       "get_checksum": true,
2026-08-26 19:17:33.164440 | controller-1 |       "get_mime": true,
2026-08-26 19:17:33.164457 | controller-1 |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-26 19:17:33.164476 | controller-1 |     }
2026-08-26 19:17:33.164493 | controller-1 |   },
2026-08-26 19:17:33.164511 | controller-1 |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-26 19:17:33.164528 | controller-1 |   "stat": {
2026-08-26 19:17:33.164553 | controller-1 |     "atime": 1787771835.455162,
2026-08-26 19:17:33.164571 | controller-1 |     "attr_flags": "e",
2026-08-26 19:17:33.164589 | controller-1 |     "attributes": [
2026-08-26 19:17:33.164606 | controller-1 |       "extents"
2026-08-26 19:17:33.164623 | controller-1 |     ],
2026-08-26 19:17:33.164651 | controller-1 |     "block_size": 4096,
2026-08-26 19:17:33.164669 | controller-1 |     "blocks": 8,
2026-08-26 19:17:33.164687 | controller-1 |     "charset": "unknown",
2026-08-26 19:17:33.164706 | controller-1 |     "ctime": 1787771835.4911628,
2026-08-26 19:17:33.164723 | controller-1 |     "dev": 65025,
2026-08-26 19:17:33.164751 | controller-1 |     "device_type": 0,
2026-08-26 19:17:33.164774 | controller-1 |     "executable": true,
2026-08-26 19:17:33.164793 | controller-1 |     "exists": true,
2026-08-26 19:17:33.164821 | controller-1 |     "gid": 1000,
2026-08-26 19:17:33.164839 | controller-1 |     "gr_name": "zuul",
2026-08-26 19:17:33.164857 | controller-1 |     "inode": 520215,
2026-08-26 19:17:33.164874 | controller-1 |     "isblk": false,
2026-08-26 19:17:33.164893 | controller-1 |     "ischr": false,
2026-08-26 19:17:33.164911 | controller-1 |     "isdir": true,
2026-08-26 19:17:33.164928 | controller-1 |     "isfifo": false,
2026-08-26 19:17:33.164944 | controller-1 |     "isgid": false,
2026-08-26 19:17:33.164980 | controller-1 |     "islnk": false,
2026-08-26 19:17:33.164999 | controller-1 |     "isreg": false,
2026-08-26 19:17:33.165017 | controller-1 |     "issock": false,
2026-08-26 19:17:33.165034 | controller-1 |     "isuid": false,
2026-08-26 19:17:33.165063 | controller-1 |     "mimetype": "unknown",
2026-08-26 19:17:33.165082 | controller-1 |     "mode": "0755",
2026-08-26 19:17:33.165100 | controller-1 |     "mtime": 1787771814.5828044,
2026-08-26 19:17:33.165117 | controller-1 |     "nlink": 14,
2026-08-26 19:17:33.165134 | controller-1 |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-26 19:17:33.165151 | controller-1 |     "pw_name": "zuul",
2026-08-26 19:17:33.165172 | controller-1 |     "readable": true,
2026-08-26 19:17:33.165190 | controller-1 |     "rgrp": true,
2026-08-26 19:17:33.165207 | controller-1 |     "roth": true,
2026-08-26 19:17:33.165225 | controller-1 |     "rusr": true,
2026-08-26 19:17:33.165242 | controller-1 |     "size": 4096,
2026-08-26 19:17:33.165259 | controller-1 |     "uid": 1000,
2026-08-26 19:17:33.165276 | controller-1 |     "version": "3031850061",
2026-08-26 19:17:33.165293 | controller-1 |     "wgrp": false,
2026-08-26 19:17:33.165312 | controller-1 |     "woth": false,
2026-08-26 19:17:33.165329 | controller-1 |     "writeable": true,
2026-08-26 19:17:33.165346 | controller-1 |     "wusr": true,
2026-08-26 19:17:33.165381 | controller-1 |     "xgrp": true,
2026-08-26 19:17:33.165399 | controller-1 |     "xoth": true,
2026-08-26 19:17:33.165417 | controller-1 |     "xusr": true
2026-08-26 19:17:33.165434 | controller-1 |   }
2026-08-26 19:17:33.165451 | controller-1 | }
2026-08-26 19:17:33.165483 | controller-1 | ok: All items complete
2026-08-26 19:17:33.165504 | 
2026-08-26 19:17:33.166836 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:33.168117 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:33.184640 | 
2026-08-26 19:17:33.184792 | LOOP [Switch "ansible_host" to private IP]
2026-08-26 19:17:33.547975 | controller-1 | changed: 3 replacements made
2026-08-26 19:17:33.549845 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:33.554127 | controller-1 | skipping: Conditional result was False
2026-08-26 19:17:33.573156 | 
2026-08-26 19:17:33.573270 | TASK [Run Molecule scenario]
2026-08-26 19:17:34.044391 | controller-1 | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-26 19:17:34.044495 | controller-1 | Creating virtual environment at: .venv
2026-08-26 19:17:34.419414 | controller-1 | Downloading pygments (1.2MiB)
2026-08-26 19:17:34.435043 | controller-1 | Downloading ansible-core (2.3MiB)
2026-08-26 19:17:34.435609 | controller-1 | Downloading cryptography (4.3MiB)
2026-08-26 19:17:34.609565 | controller-1 |  Downloading cryptography
2026-08-26 19:17:34.662734 | controller-1 |  Downloading pygments
2026-08-26 19:17:34.825025 | controller-1 |  Downloading ansible-core
2026-08-26 19:17:34.897549 | controller-1 | Installed 36 packages in 71ms
2026-08-26 19:17:35.548307 | controller-1 | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-26 19:17:35.548357 | controller-1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-26 19:17:36.497648 | controller-1 | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-26 19:17:36.497711 | controller-1 | INFO     default scenario not found, disabling shared state.
2026-08-26 19:17:36.497802 | controller-1 | INFO     [cluster-upgrade > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-26 19:17:36.497871 | controller-1 | INFO     [cluster-upgrade > prerun] Performing prerun with role_name_check=0...
2026-08-26 19:27:40.402887 | controller-1 | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0
2026-08-26 19:27:40.402948 | controller-1 | ERROR    Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0, returned 1 code:
2026-08-26 19:27:40.402982 | controller-1 | Starting galaxy collection install process
2026-08-26 19:27:40.402992 | controller-1 | Process install dependency map
2026-08-26 19:27:40.403001 | controller-1 |
2026-08-26 19:27:40.403009 | controller-1 | ansible-galaxy
2026-08-26 19:27:40.403017 | controller-1 |   config file = None
2026-08-26 19:27:40.403024 | controller-1 |   configured module search path =
2026-08-26 19:27:40.403032 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-08-26 19:27:40.403049 | controller-1 |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-08-26 19:27:40.403059 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-08-26 19:27:40.403072 | controller-1 |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40)  (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-08-26 19:27:40.403080 | controller-1 |   jinja version = 3.1.6
2026-08-26 19:27:40.403087 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-08-26 19:27:40.403094 | controller-1 | No config file found; using defaults
2026-08-26 19:27:40.403101 | controller-1 | Found installed collection community.crypto:3.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-26 19:27:40.403108 | controller-1 | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-26 19:27:40.403115 | controller-1 | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-26 19:27:40.403122 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-26 19:27:40.403129 | controller-1 | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-08-26 19:27:40.403144 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-08-26 19:27:40.403161 | controller-1 | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection vexxhost.containers: Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-27T19:26:39Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results'
2026-08-26 19:27:40.403191 | controller-1 | : Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-27T19:26:39Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results'
2026-08-26 19:27:40.403204 | controller-1 |
2026-08-26 19:27:40.406362 | controller-1 | Traceback (most recent call last):
2026-08-26 19:27:40.409546 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-08-26 19:27:40.409569 | controller-1 |     sys.exit(main())
2026-08-26 19:27:40.409577 | controller-1 |              ~~~~^^
2026-08-26 19:27:40.409851 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1485, in __call__
2026-08-26 19:27:40.409868 | controller-1 |     return self.main(*args, **kwargs)
2026-08-26 19:27:40.409878 | controller-1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.410098 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1406, in main
2026-08-26 19:27:40.410107 | controller-1 |     rv = self.invoke(ctx)
2026-08-26 19:27:40.410373 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1873, in invoke
2026-08-26 19:27:40.410381 | controller-1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-08-26 19:27:40.410389 | controller-1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-08-26 19:27:40.410649 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1269, in invoke
2026-08-26 19:27:40.410661 | controller-1 |     return ctx.invoke(self.callback, **ctx.params)
2026-08-26 19:27:40.410669 | controller-1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.410909 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 824, in invoke
2026-08-26 19:27:40.410918 | controller-1 |     return callback(*args, **kwargs)
2026-08-26 19:27:40.411140 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-08-26 19:27:40.411149 | controller-1 |     return func(*args, **kwargs)
2026-08-26 19:27:40.411370 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func
2026-08-26 19:27:40.411379 | controller-1 |     return f(get_current_context(), *args, **kwargs)
2026-08-26 19:27:40.411597 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-08-26 19:27:40.411605 | controller-1 |     return func(ctx)
2026-08-26 19:27:40.411950 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test
2026-08-26 19:27:40.411961 | controller-1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-08-26 19:27:40.411968 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.412235 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-08-26 19:27:40.412245 | controller-1 |     _run_scenarios(scenarios, command_args, default_config)
2026-08-26 19:27:40.412253 | controller-1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.412670 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-08-26 19:27:40.412687 | controller-1 |     scenario.config.runtime.prepare_environment(
2026-08-26 19:27:40.412694 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-26 19:27:40.412701 | controller-1 |         install_local=True,
2026-08-26 19:27:40.412708 | controller-1 |         ^^^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.412724 | controller-1 |         role_name_check=role_name_check,
2026-08-26 19:27:40.412731 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.412738 | controller-1 |     )
2026-08-26 19:27:40.412745 | controller-1 |     ^
2026-08-26 19:27:40.413185 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-08-26 19:27:40.413194 | controller-1 |     self.install_collection(
2026-08-26 19:27:40.413201 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-26 19:27:40.413208 | controller-1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-08-26 19:27:40.413216 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.413223 | controller-1 |         destination=destination,
2026-08-26 19:27:40.413230 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 19:27:40.413236 | controller-1 |     )
2026-08-26 19:27:40.413243 | controller-1 |     ^
2026-08-26 19:27:40.413423 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-08-26 19:27:40.413431 | controller-1 |     raise InvalidPrerequisiteError(msg)
2026-08-26 19:27:40.414581 | controller-1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0, returned 1 code:
2026-08-26 19:27:40.414601 | controller-1 | Starting galaxy collection install process
2026-08-26 19:27:40.414611 | controller-1 | Process install dependency map
2026-08-26 19:27:40.414621 | controller-1 |
2026-08-26 19:27:40.414631 | controller-1 | ansible-galaxy [core 2.21.3]
2026-08-26 19:27:40.414640 | controller-1 |   config file = None
2026-08-26 19:27:40.414649 | controller-1 |   configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-08-26 19:27:40.414670 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-08-26 19:27:40.414678 | controller-1 |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-08-26 19:27:40.414687 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-08-26 19:27:40.414695 | controller-1 |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-08-26 19:27:40.414702 | controller-1 |   jinja version = 3.1.6
2026-08-26 19:27:40.414709 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-08-26 19:27:40.414716 | controller-1 | No config file found; using defaults
2026-08-26 19:27:40.414723 | controller-1 | Found installed collection community.crypto:3.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-26 19:27:40.414729 | controller-1 | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-26 19:27:40.414736 | controller-1 | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-26 19:27:40.414743 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-26 19:27:40.414750 | controller-1 | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-08-26 19:27:40.414766 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-08-26 19:27:40.414775 | controller-1 | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection vexxhost.containers: Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-27T19:26:39Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results'
2026-08-26 19:27:40.414782 | controller-1 | [ERROR]: Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-27T19:26:39Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results'
2026-08-26 19:27:40.414790 | controller-1 |
2026-08-26 19:27:40.885207 | controller-1 | ERROR
2026-08-26 19:27:40.885456 | controller-1 | {
2026-08-26 19:27:40.885514 | controller-1 |   "delta": "0:10:06.558518",
2026-08-26 19:27:40.885553 | controller-1 |   "end": "2026-08-26 19:27:40.497507",
2026-08-26 19:27:40.885586 | controller-1 |   "msg": "non-zero return code",
2026-08-26 19:27:40.885612 | controller-1 |   "rc": 1,
2026-08-26 19:27:40.885641 | controller-1 |   "start": "2026-08-26 19:17:33.938989"
2026-08-26 19:27:40.885677 | controller-1 | }
failure
2026-08-26 19:27:40.890841 | 
2026-08-26 19:27:40.890932 | PLAY RECAP
2026-08-26 19:27:40.891020 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:27:40.891070 | 
2026-08-26 19:27:41.001415 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-26 19:27:41.006843 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-26 19:27:41.618190 | 
2026-08-26 19:27:41.618305 | PLAY [all]
2026-08-26 19:27:41.629334 | 
2026-08-26 19:27:41.629411 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-26 19:27:42.047901 | controller-1 | changed
2026-08-26 19:27:42.237280 | controller-2 | changed
2026-08-26 19:27:42.242404 | controller-3 | changed
2026-08-26 19:27:42.248254 | 
2026-08-26 19:27:42.248447 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-26 19:27:42.706872 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:42.707013 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:42.714852 | controller-2 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.717432 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.719206 | controller-2 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.720900 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.723186 | controller-2 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.724926 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.726543 | controller-2 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.728823 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.731409 | controller-2 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.733357 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.723426 | controller-3 | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:42.723537 | controller-3 | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:42.731118 | controller-3 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.733973 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.735524 | controller-3 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.737191 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.739605 | controller-3 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.741984 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.743909 | controller-3 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.746741 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.748567 | controller-3 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.750774 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.709733 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:42.709826 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:42.717184 | controller-1 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.719157 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.720880 | controller-1 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.723076 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.725891 | controller-1 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.727326 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.728934 | controller-1 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.730940 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.732588 | controller-1 | environment: line 1: kubectl: command not found
2026-08-26 19:27:42.734617 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 19:27:42.811413 | controller-1 | ok: Runtime: 0:00:00.038657
2026-08-26 19:27:42.821039 | controller-2 | ok: Runtime: 0:00:00.041700
2026-08-26 19:27:42.830199 | controller-3 | ok: Runtime: 0:00:00.042825
2026-08-26 19:27:42.835506 | 
2026-08-26 19:27:42.835578 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-26 19:27:43.084052 | controller-1 | changed
2026-08-26 19:27:43.091112 | controller-2 | changed
2026-08-26 19:27:43.110632 | controller-3 | changed
2026-08-26 19:27:43.115979 | 
2026-08-26 19:27:43.116081 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-26 19:27:43.412279 | controller-2 | environment: line 5: kubectl: command not found
2026-08-26 19:27:43.413151 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.414101 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.414137 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.475015 | controller-3 | environment: line 5: kubectl: command not found
2026-08-26 19:27:43.476043 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.476233 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.477236 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.406248 | controller-1 | environment: line 5: kubectl: command not found
2026-08-26 19:27:43.407640 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.407910 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.408443 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 19:27:43.677809 | controller-1 | ok: Runtime: 0:00:00.012805
2026-08-26 19:27:43.713952 | controller-2 | ok: Runtime: 0:00:00.014231
2026-08-26 19:27:43.720599 | controller-3 | ok: Runtime: 0:00:00.013923
2026-08-26 19:27:43.724953 | 
2026-08-26 19:27:43.725099 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-26 19:27:44.331701 | controller-2 | changed:
2026-08-26 19:27:44.332069 | controller-2 | created directory /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/logs/controller-2
2026-08-26 19:27:44.332138 | controller-2 | cd+++++++++ objects/
2026-08-26 19:27:44.332187 | controller-2 | cd+++++++++ objects/cluster/
2026-08-26 19:27:44.332232 | controller-2 | cd+++++++++ objects/namespaced/
2026-08-26 19:27:44.337608 | controller-1 | changed:
2026-08-26 19:27:44.337696 | controller-1 | created directory /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/logs/controller-1
2026-08-26 19:27:44.337736 | controller-1 | cd+++++++++ objects/
2026-08-26 19:27:44.337767 | controller-1 | cd+++++++++ objects/cluster/
2026-08-26 19:27:44.337795 | controller-1 | cd+++++++++ objects/namespaced/
2026-08-26 19:27:44.373971 | controller-3 | changed:
2026-08-26 19:27:44.374069 | controller-3 | created directory /var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/logs/controller-3
2026-08-26 19:27:44.374096 | controller-3 | cd+++++++++ objects/
2026-08-26 19:27:44.374118 | controller-3 | cd+++++++++ objects/cluster/
2026-08-26 19:27:44.374138 | controller-3 | cd+++++++++ objects/namespaced/
2026-08-26 19:27:44.393418 | 
2026-08-26 19:27:44.393547 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-26 19:27:44.636874 | controller-1 | changed
2026-08-26 19:27:44.653789 | controller-2 | changed
2026-08-26 19:27:44.670964 | controller-3 | changed
2026-08-26 19:27:44.678966 | 
2026-08-26 19:27:44.679078 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-26 19:27:44.920628 | controller-1 | changed
2026-08-26 19:27:44.938677 | controller-2 | changed
2026-08-26 19:27:44.944747 | controller-3 | changed
2026-08-26 19:27:44.949946 | 
2026-08-26 19:27:44.950052 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-26 19:27:45.242426 | controller-2 | environment: line 3: kubectl: command not found
2026-08-26 19:27:45.263037 | controller-3 | environment: line 3: kubectl: command not found
2026-08-26 19:27:45.262563 | controller-1 | environment: line 3: kubectl: command not found
2026-08-26 19:27:45.510593 | controller-2 | ok: Runtime: 0:00:00.013484
2026-08-26 19:27:45.518404 | controller-3 | ok: Runtime: 0:00:00.013039
2026-08-26 19:27:45.523955 | controller-1 | ok: Runtime: 0:00:00.012591
2026-08-26 19:27:45.527754 | 
2026-08-26 19:27:45.527818 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-26 19:27:46.034400 | controller-3 | changed:
2026-08-26 19:27:46.034647 | controller-3 | cd+++++++++ pod-logs/
2026-08-26 19:27:46.034694 | controller-3 | cd+++++++++ pod-logs/failed-pods/
2026-08-26 19:27:46.039545 | controller-1 | changed:
2026-08-26 19:27:46.039593 | controller-1 | cd+++++++++ pod-logs/
2026-08-26 19:27:46.039619 | controller-1 | cd+++++++++ pod-logs/failed-pods/
2026-08-26 19:27:46.042769 | controller-2 | changed:
2026-08-26 19:27:46.042814 | controller-2 | cd+++++++++ pod-logs/
2026-08-26 19:27:46.042841 | controller-2 | cd+++++++++ pod-logs/failed-pods/
2026-08-26 19:27:46.056021 | 
2026-08-26 19:27:46.056114 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-26 19:27:46.284196 | controller-2 | changed: "values"
2026-08-26 19:27:46.320379 | controller-1 | changed: "values"
2026-08-26 19:27:46.334291 | controller-3 | changed: "values"
2026-08-26 19:27:46.507244 | controller-2 | changed: "releases"
2026-08-26 19:27:46.541246 | controller-1 | changed: "releases"
2026-08-26 19:27:46.541325 | controller-1 | changed: All items complete
2026-08-26 19:27:46.541361 | 
2026-08-26 19:27:46.553845 | controller-3 | changed: "releases"
2026-08-26 19:27:46.564196 | 
2026-08-26 19:27:46.564291 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-26 19:27:46.860619 | controller-2 | /bin/bash: line 3: kubectl: command not found
2026-08-26 19:27:46.881052 | controller-3 | /bin/bash: line 3: kubectl: command not found
2026-08-26 19:27:46.863516 | controller-1 | /bin/bash: line 3: kubectl: command not found
2026-08-26 19:27:47.126014 | controller-1 | ok: Runtime: 0:00:00.008659
2026-08-26 19:27:47.135594 | controller-2 | ok: Runtime: 0:00:00.008979
2026-08-26 19:27:47.141389 | controller-3 | ok: Runtime: 0:00:00.009154
2026-08-26 19:27:47.145190 | 
2026-08-26 19:27:47.145252 | TASK [helm-release-status : Downloads logs to executor]
2026-08-26 19:27:47.626529 | controller-1 | changed:
2026-08-26 19:27:47.626972 | controller-1 | cd+++++++++ helm/
2026-08-26 19:27:47.630027 | controller-1 | cd+++++++++ helm/releases/
2026-08-26 19:27:47.630111 | controller-1 | cd+++++++++ helm/values/
2026-08-26 19:27:47.647955 | controller-2 | changed:
2026-08-26 19:27:47.648062 | controller-2 | cd+++++++++ helm/
2026-08-26 19:27:47.648119 | controller-2 | cd+++++++++ helm/releases/
2026-08-26 19:27:47.648166 | controller-2 | cd+++++++++ helm/values/
2026-08-26 19:27:47.664009 | controller-3 | changed:
2026-08-26 19:27:47.664067 | controller-3 | cd+++++++++ helm/
2026-08-26 19:27:47.664092 | controller-3 | cd+++++++++ helm/releases/
2026-08-26 19:27:47.664114 | controller-3 | cd+++++++++ helm/values/
2026-08-26 19:27:47.675438 | 
2026-08-26 19:27:47.675492 | PLAY RECAP
2026-08-26 19:27:47.675542 | controller-1 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:27:47.675570 | controller-2 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:27:47.675596 | controller-3 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 19:27:47.675614 | 
2026-08-26 19:27:47.797617 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-26 19:27:47.804680 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 19:27:48.407633 | 
2026-08-26 19:27:48.407742 | PLAY [all]
2026-08-26 19:27:48.419796 | 
2026-08-26 19:27:48.419947 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-26 19:27:48.465573 | controller-1 | ok
2026-08-26 19:27:48.470619 | controller-2 | ok
2026-08-26 19:27:48.487957 | controller-3 | ok
2026-08-26 19:27:48.493894 | 
2026-08-26 19:27:48.493983 | TASK [fetch-output : Set log path for single node]
2026-08-26 19:27:48.525231 | controller-1 | skipping: Conditional result was False
2026-08-26 19:27:48.526010 | controller-2 | skipping: Conditional result was False
2026-08-26 19:27:48.536480 | controller-3 | skipping: Conditional result was False
2026-08-26 19:27:48.543242 | 
2026-08-26 19:27:48.543344 | LOOP [fetch-output : Ensure local output dirs]
2026-08-26 19:27:48.901990 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/logs/controller-1"
2026-08-26 19:27:48.904451 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/logs/controller-3"
2026-08-26 19:27:48.927917 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/logs/controller-2"
2026-08-26 19:27:49.101616 | controller-3 -> localhost | changed: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/artifacts"
2026-08-26 19:27:49.103089 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/artifacts"
2026-08-26 19:27:49.113028 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/artifacts"
2026-08-26 19:27:49.424745 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/docs"
2026-08-26 19:27:49.426075 | controller-3 -> localhost | changed: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/docs"
2026-08-26 19:27:49.429682 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/5f6facb2e75748f6ab2572a45be4e29c/work/docs"
2026-08-26 19:27:49.446978 | 
2026-08-26 19:27:49.447167 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-26 19:27:50.135663 | controller-1 | changed: .d..t...... ./
2026-08-26 19:27:50.135905 | controller-1 | ok: All items complete
2026-08-26 19:27:50.135946 | 
2026-08-26 19:27:50.617942 | controller-3 | changed: .d..t...... ./
2026-08-26 19:27:50.731658 | controller-2 | changed: .d..t...... ./
2026-08-26 19:27:51.087598 | controller-3 | changed: .d..t...... ./
2026-08-26 19:27:51.176859 | controller-2 | ok
2026-08-26 19:27:51.536732 | controller-3 | changed: .d..t...... ./
2026-08-26 19:27:51.578886 | controller-1 | ok
2026-08-26 19:27:51.649179 | controller-2 | ok
2026-08-26 19:27:52.037734 | controller-1 | ok
2026-08-26 19:27:52.037870 | controller-1 | changed: All items complete
2026-08-26 19:27:52.037912 | 
2026-08-26 19:27:52.055493 | 
2026-08-26 19:27:52.055597 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-26 19:27:52.466204 | controller-1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007845
2026-08-26 19:27:52.690295 | controller-1 -> localhost | ok: Item: docs Runtime: 0:00:00.006180
2026-08-26 19:27:52.718306 | 
2026-08-26 19:27:52.718412 | PLAY [all]
2026-08-26 19:27:52.724621 | 
2026-08-26 19:27:52.724681 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-26 19:27:53.195879 | controller-1 | changed
2026-08-26 19:27:53.353445 | controller-3 | changed
2026-08-26 19:27:53.369254 | controller-2 | changed
2026-08-26 19:27:53.385534 | 
2026-08-26 19:27:53.385597 | PLAY RECAP
2026-08-26 19:27:53.385644 | controller-1 | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 19:27:53.385672 | controller-2 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 19:27:53.385696 | controller-3 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 19:27:53.385727 | 
2026-08-26 19:27:53.527166 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 19:27:53.533479 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-26 19:27:54.111268 | 
2026-08-26 19:27:54.111372 | PLAY [localhost]
2026-08-26 19:27:54.121367 | 
2026-08-26 19:27:54.121439 | TASK [Generate Zuul manifest]
2026-08-26 19:27:54.143038 | localhost | ok
2026-08-26 19:27:54.161132 | 
2026-08-26 19:27:54.161214 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-26 19:27:54.513472 | localhost | changed
2026-08-26 19:27:54.527988 | 
2026-08-26 19:27:54.528066 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-26 19:27:54.557301 | localhost | ok
2026-08-26 19:27:54.566851 | 
2026-08-26 19:27:54.566938 | TASK [Upload logs]
2026-08-26 19:27:54.584689 | localhost | ok
2026-08-26 19:27:54.643159 | 
2026-08-26 19:27:54.643261 | TASK [Set zuul-log-path fact]
2026-08-26 19:27:54.661841 | localhost | ok
2026-08-26 19:27:54.675183 | 
2026-08-26 19:27:54.675248 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 19:27:54.706494 | localhost | ok
2026-08-26 19:27:54.715834 | 
2026-08-26 19:27:54.715895 | TASK [upload-logs : Create log directories]
2026-08-26 19:27:55.099525 | localhost | changed
2026-08-26 19:27:55.130475 | 
2026-08-26 19:27:55.130589 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-26 19:27:55.485871 | localhost -> localhost | ok: Runtime: 0:00:00.006396
2026-08-26 19:27:55.492390 | 
2026-08-26 19:27:55.492480 | TASK [upload-logs : Upload logs to log server]
2026-08-26 19:27:55.909125 | localhost | Output suppressed because no_log was given
2026-08-26 19:27:55.914034 | 
2026-08-26 19:27:55.914106 | LOOP [upload-logs : Compress console log and json output]
2026-08-26 19:27:55.956344 | localhost | skipping: Conditional result was False
2026-08-26 19:27:55.966556 | localhost | skipping: Conditional result was False
2026-08-26 19:27:55.978764 | 
2026-08-26 19:27:55.978922 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-26 19:27:56.019602 | localhost | skipping: Conditional result was False
2026-08-26 19:27:56.019962 | 
2026-08-26 19:27:56.023521 | localhost | skipping: Conditional result was False
2026-08-26 19:27:56.031164 | 
2026-08-26 19:27:56.031264 | LOOP [upload-logs : Upload console log and json output]