2026-09-10 00:42:48.067231 | Job console starting
2026-09-10 00:42:48.107598 | Updating git repos
2026-09-10 00:42:48.151801 | Cloning repos into workspace
2026-09-10 00:42:48.206127 | Restoring repo states
2026-09-10 00:42:48.223488 | Merging changes
2026-09-10 00:42:49.068253 | Checking out repos
2026-09-10 00:42:49.503663 | Preparing playbooks
2026-09-10 00:42:51.567916 | Running Ansible setup
2026-09-10 00:42:57.137344 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-10 00:42:57.724348 | 
2026-09-10 00:42:57.724479 | PLAY [localhost]
2026-09-10 00:42:57.731227 | 
2026-09-10 00:42:57.731292 | TASK [Gathering Facts]
2026-09-10 00:42:58.622416 | localhost | ok
2026-09-10 00:42:58.637822 | 
2026-09-10 00:42:58.637943 | TASK [Setup log path fact]
2026-09-10 00:42:58.657546 | localhost | ok
2026-09-10 00:42:58.671514 | 
2026-09-10 00:42:58.671601 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-10 00:42:58.711350 | localhost | ok
2026-09-10 00:42:58.721873 | 
2026-09-10 00:42:58.721975 | TASK [emit-job-header : Print job information]
2026-09-10 00:42:58.765836 | # Job Information
2026-09-10 00:42:58.766093 | Ansible Version: 2.16.18
2026-09-10 00:42:58.766178 | Job: ansible-collection-kubernetes-molecule-cluster-upgrade
2026-09-10 00:42:58.766222 | Pipeline: check
2026-09-10 00:42:58.766261 | Executor: 2d72f0692154
2026-09-10 00:42:58.766298 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293
2026-09-10 00:42:58.766342 | Event ID: 74b07920-acb0-11f1-857a-6fc2eb2d7428
2026-09-10 00:42:58.771962 | 
2026-09-10 00:42:58.772085 | LOOP [emit-job-header : Print node information]
2026-09-10 00:42:58.876446 | localhost | ok:
2026-09-10 00:42:58.877461 | localhost | # Node Information
2026-09-10 00:42:58.877528 | localhost | Inventory Hostname: controller-1
2026-09-10 00:42:58.877571 | localhost | Hostname: np0000192406
2026-09-10 00:42:58.877609 | localhost | Username: zuul
2026-09-10 00:42:58.877661 | localhost | Distro: Debian 13.6
2026-09-10 00:42:58.877700 | localhost | Provider: yul1
2026-09-10 00:42:58.877736 | localhost | Region: ca-ymq-1
2026-09-10 00:42:58.877770 | localhost | Label: debian-trixie
2026-09-10 00:42:58.877810 | localhost | Product Name: OpenStack Nova
2026-09-10 00:42:58.877862 | localhost | Interface IP: 162.253.55.223
2026-09-10 00:42:58.904094 | localhost | ok:
2026-09-10 00:42:58.904238 | localhost | # Node Information
2026-09-10 00:42:58.904304 | localhost | Inventory Hostname: controller-2
2026-09-10 00:42:58.904357 | localhost | Hostname: np0000192407
2026-09-10 00:42:58.904410 | localhost | Username: zuul
2026-09-10 00:42:58.904457 | localhost | Distro: Debian 13.6
2026-09-10 00:42:58.904544 | localhost | Provider: yul1
2026-09-10 00:42:58.904593 | localhost | Region: ca-ymq-1
2026-09-10 00:42:58.904638 | localhost | Label: debian-trixie
2026-09-10 00:42:58.904684 | localhost | Product Name: OpenStack Nova
2026-09-10 00:42:58.904730 | localhost | Interface IP: 162.253.55.144
2026-09-10 00:42:58.934456 | localhost | ok:
2026-09-10 00:42:58.934550 | localhost | # Node Information
2026-09-10 00:42:58.934598 | localhost | Inventory Hostname: controller-3
2026-09-10 00:42:58.934638 | localhost | Hostname: np0000192408
2026-09-10 00:42:58.934676 | localhost | Username: zuul
2026-09-10 00:42:58.934713 | localhost | Distro: Debian 13.6
2026-09-10 00:42:58.934751 | localhost | Provider: yul1
2026-09-10 00:42:58.934785 | localhost | Region: ca-ymq-1
2026-09-10 00:42:58.934822 | localhost | Label: debian-trixie
2026-09-10 00:42:58.934856 | localhost | Product Name: OpenStack Nova
2026-09-10 00:42:58.934891 | localhost | Interface IP: 162.253.55.4
2026-09-10 00:42:58.948769 | 
2026-09-10 00:42:58.948960 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-10 00:42:59.319189 | localhost -> localhost | changed
2026-09-10 00:42:59.328555 | 
2026-09-10 00:42:59.328753 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-10 00:43:00.301026 | localhost -> localhost | changed
2026-09-10 00:43:00.315243 | 
2026-09-10 00:43:00.315486 | PLAY [all]
2026-09-10 00:43:00.341205 | 
2026-09-10 00:43:00.341485 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-10 00:43:00.571206 | controller-1 -> localhost | ok
2026-09-10 00:43:00.580533 | 
2026-09-10 00:43:00.580936 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-10 00:43:00.622763 | controller-1 | ok
2026-09-10 00:43:00.629678 | controller-2 | ok
2026-09-10 00:43:00.644728 | controller-3 | ok
2026-09-10 00:43:00.670823 | controller-1 | included: /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-10 00:43:00.670910 | controller-2 | included: /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-10 00:43:00.670941 | controller-3 | included: /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-10 00:43:00.676670 | 
2026-09-10 00:43:00.676747 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-10 00:43:01.354514 | controller-1 -> localhost | Generating public/private rsa key pair.
2026-09-10 00:43:01.354816 | controller-1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/be4a430e83ed49b0a3b95e96fa65df00_id_rsa
2026-09-10 00:43:01.354878 | controller-1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/be4a430e83ed49b0a3b95e96fa65df00_id_rsa.pub
2026-09-10 00:43:01.354925 | controller-1 -> localhost | The key fingerprint is:
2026-09-10 00:43:01.354968 | controller-1 -> localhost | SHA256:l66h0iJ0qHcmGC/ubfRDGEqSDLJuTEwRo/Qw48v/yZ0 zuul-build-sshkey
2026-09-10 00:43:01.355012 | controller-1 -> localhost | The key's randomart image is:
2026-09-10 00:43:01.355053 | controller-1 -> localhost | +---[RSA 3072]----+
2026-09-10 00:43:01.355094 | controller-1 -> localhost | | Oo              |
2026-09-10 00:43:01.355162 | controller-1 -> localhost | |*.*              |
2026-09-10 00:43:01.355206 | controller-1 -> localhost | |B+ .             |
2026-09-10 00:43:01.355245 | controller-1 -> localhost | |==..       .     |
2026-09-10 00:43:01.355286 | controller-1 -> localhost | |*o..o   S o      |
2026-09-10 00:43:01.355325 | controller-1 -> localhost | |.=+o..   o       |
2026-09-10 00:43:01.355365 | controller-1 -> localhost | |.*.oo.  . .      |
2026-09-10 00:43:01.355404 | controller-1 -> localhost | |+ *.Bo+..o       |
2026-09-10 00:43:01.355444 | controller-1 -> localhost | |o=.* *oE.        |
2026-09-10 00:43:01.355484 | controller-1 -> localhost | +----[SHA256]-----+
2026-09-10 00:43:01.355577 | controller-1 -> localhost | ok: Runtime: 0:00:00.232265
2026-09-10 00:43:01.363979 | 
2026-09-10 00:43:01.364125 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-10 00:43:01.417895 | controller-1 | ok
2026-09-10 00:43:01.421904 | controller-2 | ok
2026-09-10 00:43:01.437035 | controller-3 | ok
2026-09-10 00:43:01.454985 | controller-1 | included: /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-10 00:43:01.455047 | controller-2 | included: /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-10 00:43:01.455077 | controller-3 | included: /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-10 00:43:01.468284 | 
2026-09-10 00:43:01.468340 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-10 00:43:01.498296 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:01.498962 | controller-2 | skipping: Conditional result was False
2026-09-10 00:43:01.509282 | controller-3 | skipping: Conditional result was False
2026-09-10 00:43:01.515959 | 
2026-09-10 00:43:01.516044 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-10 00:43:02.045908 | controller-2 | changed
2026-09-10 00:43:02.049739 | controller-3 | changed
2026-09-10 00:43:02.062559 | controller-1 | changed
2026-09-10 00:43:02.066127 | 
2026-09-10 00:43:02.066183 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-10 00:43:02.289738 | controller-1 | ok
2026-09-10 00:43:02.322972 | controller-3 | ok
2026-09-10 00:43:02.329665 | controller-2 | ok
2026-09-10 00:43:02.340248 | 
2026-09-10 00:43:02.340385 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-10 00:43:02.910412 | controller-1 | changed
2026-09-10 00:43:02.923866 | controller-3 | changed
2026-09-10 00:43:02.936772 | controller-2 | changed
2026-09-10 00:43:02.940089 | 
2026-09-10 00:43:02.940158 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-10 00:43:03.515197 | controller-2 | changed
2026-09-10 00:43:03.532049 | controller-1 | changed
2026-09-10 00:43:03.573942 | controller-3 | changed
2026-09-10 00:43:03.583883 | 
2026-09-10 00:43:03.584062 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-10 00:43:03.617276 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:03.619266 | controller-2 | skipping: Conditional result was False
2026-09-10 00:43:03.639786 | controller-3 | skipping: Conditional result was False
2026-09-10 00:43:03.646666 | 
2026-09-10 00:43:03.646775 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-10 00:43:03.995689 | controller-1 -> localhost | changed
2026-09-10 00:43:04.017280 | 
2026-09-10 00:43:04.017616 | TASK [add-build-sshkey : Add back temp key]
2026-09-10 00:43:04.320756 | controller-1 -> localhost | Identity added: /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/be4a430e83ed49b0a3b95e96fa65df00_id_rsa (zuul-build-sshkey)
2026-09-10 00:43:04.320968 | controller-1 -> localhost | ok: Runtime: 0:00:00.019105
2026-09-10 00:43:04.324536 | 
2026-09-10 00:43:04.324591 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-10 00:43:04.629176 | controller-2 | ok
2026-09-10 00:43:04.632085 | controller-1 | ok
2026-09-10 00:43:04.644771 | controller-3 | ok
2026-09-10 00:43:04.648207 | 
2026-09-10 00:43:04.648265 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-10 00:43:04.678384 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:04.689544 | controller-2 | skipping: Conditional result was False
2026-09-10 00:43:04.710001 | controller-3 | skipping: Conditional result was False
2026-09-10 00:43:04.725298 | 
2026-09-10 00:43:04.725511 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-10 00:43:05.093658 | controller-1 | ok
2026-09-10 00:43:05.096851 | controller-2 | ok
2026-09-10 00:43:05.121750 | controller-3 | ok
2026-09-10 00:43:05.125562 | 
2026-09-10 00:43:05.125618 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-10 00:43:06.273665 | controller-2 | Output suppressed because no_log was given
2026-09-10 00:43:06.289908 | controller-1 | Output suppressed because no_log was given
2026-09-10 00:43:06.305939 | controller-3 | Output suppressed because no_log was given
2026-09-10 00:43:06.338595 | 
2026-09-10 00:43:06.338706 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-10 00:43:06.568350 | controller-1 | ok: "logs"
2026-09-10 00:43:06.568868 | controller-1 | ok: All items complete
2026-09-10 00:43:06.568950 | 
2026-09-10 00:43:06.578918 | controller-2 | ok: "logs"
2026-09-10 00:43:06.591775 | controller-3 | ok: "logs"
2026-09-10 00:43:06.760487 | controller-2 | ok: "artifacts"
2026-09-10 00:43:06.770469 | controller-1 | ok: "artifacts"
2026-09-10 00:43:06.787605 | controller-3 | ok: "artifacts"
2026-09-10 00:43:06.969760 | controller-2 | ok: "docs"
2026-09-10 00:43:07.012086 | controller-3 | ok: "docs"
2026-09-10 00:43:07.021319 | controller-1 | ok: "docs"
2026-09-10 00:43:07.035589 | 
2026-09-10 00:43:07.035712 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-10 00:43:07.278081 | controller-1 | changed: "logs"
2026-09-10 00:43:07.278687 | controller-1 | ok: All items complete
2026-09-10 00:43:07.278746 | 
2026-09-10 00:43:07.285871 | controller-2 | changed: "logs"
2026-09-10 00:43:07.298340 | controller-3 | changed: "logs"
2026-09-10 00:43:07.472072 | controller-2 | changed: "artifacts"
2026-09-10 00:43:07.493922 | controller-1 | changed: "artifacts"
2026-09-10 00:43:07.500531 | controller-3 | changed: "artifacts"
2026-09-10 00:43:07.650128 | controller-2 | changed: "docs"
2026-09-10 00:43:07.688221 | controller-3 | changed: "docs"
2026-09-10 00:43:07.696249 | controller-1 | changed: "docs"
2026-09-10 00:43:07.725580 | 
2026-09-10 00:43:07.725763 | PLAY RECAP
2026-09-10 00:43:07.725852 | controller-1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-10 00:43:07.725908 | controller-2 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-10 00:43:07.725955 | controller-3 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-10 00:43:07.725999 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:43:07.726033 | 
2026-09-10 00:43:07.899002 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-10 00:43:07.904763 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-10 00:43:08.482306 | 
2026-09-10 00:43:08.482642 | PLAY [all]
2026-09-10 00:43:08.497684 | 
2026-09-10 00:43:08.497775 | TASK [setup-uv : Extract archive]
2026-09-10 00:43:11.010048 | controller-1 | changed
2026-09-10 00:43:11.078290 | controller-3 | changed
2026-09-10 00:43:11.105652 | controller-2 | changed
2026-09-10 00:43:11.114885 | 
2026-09-10 00:43:11.115069 | TASK [setup-uv : Print version]
2026-09-10 00:43:11.583830 | controller-2 | uv 0.8.13
2026-09-10 00:43:11.568908 | controller-1 | uv 0.8.13
2026-09-10 00:43:11.569122 | controller-3 | uv 0.8.13
2026-09-10 00:43:11.697314 | controller-3 | ok: Runtime: 0:00:00.017297
2026-09-10 00:43:11.709919 | controller-1 | ok: Runtime: 0:00:00.014563
2026-09-10 00:43:11.721313 | controller-2 | ok: Runtime: 0:00:00.016865
2026-09-10 00:43:11.741728 | 
2026-09-10 00:43:11.741801 | TASK [Install Ansible collection siblings]
2026-09-10 00:43:12.150204 | controller-1 | ok:
2026-09-10 00:43:12.150377 | controller-1 | Skipping ansible.posix: no checked-out sibling
2026-09-10 00:43:12.150407 | controller-1 | Skipping community.crypto: no checked-out sibling
2026-09-10 00:43:12.150427 | controller-1 | Skipping community.general: no checked-out sibling
2026-09-10 00:43:12.150445 | controller-1 | Skipping kubernetes.core: no checked-out sibling
2026-09-10 00:43:12.150467 | controller-1 | Skipping vexxhost.containers: no checked-out sibling
2026-09-10 00:43:12.152259 | 
2026-09-10 00:43:12.152306 | PLAY RECAP
2026-09-10 00:43:12.152352 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:43:12.152377 | controller-2 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:43:12.152399 | controller-3 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:43:12.152415 | 
2026-09-10 00:43:12.280868 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-10 00:43:12.288001 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-10 00:43:12.895182 | 
2026-09-10 00:43:12.895343 | PLAY [all]
2026-09-10 00:43:12.906323 | 
2026-09-10 00:43:12.906397 | TASK [Update apt cache]
2026-09-10 00:43:17.989157 | controller-2 | ok
2026-09-10 00:43:18.677355 | controller-1 | ok
2026-09-10 00:43:18.724555 | controller-3 | ok
2026-09-10 00:43:18.732198 | 
2026-09-10 00:43:18.732394 | TASK [Disable firewalld]
2026-09-10 00:43:18.770058 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:18.781740 | controller-2 | skipping: Conditional result was False
2026-09-10 00:43:18.782188 | controller-3 | skipping: Conditional result was False
2026-09-10 00:43:18.789992 | 
2026-09-10 00:43:18.790148 | TASK [Install "jq" for log collection]
2026-09-10 00:43:21.115025 | controller-2 | changed
2026-09-10 00:43:21.612289 | controller-3 | changed
2026-09-10 00:43:21.629638 | controller-1 | changed
2026-09-10 00:43:21.632701 | 
2026-09-10 00:43:21.632790 | PLAY RECAP
2026-09-10 00:43:21.632878 | controller-1 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:43:21.632932 | controller-2 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:43:21.632979 | controller-3 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:43:21.633015 | 
2026-09-10 00:43:21.772986 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-10 00:43:21.777010 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-10 00:43:22.403879 | 
2026-09-10 00:43:22.404004 | PLAY [all]
2026-09-10 00:43:22.414256 | 
2026-09-10 00:43:22.414329 | LOOP [Check inventory directories]
2026-09-10 00:43:22.790644 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-10 00:43:22.994951 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-upgrade/inventory"
2026-09-10 00:43:23.181270 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-upgrade/inventory"
2026-09-10 00:43:23.199950 | 
2026-09-10 00:43:23.200230 | LOOP [Copy inventory file for Zuul]
2026-09-10 00:43:24.158755 | controller-1 | changed:
2026-09-10 00:43:24.159073 | controller-1 | {
2026-09-10 00:43:24.159108 | controller-1 |   "ansible_loop_var": "item",
2026-09-10 00:43:24.159145 | controller-1 |   "changed": false,
2026-09-10 00:43:24.159165 | controller-1 |   "failed": false,
2026-09-10 00:43:24.159186 | controller-1 |   "invocation": {
2026-09-10 00:43:24.159203 | controller-1 |     "module_args": {
2026-09-10 00:43:24.159220 | controller-1 |       "checksum_algorithm": "sha1",
2026-09-10 00:43:24.159237 | controller-1 |       "follow": true,
2026-09-10 00:43:24.159253 | controller-1 |       "get_attributes": true,
2026-09-10 00:43:24.159271 | controller-1 |       "get_checksum": true,
2026-09-10 00:43:24.159287 | controller-1 |       "get_mime": true,
2026-09-10 00:43:24.159303 | controller-1 |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-10 00:43:24.159320 | controller-1 |     }
2026-09-10 00:43:24.159336 | controller-1 |   },
2026-09-10 00:43:24.159352 | controller-1 |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-10 00:43:24.159368 | controller-1 |   "stat": {
2026-09-10 00:43:24.159384 | controller-1 |     "atime": 1789000986.1305587,
2026-09-10 00:43:24.159400 | controller-1 |     "attr_flags": "e",
2026-09-10 00:43:24.159417 | controller-1 |     "attributes": [
2026-09-10 00:43:24.159433 | controller-1 |       "extents"
2026-09-10 00:43:24.159448 | controller-1 |     ],
2026-09-10 00:43:24.159464 | controller-1 |     "block_size": 4096,
2026-09-10 00:43:24.159480 | controller-1 |     "blocks": 8,
2026-09-10 00:43:24.159496 | controller-1 |     "charset": "unknown",
2026-09-10 00:43:24.159513 | controller-1 |     "ctime": 1789000986.1705596,
2026-09-10 00:43:24.159529 | controller-1 |     "dev": 65025,
2026-09-10 00:43:24.159545 | controller-1 |     "device_type": 0,
2026-09-10 00:43:24.159559 | controller-1 |     "executable": true,
2026-09-10 00:43:24.159576 | controller-1 |     "exists": true,
2026-09-10 00:43:24.159591 | controller-1 |     "gid": 1000,
2026-09-10 00:43:24.159619 | controller-1 |     "gr_name": "zuul",
2026-09-10 00:43:24.159636 | controller-1 |     "inode": 521495,
2026-09-10 00:43:24.159651 | controller-1 |     "isblk": false,
2026-09-10 00:43:24.159667 | controller-1 |     "ischr": false,
2026-09-10 00:43:24.159683 | controller-1 |     "isdir": true,
2026-09-10 00:43:24.159699 | controller-1 |     "isfifo": false,
2026-09-10 00:43:24.159714 | controller-1 |     "isgid": false,
2026-09-10 00:43:24.159729 | controller-1 |     "islnk": false,
2026-09-10 00:43:24.159745 | controller-1 |     "isreg": false,
2026-09-10 00:43:24.159760 | controller-1 |     "issock": false,
2026-09-10 00:43:24.159775 | controller-1 |     "isuid": false,
2026-09-10 00:43:24.159790 | controller-1 |     "mimetype": "unknown",
2026-09-10 00:43:24.159804 | controller-1 |     "mode": "0755",
2026-09-10 00:43:24.159819 | controller-1 |     "mtime": 1789000968.3451734,
2026-09-10 00:43:24.159835 | controller-1 |     "nlink": 14,
2026-09-10 00:43:24.159850 | controller-1 |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-10 00:43:24.159866 | controller-1 |     "pw_name": "zuul",
2026-09-10 00:43:24.159884 | controller-1 |     "readable": true,
2026-09-10 00:43:24.159900 | controller-1 |     "rgrp": true,
2026-09-10 00:43:24.159916 | controller-1 |     "roth": true,
2026-09-10 00:43:24.159931 | controller-1 |     "rusr": true,
2026-09-10 00:43:24.159947 | controller-1 |     "size": 4096,
2026-09-10 00:43:24.159962 | controller-1 |     "uid": 1000,
2026-09-10 00:43:24.159978 | controller-1 |     "version": "667778869",
2026-09-10 00:43:24.159994 | controller-1 |     "wgrp": false,
2026-09-10 00:43:24.160011 | controller-1 |     "woth": false,
2026-09-10 00:43:24.160028 | controller-1 |     "writeable": true,
2026-09-10 00:43:24.160043 | controller-1 |     "wusr": true,
2026-09-10 00:43:24.160059 | controller-1 |     "xgrp": true,
2026-09-10 00:43:24.160073 | controller-1 |     "xoth": true,
2026-09-10 00:43:24.160088 | controller-1 |     "xusr": true
2026-09-10 00:43:24.160104 | controller-1 |   }
2026-09-10 00:43:24.160119 | controller-1 | }
2026-09-10 00:43:24.160169 | controller-1 | ok: All items complete
2026-09-10 00:43:24.160189 | 
2026-09-10 00:43:24.162808 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:24.169889 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:24.187458 | 
2026-09-10 00:43:24.187621 | LOOP [Switch "ansible_host" to private IP]
2026-09-10 00:43:24.563301 | controller-1 | changed: 3 replacements made
2026-09-10 00:43:24.565601 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:24.571025 | controller-1 | skipping: Conditional result was False
2026-09-10 00:43:24.584824 | 
2026-09-10 00:43:24.585022 | TASK [Run Molecule scenario]
2026-09-10 00:43:25.134704 | controller-1 | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-10 00:43:25.134859 | controller-1 | Creating virtual environment at: .venv
2026-09-10 00:43:27.093640 | controller-1 | Downloading cryptography (4.3MiB)
2026-09-10 00:43:27.094052 | controller-1 | Downloading pygments (1.2MiB)
2026-09-10 00:43:27.103223 | controller-1 | Downloading ansible-core (2.3MiB)
2026-09-10 00:43:27.354719 | controller-1 |  Downloading cryptography
2026-09-10 00:43:27.357576 | controller-1 |  Downloading pygments
2026-09-10 00:43:27.529919 | controller-1 |  Downloading ansible-core
2026-09-10 00:43:27.582092 | controller-1 | Installed 36 packages in 51ms
2026-09-10 00:43:28.278510 | controller-1 | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-10 00:43:28.278559 | controller-1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-10 00:43:29.203431 | controller-1 | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-10 00:43:29.203476 | controller-1 | INFO     default scenario not found, disabling shared state.
2026-09-10 00:43:29.203613 | controller-1 | INFO     [cluster-upgrade > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-10 00:43:29.203707 | controller-1 | INFO     [cluster-upgrade > prerun] Performing prerun with role_name_check=0...
2026-09-10 00:44:51.659061 | controller-1 | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-10 00:44:51.659165 | controller-1 | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-09-10 00:44:51.659176 | controller-1 | Starting galaxy collection install process
2026-09-10 00:44:51.659184 | controller-1 | Process install dependency map
2026-09-10 00:44:51.659194 | controller-1 |
2026-09-10 00:44:51.659202 | controller-1 | ansible-galaxy
2026-09-10 00:44:51.659210 | controller-1 |   config file = None
2026-09-10 00:44:51.659218 | controller-1 |   configured module search path =
2026-09-10 00:44:51.659225 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-10 00:44:51.659236 | 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-09-10 00:44:51.659245 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-10 00:44:51.659255 | 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-09-10 00:44:51.659264 | controller-1 |   jinja version = 3.1.6
2026-09-10 00:44:51.659271 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-10 00:44:51.659307 | controller-1 | No config file found; using defaults
2026-09-10 00:44:51.659314 | controller-1 | Created /home/zuul/.ansible/galaxy_token
2026-09-10 00:44:51.659321 | controller-1 | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: 'results'
2026-09-10 00:44:51.659328 | controller-1 | : Unexpected Exception, this is probably a bug: 'results'
2026-09-10 00:44:51.659336 | controller-1 |
2026-09-10 00:44:51.659343 | controller-1 | Traceback (most recent call last):
2026-09-10 00:44:51.659350 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 661, in cli_executor
2026-09-10 00:44:51.659357 | controller-1 |     exit_code = cli.run()
2026-09-10 00:44:51.659364 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 723, in run
2026-09-10 00:44:51.659371 | controller-1 |     return context.CLIARGS()
2026-09-10 00:44:51.659378 | controller-1 |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-10 00:44:51.659385 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper
2026-09-10 00:44:51.659405 | controller-1 |     return wrapped_method(*args, **kwargs)
2026-09-10 00:44:51.659419 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1388, in execute_install
2026-09-10 00:44:51.659426 | controller-1 |     self._execute_install_collection(
2026-09-10 00:44:51.659432 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.659439 | controller-1 |         collection_requirements, collection_path,
2026-09-10 00:44:51.659446 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659453 | controller-1 |         artifacts_manager=artifacts_manager,
2026-09-10 00:44:51.659459 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659466 | controller-1 |     )
2026-09-10 00:44:51.659473 | controller-1 |     ^
2026-09-10 00:44:51.659480 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1437, in _execute_install_collection
2026-09-10 00:44:51.659487 | controller-1 |     install_collections(
2026-09-10 00:44:51.659494 | controller-1 |     ~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.659501 | controller-1 |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-10 00:44:51.659508 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659515 | controller-1 |     ...<5 lines>...
2026-09-10 00:44:51.659522 | controller-1 |         read_requirement_paths=read_req_paths,
2026-09-10 00:44:51.659529 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659536 | controller-1 |     )
2026-09-10 00:44:51.659543 | controller-1 |     ^
2026-09-10 00:44:51.659550 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 722, in install_collections
2026-09-10 00:44:51.659557 | controller-1 |     dependency_map = _resolve_depenency_map(
2026-09-10 00:44:51.659564 | controller-1 |         collections,
2026-09-10 00:44:51.659570 | controller-1 |     ...<7 lines>...
2026-09-10 00:44:51.659577 | controller-1 |         offline=offline,
2026-09-10 00:44:51.659584 | controller-1 |     )
2026-09-10 00:44:51.659591 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1844, in _resolve_depenency_map
2026-09-10 00:44:51.659598 | controller-1 |     collection_dep_resolver.resolve(
2026-09-10 00:44:51.659604 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.659611 | controller-1 |         requested_requirements,
2026-09-10 00:44:51.659618 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659625 | controller-1 |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-10 00:44:51.659632 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659639 | controller-1 |     ).mapping,
2026-09-10 00:44:51.659646 | controller-1 |     ^
2026-09-10 00:44:51.659653 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 601, in resolve
2026-09-10 00:44:51.659660 | controller-1 |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-10 00:44:51.659675 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 434, in resolve
2026-09-10 00:44:51.659683 | controller-1 |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-10 00:44:51.659689 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659696 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria
2026-09-10 00:44:51.659703 | controller-1 |     matches = self._p.find_matches(
2026-09-10 00:44:51.659710 | controller-1 |         identifier=identifier,
2026-09-10 00:44:51.659717 | controller-1 |     ...<9 lines>...
2026-09-10 00:44:51.659771 | controller-1 |         ),
2026-09-10 00:44:51.659778 | controller-1 |     )
2026-09-10 00:44:51.659785 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 192, in find_matches
2026-09-10 00:44:51.659798 | controller-1 |     match for match in self._find_matches(list(requirements))
2026-09-10 00:44:51.659805 | controller-1 |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.659812 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 219, in _find_matches
2026-09-10 00:44:51.659819 | controller-1 |     else self._api_proxy.get_collection_versions(first_req)
2026-09-10 00:44:51.659826 | controller-1 |          ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-10 00:44:51.659833 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions
2026-09-10 00:44:51.659840 | controller-1 |     return set(
2026-09-10 00:44:51.659847 | controller-1 |         (version, api)
2026-09-10 00:44:51.659854 | controller-1 |     ...<2 lines>...
2026-09-10 00:44:51.659861 | controller-1 |         )
2026-09-10 00:44:51.659867 | controller-1 |     )
2026-09-10 00:44:51.659874 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 105, in <genexpr>
2026-09-10 00:44:51.659881 | controller-1 |     for api, version in self._get_collection_versions(
2026-09-10 00:44:51.659888 | controller-1 |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.659895 | controller-1 |         requirement,
2026-09-10 00:44:51.659901 | controller-1 |         ^^^^^^^^^^^^
2026-09-10 00:44:51.659908 | controller-1 |     )
2026-09-10 00:44:51.659915 | controller-1 |     ^
2026-09-10 00:44:51.659928 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions
2026-09-10 00:44:51.659935 | controller-1 |     raise last_error
2026-09-10 00:44:51.659942 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions
2026-09-10 00:44:51.659949 | controller-1 |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-10 00:44:51.659956 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 128, in wrapped
2026-09-10 00:44:51.659963 | controller-1 |     return method(self, *args, **kwargs)
2026-09-10 00:44:51.659970 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 830, in get_collection_versions
2026-09-10 00:44:51.659977 | controller-1 |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-09-10 00:44:51.659984 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function
2026-09-10 00:44:51.659992 | controller-1 |     return call_retryable_function()
2026-09-10 00:44:51.660001 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 366, in _call_galaxy
2026-09-10 00:44:51.660010 | controller-1 |     res = path_cache
2026-09-10 00:44:51.660020 | controller-1 |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-10 00:44:51.660029 | controller-1 | KeyError: 'results'
2026-09-10 00:44:51.660039 | controller-1 |
2026-09-10 00:44:51.660049 | controller-1 | The above exception was the direct cause of the following exception:
2026-09-10 00:44:51.660058 | controller-1 |
2026-09-10 00:44:51.660067 | controller-1 | Traceback (most recent call last):
2026-09-10 00:44:51.660077 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 670, in cli_executor
2026-09-10 00:44:51.660086 | controller-1 |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-10 00:44:51.660095 | controller-1 | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-10 00:44:51.660113 | controller-1 |
2026-09-10 00:44:51.660122 | controller-1 |
2026-09-10 00:44:51.662537 | controller-1 | Traceback (most recent call last):
2026-09-10 00:44:51.665849 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-10 00:44:51.665859 | controller-1 |     sys.exit(main())
2026-09-10 00:44:51.665862 | controller-1 |              ~~~~^^
2026-09-10 00:44:51.666142 | 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-09-10 00:44:51.666147 | controller-1 |     return self.main(*args, **kwargs)
2026-09-10 00:44:51.666150 | controller-1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.666389 | 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-09-10 00:44:51.666393 | controller-1 |     rv = self.invoke(ctx)
2026-09-10 00:44:51.666666 | 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-09-10 00:44:51.666670 | controller-1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-10 00:44:51.666673 | controller-1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-10 00:44:51.666964 | 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-09-10 00:44:51.666968 | controller-1 |     return ctx.invoke(self.callback, **ctx.params)
2026-09-10 00:44:51.666971 | controller-1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.667212 | 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-09-10 00:44:51.667216 | controller-1 |     return callback(*args, **kwargs)
2026-09-10 00:44:51.667452 | 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-09-10 00:44:51.667456 | controller-1 |     return func(*args, **kwargs)
2026-09-10 00:44:51.667710 | 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-09-10 00:44:51.667713 | controller-1 |     return f(get_current_context(), *args, **kwargs)
2026-09-10 00:44:51.668023 | 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-09-10 00:44:51.668027 | controller-1 |     return func(ctx)
2026-09-10 00:44:51.668317 | 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-09-10 00:44:51.668320 | controller-1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-10 00:44:51.668324 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.668592 | 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-09-10 00:44:51.668596 | controller-1 |     _run_scenarios(scenarios, command_args, default_config)
2026-09-10 00:44:51.668599 | controller-1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.669053 | 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-09-10 00:44:51.669056 | controller-1 |     scenario.config.runtime.prepare_environment(
2026-09-10 00:44:51.669059 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.669062 | controller-1 |         install_local=True,
2026-09-10 00:44:51.669066 | controller-1 |         ^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.669069 | controller-1 |         role_name_check=role_name_check,
2026-09-10 00:44:51.669072 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.669075 | controller-1 |     )
2026-09-10 00:44:51.669078 | controller-1 |     ^
2026-09-10 00:44:51.669487 | 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-09-10 00:44:51.669495 | controller-1 |     self.install_collection(
2026-09-10 00:44:51.669498 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.669501 | controller-1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-10 00:44:51.669505 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.669508 | controller-1 |         destination=destination,
2026-09-10 00:44:51.669511 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.669514 | controller-1 |     )
2026-09-10 00:44:51.669517 | controller-1 |     ^
2026-09-10 00:44:51.669740 | 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-09-10 00:44:51.669744 | controller-1 |     raise InvalidPrerequisiteError(msg)
2026-09-10 00:44:51.673888 | controller-1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-09-10 00:44:51.673898 | controller-1 | Starting galaxy collection install process
2026-09-10 00:44:51.673901 | controller-1 | Process install dependency map
2026-09-10 00:44:51.673905 | controller-1 |
2026-09-10 00:44:51.673908 | controller-1 | ansible-galaxy [core 2.20.1]
2026-09-10 00:44:51.673911 | controller-1 |   config file = None
2026-09-10 00:44:51.673914 | 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-09-10 00:44:51.673918 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-10 00:44:51.673921 | 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-09-10 00:44:51.673925 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-10 00:44:51.673928 | 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-09-10 00:44:51.673931 | controller-1 |   jinja version = 3.1.6
2026-09-10 00:44:51.673934 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-10 00:44:51.673937 | controller-1 | No config file found; using defaults
2026-09-10 00:44:51.673940 | controller-1 | Created /home/zuul/.ansible/galaxy_token
2026-09-10 00:44:51.673943 | controller-1 | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: 'results'
2026-09-10 00:44:51.673946 | controller-1 | [ERROR]: Unexpected Exception, this is probably a bug: 'results'
2026-09-10 00:44:51.673950 | controller-1 |
2026-09-10 00:44:51.673953 | controller-1 | Traceback (most recent call last):
2026-09-10 00:44:51.673956 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 661, in cli_executor
2026-09-10 00:44:51.673959 | controller-1 |     exit_code = cli.run()
2026-09-10 00:44:51.673962 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 723, in run
2026-09-10 00:44:51.673965 | controller-1 |     return context.CLIARGS['func']()
2026-09-10 00:44:51.673968 | controller-1 |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-10 00:44:51.673971 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper
2026-09-10 00:44:51.673974 | controller-1 |     return wrapped_method(*args, **kwargs)
2026-09-10 00:44:51.673977 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1388, in execute_install
2026-09-10 00:44:51.673984 | controller-1 |     self._execute_install_collection(
2026-09-10 00:44:51.673987 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.673990 | controller-1 |         collection_requirements, collection_path,
2026-09-10 00:44:51.673993 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674001 | controller-1 |         artifacts_manager=artifacts_manager,
2026-09-10 00:44:51.674004 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674007 | controller-1 |     )
2026-09-10 00:44:51.674010 | controller-1 |     ^
2026-09-10 00:44:51.674013 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1437, in _execute_install_collection
2026-09-10 00:44:51.674016 | controller-1 |     install_collections(
2026-09-10 00:44:51.674019 | controller-1 |     ~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.674022 | controller-1 |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-10 00:44:51.674025 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674028 | controller-1 |     ...<5 lines>...
2026-09-10 00:44:51.674031 | controller-1 |         read_requirement_paths=read_req_paths,
2026-09-10 00:44:51.674034 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674037 | controller-1 |     )
2026-09-10 00:44:51.674040 | controller-1 |     ^
2026-09-10 00:44:51.674043 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 722, in install_collections
2026-09-10 00:44:51.674046 | controller-1 |     dependency_map = _resolve_depenency_map(
2026-09-10 00:44:51.674049 | controller-1 |         collections,
2026-09-10 00:44:51.674052 | controller-1 |     ...<7 lines>...
2026-09-10 00:44:51.674055 | controller-1 |         offline=offline,
2026-09-10 00:44:51.674058 | controller-1 |     )
2026-09-10 00:44:51.674061 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1844, in _resolve_depenency_map
2026-09-10 00:44:51.674064 | controller-1 |     collection_dep_resolver.resolve(
2026-09-10 00:44:51.674067 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.674070 | controller-1 |         requested_requirements,
2026-09-10 00:44:51.674073 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674076 | controller-1 |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-10 00:44:51.674079 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674082 | controller-1 |     ).mapping,
2026-09-10 00:44:51.674085 | controller-1 |     ^
2026-09-10 00:44:51.674093 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 601, in resolve
2026-09-10 00:44:51.674097 | controller-1 |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-10 00:44:51.674100 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 434, in resolve
2026-09-10 00:44:51.674103 | controller-1 |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-10 00:44:51.674106 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674109 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria
2026-09-10 00:44:51.674112 | controller-1 |     matches = self._p.find_matches(
2026-09-10 00:44:51.674115 | controller-1 |         identifier=identifier,
2026-09-10 00:44:51.674118 | controller-1 |     ...<9 lines>...
2026-09-10 00:44:51.674122 | controller-1 |         ),
2026-09-10 00:44:51.674125 | controller-1 |     )
2026-09-10 00:44:51.674128 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 192, in find_matches
2026-09-10 00:44:51.674131 | controller-1 |     match for match in self._find_matches(list(requirements[identifier]))
2026-09-10 00:44:51.674134 | controller-1 |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-10 00:44:51.674140 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 219, in _find_matches
2026-09-10 00:44:51.674144 | controller-1 |     else self._api_proxy.get_collection_versions(first_req)
2026-09-10 00:44:51.674147 | controller-1 |          ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-10 00:44:51.674150 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions
2026-09-10 00:44:51.674153 | controller-1 |     return set(
2026-09-10 00:44:51.674156 | controller-1 |         (version, api)
2026-09-10 00:44:51.674159 | controller-1 |     ...<2 lines>...
2026-09-10 00:44:51.674162 | controller-1 |         )
2026-09-10 00:44:51.674165 | controller-1 |     )
2026-09-10 00:44:51.674168 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 105, in <genexpr>
2026-09-10 00:44:51.674171 | controller-1 |     for api, version in self._get_collection_versions(
2026-09-10 00:44:51.674174 | controller-1 |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-10 00:44:51.674177 | controller-1 |         requirement,
2026-09-10 00:44:51.674180 | controller-1 |         ^^^^^^^^^^^^
2026-09-10 00:44:51.674183 | controller-1 |     )
2026-09-10 00:44:51.674186 | controller-1 |     ^
2026-09-10 00:44:51.674189 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions
2026-09-10 00:44:51.674192 | controller-1 |     raise last_error
2026-09-10 00:44:51.674195 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions
2026-09-10 00:44:51.674198 | controller-1 |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-10 00:44:51.674201 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 128, in wrapped
2026-09-10 00:44:51.674204 | controller-1 |     return method(self, *args, **kwargs)
2026-09-10 00:44:51.674210 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 830, in get_collection_versions
2026-09-10 00:44:51.674214 | controller-1 |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-09-10 00:44:51.674217 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function
2026-09-10 00:44:51.674220 | controller-1 |     return call_retryable_function()
2026-09-10 00:44:51.674223 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 366, in _call_galaxy
2026-09-10 00:44:51.674226 | controller-1 |     res = path_cache['results']
2026-09-10 00:44:51.674229 | controller-1 |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-10 00:44:51.674232 | controller-1 | KeyError: 'results'
2026-09-10 00:44:51.674235 | controller-1 |
2026-09-10 00:44:51.674238 | controller-1 | The above exception was the direct cause of the following exception:
2026-09-10 00:44:51.674241 | controller-1 |
2026-09-10 00:44:51.674244 | controller-1 | Traceback (most recent call last):
2026-09-10 00:44:51.674247 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 670, in cli_executor
2026-09-10 00:44:51.674250 | controller-1 |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-10 00:44:51.674253 | controller-1 | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-10 00:44:51.674256 | controller-1 |
2026-09-10 00:44:51.674259 | controller-1 |
2026-09-10 00:44:52.217445 | controller-1 | ERROR
2026-09-10 00:44:52.217651 | controller-1 | {
2026-09-10 00:44:52.217688 | controller-1 |   "delta": "0:01:26.729132",
2026-09-10 00:44:52.217713 | controller-1 |   "end": "2026-09-10 00:44:51.761494",
2026-09-10 00:44:52.217736 | controller-1 |   "msg": "non-zero return code",
2026-09-10 00:44:52.217758 | controller-1 |   "rc": 1,
2026-09-10 00:44:52.217779 | controller-1 |   "start": "2026-09-10 00:43:25.032362"
2026-09-10 00:44:52.217801 | controller-1 | }
failure
2026-09-10 00:44:52.222850 | 
2026-09-10 00:44:52.222915 | PLAY RECAP
2026-09-10 00:44:52.223091 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:44:52.223145 | 
2026-09-10 00:44:52.372470 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-10 00:44:52.382961 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-10 00:44:53.025386 | 
2026-09-10 00:44:53.025889 | PLAY [all]
2026-09-10 00:44:53.036706 | 
2026-09-10 00:44:53.036782 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-10 00:44:53.450244 | controller-1 | changed
2026-09-10 00:44:53.652745 | controller-3 | changed
2026-09-10 00:44:53.670120 | controller-2 | changed
2026-09-10 00:44:53.680473 | 
2026-09-10 00:44:53.680728 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-10 00:44:54.154370 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.154497 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.162983 | controller-2 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.165300 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.170873 | controller-2 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.171709 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.172428 | controller-2 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.174515 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.179366 | controller-2 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.180686 | controller-2 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.182056 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.182362 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.157295 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.158300 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.164855 | controller-3 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.167475 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.168782 | controller-3 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.173033 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.173058 | controller-3 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.175479 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.176096 | controller-3 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.178182 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.179527 | controller-3 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.181871 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.151844 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.151950 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.158649 | controller-1 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.160760 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.161671 | controller-1 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.163677 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.165615 | controller-1 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.167413 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.168941 | controller-1 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.170871 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.172209 | controller-1 | environment: line 1: kubectl: command not found
2026-09-10 00:44:54.174191 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:44:54.265449 | controller-1 | ok: Runtime: 0:00:00.037100
2026-09-10 00:44:54.278285 | controller-2 | ok: Runtime: 0:00:00.044400
2026-09-10 00:44:54.292910 | controller-3 | ok: Runtime: 0:00:00.040168
2026-09-10 00:44:54.301805 | 
2026-09-10 00:44:54.301890 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-10 00:44:54.549084 | controller-2 | changed
2026-09-10 00:44:54.556804 | controller-1 | changed
2026-09-10 00:44:54.575614 | controller-3 | changed
2026-09-10 00:44:54.582687 | 
2026-09-10 00:44:54.582864 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-10 00:44:54.887972 | controller-2 | environment: line 5: kubectl: command not found
2026-09-10 00:44:54.890523 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.890548 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.890566 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.958884 | controller-3 | environment: line 5: kubectl: command not found
2026-09-10 00:44:54.960151 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.960269 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.960633 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.897915 | controller-1 | environment: line 5: kubectl: command not found
2026-09-10 00:44:54.898924 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.899477 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:54.899668 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:44:55.150932 | controller-2 | ok: Runtime: 0:00:00.015109
2026-09-10 00:44:55.198087 | controller-1 | ok: Runtime: 0:00:00.012354
2026-09-10 00:44:55.204852 | controller-3 | ok: Runtime: 0:00:00.015075
2026-09-10 00:44:55.210095 | 
2026-09-10 00:44:55.210181 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-10 00:44:55.803069 | controller-1 | changed:
2026-09-10 00:44:55.803488 | controller-1 | created directory /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/logs/controller-1
2026-09-10 00:44:55.803552 | controller-1 | cd+++++++++ objects/
2026-09-10 00:44:55.803596 | controller-1 | cd+++++++++ objects/cluster/
2026-09-10 00:44:55.803634 | controller-1 | cd+++++++++ objects/namespaced/
2026-09-10 00:44:55.833640 | controller-3 | changed:
2026-09-10 00:44:55.833896 | controller-3 | created directory /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/logs/controller-3
2026-09-10 00:44:55.834059 | controller-3 | cd+++++++++ objects/
2026-09-10 00:44:55.834201 | controller-3 | cd+++++++++ objects/cluster/
2026-09-10 00:44:55.834374 | controller-3 | cd+++++++++ objects/namespaced/
2026-09-10 00:44:55.861938 | controller-2 | changed:
2026-09-10 00:44:55.862047 | controller-2 | created directory /var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/logs/controller-2
2026-09-10 00:44:55.862116 | controller-2 | cd+++++++++ objects/
2026-09-10 00:44:55.862147 | controller-2 | cd+++++++++ objects/cluster/
2026-09-10 00:44:55.862171 | controller-2 | cd+++++++++ objects/namespaced/
2026-09-10 00:44:55.896126 | 
2026-09-10 00:44:55.896350 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-10 00:44:56.148883 | controller-1 | changed
2026-09-10 00:44:56.166458 | controller-2 | changed
2026-09-10 00:44:56.183201 | controller-3 | changed
2026-09-10 00:44:56.192205 | 
2026-09-10 00:44:56.192396 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-10 00:44:56.445009 | controller-1 | changed
2026-09-10 00:44:56.482463 | controller-2 | changed
2026-09-10 00:44:56.499000 | controller-3 | changed
2026-09-10 00:44:56.506884 | 
2026-09-10 00:44:56.507050 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-10 00:44:56.859573 | controller-2 | environment: line 3: kubectl: command not found
2026-09-10 00:44:56.860094 | controller-3 | environment: line 3: kubectl: command not found
2026-09-10 00:44:56.833167 | controller-1 | environment: line 3: kubectl: command not found
2026-09-10 00:44:57.094876 | controller-1 | ok: Runtime: 0:00:00.011329
2026-09-10 00:44:57.106401 | controller-2 | ok: Runtime: 0:00:00.012468
2026-09-10 00:44:57.117289 | controller-3 | ok: Runtime: 0:00:00.013765
2026-09-10 00:44:57.124903 | 
2026-09-10 00:44:57.125028 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-10 00:44:57.667178 | controller-1 | changed:
2026-09-10 00:44:57.667557 | controller-1 | cd+++++++++ pod-logs/
2026-09-10 00:44:57.667614 | controller-1 | cd+++++++++ pod-logs/failed-pods/
2026-09-10 00:44:57.674728 | controller-2 | changed:
2026-09-10 00:44:57.674815 | controller-2 | cd+++++++++ pod-logs/
2026-09-10 00:44:57.674863 | controller-2 | cd+++++++++ pod-logs/failed-pods/
2026-09-10 00:44:57.712603 | controller-3 | changed:
2026-09-10 00:44:57.712710 | controller-3 | cd+++++++++ pod-logs/
2026-09-10 00:44:57.712761 | controller-3 | cd+++++++++ pod-logs/failed-pods/
2026-09-10 00:44:57.739463 | 
2026-09-10 00:44:57.739711 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-10 00:44:58.007419 | controller-1 | changed: "values"
2026-09-10 00:44:58.014860 | controller-2 | changed: "values"
2026-09-10 00:44:58.038509 | controller-3 | changed: "values"
2026-09-10 00:44:58.237324 | controller-2 | changed: "releases"
2026-09-10 00:44:58.254357 | controller-1 | changed: "releases"
2026-09-10 00:44:58.261459 | controller-3 | changed: "releases"
2026-09-10 00:44:58.261517 | controller-3 | changed: All items complete
2026-09-10 00:44:58.261542 | 
2026-09-10 00:44:58.280974 | 
2026-09-10 00:44:58.281166 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-10 00:44:58.599207 | controller-2 | /bin/bash: line 3: kubectl: command not found
2026-09-10 00:44:58.613619 | controller-3 | /bin/bash: line 3: kubectl: command not found
2026-09-10 00:44:58.596236 | controller-1 | /bin/bash: line 3: kubectl: command not found
2026-09-10 00:44:58.845819 | controller-2 | ok: Runtime: 0:00:00.010373
2026-09-10 00:44:58.858753 | controller-1 | ok: Runtime: 0:00:00.009243
2026-09-10 00:44:58.869692 | controller-3 | ok: Runtime: 0:00:00.008023
2026-09-10 00:44:58.877109 | 
2026-09-10 00:44:58.877251 | TASK [helm-release-status : Downloads logs to executor]
2026-09-10 00:44:59.435117 | controller-1 | changed:
2026-09-10 00:44:59.435837 | controller-1 | cd+++++++++ helm/
2026-09-10 00:44:59.436067 | controller-1 | cd+++++++++ helm/releases/
2026-09-10 00:44:59.436144 | controller-1 | cd+++++++++ helm/values/
2026-09-10 00:44:59.454303 | controller-3 | changed:
2026-09-10 00:44:59.454404 | controller-3 | cd+++++++++ helm/
2026-09-10 00:44:59.454454 | controller-3 | cd+++++++++ helm/releases/
2026-09-10 00:44:59.454494 | controller-3 | cd+++++++++ helm/values/
2026-09-10 00:44:59.460985 | controller-2 | changed:
2026-09-10 00:44:59.461076 | controller-2 | cd+++++++++ helm/
2026-09-10 00:44:59.461125 | controller-2 | cd+++++++++ helm/releases/
2026-09-10 00:44:59.461163 | controller-2 | cd+++++++++ helm/values/
2026-09-10 00:44:59.483110 | 
2026-09-10 00:44:59.483180 | PLAY RECAP
2026-09-10 00:44:59.483265 | controller-1 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:44:59.483304 | controller-2 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:44:59.483337 | controller-3 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:44:59.483361 | 
2026-09-10 00:44:59.645219 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-10 00:44:59.649249 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-10 00:45:00.245358 | 
2026-09-10 00:45:00.245493 | PLAY [all]
2026-09-10 00:45:00.256031 | 
2026-09-10 00:45:00.256129 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-10 00:45:00.316760 | controller-1 | ok
2026-09-10 00:45:00.319864 | controller-2 | ok
2026-09-10 00:45:00.334549 | controller-3 | ok
2026-09-10 00:45:00.338671 | 
2026-09-10 00:45:00.338782 | TASK [fetch-output : Set log path for single node]
2026-09-10 00:45:00.371492 | controller-1 | skipping: Conditional result was False
2026-09-10 00:45:00.382386 | controller-2 | skipping: Conditional result was False
2026-09-10 00:45:00.392782 | controller-3 | skipping: Conditional result was False
2026-09-10 00:45:00.397844 | 
2026-09-10 00:45:00.397933 | LOOP [fetch-output : Ensure local output dirs]
2026-09-10 00:45:00.805197 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/logs/controller-1"
2026-09-10 00:45:00.811123 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/logs/controller-3"
2026-09-10 00:45:00.839152 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/logs/controller-2"
2026-09-10 00:45:01.042695 | controller-1 -> localhost | changed: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/artifacts"
2026-09-10 00:45:01.054945 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/artifacts"
2026-09-10 00:45:01.059404 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/artifacts"
2026-09-10 00:45:01.237649 | controller-1 -> localhost | changed: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/docs"
2026-09-10 00:45:01.265021 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/docs"
2026-09-10 00:45:01.265218 | controller-2 -> localhost | changed: All items complete
2026-09-10 00:45:01.265292 | 
2026-09-10 00:45:01.269798 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/be4a430e83ed49b0a3b95e96fa65df00/work/docs"
2026-09-10 00:45:01.288138 | 
2026-09-10 00:45:01.288385 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-10 00:45:01.989200 | controller-2 | changed: .d..t...... ./
2026-09-10 00:45:01.989594 | controller-2 | ok: All items complete
2026-09-10 00:45:01.989650 | 
2026-09-10 00:45:01.997637 | controller-1 | changed: .d..t...... ./
2026-09-10 00:45:02.029602 | controller-3 | changed: .d..t...... ./
2026-09-10 00:45:02.512404 | controller-2 | changed: .d..t...... ./
2026-09-10 00:45:02.527511 | controller-3 | ok
2026-09-10 00:45:02.535926 | controller-1 | ok
2026-09-10 00:45:03.031947 | controller-3 | changed: .d..t...... ./
2026-09-10 00:45:03.040903 | controller-2 | ok
2026-09-10 00:45:03.057260 | controller-1 | ok
2026-09-10 00:45:03.057513 | controller-1 | changed: All items complete
2026-09-10 00:45:03.057581 | 
2026-09-10 00:45:03.094558 | 
2026-09-10 00:45:03.094691 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-10 00:45:03.536695 | controller-1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008034
2026-09-10 00:45:03.807806 | controller-1 -> localhost | ok: Item: docs Runtime: 0:00:00.008272
2026-09-10 00:45:03.844745 | 
2026-09-10 00:45:03.844922 | PLAY [all]
2026-09-10 00:45:03.855337 | 
2026-09-10 00:45:03.855497 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-10 00:45:04.322641 | controller-1 | changed
2026-09-10 00:45:04.483253 | controller-2 | changed
2026-09-10 00:45:04.510442 | controller-3 | changed
2026-09-10 00:45:04.532346 | 
2026-09-10 00:45:04.532552 | PLAY RECAP
2026-09-10 00:45:04.532644 | controller-1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:45:04.532699 | controller-2 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:45:04.532748 | controller-3 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:45:04.532785 | 
2026-09-10 00:45:04.685769 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-10 00:45:04.691890 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-10 00:45:05.287804 | 
2026-09-10 00:45:05.287917 | PLAY [localhost]
2026-09-10 00:45:05.297327 | 
2026-09-10 00:45:05.297399 | TASK [Generate Zuul manifest]
2026-09-10 00:45:05.324603 | localhost | ok
2026-09-10 00:45:05.337126 | 
2026-09-10 00:45:05.337205 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-10 00:45:05.769757 | localhost | changed
2026-09-10 00:45:05.797770 | 
2026-09-10 00:45:05.797925 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-10 00:45:05.837637 | localhost | ok
2026-09-10 00:45:05.846961 | 
2026-09-10 00:45:05.847050 | TASK [Upload logs]
2026-09-10 00:45:05.866413 | localhost | ok
2026-09-10 00:45:05.924623 | 
2026-09-10 00:45:05.924851 | TASK [Set zuul-log-path fact]
2026-09-10 00:45:05.953580 | localhost | ok
2026-09-10 00:45:05.966159 | 
2026-09-10 00:45:05.966251 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-10 00:45:06.005291 | localhost | ok
2026-09-10 00:45:06.017627 | 
2026-09-10 00:45:06.017775 | TASK [upload-logs : Create log directories]
2026-09-10 00:45:06.638293 | localhost | changed
2026-09-10 00:45:06.695261 | 
2026-09-10 00:45:06.695403 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-10 00:45:07.109225 | localhost -> localhost | ok: Runtime: 0:00:00.005568
2026-09-10 00:45:07.114885 | 
2026-09-10 00:45:07.114951 | TASK [upload-logs : Upload logs to log server]
2026-09-10 00:45:07.602629 | localhost | Output suppressed because no_log was given
2026-09-10 00:45:07.611092 | 
2026-09-10 00:45:07.611679 | LOOP [upload-logs : Compress console log and json output]
2026-09-10 00:45:07.670673 | localhost | skipping: Conditional result was False
2026-09-10 00:45:07.681552 | localhost | skipping: Conditional result was False
2026-09-10 00:45:07.692703 | 
2026-09-10 00:45:07.692795 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-10 00:45:07.732804 | localhost | skipping: Conditional result was False
2026-09-10 00:45:07.733032 | 
2026-09-10 00:45:07.736559 | localhost | skipping: Conditional result was False
2026-09-10 00:45:07.750095 | 
2026-09-10 00:45:07.750176 | LOOP [upload-logs : Upload console log and json output]