2026-07-28 05:14:49.156074 | Job console starting
2026-07-28 05:14:49.165432 | Updating git repos
2026-07-28 05:14:53.121733 | Cloning repos into workspace
2026-07-28 05:15:13.268128 | Restoring repo states
2026-07-28 05:15:23.293884 | Merging changes
2026-07-28 05:15:24.444587 | Checking out repos
2026-07-28 05:15:26.880657 | Preparing playbooks
2026-07-28 05:15:30.332000 | Running Ansible setup
2026-07-28 05:15:37.596886 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-28 05:15:38.242958 | 
2026-07-28 05:15:38.243082 | PLAY [localhost]
2026-07-28 05:15:38.250595 | 
2026-07-28 05:15:38.250666 | TASK [Gathering Facts]
2026-07-28 05:15:39.194896 | localhost | ok
2026-07-28 05:15:39.204897 | 
2026-07-28 05:15:39.205018 | TASK [Setup log path fact]
2026-07-28 05:15:39.225355 | localhost | ok
2026-07-28 05:15:39.237741 | 
2026-07-28 05:15:39.237809 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-28 05:15:39.267007 | localhost | ok
2026-07-28 05:15:39.275743 | 
2026-07-28 05:15:39.275838 | TASK [emit-job-header : Print job information]
2026-07-28 05:15:39.316275 | # Job Information
2026-07-28 05:15:39.316487 | Ansible Version: 2.16.18
2026-07-28 05:15:39.316533 | Job: atmosphere-molecule-aio-ovn-selective
2026-07-28 05:15:39.316565 | Pipeline: check
2026-07-28 05:15:39.316594 | Executor: 2d72f0692154
2026-07-28 05:15:39.316621 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4152
2026-07-28 05:15:39.316654 | Event ID: f82624c0-8a42-11f1-86dd-541e7fd58861
2026-07-28 05:15:39.320802 | 
2026-07-28 05:15:39.320894 | LOOP [emit-job-header : Print node information]
2026-07-28 05:15:39.413808 | localhost | ok:
2026-07-28 05:15:39.414052 | localhost | # Node Information
2026-07-28 05:15:39.414092 | localhost | Inventory Hostname: instance
2026-07-28 05:15:39.414122 | localhost | Hostname: np0000187240
2026-07-28 05:15:39.414149 | localhost | Username: zuul
2026-07-28 05:15:39.414180 | localhost | Distro: Ubuntu 22.04
2026-07-28 05:15:39.414207 | localhost | Provider: yul1
2026-07-28 05:15:39.414234 | localhost | Region: ca-ymq-1
2026-07-28 05:15:39.414260 | localhost | Label: ubuntu-jammy-16
2026-07-28 05:15:39.414285 | localhost | Product Name: OpenStack Nova
2026-07-28 05:15:39.414310 | localhost | Interface IP: 199.204.45.77
2026-07-28 05:15:39.426933 | 
2026-07-28 05:15:39.427088 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-28 05:15:39.826818 | localhost -> localhost | changed
2026-07-28 05:15:39.835736 | 
2026-07-28 05:15:39.835809 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-28 05:15:40.764730 | localhost -> localhost | changed
2026-07-28 05:15:40.773265 | 
2026-07-28 05:15:40.773340 | PLAY [all]
2026-07-28 05:15:40.784222 | 
2026-07-28 05:15:40.784292 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-28 05:15:41.040386 | instance -> localhost | ok
2026-07-28 05:15:41.048025 | 
2026-07-28 05:15:41.048109 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-28 05:15:41.081703 | instance | ok
2026-07-28 05:15:41.096746 | instance | included: /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-28 05:15:41.102335 | 
2026-07-28 05:15:41.102394 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-28 05:15:42.007226 | instance -> localhost | Generating public/private rsa key pair.
2026-07-28 05:15:42.007443 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/work/a711eb6c274642baa207af412ad7115e_id_rsa
2026-07-28 05:15:42.007486 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/work/a711eb6c274642baa207af412ad7115e_id_rsa.pub
2026-07-28 05:15:42.007520 | instance -> localhost | The key fingerprint is:
2026-07-28 05:15:42.007551 | instance -> localhost | SHA256:CD4e7QhrTBEic9J/2VyQR8R+0qktQJova1wkHH8KouA zuul-build-sshkey
2026-07-28 05:15:42.007597 | instance -> localhost | The key's randomart image is:
2026-07-28 05:15:42.007627 | instance -> localhost | +---[RSA 3072]----+
2026-07-28 05:15:42.007664 | instance -> localhost | |=.o     .*o      |
2026-07-28 05:15:42.007696 | instance -> localhost | |.=..  . o +      |
2026-07-28 05:15:42.007726 | instance -> localhost | |  .... X + . .   |
2026-07-28 05:15:42.007759 | instance -> localhost | |.  oooO.* + +    |
2026-07-28 05:15:42.007789 | instance -> localhost | |..o.+oo=S+ =     |
2026-07-28 05:15:42.007818 | instance -> localhost | | E.+ =. + o .    |
2026-07-28 05:15:42.007846 | instance -> localhost | |  + o..+   .     |
2026-07-28 05:15:42.007874 | instance -> localhost | | .    +          |
2026-07-28 05:15:42.007906 | instance -> localhost | |     .           |
2026-07-28 05:15:42.007936 | instance -> localhost | +----[SHA256]-----+
2026-07-28 05:15:42.008004 | instance -> localhost | ok: Runtime: 0:00:00.511624
2026-07-28 05:15:42.014860 | 
2026-07-28 05:15:42.014926 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-28 05:15:42.047421 | instance | ok
2026-07-28 05:15:42.057154 | instance | included: /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-28 05:15:42.064646 | 
2026-07-28 05:15:42.064717 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-28 05:15:42.090374 | instance | skipping: Conditional result was False
2026-07-28 05:15:42.101245 | 
2026-07-28 05:15:42.101315 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-28 05:15:42.531321 | instance | changed
2026-07-28 05:15:42.573626 | 
2026-07-28 05:15:42.573769 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-28 05:15:42.752436 | instance | ok
2026-07-28 05:15:42.758017 | 
2026-07-28 05:15:42.758085 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-28 05:15:43.231245 | instance | changed
2026-07-28 05:15:43.237042 | 
2026-07-28 05:15:43.237111 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-28 05:15:43.700576 | instance | changed
2026-07-28 05:15:43.708202 | 
2026-07-28 05:15:43.708322 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-28 05:15:43.734620 | instance | skipping: Conditional result was False
2026-07-28 05:15:43.745479 | 
2026-07-28 05:15:43.745551 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-28 05:15:44.068687 | instance -> localhost | changed
2026-07-28 05:15:44.083542 | 
2026-07-28 05:15:44.083607 | TASK [add-build-sshkey : Add back temp key]
2026-07-28 05:15:44.361615 | instance -> localhost | Identity added: /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/work/a711eb6c274642baa207af412ad7115e_id_rsa (zuul-build-sshkey)
2026-07-28 05:15:44.361825 | instance -> localhost | ok: Runtime: 0:00:00.015048
2026-07-28 05:15:44.367528 | 
2026-07-28 05:15:44.367591 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-28 05:15:44.626983 | instance | ok
2026-07-28 05:15:44.634217 | 
2026-07-28 05:15:44.650936 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-28 05:15:44.677727 | instance | skipping: Conditional result was False
2026-07-28 05:15:44.691398 | 
2026-07-28 05:15:44.691460 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-28 05:15:44.957809 | instance | ok
2026-07-28 05:15:44.965505 | 
2026-07-28 05:15:44.965566 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-28 05:15:49.491928 | instance | Output suppressed because no_log was given
2026-07-28 05:15:49.503310 | 
2026-07-28 05:15:49.503376 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-28 05:15:49.697535 | instance | ok: "logs"
2026-07-28 05:15:49.697780 | instance | ok: All items complete
2026-07-28 05:15:49.697808 | 
2026-07-28 05:15:49.846908 | instance | ok: "artifacts"
2026-07-28 05:15:49.998039 | instance | ok: "docs"
2026-07-28 05:15:50.009817 | 
2026-07-28 05:15:50.009962 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-28 05:15:50.207656 | instance | changed: "logs"
2026-07-28 05:15:50.365538 | instance | changed: "artifacts"
2026-07-28 05:15:50.524858 | instance | changed: "docs"
2026-07-28 05:15:50.545497 | 
2026-07-28 05:15:50.545590 | PLAY RECAP
2026-07-28 05:15:50.545635 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-28 05:15:50.545663 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:15:50.545685 | 
2026-07-28 05:15:50.723632 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-28 05:15:50.730131 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-28 05:15:51.338008 | 
2026-07-28 05:15:51.338128 | PLAY [all]
2026-07-28 05:15:51.349600 | 
2026-07-28 05:15:51.349681 | TASK [setup-uv : Extract archive]
2026-07-28 05:15:53.885515 | instance | changed
2026-07-28 05:15:53.891485 | 
2026-07-28 05:15:53.891567 | TASK [setup-uv : Print version]
2026-07-28 05:15:54.235798 | instance | uv 0.8.13
2026-07-28 05:15:54.436086 | instance | ok: Runtime: 0:00:00.014373
2026-07-28 05:15:54.449179 | 
2026-07-28 05:15:54.449262 | TASK [Install Ansible collection siblings]
2026-07-28 05:16:03.340223 | instance | changed:
2026-07-28 05:16:03.340413 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-28 05:16:03.340454 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-28 05:16:03.340484 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-28 05:16:03.340513 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-28 05:16:03.340540 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-28 05:16:03.340571 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-28 05:16:03.340600 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-28 05:16:03.340629 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-28 05:16:03.340657 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-28 05:16:03.340685 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-28 05:16:03.340711 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-28 05:16:03.340739 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-28 05:16:03.340771 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-28 05:16:03.340803 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-28 05:16:03.340836 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-28 05:16:03.340867 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-28 05:16:03.340899 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-28 05:16:03.340930 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-28 05:16:03.340989 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-28 05:16:03.341028 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-28 05:16:03.341053 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-28 05:16:03.341084 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-28 05:16:03.341110 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-28 05:16:03.341158 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-28 05:16:03.341193 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-28 05:16:03.341218 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-28 05:16:03.341243 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-28 05:16:03.341268 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-28 05:16:03.341293 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-28 05:16:03.341317 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-28 05:16:03.341342 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-28 05:16:03.341368 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-28 05:16:03.341403 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-28 05:16:03.341429 | 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-28 05:16:03.341463 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.341489 | instance | Process install dependency map
2026-07-28 05:16:03.341514 | instance | Starting collection install process
2026-07-28 05:16:03.341550 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-28 05:16:03.341576 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-28 05:16:03.341607 | instance | ansible.netcommon:* was installed successfully
2026-07-28 05:16:03.341634 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-28 05:16:03.341659 | instance | Creating virtual environment at: .venv
2026-07-28 05:16:03.341684 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-28 05:16:03.341708 | instance | Downloading pygments (1.2MiB)
2026-07-28 05:16:03.341741 | instance | Downloading netaddr (2.2MiB)
2026-07-28 05:16:03.341771 | instance | Downloading pydantic-core (2.0MiB)
2026-07-28 05:16:03.341797 | instance | Downloading ansible-core (2.1MiB)
2026-07-28 05:16:03.341823 | instance | Downloading openstacksdk (1.7MiB)
2026-07-28 05:16:03.341848 | instance | Downloading rjsonnet (1.2MiB)
2026-07-28 05:16:03.341873 | instance | Downloading cryptography (4.2MiB)
2026-07-28 05:16:03.341909 | instance | Downloading kubernetes (1.9MiB)
2026-07-28 05:16:03.341942 | instance | Downloading setuptools (1.1MiB)
2026-07-28 05:16:03.341967 | instance |    Building pyperclip==1.9.0
2026-07-28 05:16:03.341994 | instance |  Downloading rjsonnet
2026-07-28 05:16:03.342019 | instance |  Downloading pydantic-core
2026-07-28 05:16:03.342045 | instance |  Downloading netaddr
2026-07-28 05:16:03.342070 | instance |  Downloading pygments
2026-07-28 05:16:03.342095 | instance |  Downloading cryptography
2026-07-28 05:16:03.342119 | instance |  Downloading setuptools
2026-07-28 05:16:03.342151 | instance |  Downloading kubernetes
2026-07-28 05:16:03.342176 | instance |  Downloading ansible-core
2026-07-28 05:16:03.342202 | instance |  Downloading openstacksdk
2026-07-28 05:16:03.342227 | instance |       Built pyperclip==1.9.0
2026-07-28 05:16:03.342252 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-28 05:16:03.342278 | instance | Installed 83 packages in 71ms
2026-07-28 05:16:03.342302 | instance | Checked out ansible.posix to 1.6.0
2026-07-28 05:16:03.342328 | 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-28 05:16:03.342353 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.342378 | instance | Process install dependency map
2026-07-28 05:16:03.342410 | instance | Starting collection install process
2026-07-28 05:16:03.342448 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-28 05:16:03.342475 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-28 05:16:03.342507 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-28 05:16:03.342533 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.342558 | instance | 2.17.12
2026-07-28 05:16:03.342583 | instance | Checked out ansible.utils to v6.0.3
2026-07-28 05:16:03.342608 | 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-28 05:16:03.342633 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.342658 | instance | Process install dependency map
2026-07-28 05:16:03.342682 | instance | Starting collection install process
2026-07-28 05:16:03.342712 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-28 05:16:03.342750 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-28 05:16:03.342784 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-28 05:16:03.342830 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.342859 | instance | 2.17.12
2026-07-28 05:16:03.342885 | instance | Checked out atmosphere.common to v0.7.0
2026-07-28 05:16:03.342911 | 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-28 05:16:03.342939 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.342964 | instance | Process install dependency map
2026-07-28 05:16:03.343002 | instance | Starting collection install process
2026-07-28 05:16:03.343031 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-28 05:16:03.343058 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-28 05:16:03.343084 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-28 05:16:03.343111 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.343150 | instance | 2.17.12
2026-07-28 05:16:03.343182 | instance | Checked out community.crypto to 2.2.3
2026-07-28 05:16:03.343217 | 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-28 05:16:03.343246 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.343288 | instance | Process install dependency map
2026-07-28 05:16:03.343315 | instance | Starting collection install process
2026-07-28 05:16:03.343342 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-28 05:16:03.343383 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-28 05:16:03.343421 | instance | community.crypto:2.2.3 was installed successfully
2026-07-28 05:16:03.343450 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.343475 | instance | 2.17.12
2026-07-28 05:16:03.343501 | instance | Checked out community.general to 7.3.0
2026-07-28 05:16:03.343527 | 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-28 05:16:03.343561 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.343590 | instance | Process install dependency map
2026-07-28 05:16:03.343643 | instance | Starting collection install process
2026-07-28 05:16:03.343672 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-28 05:16:03.343698 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-28 05:16:03.343725 | instance | community.general:7.3.0 was installed successfully
2026-07-28 05:16:03.343767 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.343793 | instance | 2.17.12
2026-07-28 05:16:03.343828 | instance | Checked out community.mysql to 3.6.0
2026-07-28 05:16:03.343868 | 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-28 05:16:03.343905 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.343933 | instance | Process install dependency map
2026-07-28 05:16:03.343959 | instance | Starting collection install process
2026-07-28 05:16:03.343986 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-28 05:16:03.344013 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-28 05:16:03.344040 | instance | community.mysql:3.6.0 was installed successfully
2026-07-28 05:16:03.344067 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.344093 | instance | 2.17.12
2026-07-28 05:16:03.344120 | instance | Checked out kubernetes.core to 2.4.0
2026-07-28 05:16:03.344159 | 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-28 05:16:03.344187 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.344214 | instance | Process install dependency map
2026-07-28 05:16:03.344240 | instance | Starting collection install process
2026-07-28 05:16:03.344267 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-28 05:16:03.344293 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-28 05:16:03.344320 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-28 05:16:03.344347 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.344395 | instance | 2.17.12
2026-07-28 05:16:03.344422 | instance | Checked out openstack.cloud to 2.6.0
2026-07-28 05:16:03.344449 | 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-28 05:16:03.344476 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.344503 | instance | Process install dependency map
2026-07-28 05:16:03.344528 | instance | Starting collection install process
2026-07-28 05:16:03.344555 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-28 05:16:03.344581 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-28 05:16:03.344607 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-28 05:16:03.344634 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.344660 | instance | 2.17.12
2026-07-28 05:16:03.344695 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-28 05:16:03.344738 | 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-28 05:16:03.344772 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.344799 | instance | Process install dependency map
2026-07-28 05:16:03.344837 | instance | Starting collection install process
2026-07-28 05:16:03.344871 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-28 05:16:03.344898 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-28 05:16:03.344924 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-28 05:16:03.344949 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.344998 | instance | 2.17.12
2026-07-28 05:16:03.345040 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-28 05:16:03.345069 | 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-28 05:16:03.345096 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.345123 | instance | Process install dependency map
2026-07-28 05:16:03.345149 | instance | Starting collection install process
2026-07-28 05:16:03.345175 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-28 05:16:03.345201 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-28 05:16:03.345227 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-28 05:16:03.345253 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.345279 | instance | 2.17.12
2026-07-28 05:16:03.345305 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-28 05:16:03.345330 | 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-28 05:16:03.345357 | instance | Starting galaxy collection install process
2026-07-28 05:16:03.345382 | instance | Process install dependency map
2026-07-28 05:16:03.345407 | instance | Starting collection install process
2026-07-28 05:16:03.345434 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-28 05:16:03.345459 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-28 05:16:03.345486 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-28 05:16:03.345512 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:03.345538 | instance | 2.17.12
2026-07-28 05:16:03.352621 | 
2026-07-28 05:16:03.352676 | PLAY RECAP
2026-07-28 05:16:03.352725 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:16:03.352746 | 
2026-07-28 05:16:03.478153 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-28 05:16:03.487737 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-28 05:16:04.154166 | 
2026-07-28 05:16:04.154317 | PLAY [all]
2026-07-28 05:16:04.166640 | 
2026-07-28 05:16:04.166723 | TASK [Install "jq" for log collection]
2026-07-28 05:19:32.036263 | instance | changed
2026-07-28 05:19:32.038539 | 
2026-07-28 05:19:32.038604 | PLAY RECAP
2026-07-28 05:19:32.038661 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:19:32.038737 | 
2026-07-28 05:19:32.158004 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-28 05:19:32.169154 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-28 05:19:32.825977 | 
2026-07-28 05:19:32.826531 | PLAY [Prepare selective Molecule plan]
2026-07-28 05:19:32.838947 | 
2026-07-28 05:19:32.839063 | TASK [Generate selective Molecule plan]
2026-07-28 05:19:33.384332 | instance | ok: Runtime: 0:00:00.164393
2026-07-28 05:19:33.389551 | 
2026-07-28 05:19:33.389642 | TASK [Render selective Molecule plan]
2026-07-28 05:19:33.663057 | instance | Selective Molecule plan: full
2026-07-28 05:19:33.663155 | instance | Changed files:
2026-07-28 05:19:33.663168 | instance |   M .zuul.yaml
2026-07-28 05:19:33.663177 | instance |   A atmosphere/ci/__init__.py
2026-07-28 05:19:33.663189 | instance |   A atmosphere/ci/molecule_plan.py
2026-07-28 05:19:33.663200 | instance |   A charts/patches/tempest/0002-mount-only-ca-certificate.patch
2026-07-28 05:19:33.663212 | instance |   A charts/patches/tempest/0003-use-ca-for-python-ssl.patch
2026-07-28 05:19:33.663225 | instance |   A charts/patches/tempest/0004-support-ca-only-identity-trust.patch
2026-07-28 05:19:33.663236 | instance |   M charts/tempest/templates/job-ks-user.yaml
2026-07-28 05:19:33.663245 | instance |   M charts/tempest/templates/job-run-tests.yaml
2026-07-28 05:19:33.663255 | instance |   M charts/tempest/values.yaml
2026-07-28 05:19:33.663264 | instance |   A ci/molecule-plan.yaml
2026-07-28 05:19:33.663273 | instance |   M doc/source/deploy/index.rst
2026-07-28 05:19:33.663282 | instance |   A doc/source/deploy/selective-ci.rst
2026-07-28 05:19:33.663291 | instance |   M molecule/aio/converge.yml
2026-07-28 05:19:33.663300 | instance |   M molecule/aio/prepare.yml
2026-07-28 05:19:33.663308 | instance |   M molecule/aio/verify.yml
2026-07-28 05:19:33.663317 | instance |   M playbooks/tempest.yml
2026-07-28 05:19:33.663326 | instance |   M pyproject.toml
2026-07-28 05:19:33.663335 | instance |   A releasenotes/notes/selective-molecule-ci-3233e7ee8348d528.yaml
2026-07-28 05:19:33.663344 | instance |   M roles/tempest/defaults/main.yml
2026-07-28 05:19:33.663352 | instance |   M roles/tempest/tasks/main.yml
2026-07-28 05:19:33.663361 | instance |   M roles/tempest/vars/main.yml
2026-07-28 05:19:33.663370 | instance |   A test-playbooks/molecule/poll-command.yml
2026-07-28 05:19:33.663379 | instance |   A test-playbooks/molecule/post.yml
2026-07-28 05:19:33.663392 | instance |   A test-playbooks/molecule/prepare.yml
2026-07-28 05:19:33.663401 | instance |   A test-playbooks/molecule/run-command.yml
2026-07-28 05:19:33.663409 | instance |   A test-playbooks/molecule/selective-run.yml
2026-07-28 05:19:33.663418 | instance |   A tests/unit/ci/__init__.py
2026-07-28 05:19:33.663427 | instance |   A tests/unit/ci/test_molecule_plan.py
2026-07-28 05:19:33.663436 | instance |   M uv.lock
2026-07-28 05:19:33.663445 | instance | Job decisions:
2026-07-28 05:19:33.663454 | instance |   RUN  aio-openvswitch: full fallback
2026-07-28 05:19:33.663462 | instance |   RUN  aio-ovn: full fallback
2026-07-28 05:19:33.663471 | instance |   RUN  csi-local-path-provisioner: full fallback
2026-07-28 05:19:33.663480 | instance |   RUN  csi-rbd: full fallback
2026-07-28 05:19:33.663489 | instance |   RUN  keycloak: full fallback
2026-07-28 05:19:33.663498 | instance | Reason: the CI selection policy changed
2026-07-28 05:19:33.663506 | instance | Reason: shared deployment code can affect unrelated components
2026-07-28 05:19:33.663515 | instance | Reason: operational workflows need the complete integration environment
2026-07-28 05:19:33.930215 | instance | ok: Runtime: 0:00:00.092013
2026-07-28 05:19:33.937426 | 
2026-07-28 05:19:33.937501 | TASK [Report selective Molecule plan]
2026-07-28 05:19:33.974345 | instance | ok:
2026-07-28 05:19:33.974555 | instance | Selective Molecule plan: full
2026-07-28 05:19:33.974604 | instance | Changed files:
2026-07-28 05:19:33.974630 | instance |   M .zuul.yaml
2026-07-28 05:19:33.974653 | instance |   A atmosphere/ci/__init__.py
2026-07-28 05:19:33.974677 | instance |   A atmosphere/ci/molecule_plan.py
2026-07-28 05:19:33.974709 | instance |   A charts/patches/tempest/0002-mount-only-ca-certificate.patch
2026-07-28 05:19:33.974743 | instance |   A charts/patches/tempest/0003-use-ca-for-python-ssl.patch
2026-07-28 05:19:33.974775 | instance |   A charts/patches/tempest/0004-support-ca-only-identity-trust.patch
2026-07-28 05:19:33.974796 | instance |   M charts/tempest/templates/job-ks-user.yaml
2026-07-28 05:19:33.974818 | instance |   M charts/tempest/templates/job-run-tests.yaml
2026-07-28 05:19:33.974839 | instance |   M charts/tempest/values.yaml
2026-07-28 05:19:33.974860 | instance |   A ci/molecule-plan.yaml
2026-07-28 05:19:33.974882 | instance |   M doc/source/deploy/index.rst
2026-07-28 05:19:33.974903 | instance |   A doc/source/deploy/selective-ci.rst
2026-07-28 05:19:33.974927 | instance |   M molecule/aio/converge.yml
2026-07-28 05:19:33.974952 | instance |   M molecule/aio/prepare.yml
2026-07-28 05:19:33.974979 | instance |   M molecule/aio/verify.yml
2026-07-28 05:19:33.975007 | instance |   M playbooks/tempest.yml
2026-07-28 05:19:33.975028 | instance |   M pyproject.toml
2026-07-28 05:19:33.975050 | instance |   A releasenotes/notes/selective-molecule-ci-3233e7ee8348d528.yaml
2026-07-28 05:19:33.975071 | instance |   M roles/tempest/defaults/main.yml
2026-07-28 05:19:33.975092 | instance |   M roles/tempest/tasks/main.yml
2026-07-28 05:19:33.975113 | instance |   M roles/tempest/vars/main.yml
2026-07-28 05:19:33.975135 | instance |   A test-playbooks/molecule/poll-command.yml
2026-07-28 05:19:33.975156 | instance |   A test-playbooks/molecule/post.yml
2026-07-28 05:19:33.975177 | instance |   A test-playbooks/molecule/prepare.yml
2026-07-28 05:19:33.975197 | instance |   A test-playbooks/molecule/run-command.yml
2026-07-28 05:19:33.975218 | instance |   A test-playbooks/molecule/selective-run.yml
2026-07-28 05:19:33.975238 | instance |   A tests/unit/ci/__init__.py
2026-07-28 05:19:33.975263 | instance |   A tests/unit/ci/test_molecule_plan.py
2026-07-28 05:19:33.975284 | instance |   M uv.lock
2026-07-28 05:19:33.975305 | instance | Job decisions:
2026-07-28 05:19:33.975325 | instance |   RUN  aio-openvswitch: full fallback
2026-07-28 05:19:33.975352 | instance |   RUN  aio-ovn: full fallback
2026-07-28 05:19:33.975373 | instance |   RUN  csi-local-path-provisioner: full fallback
2026-07-28 05:19:33.975394 | instance |   RUN  csi-rbd: full fallback
2026-07-28 05:19:33.975414 | instance |   RUN  keycloak: full fallback
2026-07-28 05:19:33.975435 | instance | Reason: the CI selection policy changed
2026-07-28 05:19:33.975455 | instance | Reason: shared deployment code can affect unrelated components
2026-07-28 05:19:33.975476 | instance | Reason: operational workflows need the complete integration environment
2026-07-28 05:19:33.977113 | 
2026-07-28 05:19:33.977174 | PLAY RECAP
2026-07-28 05:19:33.977226 | instance | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:19:33.977254 | 
2026-07-28 05:19:34.136203 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-28 05:19:34.146563 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-28 05:19:34.769406 | 
2026-07-28 05:19:34.769525 | PLAY [Run selected Molecule scenario]
2026-07-28 05:19:34.781035 | 
2026-07-28 05:19:34.781114 | TASK [Read selective Molecule plan]
2026-07-28 05:19:35.127967 | instance | ok
2026-07-28 05:19:35.134535 | 
2026-07-28 05:19:35.134631 | TASK [Resolve this job decision]
2026-07-28 05:19:35.188304 | instance | ok
2026-07-28 05:19:35.198280 | 
2026-07-28 05:19:35.198341 | TASK [Assert this job exists in the plan]
2026-07-28 05:19:35.231870 | instance | ok: All assertions passed
2026-07-28 05:19:35.236649 | 
2026-07-28 05:19:35.236718 | TASK [Resolve selected scenario inputs]
2026-07-28 05:19:35.270423 | instance | ok
2026-07-28 05:19:35.276913 | 
2026-07-28 05:19:35.276994 | TASK [Report this job decision]
2026-07-28 05:19:35.341268 | instance | ok:
2026-07-28 05:19:35.341439 | instance | Selective Molecule job: aio-ovn
2026-07-28 05:19:35.341467 | instance | Decision: RUN
2026-07-28 05:19:35.341491 | instance | Reason: full fallback
2026-07-28 05:19:35.341515 | instance | Scenario: aio
2026-07-28 05:19:35.341536 | instance | Targets: (none)
2026-07-28 05:19:35.341556 | instance | Components: (full scenario)
2026-07-28 05:19:35.346918 | 
2026-07-28 05:19:35.347007 | TASK [Prepare Molecule command]
2026-07-28 05:19:35.412620 | instance | ok
2026-07-28 05:19:35.417335 | 
2026-07-28 05:19:35.417399 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-28 05:19:35.461014 | instance | ok
2026-07-28 05:19:35.470028 | instance | included: /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/run-command.yml
2026-07-28 05:19:35.475590 | 
2026-07-28 05:19:35.475652 | TASK [Create Molecule log directory]
2026-07-28 05:19:35.794497 | instance | changed
2026-07-28 05:19:35.800891 | 
2026-07-28 05:19:35.800998 | TASK [Resolve live Molecule log path]
2026-07-28 05:19:35.835108 | instance | ok
2026-07-28 05:19:35.844267 | 
2026-07-28 05:19:35.844342 | TASK [Start Molecule aio (aio-ovn)]
2026-07-28 05:19:36.533075 | instance | ok: ERROR (ignored)
2026-07-28 05:19:36.533351 | instance | {
2026-07-28 05:19:36.533392 | instance |   "ansible_job_id": "j472831559609.1554",
2026-07-28 05:19:36.533421 | instance |   "finished": 0,
2026-07-28 05:19:36.533447 | instance |   "results_file": "/home/zuul/.ansible_async/j472831559609.1554",
2026-07-28 05:19:36.533473 | instance |   "started": 1
2026-07-28 05:19:36.533498 | instance | }
2026-07-28 05:19:36.574324 | 
2026-07-28 05:19:36.574455 | TASK [Initialize Molecule aio (aio-ovn) progress monitor]
2026-07-28 05:19:36.629274 | instance | ok
2026-07-28 05:19:36.636876 | 
2026-07-28 05:19:36.636947 | TASK [Monitor Molecule aio (aio-ovn)]
2026-07-28 05:19:36.670756 | instance | ok
2026-07-28 05:19:36.682544 | instance | included: /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-28 05:19:36.695641 | 
2026-07-28 05:19:36.695906 | TASK [Wait before polling Molecule aio (aio-ovn)]
2026-07-28 05:19:56.755670 | instance | ok
2026-07-28 05:19:56.763839 | 
2026-07-28 05:19:56.763935 | TASK [Count Molecule aio (aio-ovn) progress poll]
2026-07-28 05:19:56.816065 | instance | ok
2026-07-28 05:19:56.823444 | 
2026-07-28 05:19:56.823510 | TASK [Poll Molecule aio (aio-ovn) status]
2026-07-28 05:19:57.161261 | instance | ok: non-zero return code
2026-07-28 05:19:57.168646 | 
2026-07-28 05:19:57.168717 | TASK [Inspect Molecule aio (aio-ovn) live log]
2026-07-28 05:19:57.442587 | instance | ok
2026-07-28 05:19:57.450096 | 
2026-07-28 05:19:57.450164 | TASK [Calculate new Molecule aio (aio-ovn) output]
2026-07-28 05:19:57.504584 | instance | ok
2026-07-28 05:19:57.512368 | 
2026-07-28 05:19:57.512437 | TASK [Read new Molecule aio (aio-ovn) output]
2026-07-28 05:19:58.040817 | instance | skipping: Conditional result was False
2026-07-28 05:19:58.053686 | 
2026-07-28 05:19:58.053764 | TASK [Print new Molecule aio (aio-ovn) output]
2026-07-28 05:19:58.097579 | instance | skipping: Conditional result was False
2026-07-28 05:19:58.110044 | 
2026-07-28 05:19:58.110114 | TASK [Advance Molecule aio (aio-ovn) live log offset]
2026-07-28 05:19:58.165773 | instance | ok
2026-07-28 05:19:58.171628 | 
2026-07-28 05:19:58.171697 | TASK [Report Molecule aio (aio-ovn) progress heartbeat]
2026-07-28 05:19:58.206990 | instance | skipping: Conditional result was False
2026-07-28 05:19:58.218979 | 
2026-07-28 05:19:58.219089 | TASK [Continue monitoring Molecule aio (aio-ovn)]
2026-07-28 05:19:58.263972 | instance | skipping: Conditional result was False
2026-07-28 05:19:58.274917 | 
2026-07-28 05:19:58.275012 | TASK [Clean up Molecule aio (aio-ovn) async state]
2026-07-28 05:19:58.472543 | instance | ok
2026-07-28 05:19:58.481378 | 
2026-07-28 05:19:58.481451 | TASK [Assert Molecule aio (aio-ovn) succeeded]
2026-07-28 05:19:58.536848 | instance | ERROR
2026-07-28 05:19:58.537120 | instance | {
2026-07-28 05:19:58.537167 | instance |   "assertion": "(_atmosphere_ci_phase_result.rc | default(1) | int) == 0",
2026-07-28 05:19:58.537205 | instance |   "evaluated_to": false,
2026-07-28 05:19:58.537232 | instance |   "msg": "Molecule aio (aio-ovn) failed with exit code 1. Complete output is in /tmp/logs/molecule-aio-ovn.log."
2026-07-28 05:19:58.537258 | instance | }
failure
2026-07-28 05:19:58.538624 | 
2026-07-28 05:19:58.538677 | PLAY RECAP
2026-07-28 05:19:58.538726 | instance | ok: 19 changed: 2 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0
2026-07-28 05:19:58.538748 | 
2026-07-28 05:19:58.679788 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-28 05:19:58.691830 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-28 05:19:59.331301 | 
2026-07-28 05:19:59.331487 | PLAY [Publish selective Molecule plan]
2026-07-28 05:19:59.344883 | 
2026-07-28 05:19:59.345232 | TASK [Check for selective Molecule plan]
2026-07-28 05:19:59.705604 | instance | ok
2026-07-28 05:19:59.711796 | 
2026-07-28 05:19:59.711870 | TASK [Create log directory]
2026-07-28 05:20:00.082610 | instance | ok
2026-07-28 05:20:00.088208 | 
2026-07-28 05:20:00.088277 | TASK [Copy selective Molecule plan to logs]
2026-07-28 05:20:00.499131 | instance | changed
2026-07-28 05:20:00.501015 | 
2026-07-28 05:20:00.501066 | PLAY RECAP
2026-07-28 05:20:00.501116 | instance | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:20:00.501138 | 
2026-07-28 05:20:00.685174 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-28 05:20:00.692126 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-28 05:20:01.330657 | 
2026-07-28 05:20:01.330796 | PLAY [all]
2026-07-28 05:20:01.344541 | 
2026-07-28 05:20:01.344618 | TASK [gather-host-logs : creating directory for system status]
2026-07-28 05:20:01.692538 | instance | changed
2026-07-28 05:20:01.698904 | 
2026-07-28 05:20:01.698985 | TASK [gather-host-logs : Get logs for each host]
2026-07-28 05:20:02.032729 | instance | + systemd-cgls --full --all --no-pager
2026-07-28 05:20:02.042265 | instance | + ip addr
2026-07-28 05:20:02.043806 | instance | + ip route
2026-07-28 05:20:02.047377 | instance | + lsblk
2026-07-28 05:20:02.049674 | instance | + mount
2026-07-28 05:20:02.051021 | instance | + docker images
2026-07-28 05:20:02.051445 | instance | /bin/bash: line 7: docker: command not found
2026-07-28 05:20:02.051679 | instance | + brctl show
2026-07-28 05:20:02.052053 | instance | /bin/bash: line 8: brctl: command not found
2026-07-28 05:20:02.052261 | instance | + ps aux --sort=-%mem
2026-07-28 05:20:02.065906 | instance | + dpkg -l
2026-07-28 05:20:02.072026 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-28 05:20:02.072571 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-28 05:20:02.072688 | instance | /bin/bash: line 11: docker: command not found
2026-07-28 05:20:02.073123 | instance | + '[' '!' -z '' ']'
2026-07-28 05:20:02.236595 | instance | ok: Runtime: 0:00:00.045362
2026-07-28 05:20:02.244453 | 
2026-07-28 05:20:02.244521 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-28 05:20:02.886805 | instance | changed:
2026-07-28 05:20:02.887006 | instance | created directory /var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/work/logs/instance
2026-07-28 05:20:02.887046 | instance | cd+++++++++ system/
2026-07-28 05:20:02.887077 | instance | >f+++++++++ system/brctl-show.txt
2026-07-28 05:20:02.887119 | instance | >f+++++++++ system/docker-images.txt
2026-07-28 05:20:02.887146 | instance | >f+++++++++ system/ip-addr.txt
2026-07-28 05:20:02.887178 | instance | >f+++++++++ system/ip-route.txt
2026-07-28 05:20:02.887206 | instance | >f+++++++++ system/lsblk.txt
2026-07-28 05:20:02.887233 | instance | >f+++++++++ system/mount.txt
2026-07-28 05:20:02.887262 | instance | >f+++++++++ system/packages.txt
2026-07-28 05:20:02.887289 | instance | >f+++++++++ system/ps.txt
2026-07-28 05:20:02.887315 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-28 05:20:02.898875 | 
2026-07-28 05:20:02.898949 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-28 05:20:03.115945 | instance | changed: "values"
2026-07-28 05:20:03.274369 | instance | changed: "releases"
2026-07-28 05:20:03.292197 | 
2026-07-28 05:20:03.293276 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-28 05:20:03.526804 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-28 05:20:03.864632 | instance | ok: Runtime: 0:00:00.006018
2026-07-28 05:20:03.871192 | 
2026-07-28 05:20:03.871262 | TASK [helm-release-status : Downloads logs to executor]
2026-07-28 05:20:04.368993 | instance | changed:
2026-07-28 05:20:04.369141 | instance | cd+++++++++ helm/
2026-07-28 05:20:04.369169 | instance | cd+++++++++ helm/releases/
2026-07-28 05:20:04.369190 | instance | cd+++++++++ helm/values/
2026-07-28 05:20:04.378547 | 
2026-07-28 05:20:04.378611 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-28 05:20:04.582404 | instance | changed
2026-07-28 05:20:04.589007 | 
2026-07-28 05:20:04.589077 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-28 05:20:04.791066 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:20:04.791164 | instance |
2026-07-28 05:20:04.796500 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:20:04.797798 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:20:04.799302 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:20:04.799904 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:20:04.802506 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:20:04.803747 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:20:04.803984 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:20:04.805177 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:20:04.806615 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:20:04.807934 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:20:05.124914 | instance | ok: Runtime: 0:00:00.026441
2026-07-28 05:20:05.130016 | 
2026-07-28 05:20:05.130086 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-28 05:20:05.327562 | instance | changed
2026-07-28 05:20:05.333945 | 
2026-07-28 05:20:05.334022 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-28 05:20:05.544260 | instance | environment: line 5: kubectl: command not found
2026-07-28 05:20:05.544926 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:20:05.545555 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:20:05.545712 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:20:05.870475 | instance | ok: Runtime: 0:00:00.009402
2026-07-28 05:20:05.876615 | 
2026-07-28 05:20:05.876685 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-28 05:20:06.385677 | instance | changed:
2026-07-28 05:20:06.385916 | instance | cd+++++++++ objects/
2026-07-28 05:20:06.385944 | instance | cd+++++++++ objects/cluster/
2026-07-28 05:20:06.385966 | instance | cd+++++++++ objects/namespaced/
2026-07-28 05:20:06.394848 | 
2026-07-28 05:20:06.394918 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-28 05:20:06.591657 | instance | changed
2026-07-28 05:20:06.597995 | 
2026-07-28 05:20:06.598062 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-28 05:20:06.784025 | instance | changed
2026-07-28 05:20:06.791434 | 
2026-07-28 05:20:06.791518 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-28 05:20:07.001439 | instance | environment: line 3: kubectl: command not found
2026-07-28 05:20:07.328106 | instance | ok: Runtime: 0:00:00.009688
2026-07-28 05:20:07.335160 | 
2026-07-28 05:20:07.335258 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-28 05:20:07.826770 | instance | changed:
2026-07-28 05:20:07.826925 | instance | cd+++++++++ pod-logs/
2026-07-28 05:20:07.826953 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-28 05:20:07.835674 | 
2026-07-28 05:20:07.835737 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-28 05:20:08.032693 | instance | changed
2026-07-28 05:20:08.038325 | 
2026-07-28 05:20:08.038393 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-28 05:20:08.240612 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-28 05:20:08.576337 | instance | ok: Runtime: 0:00:00.035046
2026-07-28 05:20:08.582802 | 
2026-07-28 05:20:08.582874 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-28 05:20:08.784260 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-28 05:20:08.813774 | instance | ceph-mgr endpoints:
2026-07-28 05:20:09.118636 | instance | ok: Runtime: 0:00:00.035988
2026-07-28 05:20:09.123658 | 
2026-07-28 05:20:09.123727 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-28 05:20:09.364060 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-28 05:20:09.695663 | instance | ok: Runtime: 0:00:00.035123
2026-07-28 05:20:09.702457 | 
2026-07-28 05:20:09.702529 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-28 05:20:10.181124 | instance | changed: cd+++++++++ prometheus/
2026-07-28 05:20:10.192624 | 
2026-07-28 05:20:10.192690 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-28 05:20:10.397639 | instance | changed
2026-07-28 05:20:10.404306 | 
2026-07-28 05:20:10.404402 | TASK [gather-selenium-data : Get selenium data]
2026-07-28 05:20:10.616366 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-28 05:20:10.617868 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-28 05:20:10.943648 | instance | ERROR
2026-07-28 05:20:10.943975 | instance | {
2026-07-28 05:20:10.944012 | instance |   "delta": "0:00:00.007504",
2026-07-28 05:20:10.944034 | instance |   "end": "2026-07-28 05:20:10.618265",
2026-07-28 05:20:10.944053 | instance |   "msg": "non-zero return code",
2026-07-28 05:20:10.944072 | instance |   "rc": 1,
2026-07-28 05:20:10.944091 | instance |   "start": "2026-07-28 05:20:10.610761"
2026-07-28 05:20:10.944108 | instance | }
2026-07-28 05:20:10.944133 | instance | ERROR: Ignoring Errors
2026-07-28 05:20:10.949369 | 
2026-07-28 05:20:10.949444 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-28 05:20:11.449226 | instance | changed: cd+++++++++ selenium/
2026-07-28 05:20:11.458616 | 
2026-07-28 05:20:11.458687 | PLAY RECAP
2026-07-28 05:20:11.458750 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-28 05:20:11.458780 | 
2026-07-28 05:20:11.584078 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-28 05:20:11.595893 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-28 05:20:12.204546 | 
2026-07-28 05:20:12.205002 | PLAY [all]
2026-07-28 05:20:12.216384 | 
2026-07-28 05:20:12.216465 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-28 05:20:12.261589 | instance | skipping: Conditional result was False
2026-07-28 05:20:12.272093 | 
2026-07-28 05:20:12.272174 | TASK [fetch-output : Set log path for single node]
2026-07-28 05:20:12.316171 | instance | ok
2026-07-28 05:20:12.320777 | 
2026-07-28 05:20:12.320846 | LOOP [fetch-output : Ensure local output dirs]
2026-07-28 05:20:12.714391 | instance -> localhost | ok: "/var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/work/logs"
2026-07-28 05:20:12.927580 | instance -> localhost | changed: "/var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/work/artifacts"
2026-07-28 05:20:13.132322 | instance -> localhost | changed: "/var/lib/zuul/builds/a711eb6c274642baa207af412ad7115e/work/docs"
2026-07-28 05:20:13.150212 | 
2026-07-28 05:20:13.150372 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-28 05:20:13.784022 | instance | changed: .d..t...... ./
2026-07-28 05:20:13.784322 | instance | changed: All items complete
2026-07-28 05:20:13.784361 | 
2026-07-28 05:20:14.216163 | instance | changed: .d..t...... ./
2026-07-28 05:20:14.676432 | instance | changed: .d..t...... ./
2026-07-28 05:20:14.701945 | 
2026-07-28 05:20:14.702093 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-28 05:20:15.095649 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007331
2026-07-28 05:20:15.306884 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007528
2026-07-28 05:20:15.321191 | 
2026-07-28 05:20:15.321975 | PLAY [all]
2026-07-28 05:20:15.328062 | 
2026-07-28 05:20:15.328140 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-28 05:20:15.746593 | instance | changed
2026-07-28 05:20:15.753890 | 
2026-07-28 05:20:15.753959 | PLAY RECAP
2026-07-28 05:20:15.754019 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-28 05:20:15.754049 | 
2026-07-28 05:20:15.872698 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-28 05:20:15.888057 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-28 05:20:16.473072 | 
2026-07-28 05:20:16.473288 | PLAY [localhost]
2026-07-28 05:20:16.482932 | 
2026-07-28 05:20:16.483008 | TASK [Generate Zuul manifest]
2026-07-28 05:20:16.503568 | localhost | ok
2026-07-28 05:20:16.521220 | 
2026-07-28 05:20:16.521298 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-28 05:20:17.063603 | localhost | changed
2026-07-28 05:20:17.075452 | 
2026-07-28 05:20:17.075525 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-28 05:20:17.106652 | localhost | ok
2026-07-28 05:20:17.122465 | 
2026-07-28 05:20:17.122717 | TASK [Upload logs]
2026-07-28 05:20:17.143410 | localhost | ok
2026-07-28 05:20:17.246022 | 
2026-07-28 05:20:17.246179 | TASK [Set zuul-log-path fact]
2026-07-28 05:20:17.269991 | localhost | ok
2026-07-28 05:20:17.283691 | 
2026-07-28 05:20:17.283759 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-28 05:20:17.314960 | localhost | ok
2026-07-28 05:20:17.324084 | 
2026-07-28 05:20:17.324173 | TASK [upload-logs : Create log directories]
2026-07-28 05:20:17.670333 | localhost | changed
2026-07-28 05:20:17.675939 | 
2026-07-28 05:20:17.676023 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-28 05:20:18.031849 | localhost -> localhost | ok: Runtime: 0:00:00.003800
2026-07-28 05:20:18.042645 | 
2026-07-28 05:20:18.042838 | TASK [upload-logs : Upload logs to log server]
2026-07-28 05:20:18.512317 | localhost | Output suppressed because no_log was given
2026-07-28 05:20:18.517958 | 
2026-07-28 05:20:18.518030 | LOOP [upload-logs : Compress console log and json output]
2026-07-28 05:20:18.564740 | localhost | skipping: Conditional result was False
2026-07-28 05:20:18.571911 | localhost | skipping: Conditional result was False
2026-07-28 05:20:18.582015 | 
2026-07-28 05:20:18.582202 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-28 05:20:18.626368 | localhost | skipping: Conditional result was False
2026-07-28 05:20:18.626653 | 
2026-07-28 05:20:18.630835 | localhost | skipping: Conditional result was False
2026-07-28 05:20:18.648113 | 
2026-07-28 05:20:18.648357 | LOOP [upload-logs : Upload console log and json output]