2026-08-30 00:51:11.683341 | Job console starting
2026-08-30 00:51:11.701528 | Updating git repos
2026-08-30 00:51:11.732437 | Cloning repos into workspace
2026-08-30 00:51:11.777265 | Restoring repo states
2026-08-30 00:51:11.790975 | Merging changes
2026-08-30 00:51:12.376329 | Checking out repos
2026-08-30 00:51:12.424637 | Preparing playbooks
2026-08-30 00:51:14.179474 | Running Ansible setup
2026-08-30 00:51:21.334502 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-30 00:51:21.932472 | 
2026-08-30 00:51:21.932620 | PLAY [localhost]
2026-08-30 00:51:21.940087 | 
2026-08-30 00:51:21.940159 | TASK [Gathering Facts]
2026-08-30 00:51:22.828013 | localhost | ok
2026-08-30 00:51:22.839439 | 
2026-08-30 00:51:22.839544 | TASK [Setup log path fact]
2026-08-30 00:51:22.860282 | localhost | ok
2026-08-30 00:51:22.873839 | 
2026-08-30 00:51:22.873903 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-30 00:51:22.905249 | localhost | ok
2026-08-30 00:51:22.913128 | 
2026-08-30 00:51:22.913218 | TASK [emit-job-header : Print job information]
2026-08-30 00:51:22.957395 | # Job Information
2026-08-30 00:51:22.957543 | Ansible Version: 2.16.18
2026-08-30 00:51:22.957588 | Job: ansible-collection-kubernetes-molecule-cluster-upgrade
2026-08-30 00:51:22.957619 | Pipeline: check
2026-08-30 00:51:22.957647 | Executor: 2d72f0692154
2026-08-30 00:51:22.957675 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293
2026-08-30 00:51:22.957705 | Event ID: 8d112570-a40c-11f1-94f6-7482bd9224c3
2026-08-30 00:51:22.961312 | 
2026-08-30 00:51:22.961395 | LOOP [emit-job-header : Print node information]
2026-08-30 00:51:23.051456 | localhost | ok:
2026-08-30 00:51:23.051907 | localhost | # Node Information
2026-08-30 00:51:23.051971 | localhost | Inventory Hostname: controller-1
2026-08-30 00:51:23.052021 | localhost | Hostname: np0000191435
2026-08-30 00:51:23.052068 | localhost | Username: zuul
2026-08-30 00:51:23.052118 | localhost | Distro: Debian 13.6
2026-08-30 00:51:23.052143 | localhost | Provider: yul1
2026-08-30 00:51:23.052166 | localhost | Region: ca-ymq-1
2026-08-30 00:51:23.052189 | localhost | Label: debian-trixie
2026-08-30 00:51:23.052212 | localhost | Product Name: OpenStack Nova
2026-08-30 00:51:23.052234 | localhost | Interface IP: 162.253.55.116
2026-08-30 00:51:23.082843 | localhost | ok:
2026-08-30 00:51:23.082940 | localhost | # Node Information
2026-08-30 00:51:23.082993 | localhost | Inventory Hostname: controller-2
2026-08-30 00:51:23.083038 | localhost | Hostname: np0000191436
2026-08-30 00:51:23.083080 | localhost | Username: zuul
2026-08-30 00:51:23.083122 | localhost | Distro: Debian 13.6
2026-08-30 00:51:23.083164 | localhost | Provider: yul1
2026-08-30 00:51:23.083205 | localhost | Region: ca-ymq-1
2026-08-30 00:51:23.083246 | localhost | Label: debian-trixie
2026-08-30 00:51:23.083287 | localhost | Product Name: OpenStack Nova
2026-08-30 00:51:23.083329 | localhost | Interface IP: 162.253.55.77
2026-08-30 00:51:23.117001 | localhost | ok:
2026-08-30 00:51:23.117134 | localhost | # Node Information
2026-08-30 00:51:23.117185 | localhost | Inventory Hostname: controller-3
2026-08-30 00:51:23.117229 | localhost | Hostname: np0000191437
2026-08-30 00:51:23.117271 | localhost | Username: zuul
2026-08-30 00:51:23.117312 | localhost | Distro: Debian 13.6
2026-08-30 00:51:23.117352 | localhost | Provider: yul1
2026-08-30 00:51:23.117391 | localhost | Region: ca-ymq-1
2026-08-30 00:51:23.117431 | localhost | Label: debian-trixie
2026-08-30 00:51:23.117470 | localhost | Product Name: OpenStack Nova
2026-08-30 00:51:23.117509 | localhost | Interface IP: 162.253.55.183
2026-08-30 00:51:23.131444 | 
2026-08-30 00:51:23.131566 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-30 00:51:23.523203 | localhost -> localhost | changed
2026-08-30 00:51:23.532750 | 
2026-08-30 00:51:23.532900 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-30 00:51:24.983861 | localhost -> localhost | changed
2026-08-30 00:51:24.992432 | 
2026-08-30 00:51:24.992522 | PLAY [all]
2026-08-30 00:51:25.003732 | 
2026-08-30 00:51:25.003801 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-30 00:51:25.256583 | controller-1 -> localhost | ok
2026-08-30 00:51:25.267213 | 
2026-08-30 00:51:25.267359 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-30 00:51:25.309594 | controller-1 | ok
2026-08-30 00:51:25.314868 | controller-2 | ok
2026-08-30 00:51:25.317583 | controller-3 | ok
2026-08-30 00:51:25.335385 | controller-1 | included: /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-30 00:51:25.335436 | controller-2 | included: /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-30 00:51:25.335461 | controller-3 | included: /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-30 00:51:25.340674 | 
2026-08-30 00:51:25.340737 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-30 00:51:26.383852 | controller-1 -> localhost | Generating public/private rsa key pair.
2026-08-30 00:51:26.384156 | controller-1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/29b4698053f2495ab9f5d27eff6437c3_id_rsa
2026-08-30 00:51:26.384225 | controller-1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/29b4698053f2495ab9f5d27eff6437c3_id_rsa.pub
2026-08-30 00:51:26.384275 | controller-1 -> localhost | The key fingerprint is:
2026-08-30 00:51:26.384322 | controller-1 -> localhost | SHA256:bptD2q3HlwZwGV2GROwRv8uqPgQ7+Iaa9uWJVEAAzQI zuul-build-sshkey
2026-08-30 00:51:26.384495 | controller-1 -> localhost | The key's randomart image is:
2026-08-30 00:51:26.384540 | controller-1 -> localhost | +---[RSA 3072]----+
2026-08-30 00:51:26.384584 | controller-1 -> localhost | |E..+...    =++o  |
2026-08-30 00:51:26.384629 | controller-1 -> localhost | |  . o.    . =o   |
2026-08-30 00:51:26.384674 | controller-1 -> localhost | |   .  .    + ..  |
2026-08-30 00:51:26.384719 | controller-1 -> localhost | |       .o o .  . |
2026-08-30 00:51:26.384762 | controller-1 -> localhost | |       .S=    .  |
2026-08-30 00:51:26.384804 | controller-1 -> localhost | |      .o+ o  . . |
2026-08-30 00:51:26.384846 | controller-1 -> localhost | |      .*+= . .o  |
2026-08-30 00:51:26.384888 | controller-1 -> localhost | |    .oo==+= +.   |
2026-08-30 00:51:26.384931 | controller-1 -> localhost | |   .ooo.*=o=.    |
2026-08-30 00:51:26.385012 | controller-1 -> localhost | +----[SHA256]-----+
2026-08-30 00:51:26.385120 | controller-1 -> localhost | ok: Runtime: 0:00:00.602713
2026-08-30 00:51:26.392567 | 
2026-08-30 00:51:26.392635 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-30 00:51:26.425966 | controller-1 | ok
2026-08-30 00:51:26.429282 | controller-2 | ok
2026-08-30 00:51:26.443266 | controller-3 | ok
2026-08-30 00:51:26.459652 | controller-1 | included: /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-30 00:51:26.459697 | controller-2 | included: /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-30 00:51:26.459720 | controller-3 | included: /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-30 00:51:26.471306 | 
2026-08-30 00:51:26.471368 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-30 00:51:26.495644 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:26.507255 | controller-2 | skipping: Conditional result was False
2026-08-30 00:51:26.507735 | controller-3 | skipping: Conditional result was False
2026-08-30 00:51:26.517635 | 
2026-08-30 00:51:26.517727 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-30 00:51:27.064573 | controller-1 | changed
2026-08-30 00:51:27.068197 | controller-2 | changed
2026-08-30 00:51:27.082648 | controller-3 | changed
2026-08-30 00:51:27.087831 | 
2026-08-30 00:51:27.087916 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-30 00:51:27.309803 | controller-1 | ok
2026-08-30 00:51:27.318072 | controller-2 | ok
2026-08-30 00:51:27.334453 | controller-3 | ok
2026-08-30 00:51:27.341935 | 
2026-08-30 00:51:27.341999 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-30 00:51:27.891624 | controller-1 | changed
2026-08-30 00:51:27.919289 | controller-2 | changed
2026-08-30 00:51:27.936109 | controller-3 | changed
2026-08-30 00:51:27.941618 | 
2026-08-30 00:51:27.941680 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-30 00:51:28.488539 | controller-1 | changed
2026-08-30 00:51:28.515552 | controller-2 | changed
2026-08-30 00:51:28.593627 | controller-3 | changed
2026-08-30 00:51:28.600679 | 
2026-08-30 00:51:28.600743 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-30 00:51:28.631404 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:28.632619 | controller-2 | skipping: Conditional result was False
2026-08-30 00:51:28.643253 | controller-3 | skipping: Conditional result was False
2026-08-30 00:51:28.654670 | 
2026-08-30 00:51:28.654760 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-30 00:51:29.015546 | controller-1 -> localhost | changed
2026-08-30 00:51:29.035447 | 
2026-08-30 00:51:29.035514 | TASK [add-build-sshkey : Add back temp key]
2026-08-30 00:51:29.364605 | controller-1 -> localhost | Identity added: /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/29b4698053f2495ab9f5d27eff6437c3_id_rsa (zuul-build-sshkey)
2026-08-30 00:51:29.364814 | controller-1 -> localhost | ok: Runtime: 0:00:00.014973
2026-08-30 00:51:29.371468 | 
2026-08-30 00:51:29.371750 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-30 00:51:29.685107 | controller-3 | ok
2026-08-30 00:51:29.688505 | controller-2 | ok
2026-08-30 00:51:29.691492 | controller-1 | ok
2026-08-30 00:51:29.695198 | 
2026-08-30 00:51:29.695267 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-30 00:51:29.731490 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:29.732222 | controller-2 | skipping: Conditional result was False
2026-08-30 00:51:29.742563 | controller-3 | skipping: Conditional result was False
2026-08-30 00:51:29.764225 | 
2026-08-30 00:51:29.764421 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-30 00:51:30.098863 | controller-3 | ok
2026-08-30 00:51:30.117347 | controller-1 | ok
2026-08-30 00:51:30.128277 | controller-2 | ok
2026-08-30 00:51:30.135458 | 
2026-08-30 00:51:30.135564 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-30 00:51:31.093361 | controller-2 | Output suppressed because no_log was given
2026-08-30 00:51:31.108329 | controller-1 | Output suppressed because no_log was given
2026-08-30 00:51:31.145386 | controller-3 | Output suppressed because no_log was given
2026-08-30 00:51:31.187545 | 
2026-08-30 00:51:31.187806 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-30 00:51:31.426879 | controller-2 | ok: "logs"
2026-08-30 00:51:31.427250 | controller-2 | ok: All items complete
2026-08-30 00:51:31.427291 | 
2026-08-30 00:51:31.432992 | controller-1 | ok: "logs"
2026-08-30 00:51:31.440738 | controller-3 | ok: "logs"
2026-08-30 00:51:31.617839 | controller-2 | ok: "artifacts"
2026-08-30 00:51:31.624556 | controller-3 | ok: "artifacts"
2026-08-30 00:51:31.640321 | controller-1 | ok: "artifacts"
2026-08-30 00:51:31.790748 | controller-3 | ok: "docs"
2026-08-30 00:51:31.798717 | controller-2 | ok: "docs"
2026-08-30 00:51:31.819960 | controller-1 | ok: "docs"
2026-08-30 00:51:31.820042 | controller-1 | ok: All items complete
2026-08-30 00:51:31.820077 | 
2026-08-30 00:51:31.837286 | 
2026-08-30 00:51:31.837431 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-30 00:51:32.055432 | controller-2 | changed: "logs"
2026-08-30 00:51:32.072769 | controller-3 | changed: "logs"
2026-08-30 00:51:32.077675 | controller-1 | changed: "logs"
2026-08-30 00:51:32.238861 | controller-2 | changed: "artifacts"
2026-08-30 00:51:32.244034 | controller-3 | changed: "artifacts"
2026-08-30 00:51:32.281427 | controller-1 | changed: "artifacts"
2026-08-30 00:51:32.432779 | controller-2 | changed: "docs"
2026-08-30 00:51:32.445834 | controller-3 | changed: "docs"
2026-08-30 00:51:32.445915 | controller-3 | changed: All items complete
2026-08-30 00:51:32.445950 | 
2026-08-30 00:51:32.484132 | controller-1 | changed: "docs"
2026-08-30 00:51:32.504637 | 
2026-08-30 00:51:32.504758 | PLAY RECAP
2026-08-30 00:51:32.504810 | controller-1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-30 00:51:32.504879 | controller-2 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-30 00:51:32.504909 | controller-3 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-30 00:51:32.504934 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:51:32.504970 | 
2026-08-30 00:51:32.681268 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-30 00:51:32.685435 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-30 00:51:33.296835 | 
2026-08-30 00:51:33.296985 | PLAY [all]
2026-08-30 00:51:33.308484 | 
2026-08-30 00:51:33.308561 | TASK [setup-uv : Extract archive]
2026-08-30 00:51:35.745852 | controller-1 | changed
2026-08-30 00:51:35.835062 | controller-2 | changed
2026-08-30 00:51:35.863686 | controller-3 | changed
2026-08-30 00:51:35.871595 | 
2026-08-30 00:51:35.871683 | TASK [setup-uv : Print version]
2026-08-30 00:51:36.303339 | controller-3 | uv 0.8.13
2026-08-30 00:51:36.293023 | controller-1 | uv 0.8.13
2026-08-30 00:51:36.305599 | controller-2 | uv 0.8.13
2026-08-30 00:51:36.442332 | controller-1 | ok: Runtime: 0:00:00.014297
2026-08-30 00:51:36.451803 | controller-2 | ok: Runtime: 0:00:00.015848
2026-08-30 00:51:36.458516 | controller-3 | ok: Runtime: 0:00:00.017055
2026-08-30 00:51:36.472926 | 
2026-08-30 00:51:36.473005 | TASK [Install Ansible collection siblings]
2026-08-30 00:51:36.874303 | controller-1 | ok:
2026-08-30 00:51:36.874562 | controller-1 | Skipping ansible.posix: no checked-out sibling
2026-08-30 00:51:36.874629 | controller-1 | Skipping community.crypto: no checked-out sibling
2026-08-30 00:51:36.874678 | controller-1 | Skipping community.general: no checked-out sibling
2026-08-30 00:51:36.874722 | controller-1 | Skipping kubernetes.core: no checked-out sibling
2026-08-30 00:51:36.874766 | controller-1 | Skipping vexxhost.containers: no checked-out sibling
2026-08-30 00:51:36.878159 | 
2026-08-30 00:51:36.878247 | PLAY RECAP
2026-08-30 00:51:36.878338 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:51:36.878396 | controller-2 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:51:36.878448 | controller-3 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:51:36.878489 | 
2026-08-30 00:51:37.024667 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-30 00:51:37.028284 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-30 00:51:37.606122 | 
2026-08-30 00:51:37.606253 | PLAY [all]
2026-08-30 00:51:37.616828 | 
2026-08-30 00:51:37.616897 | TASK [Update apt cache]
2026-08-30 00:51:43.255566 | controller-2 | ok
2026-08-30 00:51:43.313214 | controller-3 | ok
2026-08-30 00:51:43.531232 | controller-1 | ok
2026-08-30 00:51:43.535480 | 
2026-08-30 00:51:43.535578 | TASK [Disable firewalld]
2026-08-30 00:51:43.567789 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:43.568899 | controller-2 | skipping: Conditional result was False
2026-08-30 00:51:43.579499 | controller-3 | skipping: Conditional result was False
2026-08-30 00:51:43.588350 | 
2026-08-30 00:51:43.588489 | TASK [Install "jq" for log collection]
2026-08-30 00:51:46.257528 | controller-1 | changed
2026-08-30 00:51:46.276249 | controller-3 | changed
2026-08-30 00:51:46.324566 | controller-2 | changed
2026-08-30 00:51:46.327388 | 
2026-08-30 00:51:46.327456 | PLAY RECAP
2026-08-30 00:51:46.327519 | controller-1 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-30 00:51:46.327560 | controller-2 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-30 00:51:46.327596 | controller-3 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-30 00:51:46.327625 | 
2026-08-30 00:51:46.459846 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-30 00:51:46.464301 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-30 00:51:47.077800 | 
2026-08-30 00:51:47.178154 | PLAY [all]
2026-08-30 00:51:47.194599 | 
2026-08-30 00:51:47.194678 | LOOP [Check inventory directories]
2026-08-30 00:51:47.579702 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-30 00:51:47.751951 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-upgrade/inventory"
2026-08-30 00:51:47.946086 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-upgrade/inventory"
2026-08-30 00:51:47.960533 | 
2026-08-30 00:51:47.960664 | LOOP [Copy inventory file for Zuul]
2026-08-30 00:51:48.844671 | controller-1 | changed:
2026-08-30 00:51:48.993097 | controller-1 | {
2026-08-30 00:51:48.993219 | controller-1 |   "ansible_loop_var": "item",
2026-08-30 00:51:48.993280 | controller-1 |   "changed": false,
2026-08-30 00:51:48.993329 | controller-1 |   "failed": false,
2026-08-30 00:51:48.993385 | controller-1 |   "invocation": {
2026-08-30 00:51:48.993430 | controller-1 |     "module_args": {
2026-08-30 00:51:48.993474 | controller-1 |       "checksum_algorithm": "sha1",
2026-08-30 00:51:48.993538 | controller-1 |       "follow": true,
2026-08-30 00:51:48.993583 | controller-1 |       "get_attributes": true,
2026-08-30 00:51:48.993628 | controller-1 |       "get_checksum": true,
2026-08-30 00:51:48.993670 | controller-1 |       "get_mime": true,
2026-08-30 00:51:48.993711 | controller-1 |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-30 00:51:48.993754 | controller-1 |     }
2026-08-30 00:51:48.993798 | controller-1 |   },
2026-08-30 00:51:48.993839 | controller-1 |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-30 00:51:48.993880 | controller-1 |   "stat": {
2026-08-30 00:51:48.993921 | controller-1 |     "atime": 1788051090.9759085,
2026-08-30 00:51:48.993965 | controller-1 |     "attr_flags": "e",
2026-08-30 00:51:48.994004 | controller-1 |     "attributes": [
2026-08-30 00:51:48.994043 | controller-1 |       "extents"
2026-08-30 00:51:48.994081 | controller-1 |     ],
2026-08-30 00:51:48.994119 | controller-1 |     "block_size": 4096,
2026-08-30 00:51:48.994156 | controller-1 |     "blocks": 8,
2026-08-30 00:51:48.994268 | controller-1 |     "charset": "unknown",
2026-08-30 00:51:48.994336 | controller-1 |     "ctime": 1788051091.0079086,
2026-08-30 00:51:48.994380 | controller-1 |     "dev": 65025,
2026-08-30 00:51:48.994420 | controller-1 |     "device_type": 0,
2026-08-30 00:51:48.994470 | controller-1 |     "executable": true,
2026-08-30 00:51:48.994517 | controller-1 |     "exists": true,
2026-08-30 00:51:48.994635 | controller-1 |     "gid": 1000,
2026-08-30 00:51:48.994687 | controller-1 |     "gr_name": "zuul",
2026-08-30 00:51:48.994727 | controller-1 |     "inode": 521495,
2026-08-30 00:51:48.994766 | controller-1 |     "isblk": false,
2026-08-30 00:51:48.994805 | controller-1 |     "ischr": false,
2026-08-30 00:51:48.994842 | controller-1 |     "isdir": true,
2026-08-30 00:51:48.994880 | controller-1 |     "isfifo": false,
2026-08-30 00:51:48.994918 | controller-1 |     "isgid": false,
2026-08-30 00:51:48.994956 | controller-1 |     "islnk": false,
2026-08-30 00:51:48.994994 | controller-1 |     "isreg": false,
2026-08-30 00:51:48.995030 | controller-1 |     "issock": false,
2026-08-30 00:51:48.995067 | controller-1 |     "isuid": false,
2026-08-30 00:51:48.995104 | controller-1 |     "mimetype": "unknown",
2026-08-30 00:51:48.995141 | controller-1 |     "mode": "0755",
2026-08-30 00:51:48.995178 | controller-1 |     "mtime": 1788051071.8903856,
2026-08-30 00:51:48.995215 | controller-1 |     "nlink": 14,
2026-08-30 00:51:48.995252 | controller-1 |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-30 00:51:48.995290 | controller-1 |     "pw_name": "zuul",
2026-08-30 00:51:48.995328 | controller-1 |     "readable": true,
2026-08-30 00:51:48.995366 | controller-1 |     "rgrp": true,
2026-08-30 00:51:48.995404 | controller-1 |     "roth": true,
2026-08-30 00:51:48.995440 | controller-1 |     "rusr": true,
2026-08-30 00:51:48.995476 | controller-1 |     "size": 4096,
2026-08-30 00:51:48.995512 | controller-1 |     "uid": 1000,
2026-08-30 00:51:48.995549 | controller-1 |     "version": "2380442120",
2026-08-30 00:51:48.995586 | controller-1 |     "wgrp": false,
2026-08-30 00:51:48.995626 | controller-1 |     "woth": false,
2026-08-30 00:51:48.995667 | controller-1 |     "writeable": true,
2026-08-30 00:51:48.995706 | controller-1 |     "wusr": true,
2026-08-30 00:51:48.995744 | controller-1 |     "xgrp": true,
2026-08-30 00:51:48.995781 | controller-1 |     "xoth": true,
2026-08-30 00:51:48.995819 | controller-1 |     "xusr": true
2026-08-30 00:51:48.995856 | controller-1 |   }
2026-08-30 00:51:48.995894 | controller-1 | }
2026-08-30 00:51:48.995969 | controller-1 | ok: All items complete
2026-08-30 00:51:48.996015 | 
2026-08-30 00:51:48.998795 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:49.000109 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:49.014805 | 
2026-08-30 00:51:49.014895 | LOOP [Switch "ansible_host" to private IP]
2026-08-30 00:51:49.447102 | controller-1 | changed: 3 replacements made
2026-08-30 00:51:49.449837 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:49.450675 | controller-1 | skipping: Conditional result was False
2026-08-30 00:51:49.463038 | 
2026-08-30 00:51:49.463188 | TASK [Run Molecule scenario]
2026-08-30 00:51:49.971281 | controller-1 | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-30 00:51:49.971379 | controller-1 | Creating virtual environment at: .venv
2026-08-30 00:51:51.263962 | controller-1 | Downloading cryptography (4.3MiB)
2026-08-30 00:51:51.265087 | controller-1 | Downloading pygments (1.2MiB)
2026-08-30 00:51:51.280957 | controller-1 | Downloading ansible-core (2.3MiB)
2026-08-30 00:51:51.463685 | controller-1 |  Downloading cryptography
2026-08-30 00:51:51.514868 | controller-1 |  Downloading pygments
2026-08-30 00:51:51.657809 | controller-1 |  Downloading ansible-core
2026-08-30 00:51:51.724444 | controller-1 | Installed 36 packages in 65ms
2026-08-30 00:51:52.380955 | controller-1 | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-30 00:51:52.380994 | controller-1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-30 00:51:53.290510 | controller-1 | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-30 00:51:53.290573 | controller-1 | INFO     default scenario not found, disabling shared state.
2026-08-30 00:51:53.290674 | controller-1 | INFO     [cluster-upgrade > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-30 00:51:53.290744 | controller-1 | INFO     [cluster-upgrade > prerun] Performing prerun with role_name_check=0...
2026-08-30 00:53:14.676201 | controller-1 | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-08-30 00:53:14.676296 | controller-1 | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-08-30 00:53:14.676306 | controller-1 | Starting galaxy collection install process
2026-08-30 00:53:14.676315 | controller-1 | Process install dependency map
2026-08-30 00:53:14.676323 | controller-1 |
2026-08-30 00:53:14.676331 | controller-1 | ansible-galaxy
2026-08-30 00:53:14.676338 | controller-1 |   config file = None
2026-08-30 00:53:14.676346 | controller-1 |   configured module search path =
2026-08-30 00:53:14.676353 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-08-30 00:53:14.676368 | 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-30 00:53:14.676376 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-08-30 00:53:14.676386 | 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-30 00:53:14.676394 | controller-1 |   jinja version = 3.1.6
2026-08-30 00:53:14.676401 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-08-30 00:53:14.676408 | controller-1 | No config file found; using defaults
2026-08-30 00:53:14.676415 | controller-1 | Created /home/zuul/.ansible/galaxy_token
2026-08-30 00:53:14.676423 | controller-1 | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: 'results'
2026-08-30 00:53:14.676430 | controller-1 | : Unexpected Exception, this is probably a bug: 'results'
2026-08-30 00:53:14.676437 | controller-1 |
2026-08-30 00:53:14.676444 | controller-1 | Traceback (most recent call last):
2026-08-30 00:53:14.676451 | 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-08-30 00:53:14.676458 | controller-1 |     exit_code = cli.run()
2026-08-30 00:53:14.676465 | 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-08-30 00:53:14.676472 | controller-1 |     return context.CLIARGS()
2026-08-30 00:53:14.676479 | controller-1 |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-08-30 00:53:14.676486 | 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-08-30 00:53:14.676509 | controller-1 |     return wrapped_method(*args, **kwargs)
2026-08-30 00:53:14.676521 | 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-08-30 00:53:14.676528 | controller-1 |     self._execute_install_collection(
2026-08-30 00:53:14.676535 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.676542 | controller-1 |         collection_requirements, collection_path,
2026-08-30 00:53:14.676549 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676556 | controller-1 |         artifacts_manager=artifacts_manager,
2026-08-30 00:53:14.676562 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676569 | controller-1 |     )
2026-08-30 00:53:14.676576 | controller-1 |     ^
2026-08-30 00:53:14.676583 | 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-08-30 00:53:14.676590 | controller-1 |     install_collections(
2026-08-30 00:53:14.676598 | controller-1 |     ~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.676605 | controller-1 |         requirements, output_path, self.api_servers, ignore_errors,
2026-08-30 00:53:14.676612 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676619 | controller-1 |     ...<5 lines>...
2026-08-30 00:53:14.676626 | controller-1 |         read_requirement_paths=read_req_paths,
2026-08-30 00:53:14.676633 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676640 | controller-1 |     )
2026-08-30 00:53:14.676647 | controller-1 |     ^
2026-08-30 00:53:14.676654 | 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-08-30 00:53:14.676661 | controller-1 |     dependency_map = _resolve_depenency_map(
2026-08-30 00:53:14.676668 | controller-1 |         collections,
2026-08-30 00:53:14.676674 | controller-1 |     ...<7 lines>...
2026-08-30 00:53:14.676704 | controller-1 |         offline=offline,
2026-08-30 00:53:14.676711 | controller-1 |     )
2026-08-30 00:53:14.676718 | 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-08-30 00:53:14.676724 | controller-1 |     collection_dep_resolver.resolve(
2026-08-30 00:53:14.676731 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.676738 | controller-1 |         requested_requirements,
2026-08-30 00:53:14.676745 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676751 | controller-1 |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-08-30 00:53:14.676759 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676766 | controller-1 |     ).mapping,
2026-08-30 00:53:14.676772 | controller-1 |     ^
2026-08-30 00:53:14.676779 | 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-08-30 00:53:14.676786 | controller-1 |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-08-30 00:53:14.676802 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 513, in resolve
2026-08-30 00:53:14.676809 | controller-1 |     failure_criterion = self._attempt_to_pin_criterion(name)
2026-08-30 00:53:14.676816 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion
2026-08-30 00:53:14.676823 | controller-1 |     criteria = self._get_updated_criteria(candidate)
2026-08-30 00:53:14.676830 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria
2026-08-30 00:53:14.676837 | controller-1 |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-08-30 00:53:14.676844 | controller-1 |                        ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676856 | 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 410, in get_dependencies
2026-08-30 00:53:14.676863 | controller-1 |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-08-30 00:53:14.676870 | 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 171, in get_collection_dependencies
2026-08-30 00:53:14.676881 | controller-1 |     get_collection_version_metadata(collection_candidate).
2026-08-30 00:53:14.676888 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.676895 | 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 157, in get_collection_version_metadata
2026-08-30 00:53:14.676902 | controller-1 |     raise last_err
2026-08-30 00:53:14.676909 | 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 124, in get_collection_version_metadata
2026-08-30 00:53:14.676916 | controller-1 |     version_metadata = api.get_collection_version_metadata(
2026-08-30 00:53:14.676923 | controller-1 |         collection_candidate.namespace,
2026-08-30 00:53:14.676929 | controller-1 |         collection_candidate.name,
2026-08-30 00:53:14.676936 | controller-1 |         collection_candidate.ver,
2026-08-30 00:53:14.676943 | controller-1 |     )
2026-08-30 00:53:14.676955 | 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-08-30 00:53:14.676962 | controller-1 |     return method(self, *args, **kwargs)
2026-08-30 00:53:14.676969 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 770, in get_collection_version_metadata
2026-08-30 00:53:14.676976 | controller-1 |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-08-30 00:53:14.676983 | 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-08-30 00:53:14.676990 | controller-1 |     return call_retryable_function()
2026-08-30 00:53:14.676997 | 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-08-30 00:53:14.677004 | controller-1 |     res = path_cache
2026-08-30 00:53:14.677010 | controller-1 |           ~~~~~~~~~~^^^^^^^^^^^
2026-08-30 00:53:14.677017 | controller-1 | KeyError: 'results'
2026-08-30 00:53:14.677024 | controller-1 |
2026-08-30 00:53:14.677031 | controller-1 | The above exception was the direct cause of the following exception:
2026-08-30 00:53:14.677038 | controller-1 |
2026-08-30 00:53:14.677045 | controller-1 | Traceback (most recent call last):
2026-08-30 00:53:14.677052 | 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-08-30 00:53:14.677059 | controller-1 |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-08-30 00:53:14.677066 | controller-1 | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-08-30 00:53:14.677073 | controller-1 |
2026-08-30 00:53:14.677079 | controller-1 |
2026-08-30 00:53:14.679832 | controller-1 | Traceback (most recent call last):
2026-08-30 00:53:14.683127 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-08-30 00:53:14.683151 | controller-1 |     sys.exit(main())
2026-08-30 00:53:14.683160 | controller-1 |              ~~~~^^
2026-08-30 00:53:14.683394 | 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-30 00:53:14.683403 | controller-1 |     return self.main(*args, **kwargs)
2026-08-30 00:53:14.683411 | controller-1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.683646 | 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-30 00:53:14.683663 | controller-1 |     rv = self.invoke(ctx)
2026-08-30 00:53:14.684070 | 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-30 00:53:14.684094 | controller-1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-08-30 00:53:14.684103 | controller-1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-08-30 00:53:14.684347 | 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-30 00:53:14.684371 | controller-1 |     return ctx.invoke(self.callback, **ctx.params)
2026-08-30 00:53:14.684379 | controller-1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.684590 | 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-30 00:53:14.684613 | controller-1 |     return callback(*args, **kwargs)
2026-08-30 00:53:14.684853 | 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-30 00:53:14.684876 | controller-1 |     return func(*args, **kwargs)
2026-08-30 00:53:14.685055 | 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-30 00:53:14.685065 | controller-1 |     return f(get_current_context(), *args, **kwargs)
2026-08-30 00:53:14.685284 | 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-30 00:53:14.685292 | controller-1 |     return func(ctx)
2026-08-30 00:53:14.685601 | 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-30 00:53:14.685609 | controller-1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-08-30 00:53:14.685629 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.685902 | 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-30 00:53:14.685911 | controller-1 |     _run_scenarios(scenarios, command_args, default_config)
2026-08-30 00:53:14.685919 | controller-1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.686332 | 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-30 00:53:14.686340 | controller-1 |     scenario.config.runtime.prepare_environment(
2026-08-30 00:53:14.686348 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.686355 | controller-1 |         install_local=True,
2026-08-30 00:53:14.686361 | controller-1 |         ^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.686368 | controller-1 |         role_name_check=role_name_check,
2026-08-30 00:53:14.686375 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.686382 | controller-1 |     )
2026-08-30 00:53:14.686389 | controller-1 |     ^
2026-08-30 00:53:14.686781 | 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-30 00:53:14.686790 | controller-1 |     self.install_collection(
2026-08-30 00:53:14.686798 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.686805 | controller-1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-08-30 00:53:14.686812 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.686819 | controller-1 |         destination=destination,
2026-08-30 00:53:14.686826 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.686833 | controller-1 |     )
2026-08-30 00:53:14.686840 | controller-1 |     ^
2026-08-30 00:53:14.687029 | 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-30 00:53:14.687044 | controller-1 |     raise InvalidPrerequisiteError(msg)
2026-08-30 00:53:14.690730 | controller-1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-08-30 00:53:14.690754 | controller-1 | Starting galaxy collection install process
2026-08-30 00:53:14.690763 | controller-1 | Process install dependency map
2026-08-30 00:53:14.690771 | controller-1 |
2026-08-30 00:53:14.690779 | controller-1 | ansible-galaxy [core 2.20.1]
2026-08-30 00:53:14.690786 | controller-1 |   config file = None
2026-08-30 00:53:14.690793 | 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-30 00:53:14.690800 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-08-30 00:53:14.690808 | 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-30 00:53:14.690816 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-08-30 00:53:14.690823 | 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-30 00:53:14.690830 | controller-1 |   jinja version = 3.1.6
2026-08-30 00:53:14.690838 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-08-30 00:53:14.690845 | controller-1 | No config file found; using defaults
2026-08-30 00:53:14.690851 | controller-1 | Created /home/zuul/.ansible/galaxy_token
2026-08-30 00:53:14.690858 | 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-08-30 00:53:14.690865 | controller-1 | [ERROR]: Unexpected Exception, this is probably a bug: 'results'
2026-08-30 00:53:14.690872 | controller-1 |
2026-08-30 00:53:14.690879 | controller-1 | Traceback (most recent call last):
2026-08-30 00:53:14.690886 | 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-08-30 00:53:14.690893 | controller-1 |     exit_code = cli.run()
2026-08-30 00:53:14.690900 | 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-08-30 00:53:14.690906 | controller-1 |     return context.CLIARGS['func']()
2026-08-30 00:53:14.690913 | controller-1 |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-08-30 00:53:14.690920 | 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-08-30 00:53:14.690926 | controller-1 |     return wrapped_method(*args, **kwargs)
2026-08-30 00:53:14.690933 | 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-08-30 00:53:14.690940 | controller-1 |     self._execute_install_collection(
2026-08-30 00:53:14.690946 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.690953 | controller-1 |         collection_requirements, collection_path,
2026-08-30 00:53:14.690960 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.690967 | controller-1 |         artifacts_manager=artifacts_manager,
2026-08-30 00:53:14.690973 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.690980 | controller-1 |     )
2026-08-30 00:53:14.690987 | controller-1 |     ^
2026-08-30 00:53:14.690994 | 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-08-30 00:53:14.691009 | controller-1 |     install_collections(
2026-08-30 00:53:14.691016 | controller-1 |     ~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.691031 | controller-1 |         requirements, output_path, self.api_servers, ignore_errors,
2026-08-30 00:53:14.691039 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.691046 | controller-1 |     ...<5 lines>...
2026-08-30 00:53:14.691052 | controller-1 |         read_requirement_paths=read_req_paths,
2026-08-30 00:53:14.691059 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.691066 | controller-1 |     )
2026-08-30 00:53:14.691073 | controller-1 |     ^
2026-08-30 00:53:14.691080 | 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-08-30 00:53:14.691086 | controller-1 |     dependency_map = _resolve_depenency_map(
2026-08-30 00:53:14.691093 | controller-1 |         collections,
2026-08-30 00:53:14.691100 | controller-1 |     ...<7 lines>...
2026-08-30 00:53:14.691107 | controller-1 |         offline=offline,
2026-08-30 00:53:14.691113 | controller-1 |     )
2026-08-30 00:53:14.691120 | 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-08-30 00:53:14.691127 | controller-1 |     collection_dep_resolver.resolve(
2026-08-30 00:53:14.691133 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-30 00:53:14.691140 | controller-1 |         requested_requirements,
2026-08-30 00:53:14.691147 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.691154 | controller-1 |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-08-30 00:53:14.691160 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.691167 | controller-1 |     ).mapping,
2026-08-30 00:53:14.691174 | controller-1 |     ^
2026-08-30 00:53:14.691192 | 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-08-30 00:53:14.691199 | controller-1 |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-08-30 00:53:14.691206 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 513, in resolve
2026-08-30 00:53:14.691213 | controller-1 |     failure_criterion = self._attempt_to_pin_criterion(name)
2026-08-30 00:53:14.691220 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion
2026-08-30 00:53:14.691226 | controller-1 |     criteria = self._get_updated_criteria(candidate)
2026-08-30 00:53:14.691233 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria
2026-08-30 00:53:14.691240 | controller-1 |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-08-30 00:53:14.691247 | controller-1 |                        ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.691254 | 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 410, in get_dependencies
2026-08-30 00:53:14.691261 | controller-1 |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-08-30 00:53:14.691267 | 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 171, in get_collection_dependencies
2026-08-30 00:53:14.691274 | controller-1 |     get_collection_version_metadata(collection_candidate).
2026-08-30 00:53:14.691281 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^
2026-08-30 00:53:14.691288 | 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 157, in get_collection_version_metadata
2026-08-30 00:53:14.691300 | controller-1 |     raise last_err
2026-08-30 00:53:14.691307 | 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 124, in get_collection_version_metadata
2026-08-30 00:53:14.691314 | controller-1 |     version_metadata = api.get_collection_version_metadata(
2026-08-30 00:53:14.691321 | controller-1 |         collection_candidate.namespace,
2026-08-30 00:53:14.691327 | controller-1 |         collection_candidate.name,
2026-08-30 00:53:14.691334 | controller-1 |         collection_candidate.ver,
2026-08-30 00:53:14.691342 | controller-1 |     )
2026-08-30 00:53:14.691350 | 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-08-30 00:53:14.691356 | controller-1 |     return method(self, *args, **kwargs)
2026-08-30 00:53:14.691363 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 770, in get_collection_version_metadata
2026-08-30 00:53:14.691370 | controller-1 |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-08-30 00:53:14.691377 | 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-08-30 00:53:14.691384 | controller-1 |     return call_retryable_function()
2026-08-30 00:53:14.691390 | 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-08-30 00:53:14.691397 | controller-1 |     res = path_cache['results']
2026-08-30 00:53:14.691410 | controller-1 |           ~~~~~~~~~~^^^^^^^^^^^
2026-08-30 00:53:14.691417 | controller-1 | KeyError: 'results'
2026-08-30 00:53:14.691424 | controller-1 |
2026-08-30 00:53:14.691431 | controller-1 | The above exception was the direct cause of the following exception:
2026-08-30 00:53:14.691438 | controller-1 |
2026-08-30 00:53:14.691445 | controller-1 | Traceback (most recent call last):
2026-08-30 00:53:14.691451 | 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-08-30 00:53:14.691458 | controller-1 |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-08-30 00:53:14.691465 | controller-1 | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-08-30 00:53:14.691472 | controller-1 |
2026-08-30 00:53:14.691479 | controller-1 |
2026-08-30 00:53:15.063082 | controller-1 | ERROR
2026-08-30 00:53:15.063348 | controller-1 | {
2026-08-30 00:53:15.063389 | controller-1 |   "delta": "0:01:24.911771",
2026-08-30 00:53:15.063419 | controller-1 |   "end": "2026-08-30 00:53:14.791298",
2026-08-30 00:53:15.063446 | controller-1 |   "msg": "non-zero return code",
2026-08-30 00:53:15.063471 | controller-1 |   "rc": 1,
2026-08-30 00:53:15.063497 | controller-1 |   "start": "2026-08-30 00:51:49.879527"
2026-08-30 00:53:15.063522 | controller-1 | }
failure
2026-08-30 00:53:15.068607 | 
2026-08-30 00:53:15.068681 | PLAY RECAP
2026-08-30 00:53:15.068745 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:53:15.068767 | 
2026-08-30 00:53:15.223723 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-30 00:53:15.230619 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-30 00:53:15.835700 | 
2026-08-30 00:53:15.835826 | PLAY [all]
2026-08-30 00:53:15.847064 | 
2026-08-30 00:53:15.847137 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-30 00:53:16.259699 | controller-1 | changed
2026-08-30 00:53:16.439519 | controller-2 | changed
2026-08-30 00:53:16.467285 | controller-3 | changed
2026-08-30 00:53:16.475118 | 
2026-08-30 00:53:16.475217 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-30 00:53:16.911172 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:16.911298 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:16.919104 | controller-3 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.921440 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.922494 | controller-3 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.924488 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.926706 | controller-3 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.929655 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.930984 | controller-3 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.933334 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.936971 | controller-3 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.939186 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.913568 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:16.914037 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:16.922054 | controller-1 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.923878 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.924206 | controller-1 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.926587 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.931737 | controller-1 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.931763 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.931772 | controller-1 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.933981 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.935970 | controller-1 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.937973 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.926507 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:16.926863 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:16.934683 | controller-2 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.936816 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.938800 | controller-2 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.941228 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.942519 | controller-2 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.944613 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.946979 | controller-2 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.948804 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:16.950416 | controller-2 | environment: line 1: kubectl: command not found
2026-08-30 00:53:16.952034 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-30 00:53:17.043656 | controller-3 | ok: Runtime: 0:00:00.041277
2026-08-30 00:53:17.051835 | controller-1 | ok: Runtime: 0:00:00.039548
2026-08-30 00:53:17.057569 | controller-2 | ok: Runtime: 0:00:00.041423
2026-08-30 00:53:17.061470 | 
2026-08-30 00:53:17.061535 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-30 00:53:17.315861 | controller-1 | changed
2026-08-30 00:53:17.334296 | controller-2 | changed
2026-08-30 00:53:17.341215 | controller-3 | changed
2026-08-30 00:53:17.345133 | 
2026-08-30 00:53:17.345211 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-30 00:53:17.623838 | controller-1 | environment: line 5: kubectl: command not found
2026-08-30 00:53:17.625295 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.625864 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.626687 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.697853 | controller-3 | environment: line 5: kubectl: command not found
2026-08-30 00:53:17.699129 | controller-3 | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.699158 | controller-3 |
2026-08-30 00:53:17.699715 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.654790 | controller-2 | environment: line 5: kubectl: command not found
2026-08-30 00:53:17.655620 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.655989 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.656346 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-30 00:53:17.903455 | controller-1 | ok: Runtime: 0:00:00.013618
2026-08-30 00:53:17.959712 | controller-2 | ok: Runtime: 0:00:00.015022
2026-08-30 00:53:17.965510 | controller-3 | ok: Runtime: 0:00:00.011041
2026-08-30 00:53:17.970451 | 
2026-08-30 00:53:17.970520 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-30 00:53:18.576883 | controller-1 | changed:
2026-08-30 00:53:18.577237 | controller-1 | created directory /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/logs/controller-1
2026-08-30 00:53:18.577270 | controller-1 | cd+++++++++ objects/
2026-08-30 00:53:18.577293 | controller-1 | cd+++++++++ objects/cluster/
2026-08-30 00:53:18.577313 | controller-1 | cd+++++++++ objects/namespaced/
2026-08-30 00:53:18.603093 | controller-2 | changed:
2026-08-30 00:53:18.603239 | controller-2 | created directory /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/logs/controller-2
2026-08-30 00:53:18.603285 | controller-2 | cd+++++++++ objects/
2026-08-30 00:53:18.603322 | controller-2 | cd+++++++++ objects/cluster/
2026-08-30 00:53:18.603357 | controller-2 | cd+++++++++ objects/namespaced/
2026-08-30 00:53:18.609005 | controller-3 | changed:
2026-08-30 00:53:18.609072 | controller-3 | created directory /var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/logs/controller-3
2026-08-30 00:53:18.609108 | controller-3 | cd+++++++++ objects/
2026-08-30 00:53:18.609137 | controller-3 | cd+++++++++ objects/cluster/
2026-08-30 00:53:18.609165 | controller-3 | cd+++++++++ objects/namespaced/
2026-08-30 00:53:18.624978 | 
2026-08-30 00:53:18.625056 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-30 00:53:18.878733 | controller-1 | changed
2026-08-30 00:53:18.897355 | controller-2 | changed
2026-08-30 00:53:18.902356 | controller-3 | changed
2026-08-30 00:53:18.907234 | 
2026-08-30 00:53:18.907304 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-30 00:53:19.164280 | controller-1 | changed
2026-08-30 00:53:19.180297 | controller-3 | changed
2026-08-30 00:53:19.185189 | controller-2 | changed
2026-08-30 00:53:19.190745 | 
2026-08-30 00:53:19.190848 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-30 00:53:19.479872 | controller-1 | environment: line 3: kubectl: command not found
2026-08-30 00:53:19.502595 | controller-3 | environment: line 3: kubectl: command not found
2026-08-30 00:53:19.466381 | controller-2 | environment: line 3: kubectl: command not found
2026-08-30 00:53:19.753700 | controller-2 | ok: Runtime: 0:00:00.014023
2026-08-30 00:53:19.762069 | controller-1 | ok: Runtime: 0:00:00.015042
2026-08-30 00:53:19.767639 | controller-3 | ok: Runtime: 0:00:00.014177
2026-08-30 00:53:19.771509 | 
2026-08-30 00:53:19.771577 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-30 00:53:20.271381 | controller-1 | changed:
2026-08-30 00:53:20.271742 | controller-1 | cd+++++++++ pod-logs/
2026-08-30 00:53:20.271783 | controller-1 | cd+++++++++ pod-logs/failed-pods/
2026-08-30 00:53:20.310432 | controller-2 | changed:
2026-08-30 00:53:20.311014 | controller-2 | cd+++++++++ pod-logs/
2026-08-30 00:53:20.311081 | controller-2 | cd+++++++++ pod-logs/failed-pods/
2026-08-30 00:53:20.317249 | controller-3 | changed:
2026-08-30 00:53:20.317319 | controller-3 | cd+++++++++ pod-logs/
2026-08-30 00:53:20.317354 | controller-3 | cd+++++++++ pod-logs/failed-pods/
2026-08-30 00:53:20.332723 | 
2026-08-30 00:53:20.332792 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-30 00:53:20.601448 | controller-2 | changed: "values"
2026-08-30 00:53:20.606558 | controller-1 | changed: "values"
2026-08-30 00:53:20.634675 | controller-3 | changed: "values"
2026-08-30 00:53:20.804672 | controller-2 | changed: "releases"
2026-08-30 00:53:20.813991 | controller-1 | changed: "releases"
2026-08-30 00:53:20.814073 | controller-1 | changed: All items complete
2026-08-30 00:53:20.814109 | 
2026-08-30 00:53:20.863803 | controller-3 | changed: "releases"
2026-08-30 00:53:20.875390 | 
2026-08-30 00:53:20.875570 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-30 00:53:21.160541 | controller-1 | /bin/bash: line 3: kubectl: command not found
2026-08-30 00:53:21.171444 | controller-3 | /bin/bash: line 3: kubectl: command not found
2026-08-30 00:53:21.197274 | controller-2 | /bin/bash: line 3: kubectl: command not found
2026-08-30 00:53:21.451270 | controller-1 | ok: Runtime: 0:00:00.009268
2026-08-30 00:53:21.461426 | controller-3 | ok: Runtime: 0:00:00.009123
2026-08-30 00:53:21.468088 | controller-2 | ok: Runtime: 0:00:00.010677
2026-08-30 00:53:21.471960 | 
2026-08-30 00:53:21.472026 | TASK [helm-release-status : Downloads logs to executor]
2026-08-30 00:53:21.965769 | controller-1 | changed:
2026-08-30 00:53:21.966121 | controller-1 | cd+++++++++ helm/
2026-08-30 00:53:21.966164 | controller-1 | cd+++++++++ helm/releases/
2026-08-30 00:53:21.966197 | controller-1 | cd+++++++++ helm/values/
2026-08-30 00:53:22.014543 | controller-2 | changed:
2026-08-30 00:53:22.014642 | controller-2 | cd+++++++++ helm/
2026-08-30 00:53:22.014678 | controller-2 | cd+++++++++ helm/releases/
2026-08-30 00:53:22.014707 | controller-2 | cd+++++++++ helm/values/
2026-08-30 00:53:22.019734 | controller-3 | changed:
2026-08-30 00:53:22.019798 | controller-3 | cd+++++++++ helm/
2026-08-30 00:53:22.019833 | controller-3 | cd+++++++++ helm/releases/
2026-08-30 00:53:22.019862 | controller-3 | cd+++++++++ helm/values/
2026-08-30 00:53:22.033661 | 
2026-08-30 00:53:22.033756 | PLAY RECAP
2026-08-30 00:53:22.033810 | controller-1 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:53:22.033838 | controller-2 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:53:22.033863 | controller-3 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-30 00:53:22.033883 | 
2026-08-30 00:53:22.194519 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-30 00:53:22.199600 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-30 00:53:22.799822 | 
2026-08-30 00:53:22.799961 | PLAY [all]
2026-08-30 00:53:22.810514 | 
2026-08-30 00:53:22.810590 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-30 00:53:22.860643 | controller-1 | ok
2026-08-30 00:53:22.868525 | controller-2 | ok
2026-08-30 00:53:22.886504 | controller-3 | ok
2026-08-30 00:53:22.891649 | 
2026-08-30 00:53:22.891778 | TASK [fetch-output : Set log path for single node]
2026-08-30 00:53:22.923545 | controller-1 | skipping: Conditional result was False
2026-08-30 00:53:22.935056 | controller-2 | skipping: Conditional result was False
2026-08-30 00:53:22.935608 | controller-3 | skipping: Conditional result was False
2026-08-30 00:53:22.942812 | 
2026-08-30 00:53:22.942905 | LOOP [fetch-output : Ensure local output dirs]
2026-08-30 00:53:23.336231 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/logs/controller-1"
2026-08-30 00:53:23.342670 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/logs/controller-2"
2026-08-30 00:53:23.348527 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/logs/controller-3"
2026-08-30 00:53:23.538080 | controller-1 -> localhost | changed: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/artifacts"
2026-08-30 00:53:23.546309 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/artifacts"
2026-08-30 00:53:23.547262 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/artifacts"
2026-08-30 00:53:23.748654 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/docs"
2026-08-30 00:53:23.760992 | controller-3 -> localhost | changed: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/docs"
2026-08-30 00:53:23.761945 | controller-3 -> localhost | ok: All items complete
2026-08-30 00:53:23.762042 | 
2026-08-30 00:53:23.776289 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/29b4698053f2495ab9f5d27eff6437c3/work/docs"
2026-08-30 00:53:23.789935 | 
2026-08-30 00:53:23.790127 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-30 00:53:24.441422 | controller-2 | changed: .d..t...... ./
2026-08-30 00:53:24.441753 | controller-2 | changed: All items complete
2026-08-30 00:53:24.441794 | 
2026-08-30 00:53:24.449801 | controller-3 | changed: .d..t...... ./
2026-08-30 00:53:24.465055 | controller-1 | changed: .d..t...... ./
2026-08-30 00:53:24.916578 | controller-3 | changed: .d..t...... ./
2026-08-30 00:53:24.939784 | controller-1 | ok
2026-08-30 00:53:24.948073 | controller-2 | ok
2026-08-30 00:53:25.390726 | controller-3 | changed: .d..t...... ./
2026-08-30 00:53:25.422629 | controller-1 | ok
2026-08-30 00:53:25.428763 | controller-2 | ok
2026-08-30 00:53:25.451827 | 
2026-08-30 00:53:25.452076 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-30 00:53:25.868210 | controller-1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.009239
2026-08-30 00:53:25.868481 | controller-1 -> localhost | changed: All items complete
2026-08-30 00:53:25.868521 | 
2026-08-30 00:53:26.091657 | controller-1 -> localhost | ok: Item: docs Runtime: 0:00:00.008280
2026-08-30 00:53:26.111956 | 
2026-08-30 00:53:26.112084 | PLAY [all]
2026-08-30 00:53:26.117726 | 
2026-08-30 00:53:26.117792 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-30 00:53:26.590342 | controller-1 | changed
2026-08-30 00:53:26.778193 | controller-3 | changed
2026-08-30 00:53:26.805270 | controller-2 | changed
2026-08-30 00:53:26.820726 | 
2026-08-30 00:53:26.820844 | PLAY RECAP
2026-08-30 00:53:26.820989 | controller-1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-30 00:53:26.821069 | controller-2 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-30 00:53:26.821132 | controller-3 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-30 00:53:26.821181 | 
2026-08-30 00:53:26.989759 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-30 00:53:26.995366 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-30 00:53:27.608249 | 
2026-08-30 00:53:27.756326 | PLAY [localhost]
2026-08-30 00:53:27.772164 | 
2026-08-30 00:53:27.772246 | TASK [Generate Zuul manifest]
2026-08-30 00:53:27.795213 | localhost | ok
2026-08-30 00:53:27.811611 | 
2026-08-30 00:53:27.812322 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-30 00:53:28.190709 | localhost | changed
2026-08-30 00:53:28.212377 | 
2026-08-30 00:53:28.212492 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-30 00:53:28.245647 | localhost | ok
2026-08-30 00:53:28.254569 | 
2026-08-30 00:53:28.254663 | TASK [Upload logs]
2026-08-30 00:53:28.277520 | localhost | ok
2026-08-30 00:53:28.346778 | 
2026-08-30 00:53:28.346936 | TASK [Set zuul-log-path fact]
2026-08-30 00:53:28.367352 | localhost | ok
2026-08-30 00:53:28.382160 | 
2026-08-30 00:53:28.382227 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-30 00:53:28.414504 | localhost | ok
2026-08-30 00:53:28.425400 | 
2026-08-30 00:53:28.425470 | TASK [upload-logs : Create log directories]
2026-08-30 00:53:28.822900 | localhost | changed
2026-08-30 00:53:28.867883 | 
2026-08-30 00:53:28.868039 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-30 00:53:29.237245 | localhost -> localhost | ok: Runtime: 0:00:00.005965
2026-08-30 00:53:29.247727 | 
2026-08-30 00:53:29.247800 | TASK [upload-logs : Upload logs to log server]
2026-08-30 00:53:29.672896 | localhost | Output suppressed because no_log was given
2026-08-30 00:53:29.678243 | 
2026-08-30 00:53:29.678306 | LOOP [upload-logs : Compress console log and json output]
2026-08-30 00:53:29.726294 | localhost | skipping: Conditional result was False
2026-08-30 00:53:29.734796 | localhost | skipping: Conditional result was False
2026-08-30 00:53:29.751705 | 
2026-08-30 00:53:29.751845 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-30 00:53:29.795060 | localhost | skipping: Conditional result was False
2026-08-30 00:53:29.795426 | 
2026-08-30 00:53:29.799086 | localhost | skipping: Conditional result was False
2026-08-30 00:53:29.817387 | 
2026-08-30 00:53:29.817570 | LOOP [upload-logs : Upload console log and json output]