2026-09-07 01:52:19.969606 | Job console starting
2026-09-07 01:52:20.010870 | Updating git repos
2026-09-07 01:52:20.114543 | Cloning repos into workspace
2026-09-07 01:52:20.340525 | Restoring repo states
2026-09-07 01:52:20.381269 | Merging changes
2026-09-07 01:52:21.353386 | Checking out repos
2026-09-07 01:52:21.604117 | Preparing playbooks
2026-09-07 01:52:24.953627 | Running Ansible setup
2026-09-07 01:52:32.328991 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:52:32.953015 | 
2026-09-07 01:52:32.953568 | PLAY [localhost]
2026-09-07 01:52:32.971441 | 
2026-09-07 01:52:32.971730 | TASK [Gathering Facts]
2026-09-07 01:52:33.917343 | localhost | ok
2026-09-07 01:52:33.930140 | 
2026-09-07 01:52:33.930289 | TASK [Setup log path fact]
2026-09-07 01:52:33.953121 | localhost | ok
2026-09-07 01:52:33.969006 | 
2026-09-07 01:52:33.969091 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:52:34.004686 | localhost | ok
2026-09-07 01:52:34.018844 | 
2026-09-07 01:52:34.018982 | TASK [emit-job-header : Print job information]
2026-09-07 01:52:34.065856 | # Job Information
2026-09-07 01:52:34.066077 | Ansible Version: 2.16.18
2026-09-07 01:52:34.066143 | Job: ansible-collection-kubernetes-molecule-cluster-upgrade
2026-09-07 01:52:34.066189 | Pipeline: check
2026-09-07 01:52:34.066229 | Executor: 2d72f0692154
2026-09-07 01:52:34.066268 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300
2026-09-07 01:52:34.066312 | Event ID: 1a3e6320-aa5e-11f1-83e1-f73eae392cf5
2026-09-07 01:52:34.071525 | 
2026-09-07 01:52:34.071723 | LOOP [emit-job-header : Print node information]
2026-09-07 01:52:34.168976 | localhost | ok:
2026-09-07 01:52:34.169492 | localhost | # Node Information
2026-09-07 01:52:34.169521 | localhost | Inventory Hostname: controller-1
2026-09-07 01:52:34.169547 | localhost | Hostname: np0000191997
2026-09-07 01:52:34.169575 | localhost | Username: zuul
2026-09-07 01:52:34.169620 | localhost | Distro: Debian 13.6
2026-09-07 01:52:34.169649 | localhost | Provider: yul1
2026-09-07 01:52:34.169672 | localhost | Region: ca-ymq-1
2026-09-07 01:52:34.169695 | localhost | Label: debian-trixie
2026-09-07 01:52:34.169718 | localhost | Product Name: OpenStack Nova
2026-09-07 01:52:34.169741 | localhost | Interface IP: 162.253.55.15
2026-09-07 01:52:34.198240 | localhost | ok:
2026-09-07 01:52:34.198310 | localhost | # Node Information
2026-09-07 01:52:34.198344 | localhost | Inventory Hostname: controller-2
2026-09-07 01:52:34.198372 | localhost | Hostname: np0000191998
2026-09-07 01:52:34.198398 | localhost | Username: zuul
2026-09-07 01:52:34.198422 | localhost | Distro: Debian 13.6
2026-09-07 01:52:34.198446 | localhost | Provider: yul1
2026-09-07 01:52:34.198471 | localhost | Region: ca-ymq-1
2026-09-07 01:52:34.198524 | localhost | Label: debian-trixie
2026-09-07 01:52:34.198580 | localhost | Product Name: OpenStack Nova
2026-09-07 01:52:34.198641 | localhost | Interface IP: 162.253.55.152
2026-09-07 01:52:34.243133 | localhost | ok:
2026-09-07 01:52:34.243258 | localhost | # Node Information
2026-09-07 01:52:34.243305 | localhost | Inventory Hostname: controller-3
2026-09-07 01:52:34.243345 | localhost | Hostname: np0000191999
2026-09-07 01:52:34.243382 | localhost | Username: zuul
2026-09-07 01:52:34.243418 | localhost | Distro: Debian 13.6
2026-09-07 01:52:34.243453 | localhost | Provider: yul1
2026-09-07 01:52:34.243488 | localhost | Region: ca-ymq-1
2026-09-07 01:52:34.243522 | localhost | Label: debian-trixie
2026-09-07 01:52:34.243556 | localhost | Product Name: OpenStack Nova
2026-09-07 01:52:34.243606 | localhost | Interface IP: 162.253.55.85
2026-09-07 01:52:34.257171 | 
2026-09-07 01:52:34.257386 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 01:52:34.739904 | localhost -> localhost | changed
2026-09-07 01:52:34.758212 | 
2026-09-07 01:52:34.758327 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 01:52:35.924577 | localhost -> localhost | changed
2026-09-07 01:52:35.934489 | 
2026-09-07 01:52:35.934577 | PLAY [all]
2026-09-07 01:52:35.943677 | 
2026-09-07 01:52:35.943760 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 01:52:36.202457 | controller-1 -> localhost | ok
2026-09-07 01:52:36.211348 | 
2026-09-07 01:52:36.211473 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 01:52:36.253895 | controller-1 | ok
2026-09-07 01:52:36.263564 | controller-2 | ok
2026-09-07 01:52:36.269002 | controller-3 | ok
2026-09-07 01:52:36.291629 | controller-1 | included: /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:52:36.291696 | controller-2 | included: /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:52:36.291727 | controller-3 | included: /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:52:36.297824 | 
2026-09-07 01:52:36.297881 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 01:52:37.554945 | controller-1 -> localhost | Generating public/private rsa key pair.
2026-09-07 01:52:37.555219 | controller-1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/81f0cc01c3944f91bfddfe9b8e83d461_id_rsa
2026-09-07 01:52:37.555280 | controller-1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/81f0cc01c3944f91bfddfe9b8e83d461_id_rsa.pub
2026-09-07 01:52:37.555325 | controller-1 -> localhost | The key fingerprint is:
2026-09-07 01:52:37.555367 | controller-1 -> localhost | SHA256:qeO2aLEJyVA/5U4L3eWYV3HXWoDgfaE47gM2nR5UNmY zuul-build-sshkey
2026-09-07 01:52:37.555410 | controller-1 -> localhost | The key's randomart image is:
2026-09-07 01:52:37.555449 | controller-1 -> localhost | +---[RSA 3072]----+
2026-09-07 01:52:37.555488 | controller-1 -> localhost | |          ..Eo+.o|
2026-09-07 01:52:37.555528 | controller-1 -> localhost | |  .   .  ..Bo+ oo|
2026-09-07 01:52:37.555566 | controller-1 -> localhost | | . . + . ==.o .o |
2026-09-07 01:52:37.555626 | controller-1 -> localhost | |.   + + +=oo ..  |
2026-09-07 01:52:37.555666 | controller-1 -> localhost | | o . = .S.=      |
2026-09-07 01:52:37.555703 | controller-1 -> localhost | |  + . oo = .     |
2026-09-07 01:52:37.555739 | controller-1 -> localhost | |   . +o   +      |
2026-09-07 01:52:37.555776 | controller-1 -> localhost | |    +o..   .     |
2026-09-07 01:52:37.555813 | controller-1 -> localhost | |   ...o.         |
2026-09-07 01:52:37.555851 | controller-1 -> localhost | +----[SHA256]-----+
2026-09-07 01:52:37.555935 | controller-1 -> localhost | ok: Runtime: 0:00:00.641473
2026-09-07 01:52:37.564000 | 
2026-09-07 01:52:37.564128 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 01:52:37.604716 | controller-1 | ok
2026-09-07 01:52:37.625692 | controller-2 | ok
2026-09-07 01:52:37.641101 | controller-3 | ok
2026-09-07 01:52:37.659612 | controller-1 | included: /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:52:37.659682 | controller-2 | included: /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:52:37.659715 | controller-3 | included: /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:52:37.673530 | 
2026-09-07 01:52:37.673598 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 01:52:37.697535 | controller-1 | skipping: Conditional result was False
2026-09-07 01:52:37.708675 | controller-2 | skipping: Conditional result was False
2026-09-07 01:52:37.719069 | controller-3 | skipping: Conditional result was False
2026-09-07 01:52:37.726424 | 
2026-09-07 01:52:37.726966 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 01:52:38.224228 | controller-2 | changed
2026-09-07 01:52:38.262550 | controller-1 | changed
2026-09-07 01:52:38.265929 | controller-3 | changed
2026-09-07 01:52:38.269983 | 
2026-09-07 01:52:38.270047 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 01:52:38.482738 | controller-1 | ok
2026-09-07 01:52:38.495251 | controller-2 | ok
2026-09-07 01:52:38.522228 | controller-3 | ok
2026-09-07 01:52:38.530611 | 
2026-09-07 01:52:38.530852 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 01:52:39.124809 | controller-1 | changed
2026-09-07 01:52:39.156780 | controller-2 | changed
2026-09-07 01:52:39.163355 | controller-3 | changed
2026-09-07 01:52:39.171543 | 
2026-09-07 01:52:39.171705 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 01:52:39.755531 | controller-1 | changed
2026-09-07 01:52:39.803570 | controller-2 | changed
2026-09-07 01:52:39.840615 | controller-3 | changed
2026-09-07 01:52:39.849224 | 
2026-09-07 01:52:39.849358 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 01:52:39.870373 | controller-1 | skipping: Conditional result was False
2026-09-07 01:52:39.896096 | controller-2 | skipping: Conditional result was False
2026-09-07 01:52:39.907418 | controller-3 | skipping: Conditional result was False
2026-09-07 01:52:39.916020 | 
2026-09-07 01:52:39.916347 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 01:52:40.266560 | controller-1 -> localhost | changed
2026-09-07 01:52:40.312071 | 
2026-09-07 01:52:40.312180 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 01:52:40.617046 | controller-1 -> localhost | Identity added: /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/81f0cc01c3944f91bfddfe9b8e83d461_id_rsa (zuul-build-sshkey)
2026-09-07 01:52:40.617407 | controller-1 -> localhost | ok: Runtime: 0:00:00.014463
2026-09-07 01:52:40.625338 | 
2026-09-07 01:52:40.625470 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 01:52:40.954651 | controller-1 | ok
2026-09-07 01:52:40.963370 | controller-3 | ok
2026-09-07 01:52:40.969658 | controller-2 | ok
2026-09-07 01:52:40.977249 | 
2026-09-07 01:52:40.977382 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 01:52:41.015606 | controller-1 | skipping: Conditional result was False
2026-09-07 01:52:41.026773 | controller-2 | skipping: Conditional result was False
2026-09-07 01:52:41.047403 | controller-3 | skipping: Conditional result was False
2026-09-07 01:52:41.076140 | 
2026-09-07 01:52:41.076225 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 01:52:41.405889 | controller-1 | ok
2026-09-07 01:52:41.413726 | controller-3 | ok
2026-09-07 01:52:41.437883 | controller-2 | ok
2026-09-07 01:52:41.446716 | 
2026-09-07 01:52:41.447359 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 01:52:42.446283 | controller-2 | Output suppressed because no_log was given
2026-09-07 01:52:42.450034 | controller-3 | Output suppressed because no_log was given
2026-09-07 01:52:42.480247 | controller-1 | Output suppressed because no_log was given
2026-09-07 01:52:42.497263 | 
2026-09-07 01:52:42.497354 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 01:52:42.737296 | controller-1 | ok: "logs"
2026-09-07 01:52:42.737747 | controller-1 | ok: All items complete
2026-09-07 01:52:42.737806 | 
2026-09-07 01:52:42.748029 | controller-2 | ok: "logs"
2026-09-07 01:52:42.756939 | controller-3 | ok: "logs"
2026-09-07 01:52:42.914005 | controller-1 | ok: "artifacts"
2026-09-07 01:52:42.955216 | controller-2 | ok: "artifacts"
2026-09-07 01:52:42.974003 | controller-3 | ok: "artifacts"
2026-09-07 01:52:43.099194 | controller-1 | ok: "docs"
2026-09-07 01:52:43.139651 | controller-2 | ok: "docs"
2026-09-07 01:52:43.161514 | controller-3 | ok: "docs"
2026-09-07 01:52:43.161637 | controller-3 | ok: All items complete
2026-09-07 01:52:43.161688 | 
2026-09-07 01:52:43.174377 | 
2026-09-07 01:52:43.174628 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 01:52:43.426408 | controller-1 | changed: "logs"
2026-09-07 01:52:43.433239 | controller-3 | changed: "logs"
2026-09-07 01:52:43.439715 | controller-2 | changed: "logs"
2026-09-07 01:52:43.626934 | controller-2 | changed: "artifacts"
2026-09-07 01:52:43.634708 | controller-1 | changed: "artifacts"
2026-09-07 01:52:43.642300 | controller-3 | changed: "artifacts"
2026-09-07 01:52:43.808666 | controller-1 | changed: "docs"
2026-09-07 01:52:43.827643 | controller-2 | changed: "docs"
2026-09-07 01:52:43.827766 | controller-2 | changed: All items complete
2026-09-07 01:52:43.827815 | 
2026-09-07 01:52:43.850624 | controller-3 | changed: "docs"
2026-09-07 01:52:43.885252 | 
2026-09-07 01:52:43.885493 | PLAY RECAP
2026-09-07 01:52:43.885679 | controller-1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:52:43.885791 | controller-2 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:52:43.885880 | controller-3 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:52:43.885970 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:43.886034 | 
2026-09-07 01:52:44.046935 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:52:44.050771 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:52:44.744468 | 
2026-09-07 01:52:44.744642 | PLAY [all]
2026-09-07 01:52:44.755169 | 
2026-09-07 01:52:44.755240 | TASK [setup-uv : Extract archive]
2026-09-07 01:52:47.278365 | controller-3 | changed
2026-09-07 01:52:47.335941 | controller-1 | changed
2026-09-07 01:52:47.343346 | controller-2 | changed
2026-09-07 01:52:47.352443 | 
2026-09-07 01:52:47.352689 | TASK [setup-uv : Print version]
2026-09-07 01:52:47.836189 | controller-3 | uv 0.8.13
2026-09-07 01:52:47.827664 | controller-1 | uv 0.8.13
2026-09-07 01:52:47.835635 | controller-2 | uv 0.8.13
2026-09-07 01:52:47.927710 | controller-1 | ok: Runtime: 0:00:00.015163
2026-09-07 01:52:47.940277 | controller-2 | ok: Runtime: 0:00:00.011390
2026-09-07 01:52:47.951379 | controller-3 | ok: Runtime: 0:00:00.017613
2026-09-07 01:52:47.971455 | 
2026-09-07 01:52:47.971546 | TASK [Install Ansible collection siblings]
2026-09-07 01:52:48.387637 | controller-1 | ok:
2026-09-07 01:52:48.387867 | controller-1 | Skipping ansible.posix: no checked-out sibling
2026-09-07 01:52:48.387919 | controller-1 | Skipping community.crypto: no checked-out sibling
2026-09-07 01:52:48.387958 | controller-1 | Skipping community.general: no checked-out sibling
2026-09-07 01:52:48.387993 | controller-1 | Skipping kubernetes.core: no checked-out sibling
2026-09-07 01:52:48.388031 | controller-1 | Skipping vexxhost.containers: no checked-out sibling
2026-09-07 01:52:48.390283 | 
2026-09-07 01:52:48.390343 | PLAY RECAP
2026-09-07 01:52:48.390407 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:48.390452 | controller-2 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:48.390498 | controller-3 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:48.390534 | 
2026-09-07 01:52:48.549477 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:52:48.555060 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:52:49.178585 | 
2026-09-07 01:52:49.178752 | PLAY [all]
2026-09-07 01:52:49.190294 | 
2026-09-07 01:52:49.190385 | TASK [Update apt cache]
2026-09-07 01:52:54.394268 | controller-2 | ok
2026-09-07 01:52:54.675374 | controller-3 | ok
2026-09-07 01:52:55.202419 | controller-1 | ok
2026-09-07 01:52:55.215537 | 
2026-09-07 01:52:55.215754 | TASK [Disable firewalld]
2026-09-07 01:52:55.244793 | controller-1 | skipping: Conditional result was False
2026-09-07 01:52:55.256116 | controller-2 | skipping: Conditional result was False
2026-09-07 01:52:55.275108 | controller-3 | skipping: Conditional result was False
2026-09-07 01:52:55.286653 | 
2026-09-07 01:52:55.286898 | TASK [Install "jq" for log collection]
2026-09-07 01:52:57.545946 | controller-2 | changed
2026-09-07 01:52:57.962115 | controller-3 | changed
2026-09-07 01:52:57.969316 | controller-1 | changed
2026-09-07 01:52:57.972725 | 
2026-09-07 01:52:57.972817 | PLAY RECAP
2026-09-07 01:52:57.972905 | controller-1 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:52:57.972961 | controller-2 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:52:57.973009 | controller-3 | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:52:57.973050 | 
2026-09-07 01:52:58.106011 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:52:58.115104 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:52:58.714635 | 
2026-09-07 01:52:58.714774 | PLAY [all]
2026-09-07 01:52:58.725077 | 
2026-09-07 01:52:58.725147 | LOOP [Check inventory directories]
2026-09-07 01:52:59.105160 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:52:59.289988 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-upgrade/inventory"
2026-09-07 01:52:59.488770 | controller-1 | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-upgrade/inventory"
2026-09-07 01:52:59.500731 | 
2026-09-07 01:52:59.500998 | LOOP [Copy inventory file for Zuul]
2026-09-07 01:53:00.497688 | controller-1 | changed:
2026-09-07 01:53:00.498046 | controller-1 | {
2026-09-07 01:53:00.498130 | controller-1 |   "ansible_loop_var": "item",
2026-09-07 01:53:00.498188 | controller-1 |   "changed": false,
2026-09-07 01:53:00.498235 | controller-1 |   "failed": false,
2026-09-07 01:53:00.498287 | controller-1 |   "invocation": {
2026-09-07 01:53:00.498334 | controller-1 |     "module_args": {
2026-09-07 01:53:00.498377 | controller-1 |       "checksum_algorithm": "sha1",
2026-09-07 01:53:00.498421 | controller-1 |       "follow": true,
2026-09-07 01:53:00.498609 | controller-1 |       "get_attributes": true,
2026-09-07 01:53:00.498673 | controller-1 |       "get_checksum": true,
2026-09-07 01:53:00.498738 | controller-1 |       "get_mime": true,
2026-09-07 01:53:00.498782 | controller-1 |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:53:00.498827 | controller-1 |     }
2026-09-07 01:53:00.498869 | controller-1 |   },
2026-09-07 01:53:00.498911 | controller-1 |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:53:00.498954 | controller-1 |   "stat": {
2026-09-07 01:53:00.498995 | controller-1 |     "atime": 1788745962.3204648,
2026-09-07 01:53:00.499036 | controller-1 |     "attr_flags": "e",
2026-09-07 01:53:00.499077 | controller-1 |     "attributes": [
2026-09-07 01:53:00.499118 | controller-1 |       "extents"
2026-09-07 01:53:00.499159 | controller-1 |     ],
2026-09-07 01:53:00.499200 | controller-1 |     "block_size": 4096,
2026-09-07 01:53:00.499242 | controller-1 |     "blocks": 8,
2026-09-07 01:53:00.499283 | controller-1 |     "charset": "unknown",
2026-09-07 01:53:00.499328 | controller-1 |     "ctime": 1788745962.3564658,
2026-09-07 01:53:00.499369 | controller-1 |     "dev": 65025,
2026-09-07 01:53:00.499410 | controller-1 |     "device_type": 0,
2026-09-07 01:53:00.499451 | controller-1 |     "executable": true,
2026-09-07 01:53:00.499491 | controller-1 |     "exists": true,
2026-09-07 01:53:00.499671 | controller-1 |     "gid": 1000,
2026-09-07 01:53:00.499723 | controller-1 |     "gr_name": "zuul",
2026-09-07 01:53:00.499760 | controller-1 |     "inode": 521495,
2026-09-07 01:53:00.499795 | controller-1 |     "isblk": false,
2026-09-07 01:53:00.499849 | controller-1 |     "ischr": false,
2026-09-07 01:53:00.499883 | controller-1 |     "isdir": true,
2026-09-07 01:53:00.499938 | controller-1 |     "isfifo": false,
2026-09-07 01:53:00.499971 | controller-1 |     "isgid": false,
2026-09-07 01:53:00.500004 | controller-1 |     "islnk": false,
2026-09-07 01:53:00.500037 | controller-1 |     "isreg": false,
2026-09-07 01:53:00.500070 | controller-1 |     "issock": false,
2026-09-07 01:53:00.500102 | controller-1 |     "isuid": false,
2026-09-07 01:53:00.500344 | controller-1 |     "mimetype": "unknown",
2026-09-07 01:53:00.500394 | controller-1 |     "mode": "0755",
2026-09-07 01:53:00.500459 | controller-1 |     "mtime": 1788745940.5772161,
2026-09-07 01:53:00.500537 | controller-1 |     "nlink": 14,
2026-09-07 01:53:00.500602 | controller-1 |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:53:00.500639 | controller-1 |     "pw_name": "zuul",
2026-09-07 01:53:00.500694 | controller-1 |     "readable": true,
2026-09-07 01:53:00.500730 | controller-1 |     "rgrp": true,
2026-09-07 01:53:00.500777 | controller-1 |     "roth": true,
2026-09-07 01:53:00.500812 | controller-1 |     "rusr": true,
2026-09-07 01:53:00.500846 | controller-1 |     "size": 4096,
2026-09-07 01:53:00.500880 | controller-1 |     "uid": 1000,
2026-09-07 01:53:00.500913 | controller-1 |     "version": "2471777483",
2026-09-07 01:53:00.500964 | controller-1 |     "wgrp": false,
2026-09-07 01:53:00.501002 | controller-1 |     "woth": false,
2026-09-07 01:53:00.501050 | controller-1 |     "writeable": true,
2026-09-07 01:53:00.501085 | controller-1 |     "wusr": true,
2026-09-07 01:53:00.501118 | controller-1 |     "xgrp": true,
2026-09-07 01:53:00.501151 | controller-1 |     "xoth": true,
2026-09-07 01:53:00.501197 | controller-1 |     "xusr": true
2026-09-07 01:53:00.501231 | controller-1 |   }
2026-09-07 01:53:00.501265 | controller-1 | }
2026-09-07 01:53:00.501341 | controller-1 | ok: All items complete
2026-09-07 01:53:00.501385 | 
2026-09-07 01:53:00.504204 | controller-1 | skipping: Conditional result was False
2026-09-07 01:53:00.505473 | controller-1 | skipping: Conditional result was False
2026-09-07 01:53:00.525114 | 
2026-09-07 01:53:00.525348 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 01:53:00.964299 | controller-1 | changed: 3 replacements made
2026-09-07 01:53:00.966767 | controller-1 | skipping: Conditional result was False
2026-09-07 01:53:00.971146 | controller-1 | skipping: Conditional result was False
2026-09-07 01:53:00.985196 | 
2026-09-07 01:53:00.985421 | TASK [Run Molecule scenario]
2026-09-07 01:53:01.583249 | controller-1 | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-07 01:53:01.583342 | controller-1 | Creating virtual environment at: .venv
2026-09-07 01:53:02.830597 | controller-1 | Downloading cryptography (4.3MiB)
2026-09-07 01:53:02.832697 | controller-1 | Downloading pygments (1.2MiB)
2026-09-07 01:53:02.834629 | controller-1 | Downloading ansible-core (2.3MiB)
2026-09-07 01:53:03.029071 | controller-1 |  Downloading cryptography
2026-09-07 01:53:03.077509 | controller-1 |  Downloading pygments
2026-09-07 01:53:03.231287 | controller-1 |  Downloading ansible-core
2026-09-07 01:53:03.300136 | controller-1 | Installed 36 packages in 68ms
2026-09-07 01:53:03.996620 | controller-1 | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-07 01:53:03.996695 | controller-1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-07 01:53:04.968661 | controller-1 | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-07 01:53:04.968734 | controller-1 | INFO     default scenario not found, disabling shared state.
2026-09-07 01:53:04.968791 | controller-1 | INFO     [cluster-upgrade > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-07 01:53:04.968878 | controller-1 | INFO     [cluster-upgrade > prerun] Performing prerun with role_name_check=0...
2026-09-07 01:59:41.069841 | controller-1 | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2
2026-09-07 01:59:41.069914 | controller-1 | ERROR    Command ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2, returned 1 code:
2026-09-07 01:59:41.069920 | controller-1 | Starting galaxy collection install process
2026-09-07 01:59:41.069925 | controller-1 | Process install dependency map
2026-09-07 01:59:41.069929 | controller-1 |
2026-09-07 01:59:41.069933 | controller-1 | ansible-galaxy
2026-09-07 01:59:41.069937 | controller-1 |   config file = None
2026-09-07 01:59:41.069940 | controller-1 |   configured module search path =
2026-09-07 01:59:41.069944 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 01:59:41.069955 | 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-07 01:59:41.069982 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 01:59:41.069987 | 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-07 01:59:41.069992 | controller-1 |   jinja version = 3.1.6
2026-09-07 01:59:41.069996 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 01:59:41.069999 | controller-1 | No config file found; using defaults
2026-09-07 01:59:41.070003 | controller-1 | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 01:59:41.070006 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 01:59:41.070010 | 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-07 01:59:41.070014 | controller-1 | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-07 01:59:41.070017 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 01:59:41.070024 | controller-1 | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection kubernetes.core: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/kubernetes/core/': <urlopen error  Connection reset by peer>
2026-09-07 01:59:41.070030 | controller-1 | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/kubernetes/core/': <urlopen error  Connection reset by peer>
2026-09-07 01:59:41.070046 | controller-1 |
2026-09-07 01:59:41.070049 | controller-1 | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/kubernetes/core/'.
2026-09-07 01:59:41.070053 | controller-1 |
2026-09-07 01:59:41.070056 | controller-1 | <<< caused by >>>
2026-09-07 01:59:41.070059 | controller-1 |
2026-09-07 01:59:41.070062 | controller-1 | <urlopen error  Connection reset by peer>
2026-09-07 01:59:41.070065 | controller-1 |
2026-09-07 01:59:41.070069 | controller-1 |
2026-09-07 01:59:41.073818 | controller-1 | Traceback (most recent call last):
2026-09-07 01:59:41.077280 | controller-1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-07 01:59:41.077290 | controller-1 |     sys.exit(main())
2026-09-07 01:59:41.077295 | controller-1 |              ~~~~^^
2026-09-07 01:59:41.077608 | 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-07 01:59:41.077616 | controller-1 |     return self.main(*args, **kwargs)
2026-09-07 01:59:41.077621 | controller-1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.077863 | 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-07 01:59:41.077870 | controller-1 |     rv = self.invoke(ctx)
2026-09-07 01:59:41.078185 | 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-07 01:59:41.078191 | controller-1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-07 01:59:41.078194 | controller-1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-07 01:59:41.078489 | 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-07 01:59:41.078495 | controller-1 |     return ctx.invoke(self.callback, **ctx.params)
2026-09-07 01:59:41.078498 | controller-1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.078730 | 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-07 01:59:41.078735 | controller-1 |     return callback(*args, **kwargs)
2026-09-07 01:59:41.078963 | 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-07 01:59:41.078968 | controller-1 |     return func(*args, **kwargs)
2026-09-07 01:59:41.079199 | 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-07 01:59:41.079206 | controller-1 |     return f(get_current_context(), *args, **kwargs)
2026-09-07 01:59:41.079445 | 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-07 01:59:41.079452 | controller-1 |     return func(ctx)
2026-09-07 01:59:41.079719 | 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-07 01:59:41.079725 | controller-1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-07 01:59:41.079731 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.079985 | 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-07 01:59:41.079991 | controller-1 |     _run_scenarios(scenarios, command_args, default_config)
2026-09-07 01:59:41.079996 | controller-1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.080421 | 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-07 01:59:41.080428 | controller-1 |     scenario.config.runtime.prepare_environment(
2026-09-07 01:59:41.080441 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 01:59:41.080446 | controller-1 |         install_local=True,
2026-09-07 01:59:41.080451 | controller-1 |         ^^^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.080456 | controller-1 |         role_name_check=role_name_check,
2026-09-07 01:59:41.080461 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.080465 | controller-1 |     )
2026-09-07 01:59:41.080470 | controller-1 |     ^
2026-09-07 01:59:41.080845 | 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-07 01:59:41.080853 | controller-1 |     self.install_collection(
2026-09-07 01:59:41.080859 | controller-1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 01:59:41.080865 | controller-1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-07 01:59:41.080871 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.080876 | controller-1 |         destination=destination,
2026-09-07 01:59:41.080881 | controller-1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:59:41.080886 | controller-1 |     )
2026-09-07 01:59:41.080891 | controller-1 |     ^
2026-09-07 01:59:41.081093 | 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-07 01:59:41.081101 | controller-1 |     raise InvalidPrerequisiteError(msg)
2026-09-07 01:59:41.082426 | controller-1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2, returned 1 code:
2026-09-07 01:59:41.082436 | controller-1 | Starting galaxy collection install process
2026-09-07 01:59:41.082441 | controller-1 | Process install dependency map
2026-09-07 01:59:41.082446 | controller-1 |
2026-09-07 01:59:41.082451 | controller-1 | ansible-galaxy [core 2.20.1]
2026-09-07 01:59:41.082455 | controller-1 |   config file = None
2026-09-07 01:59:41.082460 | 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-07 01:59:41.082469 | controller-1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 01:59:41.082476 | 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-07 01:59:41.082482 | controller-1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 01:59:41.082487 | 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-07 01:59:41.082491 | controller-1 |   jinja version = 3.1.6
2026-09-07 01:59:41.082496 | controller-1 |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 01:59:41.082501 | controller-1 | No config file found; using defaults
2026-09-07 01:59:41.082506 | controller-1 | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 01:59:41.082510 | controller-1 | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 01:59:41.082515 | 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-07 01:59:41.082520 | controller-1 | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-07 01:59:41.082524 | controller-1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 01:59:41.082530 | controller-1 | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection kubernetes.core: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/kubernetes/core/': <urlopen error [Errno 104] Connection reset by peer>
2026-09-07 01:59:41.082541 | controller-1 | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/kubernetes/core/': <urlopen error [Errno 104] Connection reset by peer>
2026-09-07 01:59:41.082546 | controller-1 |
2026-09-07 01:59:41.082551 | controller-1 | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/kubernetes/core/'.
2026-09-07 01:59:41.082555 | controller-1 |
2026-09-07 01:59:41.082560 | controller-1 | <<< caused by >>>
2026-09-07 01:59:41.082565 | controller-1 |
2026-09-07 01:59:41.082570 | controller-1 | <urlopen error [Errno 104] Connection reset by peer>
2026-09-07 01:59:41.082575 | controller-1 |
2026-09-07 01:59:41.082580 | controller-1 |
2026-09-07 01:59:51.305693 | controller-1 | ERROR
2026-09-07 01:59:51.305861 | controller-1 | {
2026-09-07 01:59:51.305889 | controller-1 |   "delta": "0:06:39.700738",
2026-09-07 01:59:51.305909 | controller-1 |   "end": "2026-09-07 01:59:41.170454",
2026-09-07 01:59:51.305927 | controller-1 |   "msg": "non-zero return code",
2026-09-07 01:59:51.305945 | controller-1 |   "rc": 1,
2026-09-07 01:59:51.305962 | controller-1 |   "start": "2026-09-07 01:53:01.469716"
2026-09-07 01:59:51.305978 | controller-1 | }
failure
2026-09-07 01:59:51.309092 | 
2026-09-07 01:59:51.309157 | PLAY RECAP
2026-09-07 01:59:51.309200 | controller-1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:59:51.309222 | 
2026-09-07 01:59:51.482195 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:59:51.489027 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:59:52.154718 | 
2026-09-07 01:59:52.154824 | PLAY [all]
2026-09-07 01:59:52.165325 | 
2026-09-07 01:59:52.165406 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-07 01:59:52.638206 | controller-1 | changed
2026-09-07 01:59:52.828737 | controller-2 | changed
2026-09-07 01:59:52.855646 | controller-3 | changed
2026-09-07 01:59:52.865193 | 
2026-09-07 01:59:52.865378 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-07 01:59:53.349363 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:53.349584 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:53.354404 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.356037 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.358409 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.360390 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.363134 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.365839 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.365853 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.366854 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.369602 | controller-1 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.370754 | controller-1 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.329406 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:53.329541 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:53.336524 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.338147 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.338906 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.340073 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.344584 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.346719 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.346746 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.349109 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.351931 | controller-3 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.354183 | controller-3 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.327794 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:53.327894 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:53.333834 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.336592 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.338852 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.340627 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.341125 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.342569 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.345655 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.347495 | controller-2 | environment: line 1: kubectl: command not found
2026-09-07 01:59:53.347740 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.349492 | controller-2 | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:59:53.443231 | controller-2 | ok: Runtime: 0:00:00.039568
2026-09-07 01:59:53.455503 | controller-3 | ok: Runtime: 0:00:00.040088
2026-09-07 01:59:53.467217 | controller-1 | ok: Runtime: 0:00:00.035664
2026-09-07 01:59:53.474375 | 
2026-09-07 01:59:53.474497 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-07 01:59:53.737215 | controller-2 | changed
2026-09-07 01:59:53.745806 | controller-3 | changed
2026-09-07 01:59:53.755404 | controller-1 | changed
2026-09-07 01:59:53.762834 | 
2026-09-07 01:59:53.762986 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-07 01:59:54.089858 | controller-1 | environment: line 5: kubectl: command not found
2026-09-07 01:59:54.091110 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.091853 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.092247 | controller-1 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.163059 | controller-3 | environment: line 5: kubectl: command not found
2026-09-07 01:59:54.164967 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.165303 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.165863 | controller-3 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.086095 | controller-2 | environment: line 5: kubectl: command not found
2026-09-07 01:59:54.086426 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.086442 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.087917 | controller-2 | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:59:54.326912 | controller-2 | ok: Runtime: 0:00:00.011629
2026-09-07 01:59:54.361073 | controller-1 | ok: Runtime: 0:00:00.013359
2026-09-07 01:59:54.365995 | controller-3 | ok: Runtime: 0:00:00.014566
2026-09-07 01:59:54.369934 | 
2026-09-07 01:59:54.369991 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-07 01:59:54.972623 | controller-2 | changed:
2026-09-07 01:59:54.973053 | controller-2 | created directory /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/logs/controller-2
2026-09-07 01:59:54.973112 | controller-2 | cd+++++++++ objects/
2026-09-07 01:59:54.973182 | controller-2 | cd+++++++++ objects/cluster/
2026-09-07 01:59:54.973221 | controller-2 | cd+++++++++ objects/namespaced/
2026-09-07 01:59:55.052980 | controller-3 | changed:
2026-09-07 01:59:55.053189 | controller-3 | created directory /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/logs/controller-3
2026-09-07 01:59:55.053243 | controller-3 | cd+++++++++ objects/
2026-09-07 01:59:55.053286 | controller-3 | cd+++++++++ objects/cluster/
2026-09-07 01:59:55.053327 | controller-3 | cd+++++++++ objects/namespaced/
2026-09-07 01:59:55.060446 | controller-1 | changed:
2026-09-07 01:59:55.060594 | controller-1 | created directory /var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/logs/controller-1
2026-09-07 01:59:55.060647 | controller-1 | cd+++++++++ objects/
2026-09-07 01:59:55.060689 | controller-1 | cd+++++++++ objects/cluster/
2026-09-07 01:59:55.060728 | controller-1 | cd+++++++++ objects/namespaced/
2026-09-07 01:59:55.088343 | 
2026-09-07 01:59:55.088431 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-07 01:59:55.354617 | controller-1 | changed
2026-09-07 01:59:55.361923 | controller-2 | changed
2026-09-07 01:59:55.369672 | controller-3 | changed
2026-09-07 01:59:55.378462 | 
2026-09-07 01:59:55.378601 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-07 01:59:55.650110 | controller-2 | changed
2026-09-07 01:59:55.657983 | controller-1 | changed
2026-09-07 01:59:55.675246 | controller-3 | changed
2026-09-07 01:59:55.682931 | 
2026-09-07 01:59:55.683063 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-07 01:59:56.026726 | controller-1 | environment: line 3: kubectl: command not found
2026-09-07 01:59:56.018322 | controller-3 | environment: line 3: kubectl: command not found
2026-09-07 01:59:56.007787 | controller-2 | environment: line 3: kubectl: command not found
2026-09-07 01:59:56.256275 | controller-2 | ok: Runtime: 0:00:00.011786
2026-09-07 01:59:56.272207 | controller-3 | ok: Runtime: 0:00:00.011184
2026-09-07 01:59:56.285619 | controller-1 | ok: Runtime: 0:00:00.014028
2026-09-07 01:59:56.295556 | 
2026-09-07 01:59:56.295684 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-07 01:59:56.835711 | controller-2 | changed:
2026-09-07 01:59:56.836033 | controller-2 | cd+++++++++ pod-logs/
2026-09-07 01:59:56.836063 | controller-2 | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:59:56.839532 | controller-1 | changed:
2026-09-07 01:59:56.839576 | controller-1 | cd+++++++++ pod-logs/
2026-09-07 01:59:56.839600 | controller-1 | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:59:56.866166 | controller-3 | changed:
2026-09-07 01:59:56.866290 | controller-3 | cd+++++++++ pod-logs/
2026-09-07 01:59:56.866339 | controller-3 | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:59:56.891657 | 
2026-09-07 01:59:56.891817 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-07 01:59:57.157579 | controller-1 | changed: "values"
2026-09-07 01:59:57.162408 | controller-2 | changed: "values"
2026-09-07 01:59:57.188998 | controller-3 | changed: "values"
2026-09-07 01:59:57.374800 | controller-2 | changed: "releases"
2026-09-07 01:59:57.386324 | controller-1 | changed: "releases"
2026-09-07 01:59:57.419373 | controller-3 | changed: "releases"
2026-09-07 01:59:57.419490 | controller-3 | changed: All items complete
2026-09-07 01:59:57.419516 | 
2026-09-07 01:59:57.433523 | 
2026-09-07 01:59:57.433763 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-07 01:59:57.778550 | controller-1 | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:59:57.783285 | controller-3 | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:59:57.776089 | controller-2 | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:59:58.018064 | controller-2 | ok: Runtime: 0:00:00.008278
2026-09-07 01:59:58.030719 | controller-1 | ok: Runtime: 0:00:00.009197
2026-09-07 01:59:58.043028 | controller-3 | ok: Runtime: 0:00:00.009442
2026-09-07 01:59:58.050413 | 
2026-09-07 01:59:58.050534 | TASK [helm-release-status : Downloads logs to executor]
2026-09-07 01:59:58.622732 | controller-1 | changed:
2026-09-07 01:59:58.623077 | controller-1 | cd+++++++++ helm/
2026-09-07 01:59:58.623159 | controller-1 | cd+++++++++ helm/releases/
2026-09-07 01:59:58.623205 | controller-1 | cd+++++++++ helm/values/
2026-09-07 01:59:58.630496 | controller-2 | changed:
2026-09-07 01:59:58.630597 | controller-2 | cd+++++++++ helm/
2026-09-07 01:59:58.630648 | controller-2 | cd+++++++++ helm/releases/
2026-09-07 01:59:58.630690 | controller-2 | cd+++++++++ helm/values/
2026-09-07 01:59:58.637587 | controller-3 | changed:
2026-09-07 01:59:58.637678 | controller-3 | cd+++++++++ helm/
2026-09-07 01:59:58.637726 | controller-3 | cd+++++++++ helm/releases/
2026-09-07 01:59:58.637764 | controller-3 | cd+++++++++ helm/values/
2026-09-07 01:59:58.659637 | 
2026-09-07 01:59:58.659706 | PLAY RECAP
2026-09-07 01:59:58.659769 | controller-1 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:59:58.659804 | controller-2 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:59:58.659836 | controller-3 | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:59:58.659858 | 
2026-09-07 01:59:58.875697 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:59:58.879650 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 01:59:59.499815 | 
2026-09-07 01:59:59.499934 | PLAY [all]
2026-09-07 01:59:59.509708 | 
2026-09-07 01:59:59.509776 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 01:59:59.559680 | controller-2 | ok
2026-09-07 01:59:59.574754 | controller-1 | ok
2026-09-07 01:59:59.600591 | controller-3 | ok
2026-09-07 01:59:59.605063 | 
2026-09-07 01:59:59.605190 | TASK [fetch-output : Set log path for single node]
2026-09-07 01:59:59.635316 | controller-1 | skipping: Conditional result was False
2026-09-07 01:59:59.646212 | controller-2 | skipping: Conditional result was False
2026-09-07 01:59:59.656644 | controller-3 | skipping: Conditional result was False
2026-09-07 01:59:59.661706 | 
2026-09-07 01:59:59.661796 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 02:00:00.040599 | controller-2 -> localhost | ok: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/logs/controller-2"
2026-09-07 02:00:00.054606 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/logs/controller-3"
2026-09-07 02:00:00.094691 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/logs/controller-1"
2026-09-07 02:00:00.270180 | controller-2 -> localhost | changed: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/artifacts"
2026-09-07 02:00:00.278074 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/artifacts"
2026-09-07 02:00:00.321803 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/artifacts"
2026-09-07 02:00:00.515540 | controller-2 -> localhost | changed: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/docs"
2026-09-07 02:00:00.522168 | controller-3 -> localhost | ok: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/docs"
2026-09-07 02:00:00.541911 | controller-1 -> localhost | ok: "/var/lib/zuul/builds/81f0cc01c3944f91bfddfe9b8e83d461/work/docs"
2026-09-07 02:00:00.542049 | controller-1 -> localhost | ok: All items complete
2026-09-07 02:00:00.542108 | 
2026-09-07 02:00:00.555662 | 
2026-09-07 02:00:00.555896 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 02:00:01.190942 | controller-2 | changed: .d..t...... ./
2026-09-07 02:00:01.247375 | controller-1 | changed: .d..t...... ./
2026-09-07 02:00:01.280923 | controller-3 | changed: .d..t...... ./
2026-09-07 02:00:01.718571 | controller-2 | changed: .d..t...... ./
2026-09-07 02:00:01.729783 | controller-1 | ok
2026-09-07 02:00:01.769718 | controller-3 | ok
2026-09-07 02:00:02.174155 | controller-2 | changed: .d..t...... ./
2026-09-07 02:00:02.223644 | controller-1 | ok
2026-09-07 02:00:02.223800 | controller-1 | changed: All items complete
2026-09-07 02:00:02.223848 | 
2026-09-07 02:00:02.274408 | controller-3 | ok
2026-09-07 02:00:02.295002 | 
2026-09-07 02:00:02.295606 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 02:00:02.742362 | controller-1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006634
2026-09-07 02:00:02.742676 | controller-1 -> localhost | changed: All items complete
2026-09-07 02:00:02.742739 | 
2026-09-07 02:00:02.984814 | controller-1 -> localhost | ok: Item: docs Runtime: 0:00:00.006190
2026-09-07 02:00:03.018704 | 
2026-09-07 02:00:03.018906 | PLAY [all]
2026-09-07 02:00:03.028988 | 
2026-09-07 02:00:03.029138 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 02:00:03.610581 | controller-1 | changed
2026-09-07 02:00:03.728572 | controller-2 | changed
2026-09-07 02:00:03.743753 | controller-3 | changed
2026-09-07 02:00:03.764996 | 
2026-09-07 02:00:03.765182 | PLAY RECAP
2026-09-07 02:00:03.765251 | controller-1 | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 02:00:03.765288 | controller-2 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 02:00:03.765319 | controller-3 | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 02:00:03.765359 | 
2026-09-07 02:00:04.009395 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 02:00:04.017632 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 02:00:04.557329 | 
2026-09-07 02:00:04.557629 | PLAY [localhost]
2026-09-07 02:00:04.567154 | 
2026-09-07 02:00:04.567224 | TASK [Generate Zuul manifest]
2026-09-07 02:00:04.584851 | localhost | ok
2026-09-07 02:00:04.597674 | 
2026-09-07 02:00:04.597748 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 02:00:04.961991 | localhost | changed
2026-09-07 02:00:04.981659 | 
2026-09-07 02:00:04.981816 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 02:00:05.012467 | localhost | ok
2026-09-07 02:00:05.025833 | 
2026-09-07 02:00:05.025956 | TASK [Upload logs]
2026-09-07 02:00:05.047074 | localhost | ok
2026-09-07 02:00:05.112612 | 
2026-09-07 02:00:05.112719 | TASK [Set zuul-log-path fact]
2026-09-07 02:00:05.129099 | localhost | ok
2026-09-07 02:00:05.138867 | 
2026-09-07 02:00:05.138931 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 02:00:05.178419 | localhost | ok
2026-09-07 02:00:05.190103 | 
2026-09-07 02:00:05.190238 | TASK [upload-logs : Create log directories]
2026-09-07 02:00:05.656996 | localhost | changed
2026-09-07 02:00:05.703415 | 
2026-09-07 02:00:05.703590 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 02:00:06.078807 | localhost -> localhost | ok: Runtime: 0:00:00.005405
2026-09-07 02:00:06.087842 | 
2026-09-07 02:00:06.087969 | TASK [upload-logs : Upload logs to log server]
2026-09-07 02:00:06.542858 | localhost | Output suppressed because no_log was given
2026-09-07 02:00:06.549653 | 
2026-09-07 02:00:06.549785 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 02:00:06.610945 | localhost | skipping: Conditional result was False
2026-09-07 02:00:06.621845 | localhost | skipping: Conditional result was False
2026-09-07 02:00:06.632802 | 
2026-09-07 02:00:06.632986 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 02:00:06.673771 | localhost | skipping: Conditional result was False
2026-09-07 02:00:06.674254 | 
2026-09-07 02:00:06.676932 | localhost | skipping: Conditional result was False
2026-09-07 02:00:06.686083 | 
2026-09-07 02:00:06.686287 | LOOP [upload-logs : Upload console log and json output]