2026-09-24 00:46:46.859389 | Job console starting
2026-09-24 00:46:46.897702 | Updating git repos
2026-09-24 00:46:46.969639 | Cloning repos into workspace
2026-09-24 00:46:47.067573 | Restoring repo states
2026-09-24 00:46:47.080268 | Merging changes
2026-09-24 00:46:47.900216 | Checking out repos
2026-09-24 00:46:48.138414 | Preparing playbooks
2026-09-24 00:46:50.733440 | Running Ansible setup
2026-09-24 00:46:56.067302 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-24 00:46:56.646276 | 
2026-09-24 00:46:56.646686 | PLAY [localhost]
2026-09-24 00:46:56.654211 | 
2026-09-24 00:46:56.654275 | TASK [Gathering Facts]
2026-09-24 00:46:57.511681 | localhost | ok
2026-09-24 00:46:57.527079 | 
2026-09-24 00:46:57.527300 | TASK [Setup log path fact]
2026-09-24 00:46:57.552550 | localhost | ok
2026-09-24 00:46:57.572959 | 
2026-09-24 00:46:57.573061 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-24 00:46:57.615636 | localhost | ok
2026-09-24 00:46:57.631706 | 
2026-09-24 00:46:57.631960 | TASK [emit-job-header : Print job information]
2026-09-24 00:46:57.675859 | # Job Information
2026-09-24 00:46:57.676062 | Ansible Version: 2.16.18
2026-09-24 00:46:57.676110 | Job: ansible-collection-kubernetes-molecule-cluster-upgrade
2026-09-24 00:46:57.676144 | Pipeline: check
2026-09-24 00:46:57.676190 | Executor: 2d72f0692154
2026-09-24 00:46:57.676220 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293
2026-09-24 00:46:57.676250 | Event ID: e533a450-b7b0-11f1-98be-9e8d6b6260a8
2026-09-24 00:46:57.680196 | 
2026-09-24 00:46:57.680280 | LOOP [emit-job-header : Print node information]
2026-09-24 00:46:57.782799 | localhost | ok:
2026-09-24 00:46:57.783230 | localhost | # Node Information
2026-09-24 00:46:57.783296 | localhost | Inventory Hostname: controller-1
2026-09-24 00:46:57.783343 | localhost | Hostname: np0000193838
2026-09-24 00:46:57.783386 | localhost | Username: zuul
2026-09-24 00:46:57.783433 | localhost | Distro: Debian 13.7
2026-09-24 00:46:57.783476 | localhost | Provider: yul1
2026-09-24 00:46:57.783518 | localhost | Region: ca-ymq-1
2026-09-24 00:46:57.783558 | localhost | Label: debian-trixie
2026-09-24 00:46:57.783599 | localhost | Product Name: OpenStack Nova
2026-09-24 00:46:57.783637 | localhost | Interface IP: 162.253.55.162
2026-09-24 00:46:57.810019 | localhost | ok:
2026-09-24 00:46:57.810286 | localhost | # Node Information
2026-09-24 00:46:57.810362 | localhost | Inventory Hostname: controller-2
2026-09-24 00:46:57.810407 | localhost | Hostname: np0000193839
2026-09-24 00:46:57.810450 | localhost | Username: zuul
2026-09-24 00:46:57.810489 | localhost | Distro: Debian 13.7
2026-09-24 00:46:57.810527 | localhost | Provider: yul1
2026-09-24 00:46:57.810567 | localhost | Region: ca-ymq-1
2026-09-24 00:46:57.810604 | localhost | Label: debian-trixie
2026-09-24 00:46:57.810642 | localhost | Product Name: OpenStack Nova
2026-09-24 00:46:57.810683 | localhost | Interface IP: 162.253.55.169
2026-09-24 00:46:57.842993 | localhost | ok:
2026-09-24 00:46:57.843116 | localhost | # Node Information
2026-09-24 00:46:57.843209 | localhost | Inventory Hostname: controller-3
2026-09-24 00:46:57.843261 | localhost | Hostname: np0000193840
2026-09-24 00:46:57.843302 | localhost | Username: zuul
2026-09-24 00:46:57.843345 | localhost | Distro: Debian 13.7
2026-09-24 00:46:57.843383 | localhost | Provider: yul1
2026-09-24 00:46:57.843419 | localhost | Region: ca-ymq-1
2026-09-24 00:46:57.843457 | localhost | Label: debian-trixie
2026-09-24 00:46:57.843496 | localhost | Product Name: OpenStack Nova
2026-09-24 00:46:57.843533 | localhost | Interface IP: 162.253.55.149
2026-09-24 00:46:57.864552 | 
2026-09-24 00:46:57.864782 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-24 00:46:58.318608 | localhost -> localhost | changed
2026-09-24 00:46:58.331498 | 
2026-09-24 00:46:58.331650 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-24 00:46:59.210601 | localhost -> localhost | changed
2026-09-24 00:46:59.223042 | 
2026-09-24 00:46:59.223191 | PLAY [all]
2026-09-24 00:46:59.241620 | 
2026-09-24 00:46:59.241761 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-24 00:46:59.528624 | controller-1 -> localhost | ok
2026-09-24 00:46:59.539791 | 
2026-09-24 00:46:59.539932 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-24 00:46:59.571405 | controller-1 | ok
2026-09-24 00:46:59.594827 | controller-2 | ok
2026-09-24 00:46:59.610343 | controller-3 | ok
2026-09-24 00:46:59.634899 | controller-1 | included: /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-24 00:46:59.634965 | controller-2 | included: /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-24 00:46:59.634988 | controller-3 | included: /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-24 00:46:59.640624 | 
2026-09-24 00:46:59.640832 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-24 00:47:01.791075 | controller-1 -> localhost | Generating public/private rsa key pair.
2026-09-24 00:47:01.791351 | controller-1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/64bc7263de9a409998fb2cfe8348a5d1_id_rsa
2026-09-24 00:47:01.791408 | controller-1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/64bc7263de9a409998fb2cfe8348a5d1_id_rsa.pub
2026-09-24 00:47:01.791452 | controller-1 -> localhost | The key fingerprint is:
2026-09-24 00:47:01.791492 | controller-1 -> localhost | SHA256:egFcHAJvcW/ApKMTjIrMUsLbYa02gh+5rmyqE26/Apk zuul-build-sshkey
2026-09-24 00:47:01.791531 | controller-1 -> localhost | The key's randomart image is:
2026-09-24 00:47:01.791570 | controller-1 -> localhost | +---[RSA 3072]----+
2026-09-24 00:47:01.791609 | controller-1 -> localhost | |    ..o+*.       |
2026-09-24 00:47:01.791648 | controller-1 -> localhost | |.  o.o *oo       |
2026-09-24 00:47:01.791686 | controller-1 -> localhost | |..ooo.B   o      |
2026-09-24 00:47:01.791723 | controller-1 -> localhost | |=++.o+ o .       |
2026-09-24 00:47:01.791759 | controller-1 -> localhost | |=Bo=o   S        |
2026-09-24 00:47:01.791795 | controller-1 -> localhost | |E.oo.. . .       |
2026-09-24 00:47:01.791832 | controller-1 -> localhost | |o.o   . .        |
2026-09-24 00:47:01.791868 | controller-1 -> localhost | |o*     .         |
2026-09-24 00:47:01.791904 | controller-1 -> localhost | |O++o.            |
2026-09-24 00:47:01.791941 | controller-1 -> localhost | +----[SHA256]-----+
2026-09-24 00:47:01.792022 | controller-1 -> localhost | ok: Runtime: 0:00:01.706532
2026-09-24 00:47:01.799827 | 
2026-09-24 00:47:01.799946 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-24 00:47:01.829641 | controller-1 | ok
2026-09-24 00:47:01.848378 | controller-2 | ok
2026-09-24 00:47:01.866011 | controller-3 | ok
2026-09-24 00:47:01.887705 | controller-1 | included: /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-24 00:47:01.887768 | controller-2 | included: /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-24 00:47:01.887798 | controller-3 | included: /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-24 00:47:01.901054 | 
2026-09-24 00:47:01.901116 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-24 00:47:01.929763 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:01.930529 | controller-2 | skipping: Conditional result was False
2026-09-24 00:47:01.940916 | controller-3 | skipping: Conditional result was False
2026-09-24 00:47:01.947800 | 
2026-09-24 00:47:01.947883 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-24 00:47:02.494733 | controller-2 | changed
2026-09-24 00:47:02.499019 | controller-1 | changed
2026-09-24 00:47:02.502971 | controller-3 | changed
2026-09-24 00:47:02.508251 | 
2026-09-24 00:47:02.508332 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-24 00:47:02.725196 | controller-1 | ok
2026-09-24 00:47:02.775168 | controller-3 | ok
2026-09-24 00:47:02.779924 | controller-2 | ok
2026-09-24 00:47:02.789094 | 
2026-09-24 00:47:02.789376 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-24 00:47:03.359509 | controller-1 | changed
2026-09-24 00:47:03.376538 | controller-2 | changed
2026-09-24 00:47:03.403854 | controller-3 | changed
2026-09-24 00:47:03.408678 | 
2026-09-24 00:47:03.408751 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-24 00:47:03.980250 | controller-1 | changed
2026-09-24 00:47:04.006264 | controller-2 | changed
2026-09-24 00:47:04.084992 | controller-3 | changed
2026-09-24 00:47:04.106395 | 
2026-09-24 00:47:04.106574 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-24 00:47:04.141092 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:04.152617 | controller-2 | skipping: Conditional result was False
2026-09-24 00:47:04.163121 | controller-3 | skipping: Conditional result was False
2026-09-24 00:47:04.171743 | 
2026-09-24 00:47:04.171874 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-24 00:47:04.507297 | controller-1 -> localhost | changed
2026-09-24 00:47:04.550103 | 
2026-09-24 00:47:04.550316 | TASK [add-build-sshkey : Add back temp key]
2026-09-24 00:47:04.858301 | controller-1 -> localhost | Identity added: /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/64bc7263de9a409998fb2cfe8348a5d1_id_rsa (zuul-build-sshkey)
2026-09-24 00:47:04.858630 | controller-1 -> localhost | ok: Runtime: 0:00:00.017214
2026-09-24 00:47:04.866634 | 
2026-09-24 00:47:04.866810 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-24 00:47:05.190707 | controller-1 | ok
2026-09-24 00:47:05.195270 | controller-2 | ok
2026-09-24 00:47:05.199474 | controller-3 | ok
2026-09-24 00:47:05.204685 | 
2026-09-24 00:47:05.204774 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-24 00:47:05.242883 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:05.254541 | controller-2 | skipping: Conditional result was False
2026-09-24 00:47:05.265255 | controller-3 | skipping: Conditional result was False
2026-09-24 00:47:05.285279 | 
2026-09-24 00:47:05.285492 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-24 00:47:05.614655 | controller-3 | ok
2026-09-24 00:47:05.620974 | controller-1 | ok
2026-09-24 00:47:05.631489 | controller-2 | ok
2026-09-24 00:47:05.639811 | 
2026-09-24 00:47:05.639937 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-24 00:47:06.669396 | controller-3 | Output suppressed because no_log was given
2026-09-24 00:47:06.701124 | controller-2 | Output suppressed because no_log was given
2026-09-24 00:47:06.711014 | controller-1 | Output suppressed because no_log was given
2026-09-24 00:47:06.735309 | 
2026-09-24 00:47:06.735397 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-24 00:47:06.983355 | controller-2 | ok: "logs"
2026-09-24 00:47:06.983783 | controller-2 | ok: All items complete
2026-09-24 00:47:06.983838 | 
2026-09-24 00:47:06.992860 | controller-1 | ok: "logs"
2026-09-24 00:47:07.002911 | controller-3 | ok: "logs"
2026-09-24 00:47:07.159515 | controller-2 | ok: "artifacts"
2026-09-24 00:47:07.176624 | controller-1 | ok: "artifacts"
2026-09-24 00:47:07.210723 | controller-3 | ok: "artifacts"
2026-09-24 00:47:07.342312 | controller-2 | ok: "docs"
2026-09-24 00:47:07.350111 | controller-1 | ok: "docs"
2026-09-24 00:47:07.414860 | controller-3 | ok: "docs"
2026-09-24 00:47:07.414934 | controller-3 | ok: All items complete
2026-09-24 00:47:07.414964 | 
2026-09-24 00:47:07.429480 | 
2026-09-24 00:47:07.429775 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-24 00:47:07.647025 | controller-1 | changed: "logs"
2026-09-24 00:47:07.715801 | controller-3 | changed: "logs"
2026-09-24 00:47:07.723011 | controller-2 | changed: "logs"
2026-09-24 00:47:07.812264 | controller-1 | changed: "artifacts"
2026-09-24 00:47:07.928306 | controller-2 | changed: "artifacts"
2026-09-24 00:47:07.943498 | controller-3 | changed: "artifacts"
2026-09-24 00:47:07.983888 | controller-1 | changed: "docs"
2026-09-24 00:47:08.134137 | controller-3 | changed: "docs"
2026-09-24 00:47:08.134299 | controller-3 | changed: All items complete
2026-09-24 00:47:08.134359 | 
2026-09-24 00:47:08.141491 | controller-2 | changed: "docs"
2026-09-24 00:47:08.177540 | 
2026-09-24 00:47:08.177732 | PLAY RECAP
2026-09-24 00:47:08.177853 | controller-1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-24 00:47:08.177915 | controller-2 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-24 00:47:08.177982 | controller-3 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-24 00:47:08.178175 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:47:08.178227 | 
2026-09-24 00:47:08.360962 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-24 00:47:08.364377 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-24 00:47:08.973400 | 
2026-09-24 00:47:08.973584 | PLAY [all]
2026-09-24 00:47:08.985208 | 
2026-09-24 00:47:08.985310 | TASK [setup-uv : Extract archive]
2026-09-24 00:47:11.581795 | controller-3 | changed
2026-09-24 00:47:11.619622 | controller-1 | changed
2026-09-24 00:47:11.636596 | controller-2 | changed
2026-09-24 00:47:11.645454 | 
2026-09-24 00:47:11.645639 | TASK [setup-uv : Print version]
2026-09-24 00:47:12.044161 | controller-2 | uv 0.8.13
2026-09-24 00:47:12.053103 | controller-3 | uv 0.8.13
2026-09-24 00:47:12.053697 | controller-1 | uv 0.8.13
2026-09-24 00:47:12.225813 | controller-2 | ok: Runtime: 0:00:00.014388
2026-09-24 00:47:12.239605 | controller-3 | ok: Runtime: 0:00:00.014936
2026-09-24 00:47:12.251259 | controller-1 | ok: Runtime: 0:00:00.012142
2026-09-24 00:47:12.272014 | 
2026-09-24 00:47:12.272094 | TASK [Install Ansible collection siblings]
2026-09-24 00:47:12.689176 | controller-1 | ok:
2026-09-24 00:47:12.689430 | controller-1 | Skipping ansible.posix: no checked-out sibling
2026-09-24 00:47:12.689493 | controller-1 | Skipping community.crypto: no checked-out sibling
2026-09-24 00:47:12.689537 | controller-1 | Skipping community.general: no checked-out sibling
2026-09-24 00:47:12.689577 | controller-1 | Skipping kubernetes.core: no checked-out sibling
2026-09-24 00:47:12.689619 | controller-1 | Skipping vexxhost.containers: no checked-out sibling
2026-09-24 00:47:12.692479 | 
2026-09-24 00:47:12.692598 | PLAY RECAP
2026-09-24 00:47:12.692677 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:47:12.692730 | controller-2 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:47:12.692777 | controller-3 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:47:12.692812 | 
2026-09-24 00:47:12.818531 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-24 00:47:12.825570 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-24 00:47:13.355067 | 
2026-09-24 00:47:13.355196 | PLAY [all]
2026-09-24 00:47:13.364916 | 
2026-09-24 00:47:13.364984 | TASK [Update apt cache]
2026-09-24 00:47:18.901525 | controller-3 | ok
2026-09-24 00:47:19.060586 | controller-2 | ok
2026-09-24 00:47:19.561192 | controller-1 | ok
2026-09-24 00:47:19.568359 | 
2026-09-24 00:47:19.568564 | TASK [Disable firewalld]
2026-09-24 00:47:19.601545 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:19.614003 | controller-2 | skipping: Conditional result was False
2026-09-24 00:47:19.614432 | controller-3 | skipping: Conditional result was False
2026-09-24 00:47:19.621812 | 
2026-09-24 00:47:19.621936 | TASK [Install "jq" for log collection]
2026-09-24 00:47:22.088821 | controller-2 | changed
2026-09-24 00:47:22.257513 | controller-3 | changed
2026-09-24 00:47:22.273871 | controller-1 | changed
2026-09-24 00:47:22.278930 | 
2026-09-24 00:47:22.279074 | PLAY RECAP
2026-09-24 00:47:22.279208 | controller-1 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-24 00:47:22.279271 | controller-2 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-24 00:47:22.279324 | controller-3 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-24 00:47:22.279364 | 
2026-09-24 00:47:22.503286 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-24 00:47:22.507578 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-24 00:47:23.089942 | 
2026-09-24 00:47:23.090056 | PLAY [all]
2026-09-24 00:47:23.100680 | 
2026-09-24 00:47:23.100750 | LOOP [Check inventory directories]
2026-09-24 00:47:23.477783 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-24 00:47:23.678720 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-upgrade/inventory"
2026-09-24 00:47:23.878344 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-upgrade/inventory"
2026-09-24 00:47:23.896747 | 
2026-09-24 00:47:23.897034 | LOOP [Copy inventory file for Zuul]
2026-09-24 00:47:24.831089 | controller-1 | changed:
2026-09-24 00:47:24.831380 | controller-1 | {
2026-09-24 00:47:24.831419 | controller-1 |   "ansible_loop_var": "item",
2026-09-24 00:47:24.831450 | controller-1 |   "changed": false,
2026-09-24 00:47:24.831476 | controller-1 |   "failed": false,
2026-09-24 00:47:24.831505 | controller-1 |   "invocation": {
2026-09-24 00:47:24.831532 | controller-1 |     "module_args": {
2026-09-24 00:47:24.831557 | controller-1 |       "checksum_algorithm": "sha1",
2026-09-24 00:47:24.831581 | controller-1 |       "follow": true,
2026-09-24 00:47:24.831604 | controller-1 |       "get_attributes": true,
2026-09-24 00:47:24.831629 | controller-1 |       "get_checksum": true,
2026-09-24 00:47:24.831652 | controller-1 |       "get_mime": true,
2026-09-24 00:47:24.831674 | controller-1 |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-24 00:47:24.831697 | controller-1 |     }
2026-09-24 00:47:24.831719 | controller-1 |   },
2026-09-24 00:47:24.831742 | controller-1 |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-24 00:47:24.831765 | controller-1 |   "stat": {
2026-09-24 00:47:24.831788 | controller-1 |     "atime": 1790210826.5302365,
2026-09-24 00:47:24.831811 | controller-1 |     "attr_flags": "e",
2026-09-24 00:47:24.831835 | controller-1 |     "attributes": [
2026-09-24 00:47:24.831859 | controller-1 |       "extents"
2026-09-24 00:47:24.831881 | controller-1 |     ],
2026-09-24 00:47:24.831903 | controller-1 |     "block_size": 4096,
2026-09-24 00:47:24.831924 | controller-1 |     "blocks": 8,
2026-09-24 00:47:24.831946 | controller-1 |     "charset": "unknown",
2026-09-24 00:47:24.831970 | controller-1 |     "ctime": 1790210826.5702384,
2026-09-24 00:47:24.831993 | controller-1 |     "dev": 65025,
2026-09-24 00:47:24.832143 | controller-1 |     "device_type": 0,
2026-09-24 00:47:24.832174 | controller-1 |     "executable": true,
2026-09-24 00:47:24.832198 | controller-1 |     "exists": true,
2026-09-24 00:47:24.832235 | controller-1 |     "gid": 1000,
2026-09-24 00:47:24.832260 | controller-1 |     "gr_name": "zuul",
2026-09-24 00:47:24.832282 | controller-1 |     "inode": 519447,
2026-09-24 00:47:24.832304 | controller-1 |     "isblk": false,
2026-09-24 00:47:24.832325 | controller-1 |     "ischr": false,
2026-09-24 00:47:24.832357 | controller-1 |     "isdir": true,
2026-09-24 00:47:24.832387 | controller-1 |     "isfifo": false,
2026-09-24 00:47:24.832415 | controller-1 |     "isgid": false,
2026-09-24 00:47:24.832445 | controller-1 |     "islnk": false,
2026-09-24 00:47:24.832474 | controller-1 |     "isreg": false,
2026-09-24 00:47:24.832530 | controller-1 |     "issock": false,
2026-09-24 00:47:24.832561 | controller-1 |     "isuid": false,
2026-09-24 00:47:24.832590 | controller-1 |     "mimetype": "unknown",
2026-09-24 00:47:24.832619 | controller-1 |     "mode": "0755",
2026-09-24 00:47:24.832648 | controller-1 |     "mtime": 1790210807.192696,
2026-09-24 00:47:24.832676 | controller-1 |     "nlink": 14,
2026-09-24 00:47:24.832704 | controller-1 |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-24 00:47:24.832732 | controller-1 |     "pw_name": "zuul",
2026-09-24 00:47:24.832763 | controller-1 |     "readable": true,
2026-09-24 00:47:24.832792 | controller-1 |     "rgrp": true,
2026-09-24 00:47:24.832822 | controller-1 |     "roth": true,
2026-09-24 00:47:24.832853 | controller-1 |     "rusr": true,
2026-09-24 00:47:24.832883 | controller-1 |     "size": 4096,
2026-09-24 00:47:24.832914 | controller-1 |     "uid": 1000,
2026-09-24 00:47:24.832944 | controller-1 |     "version": "3960887388",
2026-09-24 00:47:24.832972 | controller-1 |     "wgrp": false,
2026-09-24 00:47:24.833001 | controller-1 |     "woth": false,
2026-09-24 00:47:24.833029 | controller-1 |     "writeable": true,
2026-09-24 00:47:24.833071 | controller-1 |     "wusr": true,
2026-09-24 00:47:24.833101 | controller-1 |     "xgrp": true,
2026-09-24 00:47:24.833143 | controller-1 |     "xoth": true,
2026-09-24 00:47:24.833173 | controller-1 |     "xusr": true
2026-09-24 00:47:24.833202 | controller-1 |   }
2026-09-24 00:47:24.833229 | controller-1 | }
2026-09-24 00:47:24.833283 | controller-1 | ok: All items complete
2026-09-24 00:47:24.833323 | 
2026-09-24 00:47:24.835025 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:24.839831 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:24.852333 | 
2026-09-24 00:47:24.852519 | LOOP [Switch "ansible_host" to private IP]
2026-09-24 00:47:25.211625 | controller-1 | changed: 3 replacements made
2026-09-24 00:47:25.213326 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:25.220622 | controller-1 | skipping: Conditional result was False
2026-09-24 00:47:25.242217 | 
2026-09-24 00:47:25.242429 | TASK [Run Molecule scenario]
2026-09-24 00:47:25.764095 | controller-1 | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-24 00:47:25.764190 | controller-1 | Creating virtual environment at: .venv
2026-09-24 00:47:27.121273 | controller-1 | Downloading pygments (1.2MiB)
2026-09-24 00:47:27.123460 | controller-1 | Downloading ansible-core (2.3MiB)
2026-09-24 00:47:27.130202 | controller-1 | Downloading cryptography (4.3MiB)
2026-09-24 00:47:27.304904 | controller-1 |  Downloading cryptography
2026-09-24 00:47:27.359380 | controller-1 |  Downloading pygments
2026-09-24 00:47:27.540395 | controller-1 |  Downloading ansible-core
2026-09-24 00:47:27.598737 | controller-1 | Installed 36 packages in 57ms
2026-09-24 00:47:28.273354 | controller-1 | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-24 00:47:28.273399 | controller-1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-24 00:47:29.077777 | controller-1 | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-24 00:47:29.077842 | controller-1 | INFO     default scenario not found, disabling shared state.
2026-09-24 00:47:29.077896 | controller-1 | INFO     [cluster-upgrade > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-24 00:47:29.077964 | controller-1 | INFO     [cluster-upgrade > prerun] Performing prerun with role_name_check=0...
2026-09-24 00:48:30.660131 | controller-1 | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-24 00:48:30.660226 | controller-1 | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-24 00:48:30.660237 | controller-1 | Starting galaxy collection install process
2026-09-24 00:48:30.660246 | controller-1 | Process install dependency map
2026-09-24 00:48:30.660255 | controller-1 |
2026-09-24 00:48:30.660263 | controller-1 | ansible-galaxy
2026-09-24 00:48:30.660271 | controller-1 |   config file = None
2026-09-24 00:48:30.660278 | controller-1 |   configured module search path =
2026-09-24 00:48:30.660286 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-24 00:48:30.660305 | 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-24 00:48:30.660315 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-24 00:48:30.660325 | controller-1 |   python version = 3.13.5 (main, Aug 10 2026, 12:06:59)  (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-24 00:48:30.660333 | controller-1 |   jinja version = 3.1.6
2026-09-24 00:48:30.660340 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-24 00:48:30.660347 | controller-1 | No config file found; using defaults
2026-09-24 00:48:30.660354 | controller-1 | Created /home/zuul/.ansible/galaxy_token
2026-09-24 00:48:30.660362 | controller-1 | : Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error  Connection reset by peer>
2026-09-24 00:48:30.660370 | controller-1 | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error  Connection reset by peer>
2026-09-24 00:48:30.660377 | controller-1 |
2026-09-24 00:48:30.660385 | controller-1 | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'.
2026-09-24 00:48:30.660392 | controller-1 |
2026-09-24 00:48:30.660399 | controller-1 | <<< caused by >>>
2026-09-24 00:48:30.660406 | controller-1 |
2026-09-24 00:48:30.660413 | controller-1 | <urlopen error  Connection reset by peer>
2026-09-24 00:48:30.660420 | controller-1 |
2026-09-24 00:48:30.660427 | controller-1 |
2026-09-24 00:48:30.662345 | controller-1 | Traceback (most recent call last):
2026-09-24 00:48:30.663969 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-24 00:48:30.664005 | controller-1 |     sys.exit(main())
2026-09-24 00:48:30.664013 | controller-1 |              ~~~~^^
2026-09-24 00:48:30.664116 | 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-24 00:48:30.664127 | controller-1 |     return self.main(*args, **kwargs)
2026-09-24 00:48:30.664134 | controller-1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.664241 | 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-24 00:48:30.664249 | controller-1 |     rv = self.invoke(ctx)
2026-09-24 00:48:30.664360 | 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-24 00:48:30.664369 | controller-1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-24 00:48:30.664376 | controller-1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-24 00:48:30.664489 | 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-24 00:48:30.664497 | controller-1 |     return ctx.invoke(self.callback, **ctx.params)
2026-09-24 00:48:30.664504 | controller-1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.664581 | 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-24 00:48:30.664590 | controller-1 |     return callback(*args, **kwargs)
2026-09-24 00:48:30.664688 | 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-24 00:48:30.664695 | controller-1 |     return func(*args, **kwargs)
2026-09-24 00:48:30.664795 | 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-24 00:48:30.664803 | controller-1 |     return f(get_current_context(), *args, **kwargs)
2026-09-24 00:48:30.664902 | 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-24 00:48:30.664910 | controller-1 |     return func(ctx)
2026-09-24 00:48:30.665066 | 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-24 00:48:30.665075 | controller-1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-24 00:48:30.665083 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.665175 | 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-24 00:48:30.665183 | controller-1 |     _run_scenarios(scenarios, command_args, default_config)
2026-09-24 00:48:30.665190 | controller-1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.665377 | 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-24 00:48:30.665384 | controller-1 |     scenario.config.runtime.prepare_environment(
2026-09-24 00:48:30.665391 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-24 00:48:30.665398 | controller-1 |         install_local=True,
2026-09-24 00:48:30.665405 | controller-1 |         ^^^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.665412 | controller-1 |         role_name_check=role_name_check,
2026-09-24 00:48:30.665419 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.665426 | controller-1 |     )
2026-09-24 00:48:30.665433 | controller-1 |     ^
2026-09-24 00:48:30.666093 | 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-24 00:48:30.666103 | controller-1 |     self.install_collection(
2026-09-24 00:48:30.666110 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-24 00:48:30.666901 | controller-1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-24 00:48:30.666910 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.666931 | controller-1 |         destination=destination,
2026-09-24 00:48:30.666938 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-24 00:48:30.666945 | controller-1 |     )
2026-09-24 00:48:30.666952 | controller-1 |     ^
2026-09-24 00:48:30.666964 | 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-24 00:48:30.666971 | controller-1 |     raise InvalidPrerequisiteError(msg)
2026-09-24 00:48:30.666979 | controller-1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-24 00:48:30.666986 | controller-1 | Starting galaxy collection install process
2026-09-24 00:48:30.666992 | controller-1 | Process install dependency map
2026-09-24 00:48:30.667000 | controller-1 |
2026-09-24 00:48:30.667007 | controller-1 | ansible-galaxy [core 2.20.1]
2026-09-24 00:48:30.667013 | controller-1 |   config file = None
2026-09-24 00:48:30.667021 | 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-24 00:48:30.667039 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-24 00:48:30.667054 | 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-24 00:48:30.667061 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-24 00:48:30.667068 | controller-1 |   python version = 3.13.5 (main, Aug 10 2026, 12:06:59) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-24 00:48:30.667075 | controller-1 |   jinja version = 3.1.6
2026-09-24 00:48:30.667082 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-24 00:48:30.667089 | controller-1 | No config file found; using defaults
2026-09-24 00:48:30.667096 | controller-1 | Created /home/zuul/.ansible/galaxy_token
2026-09-24 00:48:30.667103 | controller-1 | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error [Errno 104] Connection reset by peer>
2026-09-24 00:48:30.667114 | controller-1 | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error [Errno 104] Connection reset by peer>
2026-09-24 00:48:30.667121 | controller-1 |
2026-09-24 00:48:30.667128 | controller-1 | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'.
2026-09-24 00:48:30.667135 | controller-1 |
2026-09-24 00:48:30.667142 | controller-1 | <<< caused by >>>
2026-09-24 00:48:30.667149 | controller-1 |
2026-09-24 00:48:30.667156 | controller-1 | <urlopen error [Errno 104] Connection reset by peer>
2026-09-24 00:48:30.667162 | controller-1 |
2026-09-24 00:48:30.667169 | controller-1 |
2026-09-24 00:48:30.884224 | controller-1 | ERROR
2026-09-24 00:48:30.884540 | controller-1 | {
2026-09-24 00:48:30.884603 | controller-1 |   "delta": "0:01:05.096228",
2026-09-24 00:48:30.884645 | controller-1 |   "end": "2026-09-24 00:48:30.759368",
2026-09-24 00:48:30.884680 | controller-1 |   "msg": "non-zero return code",
2026-09-24 00:48:30.884715 | controller-1 |   "rc": 1,
2026-09-24 00:48:30.884749 | controller-1 |   "start": "2026-09-24 00:47:25.663140"
2026-09-24 00:48:30.884783 | controller-1 | }
failure
2026-09-24 00:48:30.889457 | 
2026-09-24 00:48:30.889551 | PLAY RECAP
2026-09-24 00:48:30.889630 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:48:30.889672 | 
2026-09-24 00:48:31.039542 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-24 00:48:31.046380 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-24 00:48:31.620311 | 
2026-09-24 00:48:31.620869 | PLAY [all]
2026-09-24 00:48:31.631640 | 
2026-09-24 00:48:31.631777 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-24 00:48:32.031129 | controller-1 | changed
2026-09-24 00:48:32.210598 | controller-3 | changed
2026-09-24 00:48:32.227102 | controller-2 | changed
2026-09-24 00:48:32.235917 | 
2026-09-24 00:48:32.236124 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-24 00:48:32.700585 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:32.700699 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:32.709067 | controller-1 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.711440 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.713406 | controller-1 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.716638 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.716662 | controller-1 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.718843 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.721735 | controller-1 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.724133 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.724180 | controller-1 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.726607 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.699582 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:32.699832 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:32.707679 | controller-2 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.709869 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.710716 | controller-2 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.713264 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.715473 | controller-2 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.717843 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.719508 | controller-2 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.721214 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.723020 | controller-2 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.725013 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.706377 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:32.707211 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:32.715959 | controller-3 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.718185 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.718370 | controller-3 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.720344 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.723675 | controller-3 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.725750 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.727877 | controller-3 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.729857 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.731848 | controller-3 | environment: line 1: kubectl: command not found
2026-09-24 00:48:32.733574 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-24 00:48:32.812598 | controller-1 | ok: Runtime: 0:00:00.041891
2026-09-24 00:48:32.824555 | controller-2 | ok: Runtime: 0:00:00.041569
2026-09-24 00:48:32.835535 | controller-3 | ok: Runtime: 0:00:00.041789
2026-09-24 00:48:32.842744 | 
2026-09-24 00:48:32.842868 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-24 00:48:33.104272 | controller-1 | changed
2026-09-24 00:48:33.122415 | controller-3 | changed
2026-09-24 00:48:33.130352 | controller-2 | changed
2026-09-24 00:48:33.137405 | 
2026-09-24 00:48:33.137539 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-24 00:48:33.435758 | controller-1 | environment: line 5: kubectl: command not found
2026-09-24 00:48:33.436747 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.437288 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.437756 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.534414 | controller-2 | environment: line 5: kubectl: command not found
2026-09-24 00:48:33.535494 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.536000 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.536340 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.522322 | controller-3 | environment: line 5: kubectl: command not found
2026-09-24 00:48:33.522418 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.522431 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.522896 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-24 00:48:33.748326 | controller-1 | ok: Runtime: 0:00:00.014534
2026-09-24 00:48:33.756453 | controller-3 | ok: Runtime: 0:00:00.015145
2026-09-24 00:48:33.763680 | controller-2 | ok: Runtime: 0:00:00.014031
2026-09-24 00:48:33.769288 | 
2026-09-24 00:48:33.769373 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-24 00:48:34.406852 | controller-2 | changed:
2026-09-24 00:48:34.407211 | controller-2 | created directory /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/logs/controller-2
2026-09-24 00:48:34.407271 | controller-2 | cd+++++++++ objects/
2026-09-24 00:48:34.407314 | controller-2 | cd+++++++++ objects/cluster/
2026-09-24 00:48:34.407353 | controller-2 | cd+++++++++ objects/namespaced/
2026-09-24 00:48:34.417741 | controller-3 | changed:
2026-09-24 00:48:34.417859 | controller-3 | created directory /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/logs/controller-3
2026-09-24 00:48:34.417937 | controller-3 | cd+++++++++ objects/
2026-09-24 00:48:34.418002 | controller-3 | cd+++++++++ objects/cluster/
2026-09-24 00:48:34.418092 | controller-3 | cd+++++++++ objects/namespaced/
2026-09-24 00:48:34.440450 | controller-1 | changed:
2026-09-24 00:48:34.440648 | controller-1 | created directory /var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/logs/controller-1
2026-09-24 00:48:34.440733 | controller-1 | cd+++++++++ objects/
2026-09-24 00:48:34.440809 | controller-1 | cd+++++++++ objects/cluster/
2026-09-24 00:48:34.440878 | controller-1 | cd+++++++++ objects/namespaced/
2026-09-24 00:48:34.463011 | 
2026-09-24 00:48:34.463105 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-24 00:48:34.723962 | controller-1 | changed
2026-09-24 00:48:34.731458 | controller-2 | changed
2026-09-24 00:48:34.739628 | controller-3 | changed
2026-09-24 00:48:34.750726 | 
2026-09-24 00:48:34.750966 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-24 00:48:35.013711 | controller-1 | changed
2026-09-24 00:48:35.020371 | controller-2 | changed
2026-09-24 00:48:35.057543 | controller-3 | changed
2026-09-24 00:48:35.064291 | 
2026-09-24 00:48:35.064417 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-24 00:48:35.372929 | controller-1 | environment: line 3: kubectl: command not found
2026-09-24 00:48:35.392106 | controller-2 | environment: line 3: kubectl: command not found
2026-09-24 00:48:35.392637 | controller-3 | environment: line 3: kubectl: command not found
2026-09-24 00:48:35.624255 | controller-1 | ok: Runtime: 0:00:00.014317
2026-09-24 00:48:35.636092 | controller-3 | ok: Runtime: 0:00:00.013443
2026-09-24 00:48:35.649196 | controller-2 | ok: Runtime: 0:00:00.013574
2026-09-24 00:48:35.657698 | 
2026-09-24 00:48:35.657873 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-24 00:48:36.211218 | controller-2 | changed:
2026-09-24 00:48:36.211595 | controller-2 | cd+++++++++ pod-logs/
2026-09-24 00:48:36.211660 | controller-2 | cd+++++++++ pod-logs/failed-pods/
2026-09-24 00:48:36.219473 | controller-1 | changed:
2026-09-24 00:48:36.219581 | controller-1 | cd+++++++++ pod-logs/
2026-09-24 00:48:36.219631 | controller-1 | cd+++++++++ pod-logs/failed-pods/
2026-09-24 00:48:36.226335 | controller-3 | changed:
2026-09-24 00:48:36.226443 | controller-3 | cd+++++++++ pod-logs/
2026-09-24 00:48:36.226491 | controller-3 | cd+++++++++ pod-logs/failed-pods/
2026-09-24 00:48:36.250567 | 
2026-09-24 00:48:36.250723 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-24 00:48:36.521312 | controller-1 | changed: "values"
2026-09-24 00:48:36.537601 | controller-3 | changed: "values"
2026-09-24 00:48:36.546344 | controller-2 | changed: "values"
2026-09-24 00:48:36.742524 | controller-1 | changed: "releases"
2026-09-24 00:48:36.770185 | controller-3 | changed: "releases"
2026-09-24 00:48:36.770295 | controller-3 | changed: All items complete
2026-09-24 00:48:36.770363 | 
2026-09-24 00:48:36.780588 | controller-2 | changed: "releases"
2026-09-24 00:48:36.799010 | 
2026-09-24 00:48:36.799303 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-24 00:48:37.127578 | controller-1 | /bin/bash: line 3: kubectl: command not found
2026-09-24 00:48:37.126309 | controller-2 | /bin/bash: line 3: kubectl: command not found
2026-09-24 00:48:37.153020 | controller-3 | /bin/bash: line 3: kubectl: command not found
2026-09-24 00:48:37.367943 | controller-2 | ok: Runtime: 0:00:00.010367
2026-09-24 00:48:37.373684 | controller-1 | ok: Runtime: 0:00:00.009262
2026-09-24 00:48:37.378666 | controller-3 | ok: Runtime: 0:00:00.009640
2026-09-24 00:48:37.382023 | 
2026-09-24 00:48:37.382092 | TASK [helm-release-status : Downloads logs to executor]
2026-09-24 00:48:37.933911 | controller-1 | changed:
2026-09-24 00:48:37.934296 | controller-1 | cd+++++++++ helm/
2026-09-24 00:48:37.934361 | controller-1 | cd+++++++++ helm/releases/
2026-09-24 00:48:37.934405 | controller-1 | cd+++++++++ helm/values/
2026-09-24 00:48:37.983579 | controller-2 | changed:
2026-09-24 00:48:37.983741 | controller-2 | cd+++++++++ helm/
2026-09-24 00:48:37.983791 | controller-2 | cd+++++++++ helm/releases/
2026-09-24 00:48:37.983830 | controller-2 | cd+++++++++ helm/values/
2026-09-24 00:48:38.000982 | controller-3 | changed:
2026-09-24 00:48:38.001137 | controller-3 | cd+++++++++ helm/
2026-09-24 00:48:38.001185 | controller-3 | cd+++++++++ helm/releases/
2026-09-24 00:48:38.001224 | controller-3 | cd+++++++++ helm/values/
2026-09-24 00:48:38.024954 | 
2026-09-24 00:48:38.025088 | PLAY RECAP
2026-09-24 00:48:38.025175 | controller-1 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:48:38.025229 | controller-2 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:48:38.025275 | controller-3 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-24 00:48:38.025309 | 
2026-09-24 00:48:38.155927 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-24 00:48:38.161881 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-24 00:48:38.761803 | 
2026-09-24 00:48:38.761944 | PLAY [all]
2026-09-24 00:48:38.771910 | 
2026-09-24 00:48:38.771986 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-24 00:48:38.825031 | controller-1 | ok
2026-09-24 00:48:38.859749 | controller-2 | ok
2026-09-24 00:48:38.864752 | controller-3 | ok
2026-09-24 00:48:38.869119 | 
2026-09-24 00:48:38.869239 | TASK [fetch-output : Set log path for single node]
2026-09-24 00:48:38.902385 | controller-1 | skipping: Conditional result was False
2026-09-24 00:48:38.913481 | controller-2 | skipping: Conditional result was False
2026-09-24 00:48:38.923929 | controller-3 | skipping: Conditional result was False
2026-09-24 00:48:38.929270 | 
2026-09-24 00:48:38.929358 | LOOP [fetch-output : Ensure local output dirs]
2026-09-24 00:48:39.306161 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/logs/controller-1"
2026-09-24 00:48:39.333067 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/logs/controller-2"
2026-09-24 00:48:39.334084 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/logs/controller-3"
2026-09-24 00:48:39.531158 | controller-1 -> localhost | changed: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/artifacts"
2026-09-24 00:48:39.545206 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/artifacts"
2026-09-24 00:48:39.566666 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/artifacts"
2026-09-24 00:48:39.763456 | controller-1 -> localhost | changed: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/docs"
2026-09-24 00:48:39.784915 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/docs"
2026-09-24 00:48:39.785673 | controller-2 -> localhost | changed: All items complete
2026-09-24 00:48:39.785728 | 
2026-09-24 00:48:39.805982 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/64bc7263de9a409998fb2cfe8348a5d1/work/docs"
2026-09-24 00:48:39.825113 | 
2026-09-24 00:48:39.825349 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-24 00:48:40.516666 | controller-1 | changed: .d..t...... ./
2026-09-24 00:48:40.517126 | controller-1 | ok: All items complete
2026-09-24 00:48:40.517200 | 
2026-09-24 00:48:40.524826 | controller-2 | changed: .d..t...... ./
2026-09-24 00:48:40.540029 | controller-3 | changed: .d..t...... ./
2026-09-24 00:48:41.015218 | controller-3 | ok
2026-09-24 00:48:41.022443 | controller-1 | ok
2026-09-24 00:48:41.029615 | controller-2 | changed: .d..t...... ./
2026-09-24 00:48:41.521139 | controller-3 | changed: .d..t...... ./
2026-09-24 00:48:41.525406 | controller-2 | ok
2026-09-24 00:48:41.531390 | controller-1 | changed: .d..t...... ./
2026-09-24 00:48:41.567529 | 
2026-09-24 00:48:41.567663 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-24 00:48:41.995887 | controller-1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008794
2026-09-24 00:48:41.996217 | controller-1 -> localhost | changed: All items complete
2026-09-24 00:48:41.996271 | 
2026-09-24 00:48:42.235417 | controller-1 -> localhost | ok: Item: docs Runtime: 0:00:00.007203
2026-09-24 00:48:42.249613 | 
2026-09-24 00:48:42.249718 | PLAY [all]
2026-09-24 00:48:42.254443 | 
2026-09-24 00:48:42.254508 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-24 00:48:42.704766 | controller-1 | changed
2026-09-24 00:48:42.884341 | controller-2 | changed
2026-09-24 00:48:42.901195 | controller-3 | changed
2026-09-24 00:48:42.923604 | 
2026-09-24 00:48:42.923739 | PLAY RECAP
2026-09-24 00:48:42.923823 | controller-1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-24 00:48:42.923878 | controller-2 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-24 00:48:42.923923 | controller-3 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-24 00:48:42.923957 | 
2026-09-24 00:48:43.058825 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-24 00:48:43.065109 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-24 00:48:43.646632 | 
2026-09-24 00:48:43.646768 | PLAY [localhost]
2026-09-24 00:48:43.657499 | 
2026-09-24 00:48:43.657635 | TASK [Generate Zuul manifest]
2026-09-24 00:48:43.676091 | localhost | ok
2026-09-24 00:48:43.690487 | 
2026-09-24 00:48:43.690594 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-24 00:48:44.123330 | localhost | changed
2026-09-24 00:48:44.133086 | 
2026-09-24 00:48:44.133191 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-24 00:48:44.173866 | localhost | ok
2026-09-24 00:48:44.186555 | 
2026-09-24 00:48:44.186732 | TASK [Upload logs]
2026-09-24 00:48:44.205565 | localhost | ok
2026-09-24 00:48:44.263602 | 
2026-09-24 00:48:44.263746 | TASK [Set zuul-log-path fact]
2026-09-24 00:48:44.280790 | localhost | ok
2026-09-24 00:48:44.290845 | 
2026-09-24 00:48:44.290911 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-24 00:48:44.328855 | localhost | ok
2026-09-24 00:48:44.337448 | 
2026-09-24 00:48:44.337533 | TASK [upload-logs : Create log directories]
2026-09-24 00:48:44.743347 | localhost | changed
2026-09-24 00:48:44.794085 | 
2026-09-24 00:48:44.794294 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-24 00:48:45.165294 | localhost -> localhost | ok: Runtime: 0:00:00.005577
2026-09-24 00:48:45.176257 | 
2026-09-24 00:48:45.176425 | TASK [upload-logs : Upload logs to log server]
2026-09-24 00:48:45.591991 | localhost | Output suppressed because no_log was given
2026-09-24 00:48:45.599300 | 
2026-09-24 00:48:45.599425 | LOOP [upload-logs : Compress console log and json output]
2026-09-24 00:48:45.655555 | localhost | skipping: Conditional result was False
2026-09-24 00:48:45.662346 | localhost | skipping: Conditional result was False
2026-09-24 00:48:45.675208 | 
2026-09-24 00:48:45.675382 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-24 00:48:45.717935 | localhost | skipping: Conditional result was False
2026-09-24 00:48:45.718273 | 
2026-09-24 00:48:45.719165 | localhost | skipping: Conditional result was False
2026-09-24 00:48:45.728293 | 
2026-09-24 00:48:45.728468 | LOOP [upload-logs : Upload console log and json output]