2026-07-26 18:19:26.390500 | Job console starting
2026-07-26 18:19:26.398949 | Updating git repos
2026-07-26 18:19:29.854840 | Cloning repos into workspace
2026-07-26 18:19:42.003593 | Restoring repo states
2026-07-26 18:19:50.236407 | Merging changes
2026-07-26 18:19:51.936553 | Checking out repos
2026-07-26 18:19:53.548782 | Preparing playbooks
2026-07-26 18:19:56.171669 | Running Ansible setup
2026-07-26 18:19:59.581453 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 18:20:00.173269 | 
2026-07-26 18:20:00.173392 | PLAY [localhost]
2026-07-26 18:20:00.180694 | 
2026-07-26 18:20:00.180761 | TASK [Gathering Facts]
2026-07-26 18:20:01.019075 | localhost | ok
2026-07-26 18:20:01.028078 | 
2026-07-26 18:20:01.028155 | TASK [Setup log path fact]
2026-07-26 18:20:01.047867 | localhost | ok
2026-07-26 18:20:01.060972 | 
2026-07-26 18:20:01.061044 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 18:20:01.091068 | localhost | ok
2026-07-26 18:20:01.098800 | 
2026-07-26 18:20:01.098869 | TASK [emit-job-header : Print job information]
2026-07-26 18:20:01.139782 | # Job Information
2026-07-26 18:20:01.139949 | Ansible Version: 2.16.18
2026-07-26 18:20:01.139996 | Job: atmosphere-molecule-keycloak-selective
2026-07-26 18:20:01.140030 | Pipeline: check
2026-07-26 18:20:01.140060 | Executor: 2d72f0692154
2026-07-26 18:20:01.140089 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4163
2026-07-26 18:20:01.140122 | Event ID: 27ac1810-891e-11f1-9273-d0210d8e1cbc
2026-07-26 18:20:01.144253 | 
2026-07-26 18:20:01.144355 | LOOP [emit-job-header : Print node information]
2026-07-26 18:20:01.237086 | localhost | ok:
2026-07-26 18:20:01.237394 | localhost | # Node Information
2026-07-26 18:20:01.237469 | localhost | Inventory Hostname: instance
2026-07-26 18:20:01.237526 | localhost | Hostname: np0000186995
2026-07-26 18:20:01.237578 | localhost | Username: zuul
2026-07-26 18:20:01.237635 | localhost | Distro: Ubuntu 22.04
2026-07-26 18:20:01.237692 | localhost | Provider: yul1
2026-07-26 18:20:01.237746 | localhost | Region: ca-ymq-1
2026-07-26 18:20:01.237795 | localhost | Label: ubuntu-jammy
2026-07-26 18:20:01.237843 | localhost | Product Name: OpenStack Nova
2026-07-26 18:20:01.237891 | localhost | Interface IP: 199.204.45.144
2026-07-26 18:20:01.249466 | 
2026-07-26 18:20:01.249624 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-26 18:20:01.630024 | localhost -> localhost | changed
2026-07-26 18:20:01.635159 | 
2026-07-26 18:20:01.635231 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-26 18:20:02.453019 | localhost -> localhost | changed
2026-07-26 18:20:02.460990 | 
2026-07-26 18:20:02.461076 | PLAY [all]
2026-07-26 18:20:02.470146 | 
2026-07-26 18:20:02.470218 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-26 18:20:02.707031 | instance -> localhost | ok
2026-07-26 18:20:02.717210 | 
2026-07-26 18:20:02.717281 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-26 18:20:02.751078 | instance | ok
2026-07-26 18:20:02.766173 | instance | included: /var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-26 18:20:02.771743 | 
2026-07-26 18:20:02.771805 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-26 18:20:04.165773 | instance -> localhost | Generating public/private rsa key pair.
2026-07-26 18:20:04.166160 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/dced5ba7f174414db447de96772ff864_id_rsa
2026-07-26 18:20:04.166267 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/dced5ba7f174414db447de96772ff864_id_rsa.pub
2026-07-26 18:20:04.166325 | instance -> localhost | The key fingerprint is:
2026-07-26 18:20:04.166374 | instance -> localhost | SHA256:jjIqJEnWO/UgeN49vDp2IdrSPE4OxbeSh4GC2ywvJyI zuul-build-sshkey
2026-07-26 18:20:04.166443 | instance -> localhost | The key's randomart image is:
2026-07-26 18:20:04.166491 | instance -> localhost | +---[RSA 3072]----+
2026-07-26 18:20:04.166548 | instance -> localhost | |                 |
2026-07-26 18:20:04.166594 | instance -> localhost | |                 |
2026-07-26 18:20:04.166639 | instance -> localhost | |  o              |
2026-07-26 18:20:04.166684 | instance -> localhost | |.+ +oo           |
2026-07-26 18:20:04.166728 | instance -> localhost | |+oo.=+=.S        |
2026-07-26 18:20:04.166771 | instance -> localhost | |o=.+.o=O.        |
2026-07-26 18:20:04.166798 | instance -> localhost | |= o.O=oo=        |
2026-07-26 18:20:04.166818 | instance -> localhost | |Eo.++Xoo         |
2026-07-26 18:20:04.166840 | instance -> localhost | |o=o ++=          |
2026-07-26 18:20:04.166860 | instance -> localhost | +----[SHA256]-----+
2026-07-26 18:20:04.166907 | instance -> localhost | ok: Runtime: 0:00:00.979762
2026-07-26 18:20:04.172014 | 
2026-07-26 18:20:04.172081 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-26 18:20:04.207216 | instance | ok
2026-07-26 18:20:04.216880 | instance | included: /var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-26 18:20:04.224099 | 
2026-07-26 18:20:04.224160 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-26 18:20:04.259141 | instance | skipping: Conditional result was False
2026-07-26 18:20:04.269506 | 
2026-07-26 18:20:04.269577 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-26 18:20:04.750952 | instance | changed
2026-07-26 18:20:04.755787 | 
2026-07-26 18:20:04.755850 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-26 18:20:04.932011 | instance | ok
2026-07-26 18:20:04.938062 | 
2026-07-26 18:20:04.938125 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-26 18:20:06.187636 | instance | changed
2026-07-26 18:20:06.192563 | 
2026-07-26 18:20:06.192624 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-26 18:20:06.643987 | instance | changed
2026-07-26 18:20:06.650647 | 
2026-07-26 18:20:06.650714 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-26 18:20:06.675464 | instance | skipping: Conditional result was False
2026-07-26 18:20:06.686685 | 
2026-07-26 18:20:06.686819 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-26 18:20:07.060926 | instance -> localhost | changed
2026-07-26 18:20:07.072416 | 
2026-07-26 18:20:07.072491 | TASK [add-build-sshkey : Add back temp key]
2026-07-26 18:20:07.341386 | instance -> localhost | Identity added: /var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/dced5ba7f174414db447de96772ff864_id_rsa (zuul-build-sshkey)
2026-07-26 18:20:07.341582 | instance -> localhost | ok: Runtime: 0:00:00.014098
2026-07-26 18:20:07.346504 | 
2026-07-26 18:20:07.346569 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-26 18:20:07.640695 | instance | ok
2026-07-26 18:20:07.647664 | 
2026-07-26 18:20:07.647904 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-26 18:20:07.674043 | instance | skipping: Conditional result was False
2026-07-26 18:20:07.725246 | 
2026-07-26 18:20:07.725385 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-26 18:20:08.002816 | instance | ok
2026-07-26 18:20:08.008611 | 
2026-07-26 18:20:08.008673 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-26 18:20:11.242990 | instance | Output suppressed because no_log was given
2026-07-26 18:20:11.252359 | 
2026-07-26 18:20:11.252426 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-26 18:20:11.451194 | instance | ok: "logs"
2026-07-26 18:20:11.616280 | instance | ok: All items complete
2026-07-26 18:20:11.616650 | 
2026-07-26 18:20:11.625790 | instance | ok: "artifacts"
2026-07-26 18:20:11.765763 | instance | ok: "docs"
2026-07-26 18:20:11.783787 | 
2026-07-26 18:20:11.783859 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-26 18:20:11.971988 | instance | changed: "logs"
2026-07-26 18:20:12.127035 | instance | changed: "artifacts"
2026-07-26 18:20:12.280906 | instance | changed: "docs"
2026-07-26 18:20:12.291771 | 
2026-07-26 18:20:12.291846 | PLAY RECAP
2026-07-26 18:20:12.291892 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-26 18:20:12.291920 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 18:20:12.291943 | 
2026-07-26 18:20:12.473276 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 18:20:12.479649 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 18:20:13.110471 | 
2026-07-26 18:20:13.110617 | PLAY [all]
2026-07-26 18:20:13.121309 | 
2026-07-26 18:20:13.121395 | TASK [setup-uv : Extract archive]
2026-07-26 18:20:15.298400 | instance | changed
2026-07-26 18:20:15.305926 | 
2026-07-26 18:20:15.306002 | TASK [setup-uv : Print version]
2026-07-26 18:20:15.684932 | instance | uv 0.8.13
2026-07-26 18:20:15.841191 | instance | ok: Runtime: 0:00:00.013518
2026-07-26 18:20:15.851473 | 
2026-07-26 18:20:15.851547 | TASK [Install Ansible collection siblings]
2026-07-26 18:20:24.607413 | instance | changed:
2026-07-26 18:20:24.607651 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-26 18:20:24.607690 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-26 18:20:24.607721 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-26 18:20:24.607748 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-26 18:20:24.607774 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-26 18:20:24.607807 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-26 18:20:24.607836 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-26 18:20:24.607867 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-26 18:20:24.607894 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-26 18:20:24.607920 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-26 18:20:24.607947 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-26 18:20:24.607973 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-26 18:20:24.607998 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-26 18:20:24.608024 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-26 18:20:24.608050 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-26 18:20:24.608076 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-26 18:20:24.608102 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-26 18:20:24.608128 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-26 18:20:24.608154 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-26 18:20:24.608180 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-26 18:20:24.608205 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-26 18:20:24.608229 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-26 18:20:24.608257 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 18:20:24.608299 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-26 18:20:24.608327 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-26 18:20:24.608353 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 18:20:24.608378 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 18:20:24.608402 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 18:20:24.608427 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-26 18:20:24.608452 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-26 18:20:24.608477 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 18:20:24.608502 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 18:20:24.608528 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-26 18:20:24.608553 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-26 18:20:24.608585 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.608610 | instance | Process install dependency map
2026-07-26 18:20:24.608636 | instance | Starting collection install process
2026-07-26 18:20:24.608661 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-26 18:20:24.608686 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-26 18:20:24.608711 | instance | ansible.netcommon:* was installed successfully
2026-07-26 18:20:24.608735 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-26 18:20:24.608760 | instance | Creating virtual environment at: .venv
2026-07-26 18:20:24.608784 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 18:20:24.608809 | instance | Downloading pydantic-core (2.0MiB)
2026-07-26 18:20:24.608833 | instance | Downloading cryptography (4.2MiB)
2026-07-26 18:20:24.608862 | instance | Downloading netaddr (2.2MiB)
2026-07-26 18:20:24.608887 | instance | Downloading rjsonnet (1.2MiB)
2026-07-26 18:20:24.608912 | instance | Downloading pygments (1.2MiB)
2026-07-26 18:20:24.608936 | instance | Downloading ansible-core (2.1MiB)
2026-07-26 18:20:24.608986 | instance | Downloading kubernetes (1.9MiB)
2026-07-26 18:20:24.609014 | instance | Downloading openstacksdk (1.7MiB)
2026-07-26 18:20:24.609040 | instance | Downloading setuptools (1.1MiB)
2026-07-26 18:20:24.609064 | instance |    Building pyperclip==1.9.0
2026-07-26 18:20:24.609091 | instance |  Downloading rjsonnet
2026-07-26 18:20:24.609117 | instance |  Downloading pydantic-core
2026-07-26 18:20:24.609143 | instance |  Downloading netaddr
2026-07-26 18:20:24.609168 | instance |  Downloading pygments
2026-07-26 18:20:24.609217 | instance |  Downloading cryptography
2026-07-26 18:20:24.609244 | instance |  Downloading setuptools
2026-07-26 18:20:24.609269 | instance |  Downloading kubernetes
2026-07-26 18:20:24.609302 | instance |  Downloading ansible-core
2026-07-26 18:20:24.609328 | instance |  Downloading openstacksdk
2026-07-26 18:20:24.609353 | instance |       Built pyperclip==1.9.0
2026-07-26 18:20:24.609378 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 18:20:24.609404 | instance | Installed 83 packages in 44ms
2026-07-26 18:20:24.609429 | instance | Checked out ansible.posix to 1.6.0
2026-07-26 18:20:24.609455 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-26 18:20:24.609480 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.609505 | instance | Process install dependency map
2026-07-26 18:20:24.609538 | instance | Starting collection install process
2026-07-26 18:20:24.609565 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-26 18:20:24.609590 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-26 18:20:24.609615 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-26 18:20:24.609641 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.609667 | instance | 2.17.12
2026-07-26 18:20:24.609693 | instance | Checked out ansible.utils to v6.0.3
2026-07-26 18:20:24.609718 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-26 18:20:24.609743 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.609768 | instance | Process install dependency map
2026-07-26 18:20:24.609793 | instance | Starting collection install process
2026-07-26 18:20:24.609819 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-26 18:20:24.609845 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-26 18:20:24.609871 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-26 18:20:24.609898 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.609924 | instance | 2.17.12
2026-07-26 18:20:24.609950 | instance | Checked out atmosphere.common to v0.7.0
2026-07-26 18:20:24.609975 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-26 18:20:24.610001 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.610026 | instance | Process install dependency map
2026-07-26 18:20:24.610052 | instance | Starting collection install process
2026-07-26 18:20:24.610077 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-26 18:20:24.610102 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-26 18:20:24.610126 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-26 18:20:24.610151 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.610176 | instance | 2.17.12
2026-07-26 18:20:24.610200 | instance | Checked out community.crypto to 2.2.3
2026-07-26 18:20:24.610226 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-26 18:20:24.610251 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.610276 | instance | Process install dependency map
2026-07-26 18:20:24.610301 | instance | Starting collection install process
2026-07-26 18:20:24.610326 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-26 18:20:24.610351 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-26 18:20:24.610376 | instance | community.crypto:2.2.3 was installed successfully
2026-07-26 18:20:24.610401 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.610425 | instance | 2.17.12
2026-07-26 18:20:24.610450 | instance | Checked out community.general to 7.3.0
2026-07-26 18:20:24.610475 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-07-26 18:20:24.610508 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.610533 | instance | Process install dependency map
2026-07-26 18:20:24.610559 | instance | Starting collection install process
2026-07-26 18:20:24.610584 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-26 18:20:24.610609 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-26 18:20:24.610671 | instance | community.general:7.3.0 was installed successfully
2026-07-26 18:20:24.610702 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.610729 | instance | 2.17.12
2026-07-26 18:20:24.610756 | instance | Checked out community.mysql to 3.6.0
2026-07-26 18:20:24.610784 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-26 18:20:24.610812 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.610838 | instance | Process install dependency map
2026-07-26 18:20:24.610864 | instance | Starting collection install process
2026-07-26 18:20:24.610889 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-26 18:20:24.610914 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-26 18:20:24.610939 | instance | community.mysql:3.6.0 was installed successfully
2026-07-26 18:20:24.610964 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.610989 | instance | 2.17.12
2026-07-26 18:20:24.611014 | instance | Checked out kubernetes.core to 2.4.0
2026-07-26 18:20:24.611040 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-26 18:20:24.611066 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.611091 | instance | Process install dependency map
2026-07-26 18:20:24.611116 | instance | Starting collection install process
2026-07-26 18:20:24.611141 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-26 18:20:24.611166 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-26 18:20:24.611191 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-26 18:20:24.611216 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.611241 | instance | 2.17.12
2026-07-26 18:20:24.611266 | instance | Checked out openstack.cloud to 2.6.0
2026-07-26 18:20:24.611291 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-26 18:20:24.611316 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.611341 | instance | Process install dependency map
2026-07-26 18:20:24.611366 | instance | Starting collection install process
2026-07-26 18:20:24.611390 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-26 18:20:24.611415 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-26 18:20:24.611435 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-26 18:20:24.611452 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.611469 | instance | 2.17.12
2026-07-26 18:20:24.611493 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-26 18:20:24.611511 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-26 18:20:24.611529 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.611547 | instance | Process install dependency map
2026-07-26 18:20:24.611564 | instance | Starting collection install process
2026-07-26 18:20:24.611582 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-26 18:20:24.611600 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-26 18:20:24.611618 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-26 18:20:24.611636 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.611654 | instance | 2.17.12
2026-07-26 18:20:24.611672 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-26 18:20:24.611690 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-26 18:20:24.611708 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.611726 | instance | Process install dependency map
2026-07-26 18:20:24.611743 | instance | Starting collection install process
2026-07-26 18:20:24.611761 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-26 18:20:24.611779 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-26 18:20:24.611797 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-26 18:20:24.611815 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.611833 | instance | 2.17.12
2026-07-26 18:20:24.611851 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-26 18:20:24.611869 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-26 18:20:24.611887 | instance | Starting galaxy collection install process
2026-07-26 18:20:24.611905 | instance | Process install dependency map
2026-07-26 18:20:24.611923 | instance | Starting collection install process
2026-07-26 18:20:24.611940 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-26 18:20:24.611958 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-26 18:20:24.611975 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-26 18:20:24.611993 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 18:20:24.612011 | instance | 2.17.12
2026-07-26 18:20:24.618212 | 
2026-07-26 18:20:24.618262 | PLAY RECAP
2026-07-26 18:20:24.618310 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 18:20:24.618331 | 
2026-07-26 18:20:24.760468 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 18:20:24.763278 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 18:20:25.343811 | 
2026-07-26 18:20:25.343935 | PLAY [all]
2026-07-26 18:20:25.355440 | 
2026-07-26 18:20:25.355526 | TASK [Install "jq" for log collection]
2026-07-26 18:20:37.063059 | instance | changed
2026-07-26 18:20:37.065187 | 
2026-07-26 18:20:37.065270 | PLAY RECAP
2026-07-26 18:20:37.065343 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 18:20:37.065424 | 
2026-07-26 18:20:37.175593 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 18:20:37.178414 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 18:20:37.806406 | 
2026-07-26 18:20:37.806547 | PLAY [Prepare Molecule dependencies]
2026-07-26 18:20:37.817694 | 
2026-07-26 18:20:37.817766 | TASK [Install jq for log collection]
2026-07-26 18:20:40.006306 | instance | ok
2026-07-26 18:20:40.009204 | 
2026-07-26 18:20:40.009265 | PLAY [Install Go for the Atmosphere deployment binary]
2026-07-26 18:20:40.016045 | 
2026-07-26 18:20:40.016132 | TASK [ensure-go : Check installed go version]
2026-07-26 18:20:40.552694 | instance | ok: ERROR (ignored)
2026-07-26 18:20:40.553038 | instance | {
2026-07-26 18:20:40.553086 | instance |   "failed_when_result": false,
2026-07-26 18:20:40.553121 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-07-26 18:20:40.553151 | instance |   "rc": 2
2026-07-26 18:20:40.553180 | instance | }
2026-07-26 18:20:40.558809 | 
2026-07-26 18:20:40.558877 | TASK [ensure-go : Skip if correct version of go is installed]
2026-07-26 18:20:40.611322 | instance | ok
2026-07-26 18:20:40.621058 | instance | included: /var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-07-26 18:20:40.625937 | 
2026-07-26 18:20:40.626004 | TASK [ensure-go : Create temp directory]
2026-07-26 18:20:40.941773 | instance | changed
2026-07-26 18:20:40.950443 | 
2026-07-26 18:20:40.950607 | TASK [ensure-go : Get archive checksum]
2026-07-26 18:20:41.566560 | instance | ok: OK (64 bytes)
2026-07-26 18:20:41.573958 | 
2026-07-26 18:20:41.574034 | TASK [ensure-go : Download go archive]
2026-07-26 18:20:42.859155 | instance | changed: OK (78559214 bytes)
2026-07-26 18:20:42.866014 | 
2026-07-26 18:20:42.866081 | TASK [ensure-go : Install go]
2026-07-26 18:20:48.526885 | instance | changed
2026-07-26 18:20:48.536756 | 
2026-07-26 18:20:48.536819 | PLAY [Prepare selective Molecule execution plan]
2026-07-26 18:20:48.542121 | 
2026-07-26 18:20:48.542190 | TASK [Build atmosphere binary]
2026-07-26 18:20:48.763812 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-07-26 18:20:48.767621 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-07-26 18:20:48.772747 | instance | go: downloading golang.org/x/sync v0.18.0
2026-07-26 18:20:49.024935 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-07-26 18:20:55.582779 | instance | ok: Runtime: 0:00:06.533006
2026-07-26 18:20:55.587326 | 
2026-07-26 18:20:55.587387 | TASK [Generate selective CI plan]
2026-07-26 18:20:56.155935 | instance | ok: Runtime: 0:00:00.023250
2026-07-26 18:20:56.160761 | 
2026-07-26 18:20:56.160822 | TASK [Explain selective CI plan]
2026-07-26 18:20:56.383687 | instance | CI plan mode: selective
2026-07-26 18:20:56.383874 | instance | Changed files:
2026-07-26 18:20:56.383884 | instance |   A	roles/heat/.ci-selective-test
2026-07-26 18:20:56.383891 | instance |   A	roles/octavia/.ci-selective-test
2026-07-26 18:20:56.383897 | instance | Matches:
2026-07-26 18:20:56.383912 | instance |   roles/heat/.ci-selective-test: component:heat (targets) -> heat
2026-07-26 18:20:56.383919 | instance |   roles/octavia/.ci-selective-test: component:octavia (targets) -> octavia
2026-07-26 18:20:56.383927 | instance | Direct targets: heat, octavia
2026-07-26 18:20:56.383934 | instance | Deployment roots: glance-images, heat, neutron, octavia
2026-07-26 18:20:56.383940 | instance | Verification profiles: load-balancer, orchestration
2026-07-26 18:20:56.383948 | instance | Variant openvswitch (23 components): ceph, ceph-provisioners, cert-manager, cluster-issuer, coredns, csi, glance, glance-images, heat, ingress-nginx, keycloak, keystone, kubernetes, libvirt, memcached, neutron, nova, octavia, openvswitch, percona-xtradb-cluster, percona-xtradb-cluster-operator, placement, rabbitmq-cluster-operator
2026-07-26 18:20:56.383954 | instance | Molecule jobs:
2026-07-26 18:20:56.383960 | instance |   aio-openvswitch: run (23 components) - executes the openvswitch deployment variant
2026-07-26 18:20:56.383966 | instance |   aio-ovn: skip - plan has no ovn deployment variant
2026-07-26 18:20:56.383971 | instance |   csi-local-path-provisioner: skip - none of the job's verification profiles were requested
2026-07-26 18:20:56.383980 | instance |   csi-rbd: skip - none of the job's verification profiles were requested
2026-07-26 18:20:56.383986 | instance |   keycloak: skip - none of the job's verification profiles were requested
2026-07-26 18:20:56.695308 | instance | ok: Runtime: 0:00:00.024969
2026-07-26 18:20:56.701891 | 
2026-07-26 18:20:56.701953 | TASK [Print selective CI plan]
2026-07-26 18:20:56.734650 | instance | ok
2026-07-26 18:20:56.740442 | 
2026-07-26 18:20:56.740526 | TASK [Report conservative fallback]
2026-07-26 18:20:56.764694 | instance | skipping: Conditional result was False
2026-07-26 18:20:56.773748 | 
2026-07-26 18:20:56.773809 | TASK [Create selective CI plan log directory]
2026-07-26 18:20:57.048420 | instance | changed
2026-07-26 18:20:57.056225 | 
2026-07-26 18:20:57.056287 | TASK [Publish selective CI plan with job logs]
2026-07-26 18:20:57.384314 | instance | changed
2026-07-26 18:20:57.386197 | 
2026-07-26 18:20:57.386260 | PLAY RECAP
2026-07-26 18:20:57.386320 | instance | ok: 13 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 18:20:57.386351 | 
2026-07-26 18:20:57.535022 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 18:20:57.539916 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 18:20:58.170910 | 
2026-07-26 18:20:58.171057 | PLAY [Run selected Molecule deployment and verification]
2026-07-26 18:20:58.182302 | 
2026-07-26 18:20:58.182376 | TASK [Check for selective CI plan]
2026-07-26 18:20:58.484274 | instance | ok
2026-07-26 18:20:58.490880 | 
2026-07-26 18:20:58.490957 | TASK [Validate selective CI plan]
2026-07-26 18:20:59.025950 | instance | ok: Runtime: 0:00:00.032298
2026-07-26 18:20:59.033016 | 
2026-07-26 18:20:59.033092 | TASK [Resolve selective CI plan availability]
2026-07-26 18:20:59.086322 | instance | ok
2026-07-26 18:20:59.090656 | 
2026-07-26 18:20:59.090717 | TASK [Read selective CI plan]
2026-07-26 18:20:59.406884 | instance | ok
2026-07-26 18:20:59.413884 | 
2026-07-26 18:20:59.413950 | TASK [Load selective CI plan or full fallback]
2026-07-26 18:20:59.470293 | instance | ok
2026-07-26 18:20:59.477702 | 
2026-07-26 18:20:59.477785 | TASK [Find this job in the selective CI plan]
2026-07-26 18:20:59.534169 | instance | ok
2026-07-26 18:20:59.538505 | 
2026-07-26 18:20:59.538566 | TASK [Resolve this job or use full fallback]
2026-07-26 18:20:59.589075 | instance | ok
2026-07-26 18:20:59.594606 | 
2026-07-26 18:20:59.594667 | TASK [Resolve deployment and verification inputs]
2026-07-26 18:20:59.653917 | instance | ok
2026-07-26 18:20:59.658285 | 
2026-07-26 18:20:59.658347 | TASK [Resolve deployment tags]
2026-07-26 18:20:59.718590 | instance | ok
2026-07-26 18:20:59.724571 | 
2026-07-26 18:20:59.724643 | TASK [Report Molecule job decision]
2026-07-26 18:20:59.758006 | instance | ok: {'job': 'keycloak', 'mode': 'selective', 'run': False, 'reason': "none of the job's verification profiles were requested", 'network_backend': 'openvswitch', 'csi_driver': 'rbd', 'components': [], 'verification_profiles': ['load-balancer', 'orchestration'], 'extra_vars': {}}
2026-07-26 18:20:59.765388 | 
2026-07-26 18:20:59.765451 | TASK [Copy inventory file for Zuul]
2026-07-26 18:20:59.781327 | instance | skipping: Conditional result was False
2026-07-26 18:20:59.790853 | 
2026-07-26 18:20:59.790941 | TASK [Switch ansible_host to private IP]
2026-07-26 18:20:59.807812 | instance | skipping: Conditional result was False
2026-07-26 18:20:59.815038 | 
2026-07-26 18:20:59.815128 | TASK [Prepare Molecule scenario]
2026-07-26 18:21:00.409043 | instance | skipping: Conditional result was False
2026-07-26 18:21:00.417295 | 
2026-07-26 18:21:00.417372 | TASK [Prepare host for Ceph-backed storage]
2026-07-26 18:21:00.957629 | instance | skipping: Conditional result was False
2026-07-26 18:21:00.965279 | 
2026-07-26 18:21:00.965367 | TASK [Build deployment command]
2026-07-26 18:21:00.992409 | instance | skipping: Conditional result was False
2026-07-26 18:21:01.002120 | 
2026-07-26 18:21:01.002188 | TASK [Run initial deployment with progress heartbeat]
2026-07-26 18:21:01.026760 | instance | skipping: Conditional result was False
2026-07-26 18:21:01.038885 | 
2026-07-26 18:21:01.039107 | TASK [Run idempotence deployment with progress heartbeat]
2026-07-26 18:21:01.066088 | instance | skipping: Conditional result was False
2026-07-26 18:21:01.075682 | 
2026-07-26 18:21:01.075792 | TASK [Check for br-ex interface]
2026-07-26 18:21:01.607112 | instance | skipping: Conditional result was False
2026-07-26 18:21:01.617932 | 
2026-07-26 18:21:01.618005 | TASK [Add IP address to br-ex]
2026-07-26 18:21:02.145108 | instance | skipping: Conditional result was False
2026-07-26 18:21:02.153508 | 
2026-07-26 18:21:02.153603 | TASK [Set br-ex interface up]
2026-07-26 18:21:02.682305 | instance | skipping: Conditional result was False
2026-07-26 18:21:02.693745 | 
2026-07-26 18:21:02.693893 | TASK [Resolve Molecule verification command]
2026-07-26 18:21:02.719750 | instance | skipping: Conditional result was False
2026-07-26 18:21:02.730807 | 
2026-07-26 18:21:02.730872 | TASK [Run Molecule verification with progress heartbeat]
2026-07-26 18:21:02.755928 | instance | skipping: Conditional result was False
2026-07-26 18:21:02.757874 | 
2026-07-26 18:21:02.757977 | PLAY RECAP
2026-07-26 18:21:02.758110 | instance | ok: 10 changed: 0 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2026-07-26 18:21:02.758162 | 
2026-07-26 18:21:02.918872 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 18:21:02.921673 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 18:21:03.575318 | 
2026-07-26 18:21:03.575447 | PLAY [all]
2026-07-26 18:21:03.589306 | 
2026-07-26 18:21:03.589389 | TASK [gather-host-logs : creating directory for system status]
2026-07-26 18:21:03.938327 | instance | ok
2026-07-26 18:21:03.947657 | 
2026-07-26 18:21:03.947749 | TASK [gather-host-logs : Get logs for each host]
2026-07-26 18:21:04.290940 | instance | + systemd-cgls --full --all --no-pager
2026-07-26 18:21:04.301176 | instance | + ip addr
2026-07-26 18:21:04.302959 | instance | + ip route
2026-07-26 18:21:04.304316 | instance | + lsblk
2026-07-26 18:21:04.306363 | instance | + mount
2026-07-26 18:21:04.307598 | instance | + docker images
2026-07-26 18:21:04.307930 | instance | /bin/bash: line 7: docker: command not found
2026-07-26 18:21:04.308067 | instance | + brctl show
2026-07-26 18:21:04.308372 | instance | /bin/bash: line 8: brctl: command not found
2026-07-26 18:21:04.308547 | instance | + ps aux --sort=-%mem
2026-07-26 18:21:04.319568 | instance | + dpkg -l
2026-07-26 18:21:04.325107 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-26 18:21:04.325390 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-26 18:21:04.325433 | instance | /bin/bash: line 11: docker: command not found
2026-07-26 18:21:04.325667 | instance | + '[' '!' -z '' ']'
2026-07-26 18:21:04.486499 | instance | ok: Runtime: 0:00:00.039431
2026-07-26 18:21:04.493977 | 
2026-07-26 18:21:04.494047 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-26 18:21:05.117576 | instance | ERROR
2026-07-26 18:21:05.117910 | instance | {
2026-07-26 18:21:05.117941 | instance |   "msg": "Warning: Permanently added '199.204.45.144' (ED25519) to the list of known hosts.\r\nrsync: [generator] chown \"/var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/logs/instance/system\" failed: Invalid argument (22)\nrsync: [receiver] chown \"/var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/logs/instance/system/.ci-plan.json.UJelcm\" failed: Invalid argument (22)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [generator=3.2.7]\n",
2026-07-26 18:21:05.117984 | instance |   "rc": 23
2026-07-26 18:21:05.118006 | instance | }
2026-07-26 18:21:05.118033 | instance | ERROR: Ignoring Errors
2026-07-26 18:21:05.127303 | 
2026-07-26 18:21:05.127374 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-26 18:21:05.325151 | instance | changed: "values"
2026-07-26 18:21:05.477055 | instance | changed: "releases"
2026-07-26 18:21:05.494888 | 
2026-07-26 18:21:05.495049 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-26 18:21:05.703302 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-26 18:21:06.033210 | instance | ok: Runtime: 0:00:00.005704
2026-07-26 18:21:06.039010 | 
2026-07-26 18:21:06.039075 | TASK [helm-release-status : Downloads logs to executor]
2026-07-26 18:21:06.510415 | instance | changed:
2026-07-26 18:21:06.510681 | instance | cd+++++++++ helm/
2026-07-26 18:21:06.510744 | instance | cd+++++++++ helm/releases/
2026-07-26 18:21:06.510792 | instance | cd+++++++++ helm/values/
2026-07-26 18:21:06.520262 | 
2026-07-26 18:21:06.520323 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-26 18:21:06.706198 | instance | changed
2026-07-26 18:21:06.711399 | 
2026-07-26 18:21:06.711467 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-26 18:21:06.959626 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 18:21:06.959824 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 18:21:06.964643 | instance | environment: line 1: kubectl: command not found
2026-07-26 18:21:06.965494 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 18:21:06.967002 | instance | environment: line 1: kubectl: command not found
2026-07-26 18:21:06.967893 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 18:21:06.968487 | instance | environment: line 1: kubectl: command not found
2026-07-26 18:21:06.970002 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 18:21:06.970990 | instance | environment: line 1: kubectl: command not found
2026-07-26 18:21:06.972445 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 18:21:06.973304 | instance | environment: line 1: kubectl: command not found
2026-07-26 18:21:06.973749 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 18:21:07.286179 | instance | ok: Runtime: 0:00:00.023401
2026-07-26 18:21:07.292225 | 
2026-07-26 18:21:07.292289 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-26 18:21:07.480281 | instance | changed
2026-07-26 18:21:07.484991 | 
2026-07-26 18:21:07.485059 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-26 18:21:07.689438 | instance | environment: line 5: kubectl: command not found
2026-07-26 18:21:07.690460 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 18:21:07.690718 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 18:21:07.690836 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 18:21:08.020079 | instance | ok: Runtime: 0:00:00.008051
2026-07-26 18:21:08.026888 | 
2026-07-26 18:21:08.026948 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-26 18:21:08.488534 | instance | changed:
2026-07-26 18:21:08.488930 | instance | cd+++++++++ objects/
2026-07-26 18:21:08.488976 | instance | cd+++++++++ objects/cluster/
2026-07-26 18:21:08.489000 | instance | cd+++++++++ objects/namespaced/
2026-07-26 18:21:08.498408 | 
2026-07-26 18:21:08.498468 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-26 18:21:08.685144 | instance | changed
2026-07-26 18:21:08.689943 | 
2026-07-26 18:21:08.690006 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-26 18:21:08.886080 | instance | changed
2026-07-26 18:21:08.892281 | 
2026-07-26 18:21:08.892343 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-26 18:21:09.095671 | instance | environment: line 3: kubectl: command not found
2026-07-26 18:21:09.425037 | instance | ok: Runtime: 0:00:00.007980
2026-07-26 18:21:09.432370 | 
2026-07-26 18:21:09.432435 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-26 18:21:09.930165 | instance | changed:
2026-07-26 18:21:09.930369 | instance | cd+++++++++ pod-logs/
2026-07-26 18:21:09.930412 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-26 18:21:09.944610 | 
2026-07-26 18:21:09.944681 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-26 18:21:10.144984 | instance | changed
2026-07-26 18:21:10.150811 | 
2026-07-26 18:21:10.150875 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-26 18:21:10.347804 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 18:21:10.688058 | instance | ok: Runtime: 0:00:00.036582
2026-07-26 18:21:10.693368 | 
2026-07-26 18:21:10.693434 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-26 18:21:10.902097 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 18:21:10.933787 | instance | ceph-mgr endpoints:
2026-07-26 18:21:11.224123 | instance | ok: Runtime: 0:00:00.037620
2026-07-26 18:21:11.229056 | 
2026-07-26 18:21:11.229122 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-26 18:21:11.433281 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-26 18:21:11.765665 | instance | ok: Runtime: 0:00:00.036234
2026-07-26 18:21:11.773771 | 
2026-07-26 18:21:11.773845 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-26 18:21:12.246800 | instance | changed: cd+++++++++ prometheus/
2026-07-26 18:21:12.258129 | 
2026-07-26 18:21:12.258192 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-26 18:21:12.454191 | instance | changed
2026-07-26 18:21:12.459295 | 
2026-07-26 18:21:12.459364 | TASK [gather-selenium-data : Get selenium data]
2026-07-26 18:21:12.705720 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-26 18:21:12.707248 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-26 18:21:13.041956 | instance | ERROR
2026-07-26 18:21:13.042283 | instance | {
2026-07-26 18:21:13.042356 | instance |   "delta": "0:00:00.006128",
2026-07-26 18:21:13.042405 | instance |   "end": "2026-07-26 18:21:12.707614",
2026-07-26 18:21:13.042450 | instance |   "msg": "non-zero return code",
2026-07-26 18:21:13.042499 | instance |   "rc": 1,
2026-07-26 18:21:13.042545 | instance |   "start": "2026-07-26 18:21:12.701486"
2026-07-26 18:21:13.042591 | instance | }
2026-07-26 18:21:13.042653 | instance | ERROR: Ignoring Errors
2026-07-26 18:21:13.049067 | 
2026-07-26 18:21:13.049155 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-26 18:21:13.522417 | instance | changed: cd+++++++++ selenium/
2026-07-26 18:21:13.528254 | 
2026-07-26 18:21:13.528302 | PLAY RECAP
2026-07-26 18:21:13.528360 | instance | ok: 23 changed: 21 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-07-26 18:21:13.528382 | 
2026-07-26 18:21:13.648116 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 18:21:13.654697 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 18:21:14.233681 | 
2026-07-26 18:21:14.233809 | PLAY [all]
2026-07-26 18:21:14.244735 | 
2026-07-26 18:21:14.244807 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-26 18:21:14.289955 | instance | skipping: Conditional result was False
2026-07-26 18:21:14.295264 | 
2026-07-26 18:21:14.295339 | TASK [fetch-output : Set log path for single node]
2026-07-26 18:21:14.339693 | instance | ok
2026-07-26 18:21:14.346110 | 
2026-07-26 18:21:14.346200 | LOOP [fetch-output : Ensure local output dirs]
2026-07-26 18:21:14.774645 | instance -> localhost | ok: "/var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/logs"
2026-07-26 18:21:14.977714 | instance -> localhost | changed: "/var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/artifacts"
2026-07-26 18:21:15.183601 | instance -> localhost | changed: "/var/lib/zuul/builds/dced5ba7f174414db447de96772ff864/work/docs"
2026-07-26 18:21:15.203751 | 
2026-07-26 18:21:15.203899 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-26 18:21:15.803359 | instance | changed: .d..t...... ./
2026-07-26 18:21:15.803630 | instance | changed: All items complete
2026-07-26 18:21:15.803669 | 
2026-07-26 18:21:16.237096 | instance | changed: .d..t...... ./
2026-07-26 18:21:16.665212 | instance | changed: .d..t...... ./
2026-07-26 18:21:16.692308 | 
2026-07-26 18:21:16.693151 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-26 18:21:17.093360 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006202
2026-07-26 18:21:17.314929 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007516
2026-07-26 18:21:17.338586 | 
2026-07-26 18:21:17.338700 | PLAY [all]
2026-07-26 18:21:17.358742 | 
2026-07-26 18:21:17.358807 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-26 18:21:17.767494 | instance | changed
2026-07-26 18:21:17.772864 | 
2026-07-26 18:21:17.772914 | PLAY RECAP
2026-07-26 18:21:17.772978 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 18:21:17.773003 | 
2026-07-26 18:21:17.919742 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 18:21:17.925924 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-26 18:21:18.493294 | 
2026-07-26 18:21:18.493880 | PLAY [localhost]
2026-07-26 18:21:18.503804 | 
2026-07-26 18:21:18.503872 | TASK [Generate Zuul manifest]
2026-07-26 18:21:18.526238 | localhost | ok
2026-07-26 18:21:18.541879 | 
2026-07-26 18:21:18.541954 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-26 18:21:18.905083 | localhost | changed
2026-07-26 18:21:18.915997 | 
2026-07-26 18:21:18.916069 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-26 18:21:18.947224 | localhost | ok
2026-07-26 18:21:18.957202 | 
2026-07-26 18:21:18.957291 | TASK [Upload logs]
2026-07-26 18:21:18.978110 | localhost | ok
2026-07-26 18:21:19.036604 | 
2026-07-26 18:21:19.036688 | TASK [Set zuul-log-path fact]
2026-07-26 18:21:19.057794 | localhost | ok
2026-07-26 18:21:19.071871 | 
2026-07-26 18:21:19.071935 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 18:21:19.103088 | localhost | ok
2026-07-26 18:21:19.113507 | 
2026-07-26 18:21:19.113569 | TASK [upload-logs : Create log directories]
2026-07-26 18:21:19.551345 | localhost | changed
2026-07-26 18:21:19.555917 | 
2026-07-26 18:21:19.555987 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-26 18:21:19.972752 | localhost -> localhost | ok: Runtime: 0:00:00.004697
2026-07-26 18:21:19.981800 | 
2026-07-26 18:21:19.981876 | TASK [upload-logs : Upload logs to log server]
2026-07-26 18:21:20.431171 | localhost | Output suppressed because no_log was given
2026-07-26 18:21:20.436443 | 
2026-07-26 18:21:20.436508 | LOOP [upload-logs : Compress console log and json output]
2026-07-26 18:21:20.483927 | localhost | skipping: Conditional result was False
2026-07-26 18:21:20.491389 | localhost | skipping: Conditional result was False
2026-07-26 18:21:20.502796 | 
2026-07-26 18:21:20.502917 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-26 18:21:20.547240 | localhost | skipping: Conditional result was False
2026-07-26 18:21:20.547586 | 
2026-07-26 18:21:20.551298 | localhost | skipping: Conditional result was False
2026-07-26 18:21:20.566768 | 
2026-07-26 18:21:20.566910 | LOOP [upload-logs : Upload console log and json output]