2026-09-15 16:49:07.388713 | Job console starting
2026-09-15 16:49:07.411184 | Updating git repos
2026-09-15 16:49:07.471342 | Cloning repos into workspace
2026-09-15 16:49:07.531142 | Restoring repo states
2026-09-15 16:49:07.547163 | Merging changes
2026-09-15 16:49:08.538683 | Checking out repos
2026-09-15 16:49:08.652594 | Preparing playbooks
2026-09-15 16:49:11.075289 | Running Ansible setup
2026-09-15 16:49:16.797082 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 16:49:17.509297 | 
2026-09-15 16:49:17.509798 | PLAY [localhost]
2026-09-15 16:49:17.517643 | 
2026-09-15 16:49:17.517709 | TASK [Gathering Facts]
2026-09-15 16:49:18.501913 | localhost | ok
2026-09-15 16:49:18.515382 | 
2026-09-15 16:49:18.515531 | TASK [Setup log path fact]
2026-09-15 16:49:18.535161 | localhost | ok
2026-09-15 16:49:18.553818 | 
2026-09-15 16:49:18.553900 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 16:49:18.593775 | localhost | ok
2026-09-15 16:49:18.606660 | 
2026-09-15 16:49:18.606792 | TASK [emit-job-header : Print job information]
2026-09-15 16:49:18.660308 | # Job Information
2026-09-15 16:49:18.660571 | Ansible Version: 2.16.18
2026-09-15 16:49:18.660643 | Job: ansible-collection-kubernetes-molecule-cluster-upgrade
2026-09-15 16:49:18.660690 | Pipeline: check
2026-09-15 16:49:18.660731 | Executor: 2d72f0692154
2026-09-15 16:49:18.660771 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/291
2026-09-15 16:49:18.660820 | Event ID: 7e3b23a0-b124-11f1-9be4-5c390cfddffc
2026-09-15 16:49:18.666750 | 
2026-09-15 16:49:18.666894 | LOOP [emit-job-header : Print node information]
2026-09-15 16:49:18.765684 | localhost | ok:
2026-09-15 16:49:18.766035 | localhost | # Node Information
2026-09-15 16:49:18.766105 | localhost | Inventory Hostname: controller-1
2026-09-15 16:49:18.766159 | localhost | Hostname: np0000192843
2026-09-15 16:49:18.766212 | localhost | Username: zuul
2026-09-15 16:49:18.766288 | localhost | Distro: Debian 13.6
2026-09-15 16:49:18.766360 | localhost | Provider: yul1
2026-09-15 16:49:18.766409 | localhost | Region: ca-ymq-1
2026-09-15 16:49:18.766454 | localhost | Label: debian-trixie
2026-09-15 16:49:18.766497 | localhost | Product Name: OpenStack Nova
2026-09-15 16:49:18.766539 | localhost | Interface IP: 199.204.45.71
2026-09-15 16:49:18.793001 | localhost | ok:
2026-09-15 16:49:18.793118 | localhost | # Node Information
2026-09-15 16:49:18.793171 | localhost | Inventory Hostname: controller-2
2026-09-15 16:49:18.793211 | localhost | Hostname: np0000192844
2026-09-15 16:49:18.793248 | localhost | Username: zuul
2026-09-15 16:49:18.793313 | localhost | Distro: Debian 13.6
2026-09-15 16:49:18.793353 | localhost | Provider: yul1
2026-09-15 16:49:18.793389 | localhost | Region: ca-ymq-1
2026-09-15 16:49:18.793424 | localhost | Label: debian-trixie
2026-09-15 16:49:18.793460 | localhost | Product Name: OpenStack Nova
2026-09-15 16:49:18.793499 | localhost | Interface IP: 199.204.45.238
2026-09-15 16:49:18.823755 | localhost | ok:
2026-09-15 16:49:18.823866 | localhost | # Node Information
2026-09-15 16:49:18.823913 | localhost | Inventory Hostname: controller-3
2026-09-15 16:49:18.823953 | localhost | Hostname: np0000192845
2026-09-15 16:49:18.823990 | localhost | Username: zuul
2026-09-15 16:49:18.824027 | localhost | Distro: Debian 13.6
2026-09-15 16:49:18.824062 | localhost | Provider: yul1
2026-09-15 16:49:18.824096 | localhost | Region: ca-ymq-1
2026-09-15 16:49:18.824130 | localhost | Label: debian-trixie
2026-09-15 16:49:18.824164 | localhost | Product Name: OpenStack Nova
2026-09-15 16:49:18.824199 | localhost | Interface IP: 199.204.45.24
2026-09-15 16:49:18.840718 | 
2026-09-15 16:49:18.840895 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 16:49:19.216371 | localhost -> localhost | changed
2026-09-15 16:49:19.226715 | 
2026-09-15 16:49:19.226855 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 16:49:20.043102 | localhost -> localhost | changed
2026-09-15 16:49:20.055762 | 
2026-09-15 16:49:20.055942 | PLAY [all]
2026-09-15 16:49:20.071069 | 
2026-09-15 16:49:20.071281 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 16:49:20.348939 | controller-1 -> localhost | ok
2026-09-15 16:49:20.357432 | 
2026-09-15 16:49:20.357511 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 16:49:20.387055 | controller-1 | ok
2026-09-15 16:49:20.394234 | controller-2 | ok
2026-09-15 16:49:20.407829 | controller-3 | ok
2026-09-15 16:49:20.427686 | controller-1 | included: /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 16:49:20.427735 | controller-2 | included: /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 16:49:20.427757 | controller-3 | included: /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 16:49:20.432363 | 
2026-09-15 16:49:20.432416 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 16:49:22.902850 | controller-1 -> localhost | Generating public/private rsa key pair.
2026-09-15 16:49:22.903107 | controller-1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/ce5ad0e22d664233999d1df9b5a5ec6e_id_rsa
2026-09-15 16:49:22.903165 | controller-1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/ce5ad0e22d664233999d1df9b5a5ec6e_id_rsa.pub
2026-09-15 16:49:22.903209 | controller-1 -> localhost | The key fingerprint is:
2026-09-15 16:49:22.903250 | controller-1 -> localhost | SHA256:qHNB1IjKw/EpFBYtbxXM7yw1Us8VK90UfiQu/MiV7Zk zuul-build-sshkey
2026-09-15 16:49:22.903314 | controller-1 -> localhost | The key's randomart image is:
2026-09-15 16:49:22.903354 | controller-1 -> localhost | +---[RSA 3072]----+
2026-09-15 16:49:22.903393 | controller-1 -> localhost | |  ++ +o+    ..+..|
2026-09-15 16:49:22.903433 | controller-1 -> localhost | | .+ o.= o  o.* = |
2026-09-15 16:49:22.903472 | controller-1 -> localhost | | + * o.o o..= * o|
2026-09-15 16:49:22.903509 | controller-1 -> localhost | |  * =...+ oo = oo|
2026-09-15 16:49:22.903546 | controller-1 -> localhost | |   +  o=S.  o .E.|
2026-09-15 16:49:22.903583 | controller-1 -> localhost | |     ...o        |
2026-09-15 16:49:22.903620 | controller-1 -> localhost | |    o ..         |
2026-09-15 16:49:22.903657 | controller-1 -> localhost | |     o           |
2026-09-15 16:49:22.903693 | controller-1 -> localhost | |                 |
2026-09-15 16:49:22.903731 | controller-1 -> localhost | +----[SHA256]-----+
2026-09-15 16:49:22.903813 | controller-1 -> localhost | ok: Runtime: 0:00:02.071385
2026-09-15 16:49:22.911531 | 
2026-09-15 16:49:22.911646 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 16:49:22.946866 | controller-1 | ok
2026-09-15 16:49:22.950866 | controller-2 | ok
2026-09-15 16:49:22.964375 | controller-3 | ok
2026-09-15 16:49:22.980163 | controller-1 | included: /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 16:49:22.980221 | controller-2 | included: /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 16:49:22.980251 | controller-3 | included: /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 16:49:22.992182 | 
2026-09-15 16:49:22.992242 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 16:49:23.014768 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:23.025908 | controller-2 | skipping: Conditional result was False
2026-09-15 16:49:23.036244 | controller-3 | skipping: Conditional result was False
2026-09-15 16:49:23.042761 | 
2026-09-15 16:49:23.042840 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 16:49:23.612950 | controller-1 | changed
2026-09-15 16:49:23.633608 | controller-2 | changed
2026-09-15 16:49:23.639842 | controller-3 | changed
2026-09-15 16:49:23.647864 | 
2026-09-15 16:49:23.647983 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 16:49:23.870997 | controller-2 | ok
2026-09-15 16:49:23.878741 | controller-1 | ok
2026-09-15 16:49:23.915180 | controller-3 | ok
2026-09-15 16:49:23.924588 | 
2026-09-15 16:49:23.924742 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 16:49:24.531400 | controller-1 | changed
2026-09-15 16:49:24.538968 | controller-2 | changed
2026-09-15 16:49:24.566174 | controller-3 | changed
2026-09-15 16:49:24.574464 | 
2026-09-15 16:49:24.574588 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 16:49:25.155326 | controller-1 | changed
2026-09-15 16:49:25.194856 | controller-2 | changed
2026-09-15 16:49:25.294744 | controller-3 | changed
2026-09-15 16:49:25.300819 | 
2026-09-15 16:49:25.300918 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 16:49:25.331016 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:25.342675 | controller-2 | skipping: Conditional result was False
2026-09-15 16:49:25.353513 | controller-3 | skipping: Conditional result was False
2026-09-15 16:49:25.361919 | 
2026-09-15 16:49:25.362126 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 16:49:25.814524 | controller-1 -> localhost | changed
2026-09-15 16:49:25.854173 | 
2026-09-15 16:49:25.854337 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 16:49:26.135378 | controller-1 -> localhost | Identity added: /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/ce5ad0e22d664233999d1df9b5a5ec6e_id_rsa (zuul-build-sshkey)
2026-09-15 16:49:26.135546 | controller-1 -> localhost | ok: Runtime: 0:00:00.016698
2026-09-15 16:49:26.139078 | 
2026-09-15 16:49:26.139132 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 16:49:26.457244 | controller-2 | ok
2026-09-15 16:49:26.470323 | controller-3 | ok
2026-09-15 16:49:26.473079 | controller-1 | ok
2026-09-15 16:49:26.476165 | 
2026-09-15 16:49:26.476222 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 16:49:26.498460 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:26.519419 | controller-2 | skipping: Conditional result was False
2026-09-15 16:49:26.519624 | controller-3 | skipping: Conditional result was False
2026-09-15 16:49:26.531883 | 
2026-09-15 16:49:26.531947 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 16:49:26.894048 | controller-3 | ok
2026-09-15 16:49:26.901333 | controller-1 | ok
2026-09-15 16:49:26.912379 | controller-2 | ok
2026-09-15 16:49:26.922909 | 
2026-09-15 16:49:26.923077 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 16:49:27.935094 | controller-2 | Output suppressed because no_log was given
2026-09-15 16:49:28.003613 | controller-3 | Output suppressed because no_log was given
2026-09-15 16:49:28.071127 | controller-1 | Output suppressed because no_log was given
2026-09-15 16:49:28.109534 | 
2026-09-15 16:49:28.109699 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 16:49:28.356332 | controller-1 | ok: "logs"
2026-09-15 16:49:28.356748 | controller-1 | ok: All items complete
2026-09-15 16:49:28.356805 | 
2026-09-15 16:49:28.363407 | controller-3 | ok: "logs"
2026-09-15 16:49:28.372724 | controller-2 | ok: "logs"
2026-09-15 16:49:28.566803 | controller-1 | ok: "artifacts"
2026-09-15 16:49:28.573912 | controller-3 | ok: "artifacts"
2026-09-15 16:49:28.598202 | controller-2 | ok: "artifacts"
2026-09-15 16:49:28.777193 | controller-1 | ok: "docs"
2026-09-15 16:49:28.782642 | controller-3 | ok: "docs"
2026-09-15 16:49:28.818962 | controller-2 | ok: "docs"
2026-09-15 16:49:28.819078 | controller-2 | ok: All items complete
2026-09-15 16:49:28.819100 | 
2026-09-15 16:49:28.834194 | 
2026-09-15 16:49:28.834429 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 16:49:29.093425 | controller-1 | changed: "logs"
2026-09-15 16:49:29.100806 | controller-2 | changed: "logs"
2026-09-15 16:49:29.115024 | controller-3 | changed: "logs"
2026-09-15 16:49:29.291519 | controller-1 | changed: "artifacts"
2026-09-15 16:49:29.309459 | controller-2 | changed: "artifacts"
2026-09-15 16:49:29.317796 | controller-3 | changed: "artifacts"
2026-09-15 16:49:29.492657 | controller-1 | changed: "docs"
2026-09-15 16:49:29.512052 | controller-3 | changed: "docs"
2026-09-15 16:49:29.512195 | controller-3 | changed: All items complete
2026-09-15 16:49:29.512291 | 
2026-09-15 16:49:29.530546 | controller-2 | changed: "docs"
2026-09-15 16:49:29.565159 | 
2026-09-15 16:49:29.565352 | PLAY RECAP
2026-09-15 16:49:29.565445 | controller-1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 16:49:29.565502 | controller-2 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 16:49:29.565548 | controller-3 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 16:49:29.565594 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:49:29.565637 | 
2026-09-15 16:49:29.716245 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 16:49:29.730298 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 16:49:30.383000 | 
2026-09-15 16:49:30.383118 | PLAY [all]
2026-09-15 16:49:30.392816 | 
2026-09-15 16:49:30.392888 | TASK [setup-uv : Extract archive]
2026-09-15 16:49:32.960379 | controller-2 | changed
2026-09-15 16:49:32.969797 | controller-3 | changed
2026-09-15 16:49:33.078387 | controller-1 | changed
2026-09-15 16:49:33.088025 | 
2026-09-15 16:49:33.088214 | TASK [setup-uv : Print version]
2026-09-15 16:49:33.527636 | controller-3 | uv 0.8.13
2026-09-15 16:49:33.539388 | controller-2 | uv 0.8.13
2026-09-15 16:49:33.535862 | controller-1 | uv 0.8.13
2026-09-15 16:49:33.669203 | controller-3 | ok: Runtime: 0:00:00.009455
2026-09-15 16:49:33.681947 | controller-1 | ok: Runtime: 0:00:00.017056
2026-09-15 16:49:33.693476 | controller-2 | ok: Runtime: 0:00:00.016465
2026-09-15 16:49:33.713537 | 
2026-09-15 16:49:33.713618 | TASK [Install Ansible collection siblings]
2026-09-15 16:49:34.181660 | controller-1 | ok:
2026-09-15 16:49:34.181933 | controller-1 | Skipping ansible.posix: no checked-out sibling
2026-09-15 16:49:34.182006 | controller-1 | Skipping community.crypto: no checked-out sibling
2026-09-15 16:49:34.182057 | controller-1 | Skipping community.general: no checked-out sibling
2026-09-15 16:49:34.182119 | controller-1 | Skipping kubernetes.core: no checked-out sibling
2026-09-15 16:49:34.182182 | controller-1 | Skipping vexxhost.containers: no checked-out sibling
2026-09-15 16:49:34.185370 | 
2026-09-15 16:49:34.185463 | PLAY RECAP
2026-09-15 16:49:34.185555 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:49:34.185616 | controller-2 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:49:34.185670 | controller-3 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:49:34.185711 | 
2026-09-15 16:49:34.353663 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 16:49:34.360841 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-15 16:49:34.945638 | 
2026-09-15 16:49:34.946204 | PLAY [all]
2026-09-15 16:49:34.957773 | 
2026-09-15 16:49:34.958078 | TASK [Update apt cache]
2026-09-15 16:49:40.950642 | controller-2 | ok
2026-09-15 16:49:41.019046 | controller-1 | ok
2026-09-15 16:49:41.369690 | controller-3 | ok
2026-09-15 16:49:41.374885 | 
2026-09-15 16:49:41.375008 | TASK [Disable firewalld]
2026-09-15 16:49:41.402588 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:41.413774 | controller-2 | skipping: Conditional result was False
2026-09-15 16:49:41.424260 | controller-3 | skipping: Conditional result was False
2026-09-15 16:49:41.430114 | 
2026-09-15 16:49:41.430208 | TASK [Install "jq" for log collection]
2026-09-15 16:49:44.110774 | controller-2 | changed
2026-09-15 16:49:44.181679 | controller-3 | changed
2026-09-15 16:49:44.404788 | controller-1 | changed
2026-09-15 16:49:44.408565 | 
2026-09-15 16:49:44.408662 | PLAY RECAP
2026-09-15 16:49:44.408750 | controller-1 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 16:49:44.408802 | controller-2 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 16:49:44.408847 | controller-3 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 16:49:44.408880 | 
2026-09-15 16:49:44.558838 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-15 16:49:44.564586 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 16:49:45.098456 | 
2026-09-15 16:49:45.098574 | PLAY [all]
2026-09-15 16:49:45.108375 | 
2026-09-15 16:49:45.108446 | LOOP [Check inventory directories]
2026-09-15 16:49:45.533388 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-15 16:49:45.742801 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-upgrade/inventory"
2026-09-15 16:49:45.943693 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-upgrade/inventory"
2026-09-15 16:49:45.961763 | 
2026-09-15 16:49:45.962043 | LOOP [Copy inventory file for Zuul]
2026-09-15 16:49:46.988411 | controller-1 | changed:
2026-09-15 16:49:46.988726 | controller-1 | {
2026-09-15 16:49:46.988784 | controller-1 |   "ansible_loop_var": "item",
2026-09-15 16:49:46.988830 | controller-1 |   "changed": false,
2026-09-15 16:49:46.988870 | controller-1 |   "failed": false,
2026-09-15 16:49:46.988915 | controller-1 |   "invocation": {
2026-09-15 16:49:46.988953 | controller-1 |     "module_args": {
2026-09-15 16:49:46.988989 | controller-1 |       "checksum_algorithm": "sha1",
2026-09-15 16:49:46.989025 | controller-1 |       "follow": true,
2026-09-15 16:49:46.989061 | controller-1 |       "get_attributes": true,
2026-09-15 16:49:46.989099 | controller-1 |       "get_checksum": true,
2026-09-15 16:49:46.989134 | controller-1 |       "get_mime": true,
2026-09-15 16:49:46.989169 | controller-1 |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-15 16:49:46.989205 | controller-1 |     }
2026-09-15 16:49:46.989264 | controller-1 |   },
2026-09-15 16:49:46.989301 | controller-1 |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-15 16:49:46.989337 | controller-1 |   "stat": {
2026-09-15 16:49:46.989371 | controller-1 |     "atime": 1789490967.9064927,
2026-09-15 16:49:46.989406 | controller-1 |     "attr_flags": "e",
2026-09-15 16:49:46.989440 | controller-1 |     "attributes": [
2026-09-15 16:49:46.989475 | controller-1 |       "extents"
2026-09-15 16:49:46.989509 | controller-1 |     ],
2026-09-15 16:49:46.989542 | controller-1 |     "block_size": 4096,
2026-09-15 16:49:46.989577 | controller-1 |     "blocks": 8,
2026-09-15 16:49:46.989611 | controller-1 |     "charset": "unknown",
2026-09-15 16:49:46.989649 | controller-1 |     "ctime": 1789490967.9464936,
2026-09-15 16:49:46.989684 | controller-1 |     "dev": 65025,
2026-09-15 16:49:46.989718 | controller-1 |     "device_type": 0,
2026-09-15 16:49:46.989752 | controller-1 |     "executable": true,
2026-09-15 16:49:46.989787 | controller-1 |     "exists": true,
2026-09-15 16:49:46.989837 | controller-1 |     "gid": 1000,
2026-09-15 16:49:46.989873 | controller-1 |     "gr_name": "zuul",
2026-09-15 16:49:46.989908 | controller-1 |     "inode": 521495,
2026-09-15 16:49:46.989942 | controller-1 |     "isblk": false,
2026-09-15 16:49:46.989978 | controller-1 |     "ischr": false,
2026-09-15 16:49:46.990011 | controller-1 |     "isdir": true,
2026-09-15 16:49:46.990044 | controller-1 |     "isfifo": false,
2026-09-15 16:49:46.990078 | controller-1 |     "isgid": false,
2026-09-15 16:49:46.990111 | controller-1 |     "islnk": false,
2026-09-15 16:49:46.990144 | controller-1 |     "isreg": false,
2026-09-15 16:49:46.990177 | controller-1 |     "issock": false,
2026-09-15 16:49:46.990210 | controller-1 |     "isuid": false,
2026-09-15 16:49:46.990262 | controller-1 |     "mimetype": "unknown",
2026-09-15 16:49:46.990299 | controller-1 |     "mode": "0755",
2026-09-15 16:49:46.990333 | controller-1 |     "mtime": 1789490948.4958181,
2026-09-15 16:49:46.990366 | controller-1 |     "nlink": 14,
2026-09-15 16:49:46.990400 | controller-1 |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-15 16:49:46.990434 | controller-1 |     "pw_name": "zuul",
2026-09-15 16:49:46.990468 | controller-1 |     "readable": true,
2026-09-15 16:49:46.990503 | controller-1 |     "rgrp": true,
2026-09-15 16:49:46.990537 | controller-1 |     "roth": true,
2026-09-15 16:49:46.990572 | controller-1 |     "rusr": true,
2026-09-15 16:49:46.990605 | controller-1 |     "size": 4096,
2026-09-15 16:49:46.990639 | controller-1 |     "uid": 1000,
2026-09-15 16:49:46.990673 | controller-1 |     "version": "131589747",
2026-09-15 16:49:46.990707 | controller-1 |     "wgrp": false,
2026-09-15 16:49:46.990747 | controller-1 |     "woth": false,
2026-09-15 16:49:46.990783 | controller-1 |     "writeable": true,
2026-09-15 16:49:46.990817 | controller-1 |     "wusr": true,
2026-09-15 16:49:46.990852 | controller-1 |     "xgrp": true,
2026-09-15 16:49:46.990886 | controller-1 |     "xoth": true,
2026-09-15 16:49:46.990919 | controller-1 |     "xusr": true
2026-09-15 16:49:46.990952 | controller-1 |   }
2026-09-15 16:49:46.990987 | controller-1 | }
2026-09-15 16:49:46.991055 | controller-1 | ok: All items complete
2026-09-15 16:49:46.991096 | 
2026-09-15 16:49:46.993611 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:46.997534 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:47.010896 | 
2026-09-15 16:49:47.011544 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 16:49:47.459907 | controller-1 | changed: 3 replacements made
2026-09-15 16:49:47.462303 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:47.465063 | controller-1 | skipping: Conditional result was False
2026-09-15 16:49:47.477664 | 
2026-09-15 16:49:47.477762 | TASK [Run Molecule scenario]
2026-09-15 16:49:48.022956 | controller-1 | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-15 16:49:48.023149 | controller-1 | Creating virtual environment at: .venv
2026-09-15 16:49:48.421447 | controller-1 | Downloading cryptography (4.3MiB)
2026-09-15 16:49:48.424207 | controller-1 | Downloading pygments (1.2MiB)
2026-09-15 16:49:48.425897 | controller-1 | Downloading ansible-core (2.3MiB)
2026-09-15 16:49:48.610794 | controller-1 |  Downloading cryptography
2026-09-15 16:49:48.672020 | controller-1 |  Downloading pygments
2026-09-15 16:49:48.882681 | controller-1 |  Downloading ansible-core
2026-09-15 16:49:48.954936 | controller-1 | Installed 36 packages in 71ms
2026-09-15 16:49:49.669546 | controller-1 | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-15 16:49:49.669625 | controller-1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-15 16:49:50.695077 | controller-1 | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-15 16:49:50.695160 | controller-1 | INFO     default scenario not found, disabling shared state.
2026-09-15 16:49:50.695186 | controller-1 | INFO     [cluster-upgrade > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 16:49:50.695200 | controller-1 | INFO     [cluster-upgrade > prerun] Performing prerun with role_name_check=0...
2026-09-15 16:59:45.167907 | controller-1 | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0
2026-09-15 16:59:45.167966 | controller-1 | ERROR    Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0, returned 1 code:
2026-09-15 16:59:45.167983 | controller-1 | Starting galaxy collection install process
2026-09-15 16:59:45.167987 | controller-1 | Process install dependency map
2026-09-15 16:59:45.167992 | controller-1 | Starting collection install process
2026-09-15 16:59:45.168000 | controller-1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vexxhost-containers-1.6.8.tar.gz to /home/zuul/.ansible/tmp/ansible-local-15058ann664h/tmpvf2c6gkl/vexxhost-containers-1.6.8-zhjdrh6d
2026-09-15 16:59:45.168004 | controller-1 |
2026-09-15 16:59:45.168008 | controller-1 | ansible-galaxy
2026-09-15 16:59:45.168011 | controller-1 |   config file = None
2026-09-15 16:59:45.168014 | controller-1 |   configured module search path =
2026-09-15 16:59:45.168018 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-15 16:59:45.168028 | 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-15 16:59:45.168033 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-15 16:59:45.168037 | 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-15 16:59:45.168040 | controller-1 |   jinja version = 3.1.6
2026-09-15 16:59:45.168043 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-15 16:59:45.168046 | controller-1 | No config file found; using defaults
2026-09-15 16:59:45.168052 | controller-1 | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 16:59:45.168055 | controller-1 | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-09-15 16:59:45.168059 | controller-1 | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 16:59:45.168062 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 16:59:45.168065 | controller-1 | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 16:59:45.168078 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-15 16:59:45.168081 | controller-1 | : Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-15 16:59:45.168085 | controller-1 |
2026-09-15 16:59:45.171577 | controller-1 | Traceback (most recent call last):
2026-09-15 16:59:45.175017 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-15 16:59:45.175043 | controller-1 |     sys.exit(main())
2026-09-15 16:59:45.175048 | controller-1 |              ~~~~^^
2026-09-15 16:59:45.175375 | 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-15 16:59:45.175383 | controller-1 |     return self.main(*args, **kwargs)
2026-09-15 16:59:45.175386 | controller-1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.175656 | 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-15 16:59:45.175661 | controller-1 |     rv = self.invoke(ctx)
2026-09-15 16:59:45.176043 | 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-15 16:59:45.176054 | controller-1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-15 16:59:45.176059 | controller-1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-15 16:59:45.176437 | 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-15 16:59:45.176445 | controller-1 |     return ctx.invoke(self.callback, **ctx.params)
2026-09-15 16:59:45.176450 | controller-1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.176726 | 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-15 16:59:45.176732 | controller-1 |     return callback(*args, **kwargs)
2026-09-15 16:59:45.177143 | 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-15 16:59:45.177253 | controller-1 |     return func(*args, **kwargs)
2026-09-15 16:59:45.177367 | 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-15 16:59:45.177383 | controller-1 |     return f(get_current_context(), *args, **kwargs)
2026-09-15 16:59:45.177640 | 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-15 16:59:45.177651 | controller-1 |     return func(ctx)
2026-09-15 16:59:45.178030 | 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-15 16:59:45.178051 | controller-1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-15 16:59:45.178059 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.178319 | 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-15 16:59:45.178332 | controller-1 |     _run_scenarios(scenarios, command_args, default_config)
2026-09-15 16:59:45.178340 | controller-1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.178848 | 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-15 16:59:45.178869 | controller-1 |     scenario.config.runtime.prepare_environment(
2026-09-15 16:59:45.178877 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-15 16:59:45.178884 | controller-1 |         install_local=True,
2026-09-15 16:59:45.178891 | controller-1 |         ^^^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.178899 | controller-1 |         role_name_check=role_name_check,
2026-09-15 16:59:45.178924 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.178931 | controller-1 |     )
2026-09-15 16:59:45.178938 | controller-1 |     ^
2026-09-15 16:59:45.179332 | 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-15 16:59:45.179352 | controller-1 |     self.install_collection(
2026-09-15 16:59:45.179359 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-15 16:59:45.179366 | controller-1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-15 16:59:45.179374 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.179381 | controller-1 |         destination=destination,
2026-09-15 16:59:45.179388 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:59:45.179395 | controller-1 |     )
2026-09-15 16:59:45.179402 | controller-1 |     ^
2026-09-15 16:59:45.179601 | 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-15 16:59:45.179609 | controller-1 |     raise InvalidPrerequisiteError(msg)
2026-09-15 16:59:45.180862 | controller-1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0, returned 1 code:
2026-09-15 16:59:45.180886 | controller-1 | Starting galaxy collection install process
2026-09-15 16:59:45.180894 | controller-1 | Process install dependency map
2026-09-15 16:59:45.180904 | controller-1 | Starting collection install process
2026-09-15 16:59:45.180916 | controller-1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vexxhost-containers-1.6.8.tar.gz to /home/zuul/.ansible/tmp/ansible-local-15058ann664h/tmpvf2c6gkl/vexxhost-containers-1.6.8-zhjdrh6d
2026-09-15 16:59:45.180926 | controller-1 |
2026-09-15 16:59:45.180936 | controller-1 | ansible-galaxy [core 2.21.4]
2026-09-15 16:59:45.180946 | controller-1 |   config file = None
2026-09-15 16:59:45.180955 | 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-15 16:59:45.180965 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-15 16:59:45.180976 | 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-15 16:59:45.180987 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-15 16:59:45.180996 | 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-15 16:59:45.181005 | controller-1 |   jinja version = 3.1.6
2026-09-15 16:59:45.181013 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-15 16:59:45.181022 | controller-1 | No config file found; using defaults
2026-09-15 16:59:45.181031 | controller-1 | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 16:59:45.181039 | controller-1 | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-09-15 16:59:45.181048 | controller-1 | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 16:59:45.181056 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 16:59:45.181065 | controller-1 | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 16:59:45.181084 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-15 16:59:45.181093 | controller-1 | [ERROR]: Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-15 16:59:45.181102 | controller-1 |
2026-09-15 16:59:55.525240 | controller-1 | ERROR
2026-09-15 16:59:55.525613 | controller-1 | {
2026-09-15 16:59:55.525672 | controller-1 |   "delta": "0:09:57.398392",
2026-09-15 16:59:55.525711 | controller-1 |   "end": "2026-09-15 16:59:45.306214",
2026-09-15 16:59:55.525747 | controller-1 |   "msg": "non-zero return code",
2026-09-15 16:59:55.525781 | controller-1 |   "rc": 1,
2026-09-15 16:59:55.525815 | controller-1 |   "start": "2026-09-15 16:49:47.907822"
2026-09-15 16:59:55.525849 | controller-1 | }
failure
2026-09-15 16:59:55.534173 | 
2026-09-15 16:59:55.534278 | PLAY RECAP
2026-09-15 16:59:55.534401 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:59:55.534458 | 
2026-09-15 16:59:55.704949 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 16:59:55.709616 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-15 16:59:56.267247 | 
2026-09-15 16:59:56.267568 | PLAY [all]
2026-09-15 16:59:56.278427 | 
2026-09-15 16:59:56.278506 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 16:59:56.752735 | controller-1 | changed
2026-09-15 16:59:56.902445 | controller-3 | changed
2026-09-15 16:59:56.940947 | controller-2 | changed
2026-09-15 16:59:56.950689 | 
2026-09-15 16:59:56.950890 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 16:59:57.399949 | controller-1 | 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-09-15 16:59:57.400012 | controller-1 |
2026-09-15 16:59:57.408858 | controller-1 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.409928 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.412557 | controller-1 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.415227 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.415249 | controller-1 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.415525 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.419538 | controller-1 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.420462 | controller-1 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.420703 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.421723 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.412463 | controller-2 | 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-09-15 16:59:57.412569 | controller-2 |
2026-09-15 16:59:57.419252 | controller-2 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.420469 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.421559 | controller-2 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.423889 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.425999 | controller-2 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.426704 | controller-2 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.427503 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.428316 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.432260 | controller-2 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.435142 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.385762 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:57.385847 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:57.393285 | controller-3 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.393695 | controller-3 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.394811 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.395383 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.399366 | controller-3 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.400621 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.401655 | controller-3 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.402764 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.404205 | controller-3 | environment: line 1: kubectl: command not found
2026-09-15 16:59:57.405236 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:59:57.531178 | controller-3 | ok: Runtime: 0:00:00.030399
2026-09-15 16:59:57.543351 | controller-1 | ok: Runtime: 0:00:00.037557
2026-09-15 16:59:57.554943 | controller-2 | ok: Runtime: 0:00:00.039342
2026-09-15 16:59:57.562424 | 
2026-09-15 16:59:57.562554 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 16:59:57.828225 | controller-1 | changed
2026-09-15 16:59:57.856859 | controller-2 | changed
2026-09-15 16:59:57.876157 | controller-3 | changed
2026-09-15 16:59:57.883561 | 
2026-09-15 16:59:57.883735 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 16:59:58.213364 | controller-1 | environment: line 5: kubectl: command not found
2026-09-15 16:59:58.215025 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.215560 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.215984 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.213183 | controller-2 | environment: line 5: kubectl: command not found
2026-09-15 16:59:58.214461 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.215600 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.215867 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.266481 | controller-3 | environment: line 5: kubectl: command not found
2026-09-15 16:59:58.266738 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.267958 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.268602 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:59:58.460982 | controller-1 | ok: Runtime: 0:00:00.013720
2026-09-15 16:59:58.518223 | controller-2 | ok: Runtime: 0:00:00.014302
2026-09-15 16:59:58.525799 | controller-3 | ok: Runtime: 0:00:00.015031
2026-09-15 16:59:58.530321 | 
2026-09-15 16:59:58.530397 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 16:59:59.166587 | controller-1 | changed:
2026-09-15 16:59:59.166997 | controller-1 | created directory /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/logs/controller-1
2026-09-15 16:59:59.167061 | controller-1 | cd+++++++++ objects/
2026-09-15 16:59:59.167104 | controller-1 | cd+++++++++ objects/cluster/
2026-09-15 16:59:59.167142 | controller-1 | cd+++++++++ objects/namespaced/
2026-09-15 16:59:59.204437 | controller-2 | changed:
2026-09-15 16:59:59.204581 | controller-2 | created directory /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/logs/controller-2
2026-09-15 16:59:59.204633 | controller-2 | cd+++++++++ objects/
2026-09-15 16:59:59.204673 | controller-2 | cd+++++++++ objects/cluster/
2026-09-15 16:59:59.204710 | controller-2 | cd+++++++++ objects/namespaced/
2026-09-15 16:59:59.210806 | controller-3 | changed:
2026-09-15 16:59:59.210894 | controller-3 | created directory /var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/logs/controller-3
2026-09-15 16:59:59.210941 | controller-3 | cd+++++++++ objects/
2026-09-15 16:59:59.210980 | controller-3 | cd+++++++++ objects/cluster/
2026-09-15 16:59:59.211017 | controller-3 | cd+++++++++ objects/namespaced/
2026-09-15 16:59:59.237316 | 
2026-09-15 16:59:59.237426 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 16:59:59.507154 | controller-1 | changed
2026-09-15 16:59:59.515158 | controller-3 | changed
2026-09-15 16:59:59.540046 | controller-2 | changed
2026-09-15 16:59:59.544945 | 
2026-09-15 16:59:59.545025 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 16:59:59.804707 | controller-1 | changed
2026-09-15 16:59:59.822970 | controller-2 | changed
2026-09-15 16:59:59.849651 | controller-3 | changed
2026-09-15 16:59:59.856835 | 
2026-09-15 16:59:59.856972 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 17:00:00.178630 | controller-1 | environment: line 3: kubectl: command not found
2026-09-15 17:00:00.198139 | controller-2 | environment: line 3: kubectl: command not found
2026-09-15 17:00:00.223666 | controller-3 | environment: line 3: kubectl: command not found
2026-09-15 17:00:00.435058 | controller-1 | ok: Runtime: 0:00:00.014490
2026-09-15 17:00:00.447217 | controller-2 | ok: Runtime: 0:00:00.014048
2026-09-15 17:00:00.458248 | controller-3 | ok: Runtime: 0:00:00.011846
2026-09-15 17:00:00.465576 | 
2026-09-15 17:00:00.465701 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 17:00:01.035399 | controller-3 | changed:
2026-09-15 17:00:01.035779 | controller-3 | cd+++++++++ pod-logs/
2026-09-15 17:00:01.035848 | controller-3 | cd+++++++++ pod-logs/failed-pods/
2026-09-15 17:00:01.043653 | controller-2 | changed:
2026-09-15 17:00:01.043752 | controller-2 | cd+++++++++ pod-logs/
2026-09-15 17:00:01.043800 | controller-2 | cd+++++++++ pod-logs/failed-pods/
2026-09-15 17:00:01.050043 | controller-1 | changed:
2026-09-15 17:00:01.050133 | controller-1 | cd+++++++++ pod-logs/
2026-09-15 17:00:01.050182 | controller-1 | cd+++++++++ pod-logs/failed-pods/
2026-09-15 17:00:01.070150 | 
2026-09-15 17:00:01.070231 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 17:00:01.362528 | controller-2 | changed: "values"
2026-09-15 17:00:01.374776 | controller-1 | changed: "values"
2026-09-15 17:00:01.379167 | controller-3 | changed: "values"
2026-09-15 17:00:01.584145 | controller-2 | changed: "releases"
2026-09-15 17:00:01.589665 | controller-1 | changed: "releases"
2026-09-15 17:00:01.613590 | controller-3 | changed: "releases"
2026-09-15 17:00:01.613746 | controller-3 | changed: All items complete
2026-09-15 17:00:01.613772 | 
2026-09-15 17:00:01.620432 | 
2026-09-15 17:00:01.620628 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 17:00:01.901416 | controller-1 | /bin/bash: line 3: kubectl: command not found
2026-09-15 17:00:01.890402 | controller-2 | /bin/bash: line 3: kubectl: command not found
2026-09-15 17:00:01.931546 | controller-3 | /bin/bash: line 3: kubectl: command not found
2026-09-15 17:00:02.179244 | controller-1 | ok: Runtime: 0:00:00.008368
2026-09-15 17:00:02.197265 | controller-2 | ok: Runtime: 0:00:00.011887
2026-09-15 17:00:02.208754 | controller-3 | ok: Runtime: 0:00:00.008449
2026-09-15 17:00:02.214190 | 
2026-09-15 17:00:02.214286 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 17:00:02.740466 | controller-2 | changed:
2026-09-15 17:00:02.740939 | controller-2 | cd+++++++++ helm/
2026-09-15 17:00:02.741007 | controller-2 | cd+++++++++ helm/releases/
2026-09-15 17:00:02.741057 | controller-2 | cd+++++++++ helm/values/
2026-09-15 17:00:02.748806 | controller-1 | changed:
2026-09-15 17:00:02.748902 | controller-1 | cd+++++++++ helm/
2026-09-15 17:00:02.748948 | controller-1 | cd+++++++++ helm/releases/
2026-09-15 17:00:02.748987 | controller-1 | cd+++++++++ helm/values/
2026-09-15 17:00:02.816943 | controller-3 | changed:
2026-09-15 17:00:02.817101 | controller-3 | cd+++++++++ helm/
2026-09-15 17:00:02.817147 | controller-3 | cd+++++++++ helm/releases/
2026-09-15 17:00:02.817187 | controller-3 | cd+++++++++ helm/values/
2026-09-15 17:00:02.839410 | 
2026-09-15 17:00:02.839568 | PLAY RECAP
2026-09-15 17:00:02.839653 | controller-1 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 17:00:02.839707 | controller-2 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 17:00:02.839754 | controller-3 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 17:00:02.839790 | 
2026-09-15 17:00:02.995600 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-15 17:00:02.998327 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 17:00:03.599553 | 
2026-09-15 17:00:03.599700 | PLAY [all]
2026-09-15 17:00:03.610016 | 
2026-09-15 17:00:03.610098 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 17:00:03.675297 | controller-2 | ok
2026-09-15 17:00:03.679775 | controller-1 | ok
2026-09-15 17:00:03.695681 | controller-3 | ok
2026-09-15 17:00:03.699820 | 
2026-09-15 17:00:03.699934 | TASK [fetch-output : Set log path for single node]
2026-09-15 17:00:03.729496 | controller-1 | skipping: Conditional result was False
2026-09-15 17:00:03.740456 | controller-2 | skipping: Conditional result was False
2026-09-15 17:00:03.740780 | controller-3 | skipping: Conditional result was False
2026-09-15 17:00:03.746451 | 
2026-09-15 17:00:03.746548 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 17:00:04.123571 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/logs/controller-1"
2026-09-15 17:00:04.140997 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/logs/controller-3"
2026-09-15 17:00:04.155270 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/logs/controller-2"
2026-09-15 17:00:04.341627 | controller-1 -> localhost | changed: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/artifacts"
2026-09-15 17:00:04.369228 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/artifacts"
2026-09-15 17:00:04.431896 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/artifacts"
2026-09-15 17:00:04.526885 | controller-1 -> localhost | changed: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/docs"
2026-09-15 17:00:04.593317 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/docs"
2026-09-15 17:00:04.593498 | controller-2 -> localhost | changed: All items complete
2026-09-15 17:00:04.593549 | 
2026-09-15 17:00:04.619037 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/ce5ad0e22d664233999d1df9b5a5ec6e/work/docs"
2026-09-15 17:00:04.638656 | 
2026-09-15 17:00:04.638824 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 17:00:05.274348 | controller-3 | changed: .d..t...... ./
2026-09-15 17:00:05.274744 | controller-3 | ok: All items complete
2026-09-15 17:00:05.274803 | 
2026-09-15 17:00:05.286588 | controller-1 | changed: .d..t...... ./
2026-09-15 17:00:05.308372 | controller-2 | changed: .d..t...... ./
2026-09-15 17:00:05.789282 | controller-3 | changed: .d..t...... ./
2026-09-15 17:00:05.814113 | controller-1 | ok
2026-09-15 17:00:05.838873 | controller-2 | ok
2026-09-15 17:00:06.301273 | controller-1 | changed: .d..t...... ./
2026-09-15 17:00:06.318734 | controller-3 | ok
2026-09-15 17:00:06.361892 | controller-2 | ok
2026-09-15 17:00:06.362049 | controller-2 | changed: All items complete
2026-09-15 17:00:06.362105 | 
2026-09-15 17:00:06.397795 | 
2026-09-15 17:00:06.398022 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 17:00:06.840542 | controller-1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007471
2026-09-15 17:00:07.107531 | controller-1 -> localhost | ok: Item: docs Runtime: 0:00:00.006782
2026-09-15 17:00:07.142263 | 
2026-09-15 17:00:07.142451 | PLAY [all]
2026-09-15 17:00:07.152846 | 
2026-09-15 17:00:07.152986 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 17:00:07.632217 | controller-1 | changed
2026-09-15 17:00:07.822712 | controller-2 | changed
2026-09-15 17:00:07.841124 | controller-3 | changed
2026-09-15 17:00:07.862739 | 
2026-09-15 17:00:07.862876 | PLAY RECAP
2026-09-15 17:00:07.862955 | controller-1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 17:00:07.863009 | controller-2 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 17:00:07.863059 | controller-3 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 17:00:07.863098 | 
2026-09-15 17:00:08.022568 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 17:00:08.028884 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 17:00:08.595010 | 
2026-09-15 17:00:08.595631 | PLAY [localhost]
2026-09-15 17:00:08.607371 | 
2026-09-15 17:00:08.607489 | TASK [Generate Zuul manifest]
2026-09-15 17:00:08.626310 | localhost | ok
2026-09-15 17:00:08.641138 | 
2026-09-15 17:00:08.641236 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 17:00:09.047805 | localhost | changed
2026-09-15 17:00:09.069550 | 
2026-09-15 17:00:09.069756 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 17:00:09.102316 | localhost | ok
2026-09-15 17:00:09.115736 | 
2026-09-15 17:00:09.115877 | TASK [Upload logs]
2026-09-15 17:00:09.137566 | localhost | ok
2026-09-15 17:00:09.203718 | 
2026-09-15 17:00:09.203853 | TASK [Set zuul-log-path fact]
2026-09-15 17:00:09.223050 | localhost | ok
2026-09-15 17:00:09.237428 | 
2026-09-15 17:00:09.237495 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 17:00:09.275477 | localhost | ok
2026-09-15 17:00:09.283672 | 
2026-09-15 17:00:09.283756 | TASK [upload-logs : Create log directories]
2026-09-15 17:00:09.743378 | localhost | changed
2026-09-15 17:00:09.804231 | 
2026-09-15 17:00:09.804389 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 17:00:10.188179 | localhost -> localhost | ok: Runtime: 0:00:00.006125
2026-09-15 17:00:10.197758 | 
2026-09-15 17:00:10.197905 | TASK [upload-logs : Upload logs to log server]
2026-09-15 17:00:10.675458 | localhost | Output suppressed because no_log was given
2026-09-15 17:00:10.682663 | 
2026-09-15 17:00:10.682806 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 17:00:10.748013 | localhost | skipping: Conditional result was False
2026-09-15 17:00:10.759484 | localhost | skipping: Conditional result was False
2026-09-15 17:00:10.778045 | 
2026-09-15 17:00:10.778240 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 17:00:10.825232 | localhost | skipping: Conditional result was False
2026-09-15 17:00:10.825527 | 
2026-09-15 17:00:10.829073 | localhost | skipping: Conditional result was False
2026-09-15 17:00:10.842854 | 
2026-09-15 17:00:10.843067 | LOOP [upload-logs : Upload console log and json output]