2026-07-30 20:56:20.201491 | Job console starting
2026-07-30 20:56:20.210441 | Updating git repos
2026-07-30 20:56:23.664095 | Cloning repos into workspace
2026-07-30 20:56:46.722793 | Restoring repo states
2026-07-30 20:56:59.535201 | Merging changes
2026-07-30 20:57:07.450714 | Checking out repos
2026-07-30 20:57:14.259344 | Preparing playbooks
2026-07-30 20:57:24.000969 | Running Ansible setup
2026-07-30 20:57:29.330326 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-30 20:57:30.020449 | 
2026-07-30 20:57:30.020576 | PLAY [localhost]
2026-07-30 20:57:30.028885 | 
2026-07-30 20:57:30.029003 | TASK [Gathering Facts]
2026-07-30 20:57:31.126637 | localhost | ok
2026-07-30 20:57:31.151112 | 
2026-07-30 20:57:31.153384 | TASK [Setup log path fact]
2026-07-30 20:57:31.177728 | localhost | ok
2026-07-30 20:57:31.193278 | 
2026-07-30 20:57:31.193412 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-30 20:57:31.227028 | localhost | ok
2026-07-30 20:57:31.235863 | 
2026-07-30 20:57:31.236028 | TASK [emit-job-header : Print job information]
2026-07-30 20:57:31.292653 | # Job Information
2026-07-30 20:57:31.293166 | Ansible Version: 2.16.18
2026-07-30 20:57:31.293247 | Job: atmosphere-molecule-aio-openvswitch-selective
2026-07-30 20:57:31.293300 | Pipeline: check
2026-07-30 20:57:31.293347 | Executor: 2d72f0692154
2026-07-30 20:57:31.293521 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4223
2026-07-30 20:57:31.293762 | Event ID: c441ee40-8c55-11f1-930d-178927dc4216
2026-07-30 20:57:31.303274 | 
2026-07-30 20:57:31.303499 | LOOP [emit-job-header : Print node information]
2026-07-30 20:57:31.430339 | localhost | ok:
2026-07-30 20:57:31.430547 | localhost | # Node Information
2026-07-30 20:57:31.430589 | localhost | Inventory Hostname: instance
2026-07-30 20:57:31.430670 | localhost | Hostname: np0000187674
2026-07-30 20:57:31.430711 | localhost | Username: zuul
2026-07-30 20:57:31.430744 | localhost | Distro: Ubuntu 22.04
2026-07-30 20:57:31.430772 | localhost | Provider: yul1
2026-07-30 20:57:31.430844 | localhost | Region: ca-ymq-1
2026-07-30 20:57:31.430876 | localhost | Label: ubuntu-jammy-16
2026-07-30 20:57:31.430903 | localhost | Product Name: OpenStack Nova
2026-07-30 20:57:31.430929 | localhost | Interface IP: 199.19.213.66
2026-07-30 20:57:31.449890 | 
2026-07-30 20:57:31.450002 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-30 20:57:31.882888 | localhost -> localhost | changed
2026-07-30 20:57:31.891823 | 
2026-07-30 20:57:31.892210 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-30 20:57:32.980036 | localhost -> localhost | changed
2026-07-30 20:57:32.992040 | 
2026-07-30 20:57:32.992150 | PLAY [all]
2026-07-30 20:57:33.000394 | 
2026-07-30 20:57:33.000482 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-30 20:57:33.307551 | instance -> localhost | ok
2026-07-30 20:57:33.317253 | 
2026-07-30 20:57:33.317358 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-30 20:57:33.347856 | instance | ok
2026-07-30 20:57:33.364150 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-30 20:57:33.372411 | 
2026-07-30 20:57:33.372514 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-30 20:57:34.493261 | instance -> localhost | Generating public/private rsa key pair.
2026-07-30 20:57:34.493437 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/work/4e31f735c7b14e26856e5a11b9c33b0c_id_rsa
2026-07-30 20:57:34.493481 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/work/4e31f735c7b14e26856e5a11b9c33b0c_id_rsa.pub
2026-07-30 20:57:34.493515 | instance -> localhost | The key fingerprint is:
2026-07-30 20:57:34.493547 | instance -> localhost | SHA256:llWocu4gsfqda21UOCNQ9YOMRstxz8x8awZpOijzCBM zuul-build-sshkey
2026-07-30 20:57:34.493592 | instance -> localhost | The key's randomart image is:
2026-07-30 20:57:34.493623 | instance -> localhost | +---[RSA 3072]----+
2026-07-30 20:57:34.493663 | instance -> localhost | |     .+.o  ..    |
2026-07-30 20:57:34.493696 | instance -> localhost | |    .o * O.o     |
2026-07-30 20:57:34.493726 | instance -> localhost | |  E  .= oo& .    |
2026-07-30 20:57:34.493755 | instance -> localhost | |   ...o.*=.+ .   |
2026-07-30 20:57:34.493785 | instance -> localhost | |  o oo.=S+  +    |
2026-07-30 20:57:34.493814 | instance -> localhost | |   oo=..o. o     |
2026-07-30 20:57:34.493843 | instance -> localhost | |   ....=         |
2026-07-30 20:57:34.493872 | instance -> localhost | |  .  ...+        |
2026-07-30 20:57:34.493905 | instance -> localhost | |   ...+o         |
2026-07-30 20:57:34.493935 | instance -> localhost | +----[SHA256]-----+
2026-07-30 20:57:34.494005 | instance -> localhost | ok: Runtime: 0:00:00.543347
2026-07-30 20:57:34.502016 | 
2026-07-30 20:57:34.502119 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-30 20:57:34.549061 | instance | ok
2026-07-30 20:57:34.557853 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-30 20:57:34.566682 | 
2026-07-30 20:57:34.566808 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-30 20:57:34.591133 | instance | skipping: Conditional result was False
2026-07-30 20:57:34.601174 | 
2026-07-30 20:57:34.601335 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-30 20:57:35.061072 | instance | changed
2026-07-30 20:57:35.065860 | 
2026-07-30 20:57:35.065922 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-30 20:57:35.262518 | instance | ok
2026-07-30 20:57:35.267727 | 
2026-07-30 20:57:35.267790 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-30 20:57:35.744363 | instance | changed
2026-07-30 20:57:35.750101 | 
2026-07-30 20:57:35.750167 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-30 20:57:36.205756 | instance | changed
2026-07-30 20:57:36.215024 | 
2026-07-30 20:57:36.215363 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-30 20:57:36.243290 | instance | skipping: Conditional result was False
2026-07-30 20:57:36.260184 | 
2026-07-30 20:57:36.260975 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-30 20:57:36.635092 | instance -> localhost | changed
2026-07-30 20:57:36.650425 | 
2026-07-30 20:57:36.650543 | TASK [add-build-sshkey : Add back temp key]
2026-07-30 20:57:37.077764 | instance -> localhost | Identity added: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/work/4e31f735c7b14e26856e5a11b9c33b0c_id_rsa (zuul-build-sshkey)
2026-07-30 20:57:37.077964 | instance -> localhost | ok: Runtime: 0:00:00.011502
2026-07-30 20:57:37.084870 | 
2026-07-30 20:57:37.085004 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-30 20:57:37.348754 | instance | ok
2026-07-30 20:57:37.741762 | 
2026-07-30 20:57:37.741908 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-30 20:57:37.777731 | instance | skipping: Conditional result was False
2026-07-30 20:57:37.792774 | 
2026-07-30 20:57:37.792898 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-30 20:57:38.081796 | instance | ok
2026-07-30 20:57:38.092243 | 
2026-07-30 20:57:38.092392 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-30 20:57:46.957272 | instance | Output suppressed because no_log was given
2026-07-30 20:57:47.139581 | 
2026-07-30 20:57:47.139708 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-30 20:57:47.350642 | instance | ok: "logs"
2026-07-30 20:57:47.350880 | instance | ok: All items complete
2026-07-30 20:57:47.350910 | 
2026-07-30 20:57:47.485908 | instance | ok: "artifacts"
2026-07-30 20:57:47.641483 | instance | ok: "docs"
2026-07-30 20:57:47.670239 | 
2026-07-30 20:57:47.670384 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-30 20:57:47.859372 | instance | changed: "logs"
2026-07-30 20:57:48.015548 | instance | changed: "artifacts"
2026-07-30 20:57:48.178098 | instance | changed: "docs"
2026-07-30 20:57:48.207827 | 
2026-07-30 20:57:48.208088 | PLAY RECAP
2026-07-30 20:57:48.208302 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-30 20:57:48.208426 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 20:57:48.208499 | 
2026-07-30 20:57:48.352976 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-30 20:57:48.360188 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-30 20:57:50.036817 | 
2026-07-30 20:57:50.037027 | PLAY [all]
2026-07-30 20:57:50.051585 | 
2026-07-30 20:57:50.051922 | TASK [setup-uv : Extract archive]
2026-07-30 20:57:52.603277 | instance | changed
2026-07-30 20:57:52.799950 | 
2026-07-30 20:57:52.800148 | TASK [setup-uv : Print version]
2026-07-30 20:57:53.223750 | instance | uv 0.8.13
2026-07-30 20:57:53.338424 | instance | ok: Runtime: 0:00:00.013087
2026-07-30 20:57:53.352141 | 
2026-07-30 20:57:53.352211 | TASK [Install Ansible collection siblings]
2026-07-30 20:58:02.492636 | instance | changed:
2026-07-30 20:58:02.492915 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-30 20:58:02.492976 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-30 20:58:02.493009 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-30 20:58:02.493035 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-30 20:58:02.493087 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-30 20:58:02.493125 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-30 20:58:02.493147 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-30 20:58:02.493169 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-30 20:58:02.493190 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-30 20:58:02.493208 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-30 20:58:02.493227 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-30 20:58:02.493244 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-30 20:58:02.493262 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-30 20:58:02.493280 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-30 20:58:02.493297 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-30 20:58:02.493316 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-30 20:58:02.493334 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-30 20:58:02.493352 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-30 20:58:02.493370 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-30 20:58:02.493388 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-30 20:58:02.493405 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-30 20:58:02.493423 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-30 20:58:02.493442 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-30 20:58:02.493472 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-30 20:58:02.493492 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-30 20:58:02.493510 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-30 20:58:02.493528 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-30 20:58:02.493545 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-30 20:58:02.493563 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-30 20:58:02.493580 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-30 20:58:02.493598 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-30 20:58:02.493616 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-30 20:58:02.493634 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-30 20:58:02.493652 | 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-30 20:58:02.493671 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.493688 | instance | Process install dependency map
2026-07-30 20:58:02.493706 | instance | Starting collection install process
2026-07-30 20:58:02.493723 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-30 20:58:02.493741 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-30 20:58:02.493758 | instance | ansible.netcommon:* was installed successfully
2026-07-30 20:58:02.493776 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-30 20:58:02.493793 | instance | Creating virtual environment at: .venv
2026-07-30 20:58:02.493811 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-30 20:58:02.493828 | instance | Downloading setuptools (1.1MiB)
2026-07-30 20:58:02.493845 | instance | Downloading netaddr (2.2MiB)
2026-07-30 20:58:02.493879 | instance | Downloading openstacksdk (1.7MiB)
2026-07-30 20:58:02.493923 | instance | Downloading pygments (1.2MiB)
2026-07-30 20:58:02.493946 | instance | Downloading kubernetes (1.9MiB)
2026-07-30 20:58:02.493965 | instance | Downloading cryptography (4.2MiB)
2026-07-30 20:58:02.493984 | instance | Downloading rjsonnet (1.2MiB)
2026-07-30 20:58:02.494002 | instance | Downloading pydantic-core (2.0MiB)
2026-07-30 20:58:02.494020 | instance | Downloading ansible-core (2.1MiB)
2026-07-30 20:58:02.494038 | instance |    Building pyperclip==1.9.0
2026-07-30 20:58:02.494057 | instance |  Downloading rjsonnet
2026-07-30 20:58:02.494080 | instance |  Downloading pydantic-core
2026-07-30 20:58:02.494110 | instance |  Downloading netaddr
2026-07-30 20:58:02.494137 | instance |  Downloading pygments
2026-07-30 20:58:02.494157 | instance |  Downloading cryptography
2026-07-30 20:58:02.494175 | instance |  Downloading setuptools
2026-07-30 20:58:02.494193 | instance |  Downloading kubernetes
2026-07-30 20:58:02.494211 | instance |  Downloading ansible-core
2026-07-30 20:58:02.494231 | instance |  Downloading openstacksdk
2026-07-30 20:58:02.494261 | instance |       Built pyperclip==1.9.0
2026-07-30 20:58:02.494290 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-30 20:58:02.494310 | instance | Installed 87 packages in 44ms
2026-07-30 20:58:02.494328 | instance | Checked out ansible.posix to 1.6.0
2026-07-30 20:58:02.494347 | 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-30 20:58:02.494365 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.494383 | instance | Process install dependency map
2026-07-30 20:58:02.494409 | instance | Starting collection install process
2026-07-30 20:58:02.494428 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-30 20:58:02.494446 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-30 20:58:02.494464 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-30 20:58:02.494481 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.494499 | instance | 2.17.12
2026-07-30 20:58:02.494516 | instance | Checked out ansible.utils to v6.0.3
2026-07-30 20:58:02.494534 | 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-30 20:58:02.494551 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.494569 | instance | Process install dependency map
2026-07-30 20:58:02.494586 | instance | Starting collection install process
2026-07-30 20:58:02.494604 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-30 20:58:02.494621 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-30 20:58:02.494638 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-30 20:58:02.494656 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.494674 | instance | 2.17.12
2026-07-30 20:58:02.494691 | instance | Checked out atmosphere.common to v0.7.0
2026-07-30 20:58:02.494709 | 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-30 20:58:02.494727 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.494744 | instance | Process install dependency map
2026-07-30 20:58:02.494762 | instance | Starting collection install process
2026-07-30 20:58:02.494779 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-30 20:58:02.494796 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-30 20:58:02.494813 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-30 20:58:02.494831 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.494875 | instance | 2.17.12
2026-07-30 20:58:02.494915 | instance | Checked out community.crypto to 2.2.3
2026-07-30 20:58:02.494951 | 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-30 20:58:02.494985 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.495019 | instance | Process install dependency map
2026-07-30 20:58:02.495043 | instance | Starting collection install process
2026-07-30 20:58:02.495062 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-30 20:58:02.495081 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-30 20:58:02.495099 | instance | community.crypto:2.2.3 was installed successfully
2026-07-30 20:58:02.495118 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.495136 | instance | 2.17.12
2026-07-30 20:58:02.495154 | instance | Checked out community.general to 7.3.0
2026-07-30 20:58:02.495173 | 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-30 20:58:02.495198 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.495218 | instance | Process install dependency map
2026-07-30 20:58:02.495235 | instance | Starting collection install process
2026-07-30 20:58:02.495254 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-30 20:58:02.495271 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-30 20:58:02.495289 | instance | community.general:7.3.0 was installed successfully
2026-07-30 20:58:02.495307 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.495326 | instance | 2.17.12
2026-07-30 20:58:02.495345 | instance | Checked out community.mysql to 3.6.0
2026-07-30 20:58:02.495365 | 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-30 20:58:02.495384 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.495402 | instance | Process install dependency map
2026-07-30 20:58:02.495420 | instance | Starting collection install process
2026-07-30 20:58:02.495438 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-30 20:58:02.495456 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-30 20:58:02.495474 | instance | community.mysql:3.6.0 was installed successfully
2026-07-30 20:58:02.495491 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.495509 | instance | 2.17.12
2026-07-30 20:58:02.495526 | instance | Checked out kubernetes.core to 2.4.0
2026-07-30 20:58:02.495544 | 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-30 20:58:02.495562 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.495580 | instance | Process install dependency map
2026-07-30 20:58:02.495598 | instance | Starting collection install process
2026-07-30 20:58:02.495616 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-30 20:58:02.495633 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-30 20:58:02.495651 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-30 20:58:02.495669 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.495687 | instance | 2.17.12
2026-07-30 20:58:02.495704 | instance | Checked out openstack.cloud to 2.6.0
2026-07-30 20:58:02.495722 | 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-30 20:58:02.495739 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.495756 | instance | Process install dependency map
2026-07-30 20:58:02.495773 | instance | Starting collection install process
2026-07-30 20:58:02.495791 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-30 20:58:02.495808 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-30 20:58:02.495826 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-30 20:58:02.495843 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.495860 | instance | 2.17.12
2026-07-30 20:58:02.495884 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-30 20:58:02.495902 | 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-30 20:58:02.495920 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.495938 | instance | Process install dependency map
2026-07-30 20:58:02.495961 | instance | Starting collection install process
2026-07-30 20:58:02.495980 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-30 20:58:02.495998 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-30 20:58:02.496032 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-30 20:58:02.496050 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.496068 | instance | 2.17.12
2026-07-30 20:58:02.496085 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-30 20:58:02.496103 | 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-30 20:58:02.496121 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.496139 | instance | Process install dependency map
2026-07-30 20:58:02.496157 | instance | Starting collection install process
2026-07-30 20:58:02.496175 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-30 20:58:02.496193 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-30 20:58:02.496211 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-30 20:58:02.496228 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.496246 | instance | 2.17.12
2026-07-30 20:58:02.496263 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-30 20:58:02.496281 | 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-30 20:58:02.496299 | instance | Starting galaxy collection install process
2026-07-30 20:58:02.496316 | instance | Process install dependency map
2026-07-30 20:58:02.496339 | instance | Starting collection install process
2026-07-30 20:58:02.496381 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-30 20:58:02.496415 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-30 20:58:02.496435 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-30 20:58:02.496455 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-30 20:58:02.496473 | instance | 2.17.12
2026-07-30 20:58:02.502871 | 
2026-07-30 20:58:02.502923 | PLAY RECAP
2026-07-30 20:58:02.502969 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 20:58:02.502990 | 
2026-07-30 20:58:02.658632 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-30 20:58:02.888798 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-30 20:58:03.760986 | 
2026-07-30 20:58:03.761154 | PLAY [all]
2026-07-30 20:58:03.773990 | 
2026-07-30 20:58:03.774130 | TASK [Install "jq" for log collection]
2026-07-30 20:58:17.658763 | instance | changed
2026-07-30 20:58:19.287513 | 
2026-07-30 20:58:19.287642 | PLAY RECAP
2026-07-30 20:58:19.287716 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 20:58:19.287771 | 
2026-07-30 20:58:19.464083 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-30 20:58:19.473019 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-30 20:58:20.233060 | 
2026-07-30 20:58:20.233208 | PLAY [Prepare selective Molecule plan]
2026-07-30 20:58:20.246705 | 
2026-07-30 20:58:20.246966 | TASK [Generate selective Molecule plan]
2026-07-30 20:58:21.291477 | instance | ok: Runtime: 0:00:00.190828
2026-07-30 20:58:21.296450 | 
2026-07-30 20:58:21.296534 | TASK [Render selective Molecule plan]
2026-07-30 20:58:21.578960 | instance | Selective Molecule plan: selective
2026-07-30 20:58:21.579161 | instance | Changed files:
2026-07-30 20:58:21.579170 | instance |   A roles/udev/SELECTIVE_CI_VALIDATION.md
2026-07-30 20:58:21.579177 | instance | Targets: udev
2026-07-30 20:58:21.579183 | instance | Job decisions:
2026-07-30 20:58:21.579193 | instance |   RUN  aio-openvswitch: selected by changed components
2026-07-30 20:58:21.579204 | instance |        components: udev
2026-07-30 20:58:21.579210 | instance |        verification: host-command:udevadm control --ping
2026-07-30 20:58:21.579219 | instance |   SKIP aio-ovn: no changed component requires this job
2026-07-30 20:58:21.579225 | instance |   SKIP csi-local-path-provisioner: no changed component requires this job
2026-07-30 20:58:21.579231 | instance |   SKIP csi-rbd: no changed component requires this job
2026-07-30 20:58:21.579238 | instance |   SKIP keycloak: no changed component requires this job
2026-07-30 20:58:22.124320 | instance | ok: Runtime: 0:00:00.104652
2026-07-30 20:58:22.126203 | 
2026-07-30 20:58:22.126267 | PLAY RECAP
2026-07-30 20:58:22.126326 | instance | ok: 2 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 20:58:22.126357 | 
2026-07-30 20:58:22.252845 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-30 20:58:22.264633 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-30 20:58:22.920501 | 
2026-07-30 20:58:22.920628 | PLAY [Run selected Molecule scenario]
2026-07-30 20:58:22.933128 | 
2026-07-30 20:58:22.933213 | TASK [Read selective Molecule plan]
2026-07-30 20:58:23.270267 | instance | ok
2026-07-30 20:58:23.276850 | 
2026-07-30 20:58:23.276983 | TASK [Resolve this job decision]
2026-07-30 20:58:23.333585 | instance | ok
2026-07-30 20:58:23.342859 | 
2026-07-30 20:58:23.342974 | TASK [Assert this job exists in the plan]
2026-07-30 20:58:23.385829 | instance | ok: All assertions passed
2026-07-30 20:58:23.392736 | 
2026-07-30 20:58:23.392832 | TASK [Resolve selected scenario inputs]
2026-07-30 20:58:23.428480 | instance | ok
2026-07-30 20:58:23.436513 | 
2026-07-30 20:58:23.444228 | TASK [Report this job decision]
2026-07-30 20:58:23.512045 | instance | ok:
2026-07-30 20:58:23.512257 | instance | Selective Molecule job: aio-openvswitch
2026-07-30 20:58:23.512299 | instance | Decision: RUN
2026-07-30 20:58:23.512334 | instance | Reason: selected by changed components
2026-07-30 20:58:23.512371 | instance | Scenario: aio
2026-07-30 20:58:23.512401 | instance | Targets: udev
2026-07-30 20:58:23.512430 | instance | Components: udev
2026-07-30 20:58:23.519954 | 
2026-07-30 20:58:23.520019 | LOOP [Check Molecule inventory directories]
2026-07-30 20:58:23.866988 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-07-30 20:58:24.024677 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/aio/inventory"
2026-07-30 20:58:24.176796 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/aio/inventory"
2026-07-30 20:58:24.195424 | 
2026-07-30 20:58:24.195585 | LOOP [Copy Zuul inventory for Molecule]
2026-07-30 20:58:24.881846 | instance | changed:
2026-07-30 20:58:24.882173 | instance | {
2026-07-30 20:58:24.882237 | instance |   "ansible_loop_var": "item",
2026-07-30 20:58:24.882271 | instance |   "changed": false,
2026-07-30 20:58:24.882301 | instance |   "failed": false,
2026-07-30 20:58:24.882327 | instance |   "invocation": {
2026-07-30 20:58:24.882352 | instance |     "module_args": {
2026-07-30 20:58:24.882376 | instance |       "checksum_algorithm": "sha1",
2026-07-30 20:58:24.882400 | instance |       "follow": true,
2026-07-30 20:58:24.882423 | instance |       "get_attributes": true,
2026-07-30 20:58:24.882447 | instance |       "get_checksum": true,
2026-07-30 20:58:24.882470 | instance |       "get_mime": true,
2026-07-30 20:58:24.882493 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-07-30 20:58:24.882606 | instance |     }
2026-07-30 20:58:24.882655 | instance |   },
2026-07-30 20:58:24.882684 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-07-30 20:58:24.882710 | instance |   "stat": {
2026-07-30 20:58:24.882734 | instance |     "atime": 1785445101.5270777,
2026-07-30 20:58:24.882759 | instance |     "attr_flags": "e",
2026-07-30 20:58:24.882783 | instance |     "attributes": [
2026-07-30 20:58:24.882806 | instance |       "extents"
2026-07-30 20:58:24.882829 | instance |     ],
2026-07-30 20:58:24.882852 | instance |     "block_size": 4096,
2026-07-30 20:58:24.882876 | instance |     "blocks": 8,
2026-07-30 20:58:24.882899 | instance |     "charset": "unknown",
2026-07-30 20:58:24.882922 | instance |     "ctime": 1785445100.7910593,
2026-07-30 20:58:24.882945 | instance |     "dev": 64513,
2026-07-30 20:58:24.882970 | instance |     "device_type": 0,
2026-07-30 20:58:24.882993 | instance |     "executable": true,
2026-07-30 20:58:24.883116 | instance |     "exists": true,
2026-07-30 20:58:24.883161 | instance |     "gid": 1000,
2026-07-30 20:58:24.883189 | instance |     "gr_name": "zuul",
2026-07-30 20:58:24.883214 | instance |     "inode": 1047160,
2026-07-30 20:58:24.883238 | instance |     "isblk": false,
2026-07-30 20:58:24.883263 | instance |     "ischr": false,
2026-07-30 20:58:24.883289 | instance |     "isdir": true,
2026-07-30 20:58:24.883313 | instance |     "isfifo": false,
2026-07-30 20:58:24.883336 | instance |     "isgid": false,
2026-07-30 20:58:24.883359 | instance |     "islnk": false,
2026-07-30 20:58:24.883382 | instance |     "isreg": false,
2026-07-30 20:58:24.883405 | instance |     "issock": false,
2026-07-30 20:58:24.883428 | instance |     "isuid": false,
2026-07-30 20:58:24.883451 | instance |     "mimetype": "unknown",
2026-07-30 20:58:24.883476 | instance |     "mode": "0755",
2026-07-30 20:58:24.883499 | instance |     "mtime": 1785445100.7910593,
2026-07-30 20:58:24.883523 | instance |     "nlink": 25,
2026-07-30 20:58:24.883547 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-07-30 20:58:24.883570 | instance |     "pw_name": "zuul",
2026-07-30 20:58:24.883594 | instance |     "readable": true,
2026-07-30 20:58:24.883617 | instance |     "rgrp": true,
2026-07-30 20:58:24.883641 | instance |     "roth": true,
2026-07-30 20:58:24.883664 | instance |     "rusr": true,
2026-07-30 20:58:24.883687 | instance |     "size": 4096,
2026-07-30 20:58:24.883710 | instance |     "uid": 1000,
2026-07-30 20:58:24.883733 | instance |     "version": "4030767610",
2026-07-30 20:58:24.883757 | instance |     "wgrp": false,
2026-07-30 20:58:24.883780 | instance |     "woth": false,
2026-07-30 20:58:24.883804 | instance |     "writeable": true,
2026-07-30 20:58:24.883826 | instance |     "wusr": true,
2026-07-30 20:58:24.883849 | instance |     "xgrp": true,
2026-07-30 20:58:24.883872 | instance |     "xoth": true,
2026-07-30 20:58:24.883895 | instance |     "xusr": true
2026-07-30 20:58:24.883919 | instance |   }
2026-07-30 20:58:24.883943 | instance | }
2026-07-30 20:58:24.884005 | instance | ok: All items complete
2026-07-30 20:58:24.884036 | 
2026-07-30 20:58:24.885402 | instance | skipping: Conditional result was False
2026-07-30 20:58:24.886191 | instance | skipping: Conditional result was False
2026-07-30 20:58:24.897415 | 
2026-07-30 20:58:24.897602 | LOOP [Use private addresses in Molecule inventory]
2026-07-30 20:58:25.232125 | instance | changed: 1 replacements made
2026-07-30 20:58:25.233743 | instance | skipping: Conditional result was False
2026-07-30 20:58:25.234782 | instance | skipping: Conditional result was False
2026-07-30 20:58:25.252934 | 
2026-07-30 20:58:25.253177 | TASK [Prepare Molecule command]
2026-07-30 20:58:25.341235 | instance | ok
2026-07-30 20:58:25.347337 | 
2026-07-30 20:58:25.347413 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-30 20:58:25.455630 | instance | ok
2026-07-30 20:58:25.503856 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/run-command.yml
2026-07-30 20:58:25.511838 | 
2026-07-30 20:58:25.511923 | TASK [Create Molecule log directory]
2026-07-30 20:58:25.840300 | instance | changed
2026-07-30 20:58:25.845941 | 
2026-07-30 20:58:25.846090 | TASK [Resolve live Molecule log path]
2026-07-30 20:58:25.882302 | instance | ok
2026-07-30 20:58:25.894628 | 
2026-07-30 20:58:25.894729 | TASK [Start Molecule aio (aio-openvswitch)]
2026-07-30 20:58:26.544904 | instance | ok: ERROR (ignored)
2026-07-30 20:58:26.545188 | instance | {
2026-07-30 20:58:26.545220 | instance |   "ansible_job_id": "j180911748563.1586",
2026-07-30 20:58:26.545242 | instance |   "finished": 0,
2026-07-30 20:58:26.545261 | instance |   "results_file": "/home/zuul/.ansible_async/j180911748563.1586",
2026-07-30 20:58:26.545280 | instance |   "started": 1
2026-07-30 20:58:26.545298 | instance | }
2026-07-30 20:58:26.562530 | 
2026-07-30 20:58:26.562662 | TASK [Initialize progress monitor for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:26.635821 | instance | ok
2026-07-30 20:58:27.571903 | 
2026-07-30 20:58:27.572052 | TASK [Bound progress monitor recursion for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:27.607191 | instance | ok
2026-07-30 20:58:27.614356 | 
2026-07-30 20:58:27.614438 | TASK [Calculate progress monitor chunks for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:27.674573 | instance | ok
2026-07-30 20:58:27.682541 | 
2026-07-30 20:58:27.682631 | LOOP [Monitor Molecule aio (aio-openvswitch)]
2026-07-30 20:58:27.734761 | instance | ok: 1
2026-07-30 20:58:27.734979 | instance | changed: All items complete
2026-07-30 20:58:27.735009 | 
2026-07-30 20:58:27.739574 | instance | ok: 2
2026-07-30 20:58:27.744510 | instance | ok: 3
2026-07-30 20:58:27.748770 | instance | ok: 4
2026-07-30 20:58:27.752784 | instance | ok: 5
2026-07-30 20:58:27.768501 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml
2026-07-30 20:58:27.769736 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml
2026-07-30 20:58:27.770661 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml
2026-07-30 20:58:27.771738 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml
2026-07-30 20:58:27.773312 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml
2026-07-30 20:58:27.782146 | 
2026-07-30 20:58:27.782242 | TASK [Start progress monitor chunk for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:27.841285 | instance | ok
2026-07-30 20:58:28.123768 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-30 20:58:28.139082 | 
2026-07-30 20:58:28.139291 | TASK [Wait before polling Molecule aio (aio-openvswitch)]
2026-07-30 20:58:48.204045 | instance | ok
2026-07-30 20:58:48.336541 | 
2026-07-30 20:58:48.336656 | TASK [Count progress poll for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:48.414785 | instance | ok
2026-07-30 20:58:48.520654 | 
2026-07-30 20:58:48.520820 | TASK [Poll status for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:48.881732 | instance | ok
2026-07-30 20:58:48.890818 | 
2026-07-30 20:58:48.925633 | TASK [Inspect live log for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:49.113328 | instance | ok
2026-07-30 20:58:49.121926 | 
2026-07-30 20:58:49.122014 | TASK [Calculate new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:49.184574 | instance | ok
2026-07-30 20:58:49.195830 | 
2026-07-30 20:58:49.195997 | TASK [Read new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:49.417717 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-07-30 20:58:49.417802 | instance | INFO     [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-30 20:58:49.417822 | instance | INFO     [aio > prerun] Performing prerun with role_name_check=0...
2026-07-30 20:58:49.417832 | instance | INFO     [aio > dependency] Executing
2026-07-30 20:58:49.417842 | instance | WARNING  [aio > dependency] Missing roles requirements file: requirements.yml
2026-07-30 20:58:49.417852 | instance | WARNING  [aio > dependency] Missing collections requirements file: collections.yml
2026-07-30 20:58:49.417864 | instance | WARNING  [aio > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-30 20:58:49.417873 | instance | INFO     [aio > cleanup] Executing
2026-07-30 20:58:49.417881 | instance | WARNING  [aio > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 20:58:49.417889 | instance | INFO     [aio > destroy] Executing
2026-07-30 20:58:49.417897 | instance | WARNING  [aio > destroy] Skipping, '--destroy=never' requested.
2026-07-30 20:58:49.417905 | instance | INFO     [aio > destroy] Executed: Successful
2026-07-30 20:58:49.417912 | instance | INFO     [aio > syntax] Executing
2026-07-30 20:58:49.417920 | instance |
2026-07-30 20:58:49.417932 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/aio/converge.yml
2026-07-30 20:58:49.417940 | instance | INFO     [aio > syntax] Executed: Successful
2026-07-30 20:58:49.417947 | instance | INFO     [aio > create] Executing
2026-07-30 20:58:49.417955 | instance | WARNING  [aio > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 20:58:49.417962 | instance | INFO     [aio > prepare] Executing
2026-07-30 20:58:49.417970 | instance |
2026-07-30 20:58:49.417977 | instance | PLAY [Prepare] *****************************************************************
2026-07-30 20:58:49.417985 | instance |
2026-07-30 20:58:49.417992 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:58:49.417999 | instance | Thursday 30 July 2026  20:58:42 +0000 (0:00:00.024)       0:00:00.024 *********
2026-07-30 20:58:49.418007 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-07-30 20:58:49.418014 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-07-30 20:58:49.418022 | instance | interpreter could change the meaning of that path. See
2026-07-30 20:58:49.418030 | instance | https://docs.ansible.com/ansible-
2026-07-30 20:58:49.418037 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 20:58:49.418044 | instance | ok: [instance]
2026-07-30 20:58:49.418052 | instance |
2026-07-30 20:58:49.418059 | instance | TASK [Configure short hostname] ************************************************
2026-07-30 20:58:49.418066 | instance | Thursday 30 July 2026  20:58:43 +0000 (0:00:01.146)       0:00:01.170 *********
2026-07-30 20:58:49.418074 | instance | changed: [instance]
2026-07-30 20:58:49.418082 | instance |
2026-07-30 20:58:49.418090 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-07-30 20:58:49.418098 | instance | Thursday 30 July 2026  20:58:44 +0000 (0:00:00.671)       0:00:01.841 *********
2026-07-30 20:58:49.418106 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-07-30 20:58:49.418113 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-07-30 20:58:49.418121 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-07-30 20:58:49.418128 | instance | changed: [instance]
2026-07-30 20:58:49.418135 | instance |
2026-07-30 20:58:49.418143 | instance | TASK [Install "dirmngr" for GPG keyserver operations] **************************
2026-07-30 20:58:49.418150 | instance | Thursday 30 July 2026  20:58:44 +0000 (0:00:00.255)       0:00:02.097 *********
2026-07-30 20:58:49.418158 | instance | ok: [instance]
2026-07-30 20:58:49.418166 | instance |
2026-07-30 20:58:49.418173 | instance | TASK [Purge "snapd" package] ***************************************************
2026-07-30 20:58:49.418180 | instance | Thursday 30 July 2026  20:58:45 +0000 (0:00:01.118)       0:00:03.216 *********
2026-07-30 20:58:49.418188 | instance | ok: [instance]
2026-07-30 20:58:49.418257 | instance |
2026-07-30 20:58:49.418268 | instance | PLAY [Generate workspace for Atmosphere] ***************************************
2026-07-30 20:58:49.418274 | instance |
2026-07-30 20:58:49.418279 | instance | TASK [Create folders for workspace] ********************************************
2026-07-30 20:58:49.418285 | instance | Thursday 30 July 2026  20:58:46 +0000 (0:00:00.628)       0:00:03.844 *********
2026-07-30 20:58:49.418290 | instance | changed: [localhost] => (item=group_vars)
2026-07-30 20:58:49.418296 | instance | changed: [localhost] => (item=group_vars/all)
2026-07-30 20:58:49.418301 | instance | changed: [localhost] => (item=group_vars/controllers)
2026-07-30 20:58:49.418307 | instance | changed: [localhost] => (item=group_vars/cephs)
2026-07-30 20:58:49.418313 | instance | changed: [localhost] => (item=group_vars/computes)
2026-07-30 20:58:49.418318 | instance | changed: [localhost] => (item=host_vars)
2026-07-30 20:58:49.418323 | instance |
2026-07-30 20:58:49.418329 | instance | PLAY [Generate Ceph control plane configuration for workspace] *****************
2026-07-30 20:58:49.418334 | instance |
2026-07-30 20:58:49.418339 | instance | TASK [Ensure the Ceph control plane configuration file exists] *****************
2026-07-30 20:58:49.418345 | instance | Thursday 30 July 2026  20:58:47 +0000 (0:00:01.401)       0:00:05.245 *********
2026-07-30 20:58:49.418350 | instance | changed: [localhost]
2026-07-30 20:58:49.418356 | instance |
2026-07-30 20:58:49.418361 | instance | TASK [Load the current Ceph control plane configuration into a variable] *******
2026-07-30 20:58:49.418366 | instance | Thursday 30 July 2026  20:58:48 +0000 (0:00:00.232)       0:00:05.478 *********
2026-07-30 20:58:49.418372 | instance | ok: [localhost]
2026-07-30 20:58:49.418377 | instance |
2026-07-30 20:58:49.418383 | instance | TASK [Generate Ceph control plane values for missing variables] ****************
2026-07-30 20:58:49.418388 | instance | Thursday 30 July 2026  20:58:48 +0000 (0:00:00.028)       0:00:05.506 *********
2026-07-30 20:58:49.418394 | instance | ok: [localhost] => (item={'key': 'ceph_fsid', 'value': '7daf30b9-f9e7-5bbd-bd36-8f41fb8fd968'})
2026-07-30 20:58:49.418400 | instance | ok: [localhost] => (item={'key': 'ceph_mon_public_network', 'value': '10.96.240.0/24'})
2026-07-30 20:58:49.418405 | instance |
2026-07-30 20:58:49.418415 | instance | TASK [Write new Ceph control plane configuration file to disk] *****************
2026-07-30 20:58:49.418421 | instance | Thursday 30 July 2026  20:58:48 +0000 (0:00:00.049)       0:00:05.556 *********
2026-07-30 20:58:49.418426 | instance | changed: [localhost]
2026-07-30 20:58:49.418431 | instance |
2026-07-30 20:58:49.418437 | instance | PLAY [Generate Ceph OSD configuration for workspace] ***************************
2026-07-30 20:58:49.418442 | instance |
2026-07-30 20:58:49.418447 | instance | TASK [Ensure the Ceph OSDs configuration file exists] **************************
2026-07-30 20:58:49.418453 | instance | Thursday 30 July 2026  20:58:48 +0000 (0:00:00.654)       0:00:06.210 *********
2026-07-30 20:58:49.752743 | instance | ok: Runtime: 0:00:00.009442
2026-07-30 20:58:49.762861 | 
2026-07-30 20:58:49.762961 | TASK [Track last task for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:49.829327 | instance | ok
2026-07-30 20:58:49.840395 | 
2026-07-30 20:58:49.840661 | TASK [Advance live log offset for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:49.907137 | instance | ok
2026-07-30 20:58:49.915964 | 
2026-07-30 20:58:49.916045 | TASK [Report progress heartbeat for Molecule aio (aio-openvswitch)]
2026-07-30 20:58:49.970072 | instance | ok:
2026-07-30 20:58:49.970238 | instance | Molecule aio (aio-openvswitch) is still running after 20 seconds.
2026-07-30 20:58:49.970276 | instance | Last task: TASK [Ensure the Ceph OSDs configuration file exists]
2026-07-30 20:58:49.977749 | 
2026-07-30 20:58:49.977821 | TASK [Continue monitoring Molecule aio (aio-openvswitch)]
2026-07-30 20:58:50.032363 | instance | ok
2026-07-30 20:58:50.043330 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-30 20:58:50.054736 | 
2026-07-30 20:58:50.054832 | TASK [Wait before polling Molecule aio (aio-openvswitch)]
2026-07-30 20:59:10.114579 | instance | ok
2026-07-30 20:59:10.120680 | 
2026-07-30 20:59:10.120774 | TASK [Count progress poll for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:10.175303 | instance | ok
2026-07-30 20:59:10.181308 | 
2026-07-30 20:59:10.181385 | TASK [Poll status for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:10.419218 | instance | ok
2026-07-30 20:59:10.425543 | 
2026-07-30 20:59:10.425617 | TASK [Inspect live log for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:10.608056 | instance | ok
2026-07-30 20:59:10.702474 | 
2026-07-30 20:59:10.702613 | TASK [Calculate new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:10.750453 | instance | ok
2026-07-30 20:59:10.762342 | 
2026-07-30 20:59:10.762503 | TASK [Read new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:11.049941 | instance | changed: [localhost]
2026-07-30 20:59:11.050047 | instance |
2026-07-30 20:59:11.050061 | instance | TASK [Load the current Ceph OSDs configuration into a variable] ****************
2026-07-30 20:59:11.050076 | instance | Thursday 30 July 2026  20:58:49 +0000 (0:00:00.224)       0:00:06.434 *********
2026-07-30 20:59:11.050089 | instance | ok: [localhost]
2026-07-30 20:59:11.050099 | instance |
2026-07-30 20:59:11.050110 | instance | TASK [Generate Ceph OSDs values for missing variables] *************************
2026-07-30 20:59:11.050120 | instance | Thursday 30 July 2026  20:58:49 +0000 (0:00:00.025)       0:00:06.460 *********
2026-07-30 20:59:11.050130 | instance | ok: [localhost] => (item={'key': 'ceph_osd_devices', 'value': ['/dev/vdb', '/dev/vdc', '/dev/vdd']})
2026-07-30 20:59:11.050139 | instance |
2026-07-30 20:59:11.050148 | instance | TASK [Write new Ceph OSDs configuration file to disk] **************************
2026-07-30 20:59:11.050158 | instance | Thursday 30 July 2026  20:58:49 +0000 (0:00:00.029)       0:00:06.489 *********
2026-07-30 20:59:11.050167 | instance | changed: [localhost]
2026-07-30 20:59:11.050175 | instance |
2026-07-30 20:59:11.050184 | instance | PLAY [Generate Kubernetes configuration for workspace] *************************
2026-07-30 20:59:11.050193 | instance |
2026-07-30 20:59:11.050202 | instance | TASK [Ensure the Kubernetes configuration file exists] *************************
2026-07-30 20:59:11.050210 | instance | Thursday 30 July 2026  20:58:49 +0000 (0:00:00.486)       0:00:06.975 *********
2026-07-30 20:59:11.050219 | instance | changed: [localhost]
2026-07-30 20:59:11.050228 | instance |
2026-07-30 20:59:11.050241 | instance | TASK [Load the current Kubernetes configuration into a variable] ***************
2026-07-30 20:59:11.050250 | instance | Thursday 30 July 2026  20:58:49 +0000 (0:00:00.220)       0:00:07.195 *********
2026-07-30 20:59:11.050258 | instance | ok: [localhost]
2026-07-30 20:59:11.050267 | instance |
2026-07-30 20:59:11.050276 | instance | TASK [Generate Kubernetes values for missing variables] ************************
2026-07-30 20:59:11.050284 | instance | Thursday 30 July 2026  20:58:49 +0000 (0:00:00.024)       0:00:07.219 *********
2026-07-30 20:59:11.050293 | instance | ok: [localhost] => (item={'key': 'kubernetes_hostname', 'value': '10.96.240.10'})
2026-07-30 20:59:11.050302 | instance | ok: [localhost] => (item={'key': 'kubernetes_keepalived_vrid', 'value': 42})
2026-07-30 20:59:11.050311 | instance | ok: [localhost] => (item={'key': 'kubernetes_keepalived_vip', 'value': '10.96.240.10'})
2026-07-30 20:59:11.050320 | instance |
2026-07-30 20:59:11.050329 | instance | TASK [Write new Kubernetes configuration file to disk] *************************
2026-07-30 20:59:11.050338 | instance | Thursday 30 July 2026  20:58:49 +0000 (0:00:00.044)       0:00:07.264 *********
2026-07-30 20:59:11.050346 | instance | changed: [localhost]
2026-07-30 20:59:11.050355 | instance |
2026-07-30 20:59:11.050364 | instance | PLAY [Generate Keepalived configuration for workspace] *************************
2026-07-30 20:59:11.050372 | instance |
2026-07-30 20:59:11.050381 | instance | TASK [Ensure the Keeaplived configuration file exists] *************************
2026-07-30 20:59:11.050390 | instance | Thursday 30 July 2026  20:58:50 +0000 (0:00:00.459)       0:00:07.724 *********
2026-07-30 20:59:11.050399 | instance | changed: [localhost]
2026-07-30 20:59:11.050407 | instance |
2026-07-30 20:59:11.050416 | instance | TASK [Load the current Keepalived configuration into a variable] ***************
2026-07-30 20:59:11.050425 | instance | Thursday 30 July 2026  20:58:50 +0000 (0:00:00.222)       0:00:07.946 *********
2026-07-30 20:59:11.050433 | instance | ok: [localhost]
2026-07-30 20:59:11.050442 | instance |
2026-07-30 20:59:11.050450 | instance | TASK [Generate Keepalived values for missing variables] ************************
2026-07-30 20:59:11.050459 | instance | Thursday 30 July 2026  20:58:50 +0000 (0:00:00.024)       0:00:07.970 *********
2026-07-30 20:59:11.050484 | instance | ok: [localhost] => (item={'key': 'keepalived_interface', 'value': 'br-ex'})
2026-07-30 20:59:11.050552 | instance | ok: [localhost] => (item={'key': 'keepalived_vip', 'value': '10.96.250.10'})
2026-07-30 20:59:11.050565 | instance |
2026-07-30 20:59:11.050574 | instance | TASK [Write new Keepalived configuration file to disk] *************************
2026-07-30 20:59:11.050582 | instance | Thursday 30 July 2026  20:58:50 +0000 (0:00:00.033)       0:00:08.003 *********
2026-07-30 20:59:11.050591 | instance | changed: [localhost]
2026-07-30 20:59:11.050611 | instance |
2026-07-30 20:59:11.050621 | instance | PLAY [Generate endpoints for workspace] ****************************************
2026-07-30 20:59:11.050629 | instance |
2026-07-30 20:59:11.050638 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:11.050647 | instance | Thursday 30 July 2026  20:58:51 +0000 (0:00:00.491)       0:00:08.495 *********
2026-07-30 20:59:11.050655 | instance | ok: [localhost]
2026-07-30 20:59:11.050664 | instance |
2026-07-30 20:59:11.050673 | instance | TASK [Ensure the endpoints file exists] ****************************************
2026-07-30 20:59:11.050681 | instance | Thursday 30 July 2026  20:58:52 +0000 (0:00:00.855)       0:00:09.350 *********
2026-07-30 20:59:11.050690 | instance | changed: [localhost]
2026-07-30 20:59:11.050699 | instance |
2026-07-30 20:59:11.050707 | instance | TASK [Load the current endpoints into a variable] ******************************
2026-07-30 20:59:11.050716 | instance | Thursday 30 July 2026  20:58:52 +0000 (0:00:00.251)       0:00:09.602 *********
2026-07-30 20:59:11.050725 | instance | ok: [localhost]
2026-07-30 20:59:11.050733 | instance |
2026-07-30 20:59:11.050742 | instance | TASK [Generate endpoint skeleton for missing variables] ************************
2026-07-30 20:59:11.050751 | instance | Thursday 30 July 2026  20:58:52 +0000 (0:00:00.028)       0:00:09.631 *********
2026-07-30 20:59:11.050760 | instance | ok: [localhost] => (item=keycloak_host)
2026-07-30 20:59:11.050769 | instance | ok: [localhost] => (item=kube_prometheus_stack_grafana_host)
2026-07-30 20:59:11.050778 | instance | ok: [localhost] => (item=kube_prometheus_stack_alertmanager_host)
2026-07-30 20:59:11.050795 | instance | ok: [localhost] => (item=kube_prometheus_stack_prometheus_host)
2026-07-30 20:59:11.050804 | instance | ok: [localhost] => (item=openstack_helm_endpoints_region_name)
2026-07-30 20:59:11.050813 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_api_host)
2026-07-30 20:59:11.050821 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_api_host)
2026-07-30 20:59:11.050830 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_api_host)
2026-07-30 20:59:11.050839 | instance | ok: [localhost] => (item=openstack_helm_endpoints_placement_api_host)
2026-07-30 20:59:11.050848 | instance | ok: [localhost] => (item=openstack_helm_endpoints_barbican_api_host)
2026-07-30 20:59:11.050865 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_api_host)
2026-07-30 20:59:11.050874 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_api_host)
2026-07-30 20:59:11.050883 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_novnc_host)
2026-07-30 20:59:11.050892 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_api_host)
2026-07-30 20:59:11.050900 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_api_host)
2026-07-30 20:59:11.050909 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_api_host)
2026-07-30 20:59:11.050918 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_api_host)
2026-07-30 20:59:11.050930 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_registry_host)
2026-07-30 20:59:11.050942 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_api_host)
2026-07-30 20:59:11.050955 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_cfn_api_host)
2026-07-30 20:59:11.050969 | instance | ok: [localhost] => (item=openstack_helm_endpoints_horizon_api_host)
2026-07-30 20:59:11.050982 | instance | ok: [localhost] => (item=openstack_helm_endpoints_rgw_host)
2026-07-30 20:59:11.050994 | instance | ok: [localhost] => (item=openstack_helm_endpoints_manila_api_host)
2026-07-30 20:59:11.051006 | instance |
2026-07-30 20:59:11.051017 | instance | TASK [Write new endpoints file to disk] ****************************************
2026-07-30 20:59:11.051029 | instance | Thursday 30 July 2026  20:58:53 +0000 (0:00:00.795)       0:00:10.427 *********
2026-07-30 20:59:11.051038 | instance | changed: [localhost]
2026-07-30 20:59:11.051046 | instance |
2026-07-30 20:59:11.051060 | instance | TASK [Ensure the endpoints file exists] ****************************************
2026-07-30 20:59:11.051069 | instance | Thursday 30 July 2026  20:58:53 +0000 (0:00:00.486)       0:00:10.914 *********
2026-07-30 20:59:11.051078 | instance | changed: [localhost]
2026-07-30 20:59:11.051087 | instance |
2026-07-30 20:59:11.051104 | instance | PLAY [Generate Neutron configuration for workspace] ****************************
2026-07-30 20:59:11.051113 | instance |
2026-07-30 20:59:11.051121 | instance | TASK [Ensure the Neutron configuration file exists] ****************************
2026-07-30 20:59:11.051130 | instance | Thursday 30 July 2026  20:58:53 +0000 (0:00:00.253)       0:00:11.167 *********
2026-07-30 20:59:11.051139 | instance | changed: [localhost]
2026-07-30 20:59:11.051147 | instance |
2026-07-30 20:59:11.051156 | instance | TASK [Load the current Neutron configuration into a variable] ******************
2026-07-30 20:59:11.051164 | instance | Thursday 30 July 2026  20:58:54 +0000 (0:00:00.217)       0:00:11.384 *********
2026-07-30 20:59:11.051173 | instance | ok: [localhost]
2026-07-30 20:59:11.051182 | instance |
2026-07-30 20:59:11.051190 | instance | TASK [Generate Neutron values for missing variables] ***************************
2026-07-30 20:59:11.051199 | instance | Thursday 30 July 2026  20:58:54 +0000 (0:00:00.027)       0:00:11.412 *********
2026-07-30 20:59:11.051209 | instance | ok: [localhost] => (item={'key': 'neutron_networks', 'value': [{'name': 'public', 'external': True, 'shared': True, 'mtu_size': 1500, 'port_security_enabled': True, 'provider_network_type': 'flat', 'provider_physical_network': 'external', 'subnets': [{'name': 'public-subnet', 'cidr': '10.96.250.0/24', 'gateway_ip': '10.96.250.10', 'allocation_pool_start': '10.96.250.200', 'allocation_pool_end': '10.96.250.220', 'enable_dhcp': True}]}]})
2026-07-30 20:59:11.051218 | instance |
2026-07-30 20:59:11.051227 | instance | TASK [Write new Neutron configuration file to disk] ****************************
2026-07-30 20:59:11.051236 | instance | Thursday 30 July 2026  20:58:54 +0000 (0:00:00.036)       0:00:11.449 *********
2026-07-30 20:59:11.051244 | instance | changed: [localhost]
2026-07-30 20:59:11.051253 | instance |
2026-07-30 20:59:11.051262 | instance | PLAY [Generate Nova configuration for workspace] *******************************
2026-07-30 20:59:11.051270 | instance |
2026-07-30 20:59:11.051279 | instance | TASK [Ensure the Nova configuration file exists] *******************************
2026-07-30 20:59:11.051288 | instance | Thursday 30 July 2026  20:58:54 +0000 (0:00:00.448)       0:00:11.898 *********
2026-07-30 20:59:11.051296 | instance | changed: [localhost]
2026-07-30 20:59:11.051305 | instance |
2026-07-30 20:59:11.051314 | instance | TASK [Load the current Nova configuration into a variable] *********************
2026-07-30 20:59:11.051322 | instance | Thursday 30 July 2026  20:58:54 +0000 (0:00:00.226)       0:00:12.124 *********
2026-07-30 20:59:11.051331 | instance | ok: [localhost]
2026-07-30 20:59:11.051339 | instance |
2026-07-30 20:59:11.051348 | instance | TASK [Generate Nova values for missing variables] ******************************
2026-07-30 20:59:11.051357 | instance | Thursday 30 July 2026  20:58:54 +0000 (0:00:00.031)       0:00:12.156 *********
2026-07-30 20:59:11.051366 | instance | ok: [localhost] => (item={'key': 'nova_flavors', 'value': [{'name': 'm1.tiny', 'ram': 512, 'disk': 1, 'vcpus': 1}, {'name': 'm1.small', 'ram': 2048, 'disk': 20, 'vcpus': 1}, {'name': 'm1.medium', 'ram': 4096, 'disk': 40, 'vcpus': 2}, {'name': 'm1.large', 'ram': 8192, 'disk': 80, 'vcpus': 4}, {'name': 'm1.xlarge', 'ram': 16384, 'disk': 160, 'vcpus': 8}]})
2026-07-30 20:59:11.051375 | instance |
2026-07-30 20:59:11.051384 | instance | TASK [Write new Nova configuration file to disk] *******************************
2026-07-30 20:59:11.051393 | instance | Thursday 30 July 2026  20:58:54 +0000 (0:00:00.041)       0:00:12.197 *********
2026-07-30 20:59:11.051401 | instance | changed: [localhost]
2026-07-30 20:59:11.051410 | instance |
2026-07-30 20:59:11.051419 | instance | PLAY [Generate secrets for workspace] ******************************************
2026-07-30 20:59:11.051427 | instance |
2026-07-30 20:59:11.051436 | instance | TASK [Ensure the secrets file exists] ******************************************
2026-07-30 20:59:11.051445 | instance | Thursday 30 July 2026  20:58:55 +0000 (0:00:00.447)       0:00:12.645 *********
2026-07-30 20:59:11.051453 | instance | changed: [localhost]
2026-07-30 20:59:11.051462 | instance |
2026-07-30 20:59:11.051470 | instance | TASK [Load the current secrets into a variable] ********************************
2026-07-30 20:59:11.051479 | instance | Thursday 30 July 2026  20:58:55 +0000 (0:00:00.225)       0:00:12.870 *********
2026-07-30 20:59:11.051488 | instance | ok: [localhost]
2026-07-30 20:59:11.051502 | instance |
2026-07-30 20:59:11.051510 | instance | TASK [Generate secrets for missing variables] **********************************
2026-07-30 20:59:11.051526 | instance | Thursday 30 July 2026  20:58:55 +0000 (0:00:00.027)       0:00:12.897 *********
2026-07-30 20:59:11.051535 | instance | ok: [localhost] => (item=heat_auth_encryption_key)
2026-07-30 20:59:11.051544 | instance | ok: [localhost] => (item=keepalived_password)
2026-07-30 20:59:11.051552 | instance | ok: [localhost] => (item=keycloak_admin_password)
2026-07-30 20:59:11.051561 | instance | ok: [localhost] => (item=keycloak_database_password)
2026-07-30 20:59:11.051573 | instance | ok: [localhost] => (item=keystone_keycloak_client_secret)
2026-07-30 20:59:11.051582 | instance | ok: [localhost] => (item=keystone_oidc_crypto_passphrase)
2026-07-30 20:59:11.051591 | instance | ok: [localhost] => (item=kube_prometheus_stack_grafana_admin_password)
2026-07-30 20:59:11.051600 | instance | ok: [localhost] => (item=octavia_heartbeat_key)
2026-07-30 20:59:11.051608 | instance | ok: [localhost] => (item=openstack_helm_endpoints_rabbitmq_admin_password)
2026-07-30 20:59:11.051617 | instance | ok: [localhost] => (item=openstack_helm_endpoints_memcached_secret_key)
2026-07-30 20:59:11.051626 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_admin_password)
2026-07-30 20:59:11.051634 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_mariadb_password)
2026-07-30 20:59:11.051643 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_rabbitmq_password)
2026-07-30 20:59:11.051652 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_keystone_password)
2026-07-30 20:59:11.051660 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_mariadb_password)
2026-07-30 20:59:11.051669 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_rabbitmq_password)
2026-07-30 20:59:11.051677 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_keystone_password)
2026-07-30 20:59:11.051686 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_mariadb_password)
2026-07-30 20:59:11.051694 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_rabbitmq_password)
2026-07-30 20:59:11.051703 | instance | ok: [localhost] => (item=openstack_helm_endpoints_placement_keystone_password)
2026-07-30 20:59:11.051712 | instance | ok: [localhost] => (item=openstack_helm_endpoints_placement_mariadb_password)
2026-07-30 20:59:11.051720 | instance | ok: [localhost] => (item=openstack_helm_endpoints_barbican_keystone_password)
2026-07-30 20:59:11.051729 | instance | ok: [localhost] => (item=openstack_helm_endpoints_barbican_mariadb_password)
2026-07-30 20:59:11.051737 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_keystone_password)
2026-07-30 20:59:11.051746 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_mariadb_password)
2026-07-30 20:59:11.051754 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_rabbitmq_password)
2026-07-30 20:59:11.051763 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_metadata_secret)
2026-07-30 20:59:11.051771 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_keystone_password)
2026-07-30 20:59:11.051780 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_mariadb_password)
2026-07-30 20:59:11.051788 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_rabbitmq_password)
2026-07-30 20:59:11.051797 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_keystone_password)
2026-07-30 20:59:11.051805 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_mariadb_password)
2026-07-30 20:59:11.051814 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_rabbitmq_password)
2026-07-30 20:59:11.051822 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_keystone_password)
2026-07-30 20:59:11.051831 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_mariadb_password)
2026-07-30 20:59:11.051840 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_rabbitmq_password)
2026-07-30 20:59:11.051848 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_keystone_password)
2026-07-30 20:59:11.051857 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_mariadb_password)
2026-07-30 20:59:11.051872 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_rabbitmq_password)
2026-07-30 20:59:11.051881 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_keystone_password)
2026-07-30 20:59:11.051890 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_mariadb_password)
2026-07-30 20:59:11.051899 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_rabbitmq_password)
2026-07-30 20:59:11.051907 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_keystone_password)
2026-07-30 20:59:11.051916 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_trustee_keystone_password)
2026-07-30 20:59:11.051924 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_stack_user_keystone_password)
2026-07-30 20:59:11.051933 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_mariadb_password)
2026-07-30 20:59:11.051942 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_rabbitmq_password)
2026-07-30 20:59:11.051950 | instance | ok: [localhost] => (item=openstack_helm_endpoints_horizon_mariadb_password)
2026-07-30 20:59:11.051959 | instance | ok: [localhost] => (item=openstack_helm_endpoints_tempest_keystone_password)
2026-07-30 20:59:11.051971 | instance | ok: [localhost] => (item=openstack_helm_endpoints_openstack_exporter_keystone_password)
2026-07-30 20:59:11.051984 | instance | ok: [localhost] => (item=openstack_helm_endpoints_rgw_keystone_password)
2026-07-30 20:59:11.051997 | instance | ok: [localhost] => (item=openstack_helm_endpoints_manila_keystone_password)
2026-07-30 20:59:11.052008 | instance | ok: [localhost] => (item=openstack_helm_endpoints_manila_mariadb_password)
2026-07-30 20:59:11.052020 | instance | ok: [localhost] => (item=openstack_helm_endpoints_staffeln_mariadb_password)
2026-07-30 20:59:11.052029 | instance |
2026-07-30 20:59:11.052043 | instance | TASK [Generate base64 encoded secrets] *****************************************
2026-07-30 20:59:11.052052 | instance | Thursday 30 July 2026  20:58:55 +0000 (0:00:00.403)       0:00:13.300 *********
2026-07-30 20:59:11.052061 | instance | ok: [localhost] => (item=barbican_kek)
2026-07-30 20:59:11.052070 | instance |
2026-07-30 20:59:11.052078 | instance | TASK [Generate temporary files for generating keys for missing variables] ******
2026-07-30 20:59:11.052087 | instance | Thursday 30 July 2026  20:58:56 +0000 (0:00:00.050)       0:00:13.351 *********
2026-07-30 20:59:11.052096 | instance | changed: [localhost] => (item=manila_ssh_key)
2026-07-30 20:59:11.052104 | instance | changed: [localhost] => (item=nova_ssh_key)
2026-07-30 20:59:11.052113 | instance |
2026-07-30 20:59:11.052122 | instance | TASK [Generate SSH keys for missing variables] *********************************
2026-07-30 20:59:11.052130 | instance | Thursday 30 July 2026  20:58:56 +0000 (0:00:00.496)       0:00:13.847 *********
2026-07-30 20:59:11.052139 | instance | changed: [localhost] => (item=manila_ssh_key)
2026-07-30 20:59:11.052148 | instance | changed: [localhost] => (item=nova_ssh_key)
2026-07-30 20:59:11.052156 | instance |
2026-07-30 20:59:11.052165 | instance | TASK [Set values for SSH keys] *************************************************
2026-07-30 20:59:11.052174 | instance | Thursday 30 July 2026  20:59:00 +0000 (0:00:04.472)       0:00:18.319 *********
2026-07-30 20:59:11.052182 | instance | ok: [localhost] => (item=manila_ssh_key)
2026-07-30 20:59:11.052191 | instance | ok: [localhost] => (item=nova_ssh_key)
2026-07-30 20:59:11.052200 | instance |
2026-07-30 20:59:11.052208 | instance | TASK [Delete the temporary files generated for SSH keys] ***********************
2026-07-30 20:59:11.052217 | instance | Thursday 30 July 2026  20:59:01 +0000 (0:00:00.049)       0:00:18.369 *********
2026-07-30 20:59:11.052226 | instance | changed: [localhost] => (item=manila_ssh_key)
2026-07-30 20:59:11.052234 | instance | changed: [localhost] => (item=nova_ssh_key)
2026-07-30 20:59:11.052243 | instance |
2026-07-30 20:59:11.052251 | instance | TASK [Write new secrets file to disk] ******************************************
2026-07-30 20:59:11.052260 | instance | Thursday 30 July 2026  20:59:01 +0000 (0:00:00.445)       0:00:18.815 *********
2026-07-30 20:59:11.052292 | instance | changed: [localhost]
2026-07-30 20:59:11.052346 | instance |
2026-07-30 20:59:11.052356 | instance | TASK [Encrypt secrets file with Vault password] ********************************
2026-07-30 20:59:11.052365 | instance | Thursday 30 July 2026  20:59:01 +0000 (0:00:00.457)       0:00:19.272 *********
2026-07-30 20:59:11.052382 | instance | skipping: [localhost]
2026-07-30 20:59:11.052391 | instance |
2026-07-30 20:59:11.052399 | instance | PLAY [Setup networking] ********************************************************
2026-07-30 20:59:11.052408 | instance |
2026-07-30 20:59:11.052417 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:11.052426 | instance | Thursday 30 July 2026  20:59:01 +0000 (0:00:00.037)       0:00:19.310 *********
2026-07-30 20:59:11.052435 | instance | ok: [instance]
2026-07-30 20:59:11.052444 | instance |
2026-07-30 20:59:11.052453 | instance | TASK [Create bridge for management network] ************************************
2026-07-30 20:59:11.052461 | instance | Thursday 30 July 2026  20:59:02 +0000 (0:00:00.667)       0:00:19.977 *********
2026-07-30 20:59:11.052470 | instance | ok: [instance]
2026-07-30 20:59:11.052479 | instance |
2026-07-30 20:59:11.052488 | instance | TASK [Create fake interface for management bridge] *****************************
2026-07-30 20:59:11.052496 | instance | Thursday 30 July 2026  20:59:02 +0000 (0:00:00.327)       0:00:20.305 *********
2026-07-30 20:59:11.052505 | instance | ok: [instance]
2026-07-30 20:59:11.052514 | instance |
2026-07-30 20:59:11.052523 | instance | TASK [Assign dummy interface to management bridge] *****************************
2026-07-30 20:59:11.052531 | instance | Thursday 30 July 2026  20:59:03 +0000 (0:00:00.208)       0:00:20.513 *********
2026-07-30 20:59:11.052540 | instance | ok: [instance]
2026-07-30 20:59:11.052549 | instance |
2026-07-30 20:59:11.052557 | instance | TASK [Assign IP address for management bridge] *********************************
2026-07-30 20:59:11.052566 | instance | Thursday 30 July 2026  20:59:03 +0000 (0:00:00.192)       0:00:20.705 *********
2026-07-30 20:59:11.052575 | instance | ok: [instance]
2026-07-30 20:59:11.052584 | instance |
2026-07-30 20:59:11.052592 | instance | TASK [Bring up interfaces] *****************************************************
2026-07-30 20:59:11.052601 | instance | Thursday 30 July 2026  20:59:03 +0000 (0:00:00.199)       0:00:20.905 *********
2026-07-30 20:59:11.052610 | instance | ok: [instance] => (item=br-mgmt)
2026-07-30 20:59:11.052619 | instance | ok: [instance] => (item=dummy0)
2026-07-30 20:59:11.052627 | instance |
2026-07-30 20:59:11.052636 | instance | PLAY [Create devices for Ceph] *************************************************
2026-07-30 20:59:11.052645 | instance |
2026-07-30 20:59:11.052653 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:11.052662 | instance | Thursday 30 July 2026  20:59:03 +0000 (0:00:00.380)       0:00:21.285 *********
2026-07-30 20:59:11.052671 | instance | ok: [instance]
2026-07-30 20:59:11.052680 | instance |
2026-07-30 20:59:11.052688 | instance | TASK [Install depedencies] *****************************************************
2026-07-30 20:59:11.052697 | instance | Thursday 30 July 2026  20:59:04 +0000 (0:00:00.714)       0:00:22.000 *********
2026-07-30 20:59:11.373677 | instance | ok: Runtime: 0:00:00.009544
2026-07-30 20:59:11.384544 | 
2026-07-30 20:59:11.384670 | TASK [Track last task for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:11.470137 | instance | ok
2026-07-30 20:59:11.476169 | 
2026-07-30 20:59:11.476257 | TASK [Advance live log offset for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:11.542283 | instance | ok
2026-07-30 20:59:11.549756 | 
2026-07-30 20:59:11.549825 | TASK [Report progress heartbeat for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:11.611919 | instance | ok:
2026-07-30 20:59:11.612082 | instance | Molecule aio (aio-openvswitch) is still running after 40 seconds.
2026-07-30 20:59:11.612110 | instance | Last task: TASK [Install depedencies]
2026-07-30 20:59:11.619735 | 
2026-07-30 20:59:11.619902 | TASK [Continue monitoring Molecule aio (aio-openvswitch)]
2026-07-30 20:59:11.678952 | instance | ok
2026-07-30 20:59:11.691557 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-30 20:59:11.706634 | 
2026-07-30 20:59:11.706708 | TASK [Wait before polling Molecule aio (aio-openvswitch)]
2026-07-30 20:59:31.769304 | instance | ok
2026-07-30 20:59:31.775837 | 
2026-07-30 20:59:31.775908 | TASK [Count progress poll for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:31.829478 | instance | ok
2026-07-30 20:59:31.837521 | 
2026-07-30 20:59:31.837618 | TASK [Poll status for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:32.050870 | instance | ok
2026-07-30 20:59:32.059417 | 
2026-07-30 20:59:32.059494 | TASK [Inspect live log for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:32.240171 | instance | ok
2026-07-30 20:59:32.248249 | 
2026-07-30 20:59:32.248387 | TASK [Calculate new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:32.311118 | instance | ok
2026-07-30 20:59:32.317137 | 
2026-07-30 20:59:32.317211 | TASK [Read new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:32.544497 | instance | changed: [instance]
2026-07-30 20:59:32.555942 | instance |
2026-07-30 20:59:32.555974 | instance | TASK [Start up service] ********************************************************
2026-07-30 20:59:32.555993 | instance | Thursday 30 July 2026  20:59:25 +0000 (0:00:21.198)       0:00:43.198 *********
2026-07-30 20:59:32.556006 | instance | ok: [instance]
2026-07-30 20:59:32.556016 | instance |
2026-07-30 20:59:32.556027 | instance | TASK [Generate lvm.conf] *******************************************************
2026-07-30 20:59:32.556036 | instance | Thursday 30 July 2026  20:59:26 +0000 (0:00:00.550)       0:00:43.748 *********
2026-07-30 20:59:32.556046 | instance | ok: [instance]
2026-07-30 20:59:32.556054 | instance |
2026-07-30 20:59:32.556064 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-07-30 20:59:32.556073 | instance | Thursday 30 July 2026  20:59:26 +0000 (0:00:00.211)       0:00:43.960 *********
2026-07-30 20:59:32.556082 | instance | changed: [instance]
2026-07-30 20:59:32.556091 | instance |
2026-07-30 20:59:32.556099 | instance | TASK [Get list of all loopback devices] ****************************************
2026-07-30 20:59:32.556108 | instance | Thursday 30 July 2026  20:59:27 +0000 (0:00:00.470)       0:00:44.430 *********
2026-07-30 20:59:32.556117 | instance | ok: [instance]
2026-07-30 20:59:32.556125 | instance |
2026-07-30 20:59:32.556134 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-07-30 20:59:32.556143 | instance | Thursday 30 July 2026  20:59:27 +0000 (0:00:00.183)       0:00:44.613 *********
2026-07-30 20:59:32.556151 | instance | skipping: [instance]
2026-07-30 20:59:32.556160 | instance |
2026-07-30 20:59:32.556168 | instance | TASK [Create devices for Ceph] *************************************************
2026-07-30 20:59:32.556181 | instance | Thursday 30 July 2026  20:59:27 +0000 (0:00:00.024)       0:00:44.637 *********
2026-07-30 20:59:32.556190 | instance | changed: [instance] => (item=osd0)
2026-07-30 20:59:32.556200 | instance | changed: [instance] => (item=osd1)
2026-07-30 20:59:32.556208 | instance | changed: [instance] => (item=osd2)
2026-07-30 20:59:32.556217 | instance |
2026-07-30 20:59:32.556226 | instance | TASK [Set permissions on loopback devices] *************************************
2026-07-30 20:59:32.556234 | instance | Thursday 30 July 2026  20:59:27 +0000 (0:00:00.523)       0:00:45.160 *********
2026-07-30 20:59:32.556243 | instance | changed: [instance] => (item=osd0)
2026-07-30 20:59:32.556251 | instance | changed: [instance] => (item=osd1)
2026-07-30 20:59:32.556260 | instance | changed: [instance] => (item=osd2)
2026-07-30 20:59:32.556269 | instance |
2026-07-30 20:59:32.556277 | instance | TASK [Start loop devices] ******************************************************
2026-07-30 20:59:32.556286 | instance | Thursday 30 July 2026  20:59:28 +0000 (0:00:00.525)       0:00:45.686 *********
2026-07-30 20:59:32.556294 | instance | changed: [instance] => (item=osd0)
2026-07-30 20:59:32.556328 | instance | changed: [instance] => (item=osd1)
2026-07-30 20:59:32.556339 | instance | changed: [instance] => (item=osd2)
2026-07-30 20:59:32.556348 | instance |
2026-07-30 20:59:32.556356 | instance | TASK [Create a volume group for each loop device] ******************************
2026-07-30 20:59:32.556365 | instance | Thursday 30 July 2026  20:59:29 +0000 (0:00:00.711)       0:00:46.397 *********
2026-07-30 20:59:32.556374 | instance | changed: [instance] => (item=osd0)
2026-07-30 20:59:32.556382 | instance | changed: [instance] => (item=osd1)
2026-07-30 20:59:32.865431 | instance | ok: Runtime: 0:00:00.020023
2026-07-30 20:59:32.871967 | 
2026-07-30 20:59:32.872034 | TASK [Track last task for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:32.971794 | instance | ok
2026-07-30 20:59:32.977716 | 
2026-07-30 20:59:32.977788 | TASK [Advance live log offset for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:33.052216 | instance | ok
2026-07-30 20:59:33.058929 | 
2026-07-30 20:59:33.059040 | TASK [Report progress heartbeat for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:33.126754 | instance | ok:
2026-07-30 20:59:33.126970 | instance | Molecule aio (aio-openvswitch) is still running after 60 seconds.
2026-07-30 20:59:33.127010 | instance | Last task: TASK [Create a volume group for each loop device]
2026-07-30 20:59:33.136119 | 
2026-07-30 20:59:33.136198 | TASK [Continue monitoring Molecule aio (aio-openvswitch)]
2026-07-30 20:59:33.194463 | instance | ok
2026-07-30 20:59:33.207236 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-30 20:59:33.222627 | 
2026-07-30 20:59:33.222785 | TASK [Wait before polling Molecule aio (aio-openvswitch)]
2026-07-30 20:59:53.283299 | instance | ok
2026-07-30 20:59:53.290173 | 
2026-07-30 20:59:53.290276 | TASK [Count progress poll for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:53.360601 | instance | ok
2026-07-30 20:59:53.367257 | 
2026-07-30 20:59:53.367362 | TASK [Poll status for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:53.604180 | instance | ok
2026-07-30 20:59:53.723424 | 
2026-07-30 20:59:53.723539 | TASK [Inspect live log for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:53.925530 | instance | ok
2026-07-30 20:59:53.940215 | 
2026-07-30 20:59:53.940335 | TASK [Calculate new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:54.003824 | instance | ok
2026-07-30 20:59:54.013261 | 
2026-07-30 20:59:54.013339 | TASK [Read new output for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:54.229712 | instance | changed: [instance] => (item=osd2)
2026-07-30 20:59:54.229812 | instance |
2026-07-30 20:59:54.229826 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-07-30 20:59:54.229845 | instance | Thursday 30 July 2026  20:59:32 +0000 (0:00:03.677)       0:00:50.075 *********
2026-07-30 20:59:54.229858 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-07-30 20:59:54.229870 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-07-30 20:59:54.229882 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-07-30 20:59:54.229891 | instance |
2026-07-30 20:59:54.229902 | instance | PLAY [controllers] *************************************************************
2026-07-30 20:59:54.229919 | instance |
2026-07-30 20:59:54.229953 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.229967 | instance | Thursday 30 July 2026  20:59:34 +0000 (0:00:02.085)       0:00:52.161 *********
2026-07-30 20:59:54.229980 | instance | ok: [instance]
2026-07-30 20:59:54.229993 | instance |
2026-07-30 20:59:54.230004 | instance | TASK [Set masquerade rule] *****************************************************
2026-07-30 20:59:54.230017 | instance | Thursday 30 July 2026  20:59:35 +0000 (0:00:00.909)       0:00:53.071 *********
2026-07-30 20:59:54.230029 | instance | changed: [instance]
2026-07-30 20:59:54.230042 | instance |
2026-07-30 20:59:54.230051 | instance | PLAY RECAP *********************************************************************
2026-07-30 20:59:54.230067 | instance | instance                   : ok=24   changed=10   unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-07-30 20:59:54.230077 | instance | localhost                  : ok=40   changed=21   unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-07-30 20:59:54.230085 | instance |
2026-07-30 20:59:54.230094 | instance | Thursday 30 July 2026  20:59:36 +0000 (0:00:00.555)       0:00:53.626 *********
2026-07-30 20:59:54.230103 | instance | ===============================================================================
2026-07-30 20:59:54.230111 | instance | Install depedencies ---------------------------------------------------- 21.20s
2026-07-30 20:59:54.230120 | instance | Generate SSH keys for missing variables --------------------------------- 4.47s
2026-07-30 20:59:54.230129 | instance | Create a volume group for each loop device ------------------------------ 3.68s
2026-07-30 20:59:54.230137 | instance | Create a logical volume for each loop device ---------------------------- 2.09s
2026-07-30 20:59:54.230146 | instance | Create folders for workspace -------------------------------------------- 1.40s
2026-07-30 20:59:54.230154 | instance | Gathering Facts --------------------------------------------------------- 1.15s
2026-07-30 20:59:54.230163 | instance | Install "dirmngr" for GPG keyserver operations -------------------------- 1.12s
2026-07-30 20:59:54.230172 | instance | Gathering Facts --------------------------------------------------------- 0.91s
2026-07-30 20:59:54.230180 | instance | Gathering Facts --------------------------------------------------------- 0.86s
2026-07-30 20:59:54.230189 | instance | Generate endpoint skeleton for missing variables ------------------------ 0.80s
2026-07-30 20:59:54.230197 | instance | Gathering Facts --------------------------------------------------------- 0.71s
2026-07-30 20:59:54.230206 | instance | Start loop devices ------------------------------------------------------ 0.71s
2026-07-30 20:59:54.230215 | instance | Configure short hostname ------------------------------------------------ 0.67s
2026-07-30 20:59:54.230223 | instance | Gathering Facts --------------------------------------------------------- 0.67s
2026-07-30 20:59:54.230232 | instance | Write new Ceph control plane configuration file to disk ----------------- 0.65s
2026-07-30 20:59:54.230240 | instance | Purge "snapd" package --------------------------------------------------- 0.63s
2026-07-30 20:59:54.230249 | instance | Set masquerade rule ----------------------------------------------------- 0.56s
2026-07-30 20:59:54.230258 | instance | Start up service -------------------------------------------------------- 0.55s
2026-07-30 20:59:54.230266 | instance | Set permissions on loopback devices ------------------------------------- 0.53s
2026-07-30 20:59:54.230275 | instance | Create devices for Ceph ------------------------------------------------- 0.52s
2026-07-30 20:59:54.230294 | instance | INFO     [aio > prepare] Executed: Successful
2026-07-30 20:59:54.230304 | instance | INFO     [aio > converge] Executing
2026-07-30 20:59:54.230313 | instance |
2026-07-30 20:59:54.230322 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.230331 | instance |
2026-07-30 20:59:54.230339 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-07-30 20:59:54.230348 | instance | Thursday 30 July 2026  20:59:39 +0000 (0:00:00.017)       0:00:00.017 *********
2026-07-30 20:59:54.230357 | instance | ok: [instance]
2026-07-30 20:59:54.230366 | instance |
2026-07-30 20:59:54.230375 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-07-30 20:59:54.230383 | instance |
2026-07-30 20:59:54.230392 | instance | PLAY [Deploy Ceph OSDs] ********************************************************
2026-07-30 20:59:54.230401 | instance |
2026-07-30 20:59:54.230409 | instance | PLAY [Deploy Ceph exporters] ***************************************************
2026-07-30 20:59:54.230418 | instance |
2026-07-30 20:59:54.230427 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.230436 | instance |
2026-07-30 20:59:54.230444 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.230453 | instance |
2026-07-30 20:59:54.230462 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-07-30 20:59:54.230470 | instance | Thursday 30 July 2026  20:59:39 +0000 (0:00:00.636)       0:00:00.654 *********
2026-07-30 20:59:54.230479 | instance | ok: [instance]
2026-07-30 20:59:54.230488 | instance |
2026-07-30 20:59:54.230496 | instance | PLAY [Configure Kubernetes VIP] ************************************************
2026-07-30 20:59:54.230505 | instance |
2026-07-30 20:59:54.230513 | instance | PLAY [Install Kubernetes] ******************************************************
2026-07-30 20:59:54.230522 | instance |
2026-07-30 20:59:54.230531 | instance | PLAY [Install control-plane components] ****************************************
2026-07-30 20:59:54.230540 | instance |
2026-07-30 20:59:54.230558 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.230567 | instance |
2026-07-30 20:59:54.230576 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.230584 | instance |
2026-07-30 20:59:54.230593 | instance | PLAY [controllers] *************************************************************
2026-07-30 20:59:54.230602 | instance |
2026-07-30 20:59:54.230610 | instance | PLAY [Deploy Infrastructure] ***************************************************
2026-07-30 20:59:54.230619 | instance |
2026-07-30 20:59:54.230627 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.230677 | instance | Thursday 30 July 2026  20:59:40 +0000 (0:00:00.327)       0:00:00.982 *********
2026-07-30 20:59:54.230691 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-07-30 20:59:54.230700 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-07-30 20:59:54.230709 | instance | interpreter could change the meaning of that path. See
2026-07-30 20:59:54.230718 | instance | https://docs.ansible.com/ansible-
2026-07-30 20:59:54.230727 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 20:59:54.230736 | instance | ok: [instance]
2026-07-30 20:59:54.230745 | instance |
2026-07-30 20:59:54.230753 | instance | PLAY [Deploy Monitoring] *******************************************************
2026-07-30 20:59:54.230762 | instance |
2026-07-30 20:59:54.230771 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.230779 | instance | Thursday 30 July 2026  20:59:41 +0000 (0:00:01.332)       0:00:02.314 *********
2026-07-30 20:59:54.230788 | instance | ok: [instance]
2026-07-30 20:59:54.230797 | instance |
2026-07-30 20:59:54.230805 | instance | PLAY [Deploy OpenStack] ********************************************************
2026-07-30 20:59:54.230814 | instance |
2026-07-30 20:59:54.230823 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.230832 | instance | Thursday 30 July 2026  20:59:42 +0000 (0:00:01.065)       0:00:03.379 *********
2026-07-30 20:59:54.230847 | instance | ok: [instance]
2026-07-30 20:59:54.230856 | instance |
2026-07-30 20:59:54.230864 | instance | PLAY [Configure operating system] **********************************************
2026-07-30 20:59:54.230873 | instance |
2026-07-30 20:59:54.230882 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.230890 | instance | Thursday 30 July 2026  20:59:43 +0000 (0:00:01.295)       0:00:04.674 *********
2026-07-30 20:59:54.230899 | instance | ok: [instance]
2026-07-30 20:59:54.230908 | instance |
2026-07-30 20:59:54.230916 | instance | TASK [vexxhost.atmosphere.udev : Add udev rules for Pure Storage FlashArray] ***
2026-07-30 20:59:54.230925 | instance | Thursday 30 July 2026  20:59:44 +0000 (0:00:00.872)       0:00:05.547 *********
2026-07-30 20:59:54.230934 | instance | changed: [instance]
2026-07-30 20:59:54.230942 | instance |
2026-07-30 20:59:54.230951 | instance | TASK [vexxhost.atmosphere.udev : Add udev rules for SCSI Unit Attention] *******
2026-07-30 20:59:54.230960 | instance | Thursday 30 July 2026  20:59:45 +0000 (0:00:00.693)       0:00:06.240 *********
2026-07-30 20:59:54.230969 | instance | changed: [instance]
2026-07-30 20:59:54.230977 | instance |
2026-07-30 20:59:54.230986 | instance | RUNNING HANDLER [vexxhost.atmosphere.udev : Reload udev] ***********************
2026-07-30 20:59:54.230995 | instance | Thursday 30 July 2026  20:59:45 +0000 (0:00:00.441)       0:00:06.681 *********
2026-07-30 20:59:54.231004 | instance | ok: [instance]
2026-07-30 20:59:54.231012 | instance |
2026-07-30 20:59:54.231021 | instance | PLAY [Deploy SDN] **************************************************************
2026-07-30 20:59:54.231030 | instance |
2026-07-30 20:59:54.231038 | instance | PLAY [controllers[0]] **********************************************************
2026-07-30 20:59:54.231047 | instance |
2026-07-30 20:59:54.231055 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.231064 | instance | Thursday 30 July 2026  20:59:46 +0000 (0:00:00.394)       0:00:07.076 *********
2026-07-30 20:59:54.231073 | instance | ok: [instance]
2026-07-30 20:59:54.231082 | instance |
2026-07-30 20:59:54.231090 | instance | PLAY [controllers] *************************************************************
2026-07-30 20:59:54.231099 | instance |
2026-07-30 20:59:54.231108 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.231116 | instance | Thursday 30 July 2026  20:59:47 +0000 (0:00:01.248)       0:00:08.324 *********
2026-07-30 20:59:54.231125 | instance | ok: [instance]
2026-07-30 20:59:54.231134 | instance |
2026-07-30 20:59:54.231142 | instance | PLAY [Configure networking] ****************************************************
2026-07-30 20:59:54.231151 | instance |
2026-07-30 20:59:54.231160 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 20:59:54.231168 | instance | Thursday 30 July 2026  20:59:48 +0000 (0:00:00.923)       0:00:09.248 *********
2026-07-30 20:59:54.231177 | instance | ok: [instance]
2026-07-30 20:59:54.231185 | instance |
2026-07-30 20:59:54.231194 | instance | TASK [Add IP address to "br-ex"] ***********************************************
2026-07-30 20:59:54.231203 | instance | Thursday 30 July 2026  20:59:49 +0000 (0:00:00.897)       0:00:10.145 *********
2026-07-30 20:59:54.231212 | instance | ok: [instance]
2026-07-30 20:59:54.231220 | instance |
2026-07-30 20:59:54.231229 | instance | TASK [Set "br-ex" interface to "up"] *******************************************
2026-07-30 20:59:54.231237 | instance | Thursday 30 July 2026  20:59:49 +0000 (0:00:00.211)       0:00:10.357 *********
2026-07-30 20:59:54.231246 | instance | ok: [instance]
2026-07-30 20:59:54.231255 | instance |
2026-07-30 20:59:54.231263 | instance | PLAY RECAP *********************************************************************
2026-07-30 20:59:54.231272 | instance | instance                   : ok=14   changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 20:59:54.231281 | instance |
2026-07-30 20:59:54.231290 | instance | Thursday 30 July 2026  20:59:49 +0000 (0:00:00.192)       0:00:10.549 *********
2026-07-30 20:59:54.231298 | instance | ===============================================================================
2026-07-30 20:59:54.231307 | instance | Gathering Facts --------------------------------------------------------- 1.33s
2026-07-30 20:59:54.231321 | instance | Gathering Facts --------------------------------------------------------- 1.30s
2026-07-30 20:59:54.231330 | instance | Gathering Facts --------------------------------------------------------- 1.25s
2026-07-30 20:59:54.231339 | instance | Gathering Facts --------------------------------------------------------- 1.07s
2026-07-30 20:59:54.231348 | instance | Gathering Facts --------------------------------------------------------- 0.92s
2026-07-30 20:59:54.231361 | instance | Gathering Facts --------------------------------------------------------- 0.90s
2026-07-30 20:59:54.231370 | instance | Gathering Facts --------------------------------------------------------- 0.87s
2026-07-30 20:59:54.231379 | instance | vexxhost.atmosphere.udev : Add udev rules for Pure Storage FlashArray --- 0.69s
2026-07-30 20:59:54.231388 | instance | Set a fact with the "atmosphere_images" for other plays ----------------- 0.64s
2026-07-30 20:59:54.231399 | instance | vexxhost.atmosphere.udev : Add udev rules for SCSI Unit Attention ------- 0.44s
2026-07-30 20:59:54.231408 | instance | vexxhost.atmosphere.udev : Reload udev ---------------------------------- 0.39s
2026-07-30 20:59:54.231417 | instance | Set a fact with the "atmosphere_images" for other plays ----------------- 0.33s
2026-07-30 20:59:54.231426 | instance | Add IP address to "br-ex" ----------------------------------------------- 0.21s
2026-07-30 20:59:54.231434 | instance | Set "br-ex" interface to "up" ------------------------------------------- 0.19s
2026-07-30 20:59:54.231443 | instance | INFO     [aio > converge] Executed: Successful
2026-07-30 20:59:54.231452 | instance | INFO     [aio > idempotence] Executing
2026-07-30 20:59:54.231461 | instance |
2026-07-30 20:59:54.231470 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.231478 | instance |
2026-07-30 20:59:54.231487 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-07-30 20:59:54.231496 | instance | Thursday 30 July 2026  20:59:52 +0000 (0:00:00.019)       0:00:00.019 *********
2026-07-30 20:59:54.231505 | instance | ok: [instance]
2026-07-30 20:59:54.231513 | instance |
2026-07-30 20:59:54.231522 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-07-30 20:59:54.231531 | instance |
2026-07-30 20:59:54.231540 | instance | PLAY [Deploy Ceph OSDs] ********************************************************
2026-07-30 20:59:54.231559 | instance |
2026-07-30 20:59:54.231568 | instance | PLAY [Deploy Ceph exporters] ***************************************************
2026-07-30 20:59:54.231577 | instance |
2026-07-30 20:59:54.231585 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.231594 | instance |
2026-07-30 20:59:54.231603 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.231612 | instance |
2026-07-30 20:59:54.231620 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-07-30 20:59:54.231629 | instance | Thursday 30 July 2026  20:59:53 +0000 (0:00:00.613)       0:00:00.632 *********
2026-07-30 20:59:54.231638 | instance | ok: [instance]
2026-07-30 20:59:54.231646 | instance |
2026-07-30 20:59:54.231655 | instance | PLAY [Configure Kubernetes VIP] ************************************************
2026-07-30 20:59:54.231664 | instance |
2026-07-30 20:59:54.231672 | instance | PLAY [Install Kubernetes] ******************************************************
2026-07-30 20:59:54.231681 | instance |
2026-07-30 20:59:54.231690 | instance | PLAY [Install control-plane components] ****************************************
2026-07-30 20:59:54.231698 | instance |
2026-07-30 20:59:54.231707 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.231716 | instance |
2026-07-30 20:59:54.231724 | instance | PLAY [all] *********************************************************************
2026-07-30 20:59:54.231733 | instance |
2026-07-30 20:59:54.231742 | instance | PLAY [controllers] *************************************************************
2026-07-30 20:59:54.560553 | instance | ok: Runtime: 0:00:00.011321
2026-07-30 20:59:54.578810 | 
2026-07-30 20:59:54.578947 | TASK [Track last task for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:54.664762 | instance | ok
2026-07-30 20:59:54.670736 | 
2026-07-30 20:59:54.670808 | TASK [Advance live log offset for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:54.723169 | instance | ok
2026-07-30 20:59:54.843311 | 
2026-07-30 20:59:54.843443 | TASK [Report progress heartbeat for Molecule aio (aio-openvswitch)]
2026-07-30 20:59:54.907159 | instance | ok:
2026-07-30 20:59:54.907346 | instance | Molecule aio (aio-openvswitch) is still running after 80 seconds.
2026-07-30 20:59:54.907389 | instance | Last task: TASK [Set a fact with the "atmosphere_images" for other plays]
2026-07-30 20:59:54.958634 | 
2026-07-30 20:59:54.958760 | TASK [Continue monitoring Molecule aio (aio-openvswitch)]
2026-07-30 20:59:55.013432 | instance | ok
2026-07-30 20:59:55.027259 | instance | included: /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-30 20:59:55.041770 | 
2026-07-30 20:59:55.041840 | TASK [Wait before polling Molecule aio (aio-openvswitch)]
2026-07-30 21:00:15.114667 | instance | ok
2026-07-30 21:00:15.121474 | 
2026-07-30 21:00:15.121560 | TASK [Count progress poll for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:15.178696 | instance | ok
2026-07-30 21:00:15.187315 | 
2026-07-30 21:00:15.187419 | TASK [Poll status for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:15.439660 | instance | ok
2026-07-30 21:00:15.449524 | 
2026-07-30 21:00:15.449682 | TASK [Inspect live log for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:15.632950 | instance | ok
2026-07-30 21:00:15.639943 | 
2026-07-30 21:00:15.640016 | TASK [Calculate new output for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:15.698461 | instance | ok
2026-07-30 21:00:15.704409 | 
2026-07-30 21:00:15.704481 | TASK [Read new output for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:15.905980 | instance |
2026-07-30 21:00:15.906183 | instance | PLAY [Deploy Infrastructure] ***************************************************
2026-07-30 21:00:15.906202 | instance |
2026-07-30 21:00:15.906220 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.906238 | instance | Thursday 30 July 2026  20:59:53 +0000 (0:00:00.371)       0:00:01.004 *********
2026-07-30 21:00:15.906256 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-07-30 21:00:15.906266 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-07-30 21:00:15.906277 | instance | interpreter could change the meaning of that path. See
2026-07-30 21:00:15.906287 | instance | https://docs.ansible.com/ansible-
2026-07-30 21:00:15.906297 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 21:00:15.906306 | instance | ok: [instance]
2026-07-30 21:00:15.906315 | instance |
2026-07-30 21:00:15.906324 | instance | PLAY [Deploy Monitoring] *******************************************************
2026-07-30 21:00:15.906333 | instance |
2026-07-30 21:00:15.906342 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.906351 | instance | Thursday 30 July 2026  20:59:55 +0000 (0:00:01.329)       0:00:02.334 *********
2026-07-30 21:00:15.906359 | instance | ok: [instance]
2026-07-30 21:00:15.906368 | instance |
2026-07-30 21:00:15.906377 | instance | PLAY [Deploy OpenStack] ********************************************************
2026-07-30 21:00:15.906386 | instance |
2026-07-30 21:00:15.906399 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.906408 | instance | Thursday 30 July 2026  20:59:56 +0000 (0:00:01.102)       0:00:03.436 *********
2026-07-30 21:00:15.906417 | instance | ok: [instance]
2026-07-30 21:00:15.906425 | instance |
2026-07-30 21:00:15.906435 | instance | PLAY [Configure operating system] **********************************************
2026-07-30 21:00:15.906443 | instance |
2026-07-30 21:00:15.906452 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.906524 | instance | Thursday 30 July 2026  20:59:57 +0000 (0:00:01.276)       0:00:04.713 *********
2026-07-30 21:00:15.906537 | instance | ok: [instance]
2026-07-30 21:00:15.906546 | instance |
2026-07-30 21:00:15.906555 | instance | TASK [vexxhost.atmosphere.udev : Add udev rules for Pure Storage FlashArray] ***
2026-07-30 21:00:15.906564 | instance | Thursday 30 July 2026  20:59:58 +0000 (0:00:00.902)       0:00:05.615 *********
2026-07-30 21:00:15.906572 | instance | ok: [instance]
2026-07-30 21:00:15.906581 | instance |
2026-07-30 21:00:15.906590 | instance | TASK [vexxhost.atmosphere.udev : Add udev rules for SCSI Unit Attention] *******
2026-07-30 21:00:15.906599 | instance | Thursday 30 July 2026  20:59:59 +0000 (0:00:00.645)       0:00:06.260 *********
2026-07-30 21:00:15.906607 | instance | ok: [instance]
2026-07-30 21:00:15.906616 | instance |
2026-07-30 21:00:15.906625 | instance | PLAY [Deploy SDN] **************************************************************
2026-07-30 21:00:15.906634 | instance |
2026-07-30 21:00:15.906643 | instance | PLAY [controllers[0]] **********************************************************
2026-07-30 21:00:15.906651 | instance |
2026-07-30 21:00:15.906660 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.906669 | instance | Thursday 30 July 2026  20:59:59 +0000 (0:00:00.533)       0:00:06.794 *********
2026-07-30 21:00:15.906678 | instance | ok: [instance]
2026-07-30 21:00:15.906686 | instance |
2026-07-30 21:00:15.906695 | instance | PLAY [controllers] *************************************************************
2026-07-30 21:00:15.906704 | instance |
2026-07-30 21:00:15.906713 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.906721 | instance | Thursday 30 July 2026  21:00:00 +0000 (0:00:01.232)       0:00:08.027 *********
2026-07-30 21:00:15.906730 | instance | ok: [instance]
2026-07-30 21:00:15.906739 | instance |
2026-07-30 21:00:15.906748 | instance | PLAY [Configure networking] ****************************************************
2026-07-30 21:00:15.906756 | instance |
2026-07-30 21:00:15.906765 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.906774 | instance | Thursday 30 July 2026  21:00:01 +0000 (0:00:00.951)       0:00:08.978 *********
2026-07-30 21:00:15.906792 | instance | ok: [instance]
2026-07-30 21:00:15.906802 | instance |
2026-07-30 21:00:15.906811 | instance | TASK [Add IP address to "br-ex"] ***********************************************
2026-07-30 21:00:15.906819 | instance | Thursday 30 July 2026  21:00:02 +0000 (0:00:00.887)       0:00:09.865 *********
2026-07-30 21:00:15.906828 | instance | ok: [instance]
2026-07-30 21:00:15.906837 | instance |
2026-07-30 21:00:15.906846 | instance | TASK [Set "br-ex" interface to "up"] *******************************************
2026-07-30 21:00:15.906855 | instance | Thursday 30 July 2026  21:00:03 +0000 (0:00:00.358)       0:00:10.224 *********
2026-07-30 21:00:15.906863 | instance | ok: [instance]
2026-07-30 21:00:15.906872 | instance |
2026-07-30 21:00:15.906881 | instance | PLAY RECAP *********************************************************************
2026-07-30 21:00:15.906890 | instance | instance                   : ok=13   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 21:00:15.906899 | instance |
2026-07-30 21:00:15.906908 | instance | Thursday 30 July 2026  21:00:03 +0000 (0:00:00.191)       0:00:10.416 *********
2026-07-30 21:00:15.906917 | instance | ===============================================================================
2026-07-30 21:00:15.906926 | instance | Gathering Facts --------------------------------------------------------- 1.33s
2026-07-30 21:00:15.906935 | instance | Gathering Facts --------------------------------------------------------- 1.28s
2026-07-30 21:00:15.906943 | instance | Gathering Facts --------------------------------------------------------- 1.23s
2026-07-30 21:00:15.906952 | instance | Gathering Facts --------------------------------------------------------- 1.10s
2026-07-30 21:00:15.906961 | instance | Gathering Facts --------------------------------------------------------- 0.95s
2026-07-30 21:00:15.906970 | instance | Gathering Facts --------------------------------------------------------- 0.90s
2026-07-30 21:00:15.906979 | instance | Gathering Facts --------------------------------------------------------- 0.89s
2026-07-30 21:00:15.906987 | instance | vexxhost.atmosphere.udev : Add udev rules for Pure Storage FlashArray --- 0.65s
2026-07-30 21:00:15.906996 | instance | Set a fact with the "atmosphere_images" for other plays ----------------- 0.61s
2026-07-30 21:00:15.907005 | instance | vexxhost.atmosphere.udev : Add udev rules for SCSI Unit Attention ------- 0.53s
2026-07-30 21:00:15.907022 | instance | Set a fact with the "atmosphere_images" for other plays ----------------- 0.37s
2026-07-30 21:00:15.907032 | instance | Add IP address to "br-ex" ----------------------------------------------- 0.36s
2026-07-30 21:00:15.907041 | instance | Set "br-ex" interface to "up" ------------------------------------------- 0.19s
2026-07-30 21:00:15.907050 | instance | INFO     [aio > idempotence] Executed: Successful
2026-07-30 21:00:15.907059 | instance | INFO     [aio > side_effect] Executing
2026-07-30 21:00:15.907068 | instance | WARNING  [aio > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 21:00:15.907077 | instance | INFO     [aio > verify] Executing
2026-07-30 21:00:15.907086 | instance |
2026-07-30 21:00:15.907095 | instance | PLAY [Run tests] ***************************************************************
2026-07-30 21:00:15.907104 | instance |
2026-07-30 21:00:15.907113 | instance | TASK [Run "stestr" tests] ******************************************************
2026-07-30 21:00:15.907122 | instance | Thursday 30 July 2026  21:00:04 +0000 (0:00:00.024)       0:00:00.024 *********
2026-07-30 21:00:15.907131 | instance | [WARNING]: conditional statements should not include jinja2 templating
2026-07-30 21:00:15.907140 | instance | delimiters such as {{ }} or {% %}. Found: {{   lookup('env',
2026-07-30 21:00:15.907149 | instance | 'ATMOSPHERE_CI_RUN_STESTR')   | default('true', true)   | bool }}
2026-07-30 21:00:15.907158 | instance | skipping: [localhost]
2026-07-30 21:00:15.907166 | instance |
2026-07-30 21:00:15.907179 | instance | PLAY [Verify selected OpenStack APIs] ******************************************
2026-07-30 21:00:15.907188 | instance |
2026-07-30 21:00:15.907197 | instance | PLAY [Run Tempest tests] *******************************************************
2026-07-30 21:00:15.907206 | instance |
2026-07-30 21:00:15.907215 | instance | TASK [Gathering Facts] *********************************************************
2026-07-30 21:00:15.907230 | instance | Thursday 30 July 2026  21:00:04 +0000 (0:00:00.038)       0:00:00.063 *********
2026-07-30 21:00:15.907240 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-07-30 21:00:15.907248 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-07-30 21:00:15.907257 | instance | interpreter could change the meaning of that path. See
2026-07-30 21:00:15.907266 | instance | https://docs.ansible.com/ansible-
2026-07-30 21:00:15.907275 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 21:00:15.907287 | instance | ok: [instance]
2026-07-30 21:00:15.907299 | instance |
2026-07-30 21:00:15.907313 | instance | TASK [vexxhost.atmosphere.openstacksdk : Install openstacksdk] *****************
2026-07-30 21:00:15.907327 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:01.212)       0:00:01.275 *********
2026-07-30 21:00:15.907340 | instance | skipping: [instance]
2026-07-30 21:00:15.907354 | instance |
2026-07-30 21:00:15.907367 | instance | TASK [vexxhost.atmosphere.openstacksdk : Create openstack config directory] ****
2026-07-30 21:00:15.907378 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.038)       0:00:01.314 *********
2026-07-30 21:00:15.907387 | instance | skipping: [instance]
2026-07-30 21:00:15.907396 | instance |
2026-07-30 21:00:15.907404 | instance | TASK [vexxhost.atmosphere.openstacksdk : Generate cloud config file] ***********
2026-07-30 21:00:15.907413 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.040)       0:00:01.354 *********
2026-07-30 21:00:15.907422 | instance | skipping: [instance]
2026-07-30 21:00:15.907431 | instance |
2026-07-30 21:00:15.907439 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Retrieve list of all the needed endpoints] ***
2026-07-30 21:00:15.907449 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.048)       0:00:01.402 *********
2026-07-30 21:00:15.907458 | instance | skipping: [instance]
2026-07-30 21:00:15.907466 | instance |
2026-07-30 21:00:15.907475 | instance | TASK [Create RabbitMQ cluster] *************************************************
2026-07-30 21:00:15.907484 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.037)       0:00:01.440 *********
2026-07-30 21:00:15.907493 | instance | skipping: [instance]
2026-07-30 21:00:15.907502 | instance |
2026-07-30 21:00:15.907510 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab RabbitMQ cluster secret] ***
2026-07-30 21:00:15.907519 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.041)       0:00:01.481 *********
2026-07-30 21:00:15.907528 | instance | skipping: [instance]
2026-07-30 21:00:15.907536 | instance |
2026-07-30 21:00:15.907545 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with RabbitMQ cluster credentials] ***
2026-07-30 21:00:15.907554 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.040)       0:00:01.522 *********
2026-07-30 21:00:15.907563 | instance | skipping: [instance]
2026-07-30 21:00:15.907572 | instance |
2026-07-30 21:00:15.907581 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab Percona XtraDB cluster secret] ***
2026-07-30 21:00:15.907590 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.036)       0:00:01.558 *********
2026-07-30 21:00:15.907598 | instance | skipping: [instance]
2026-07-30 21:00:15.907607 | instance |
2026-07-30 21:00:15.907616 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with Percona XtraDB password] ***
2026-07-30 21:00:15.907625 | instance | Thursday 30 July 2026  21:00:05 +0000 (0:00:00.035)       0:00:01.594 *********
2026-07-30 21:00:15.907634 | instance | skipping: [instance]
2026-07-30 21:00:15.907642 | instance |
2026-07-30 21:00:15.907651 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Reset value for OpenStack_Helm endpoints] ***
2026-07-30 21:00:15.907660 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.037)       0:00:01.632 *********
2026-07-30 21:00:15.907668 | instance | skipping: [instance]
2026-07-30 21:00:15.907677 | instance |
2026-07-30 21:00:15.907686 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Generate OpenStack-Helm endpoints] ***
2026-07-30 21:00:15.907695 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.035)       0:00:01.667 *********
2026-07-30 21:00:15.907703 | instance | skipping: [instance]
2026-07-30 21:00:15.907712 | instance |
2026-07-30 21:00:15.907721 | instance | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Clean-up facts] ***********
2026-07-30 21:00:15.907737 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.031)       0:00:01.699 *********
2026-07-30 21:00:15.907746 | instance | skipping: [instance]
2026-07-30 21:00:15.907755 | instance |
2026-07-30 21:00:15.907763 | instance | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-07-30 21:00:15.907772 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.036)       0:00:01.736 *********
2026-07-30 21:00:15.907781 | instance | skipping: [instance]
2026-07-30 21:00:15.907790 | instance |
2026-07-30 21:00:15.907804 | instance | TASK [Generate OpenStack-Helm endpoints] ***************************************
2026-07-30 21:00:15.907818 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.043)       0:00:01.779 *********
2026-07-30 21:00:15.907828 | instance | skipping: [instance]
2026-07-30 21:00:15.907837 | instance |
2026-07-30 21:00:15.907846 | instance | TASK [vexxhost.atmosphere.tempest : Read cluster issuer CA secret] *************
2026-07-30 21:00:15.907855 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.035)       0:00:01.814 *********
2026-07-30 21:00:15.907863 | instance | skipping: [instance]
2026-07-30 21:00:15.907872 | instance |
2026-07-30 21:00:15.907881 | instance | TASK [vexxhost.atmosphere.tempest : Copy cluster issuer CA into Tempest namespace] ***
2026-07-30 21:00:15.907890 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.038)       0:00:01.853 *********
2026-07-30 21:00:15.907899 | instance | skipping: [instance]
2026-07-30 21:00:15.907907 | instance |
2026-07-30 21:00:15.907916 | instance | TASK [vexxhost.atmosphere.tempest : Get test image object] *********************
2026-07-30 21:00:15.907925 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.036)       0:00:01.889 *********
2026-07-30 21:00:15.907934 | instance | skipping: [instance]
2026-07-30 21:00:15.907942 | instance |
2026-07-30 21:00:15.907951 | instance | TASK [vexxhost.atmosphere.tempest : Configure test image ref] ******************
2026-07-30 21:00:15.907960 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.036)       0:00:01.926 *********
2026-07-30 21:00:15.907969 | instance | skipping: [instance]
2026-07-30 21:00:15.907977 | instance |
2026-07-30 21:00:15.907986 | instance | TASK [vexxhost.atmosphere.tempest : Get test flavor object] ********************
2026-07-30 21:00:15.907995 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.037)       0:00:01.964 *********
2026-07-30 21:00:15.908004 | instance | skipping: [instance]
2026-07-30 21:00:15.908012 | instance |
2026-07-30 21:00:15.908021 | instance | TASK [vexxhost.atmosphere.tempest : Set test flavor ref] ***********************
2026-07-30 21:00:15.908030 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.039)       0:00:02.003 *********
2026-07-30 21:00:15.908039 | instance | skipping: [instance]
2026-07-30 21:00:15.908048 | instance |
2026-07-30 21:00:15.908056 | instance | TASK [vexxhost.atmosphere.tempest : Get test network object] *******************
2026-07-30 21:00:15.908065 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.036)       0:00:02.040 *********
2026-07-30 21:00:15.908074 | instance | skipping: [instance]
2026-07-30 21:00:15.908083 | instance |
2026-07-30 21:00:15.908092 | instance | TASK [vexxhost.atmosphere.tempest : Set test network ref] **********************
2026-07-30 21:00:15.908100 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.035)       0:00:02.075 *********
2026-07-30 21:00:15.908109 | instance | skipping: [instance]
2026-07-30 21:00:15.908118 | instance |
2026-07-30 21:00:15.908127 | instance | TASK [vexxhost.atmosphere.tempest : Deploy Helm chart] *************************
2026-07-30 21:00:15.908136 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.044)       0:00:02.120 *********
2026-07-30 21:00:15.908144 | instance | skipping: [instance]
2026-07-30 21:00:15.908153 | instance |
2026-07-30 21:00:15.908162 | instance | TASK [vexxhost.atmosphere.tempest : Get tempest job object] ********************
2026-07-30 21:00:15.908171 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.035)       0:00:02.155 *********
2026-07-30 21:00:15.908179 | instance | skipping: [instance]
2026-07-30 21:00:15.908188 | instance |
2026-07-30 21:00:15.908197 | instance | TASK [vexxhost.atmosphere.tempest : Get tempest log] ***************************
2026-07-30 21:00:15.908206 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.036)       0:00:02.192 *********
2026-07-30 21:00:15.908220 | instance | skipping: [instance]
2026-07-30 21:00:15.908229 | instance |
2026-07-30 21:00:15.908237 | instance | TASK [vexxhost.atmosphere.tempest : Print tempest log details] *****************
2026-07-30 21:00:15.908246 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.037)       0:00:02.229 *********
2026-07-30 21:00:15.908255 | instance | skipping: [instance]
2026-07-30 21:00:15.908264 | instance |
2026-07-30 21:00:15.908273 | instance | TASK [vexxhost.atmosphere.tempest : Fail when tempest result is failed] ********
2026-07-30 21:00:15.908281 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.035)       0:00:02.265 *********
2026-07-30 21:00:15.908290 | instance | skipping: [instance]
2026-07-30 21:00:15.908323 | instance |
2026-07-30 21:00:15.908333 | instance | PLAY RECAP *********************************************************************
2026-07-30 21:00:15.908344 | instance | instance                   : ok=1    changed=0    unreachable=0    failed=0    skipped=27   rescued=0    ignored=0
2026-07-30 21:00:15.908353 | instance | localhost                  : ok=0    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-07-30 21:00:15.908362 | instance |
2026-07-30 21:00:15.908371 | instance | Thursday 30 July 2026  21:00:06 +0000 (0:00:00.037)       0:00:02.302 *********
2026-07-30 21:00:15.908380 | instance | ===============================================================================
2026-07-30 21:00:15.908392 | instance | Gathering Facts --------------------------------------------------------- 1.21s
2026-07-30 21:00:15.908405 | instance | vexxhost.atmosphere.openstacksdk : Generate cloud config file ----------- 0.05s
2026-07-30 21:00:15.908418 | instance | vexxhost.atmosphere.tempest : Set test network ref ---------------------- 0.04s
2026-07-30 21:00:15.908431 | instance | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s
2026-07-30 21:00:15.908444 | instance | Create RabbitMQ cluster ------------------------------------------------- 0.04s
2026-07-30 21:00:15.908457 | instance | vexxhost.atmosphere.openstack_helm_endpoints : Grab RabbitMQ cluster secret --- 0.04s
2026-07-30 21:00:15.908466 | instance | vexxhost.atmosphere.openstacksdk : Create openstack config directory ---- 0.04s
2026-07-30 21:00:15.908475 | instance | vexxhost.atmosphere.tempest : Get test flavor object -------------------- 0.04s
2026-07-30 21:00:15.908483 | instance | Run "stestr" tests ------------------------------------------------------ 0.04s
2026-07-30 21:00:15.908497 | instance | vexxhost.atmosphere.openstacksdk : Install openstacksdk ----------------- 0.04s
2026-07-30 21:00:15.908506 | instance | vexxhost.atmosphere.tempest : Read cluster issuer CA secret ------------- 0.04s
2026-07-30 21:00:15.908515 | instance | vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with Percona XtraDB password --- 0.04s
2026-07-30 21:00:15.908524 | instance | vexxhost.atmosphere.tempest : Configure test image ref ------------------ 0.04s
2026-07-30 21:00:15.908539 | instance | vexxhost.atmosphere.tempest : Get tempest log --------------------------- 0.04s
2026-07-30 21:00:15.908548 | instance | vexxhost.atmosphere.openstack_helm_endpoints : Retrieve list of all the needed endpoints --- 0.04s
2026-07-30 21:00:15.908557 | instance | vexxhost.atmosphere.tempest : Fail when tempest result is failed -------- 0.04s
2026-07-30 21:00:15.908566 | instance | vexxhost.atmosphere.openstack_helm_endpoints : Clean-up facts ----------- 0.04s
2026-07-30 21:00:15.908575 | instance | vexxhost.atmosphere.tempest : Get test image object --------------------- 0.04s
2026-07-30 21:00:15.908584 | instance | vexxhost.atmosphere.tempest : Get tempest job object -------------------- 0.04s
2026-07-30 21:00:15.908592 | instance | vexxhost.atmosphere.tempest : Set test flavor ref ----------------------- 0.04s
2026-07-30 21:00:15.908601 | instance | INFO     [aio > verify] Executed: Successful
2026-07-30 21:00:15.908610 | instance | INFO     [aio > cleanup] Executing
2026-07-30 21:00:15.908619 | instance | WARNING  [aio > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 21:00:15.908628 | instance | INFO     [aio > destroy] Executing
2026-07-30 21:00:15.908636 | instance | WARNING  [aio > destroy] Skipping, '--destroy=never' requested.
2026-07-30 21:00:15.908645 | instance | INFO     [aio > destroy] Executed: Successful
2026-07-30 21:00:15.908654 | instance | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-30 21:00:16.243623 | instance | ok: Runtime: 0:00:00.009395
2026-07-30 21:00:16.252132 | 
2026-07-30 21:00:16.252221 | TASK [Track last task for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.313866 | instance | ok
2026-07-30 21:00:16.325937 | 
2026-07-30 21:00:16.326077 | TASK [Advance live log offset for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.381432 | instance | ok
2026-07-30 21:00:16.390848 | 
2026-07-30 21:00:16.390955 | TASK [Report progress heartbeat for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.426121 | instance | skipping: Conditional result was False
2026-07-30 21:00:16.437404 | 
2026-07-30 21:00:16.437554 | TASK [Continue monitoring Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.483963 | instance | skipping: Conditional result was False
2026-07-30 21:00:16.498552 | 
2026-07-30 21:00:16.498631 | TASK [Start progress monitor chunk for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.553799 | instance | skipping: Conditional result was False
2026-07-30 21:00:16.565236 | 
2026-07-30 21:00:16.565314 | TASK [Start progress monitor chunk for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.610011 | instance | skipping: Conditional result was False
2026-07-30 21:00:16.619374 | 
2026-07-30 21:00:16.619454 | TASK [Start progress monitor chunk for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.664101 | instance | skipping: Conditional result was False
2026-07-30 21:00:16.673320 | 
2026-07-30 21:00:16.673434 | TASK [Start progress monitor chunk for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.718792 | instance | skipping: Conditional result was False
2026-07-30 21:00:16.731139 | 
2026-07-30 21:00:16.731287 | TASK [Clean up async state for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:16.935804 | instance | ok
2026-07-30 21:00:16.945272 | 
2026-07-30 21:00:16.945491 | TASK [Assert success for Molecule aio (aio-openvswitch)]
2026-07-30 21:00:17.020729 | instance | ok: All assertions passed
2026-07-30 21:00:17.022453 | 
2026-07-30 21:00:17.022525 | PLAY RECAP
2026-07-30 21:00:17.022588 | instance | ok: 72 changed: 4 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 0
2026-07-30 21:00:17.022621 | 
2026-07-30 21:00:17.180807 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-30 21:00:17.193981 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-30 21:00:17.815682 | 
2026-07-30 21:00:17.815807 | PLAY [Publish selective Molecule plan]
2026-07-30 21:00:17.827770 | 
2026-07-30 21:00:17.827854 | TASK [Check for selective Molecule plan]
2026-07-30 21:00:18.177835 | instance | ok
2026-07-30 21:00:18.185945 | 
2026-07-30 21:00:18.186089 | TASK [Create log directory]
2026-07-30 21:00:18.528056 | instance | ok
2026-07-30 21:00:18.534433 | 
2026-07-30 21:00:18.534503 | TASK [Copy selective Molecule plan to logs]
2026-07-30 21:00:18.964780 | instance | changed
2026-07-30 21:00:18.967165 | 
2026-07-30 21:00:18.967217 | PLAY RECAP
2026-07-30 21:00:18.967265 | instance | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 21:00:18.967287 | 
2026-07-30 21:00:19.093973 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-30 21:00:19.103283 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-30 21:00:19.797537 | 
2026-07-30 21:00:19.797682 | PLAY [all]
2026-07-30 21:00:19.812407 | 
2026-07-30 21:00:19.812553 | TASK [gather-host-logs : creating directory for system status]
2026-07-30 21:00:20.179059 | instance | changed
2026-07-30 21:00:20.186741 | 
2026-07-30 21:00:20.186888 | TASK [gather-host-logs : Get logs for each host]
2026-07-30 21:00:20.528132 | instance | + systemd-cgls --full --all --no-pager
2026-07-30 21:00:20.555684 | instance | + ip addr
2026-07-30 21:00:20.558247 | instance | + ip route
2026-07-30 21:00:20.560990 | instance | + lsblk
2026-07-30 21:00:20.566845 | instance | + mount
2026-07-30 21:00:20.569773 | instance | + docker images
2026-07-30 21:00:20.570419 | instance | /bin/bash: line 7: docker: command not found
2026-07-30 21:00:20.570744 | instance | + brctl show
2026-07-30 21:00:20.571194 | instance | /bin/bash: line 8: brctl: command not found
2026-07-30 21:00:20.571474 | instance | + ps aux --sort=-%mem
2026-07-30 21:00:20.592288 | instance | + dpkg -l
2026-07-30 21:00:20.598725 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-30 21:00:20.599056 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-30 21:00:20.599094 | instance | /bin/bash: line 11: docker: command not found
2026-07-30 21:00:20.599287 | instance | + '[' '!' -z '' ']'
2026-07-30 21:00:20.724504 | instance | ok: Runtime: 0:00:00.076256
2026-07-30 21:00:20.733270 | 
2026-07-30 21:00:20.733384 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-30 21:00:21.400041 | instance | changed:
2026-07-30 21:00:21.400172 | instance | created directory /var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/work/logs/instance
2026-07-30 21:00:21.400201 | instance | cd+++++++++ system/
2026-07-30 21:00:21.400222 | instance | >f+++++++++ system/brctl-show.txt
2026-07-30 21:00:21.400243 | instance | >f+++++++++ system/docker-images.txt
2026-07-30 21:00:21.400262 | instance | >f+++++++++ system/ip-addr.txt
2026-07-30 21:00:21.400283 | instance | >f+++++++++ system/ip-route.txt
2026-07-30 21:00:21.400303 | instance | >f+++++++++ system/lsblk.txt
2026-07-30 21:00:21.400322 | instance | >f+++++++++ system/mount.txt
2026-07-30 21:00:21.400343 | instance | >f+++++++++ system/packages.txt
2026-07-30 21:00:21.400362 | instance | >f+++++++++ system/ps.txt
2026-07-30 21:00:21.400381 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-30 21:00:21.413459 | 
2026-07-30 21:00:21.413557 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-30 21:00:21.613885 | instance | changed: "values"
2026-07-30 21:00:21.793482 | instance | changed: "releases"
2026-07-30 21:00:21.806727 | 
2026-07-30 21:00:21.806807 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-30 21:00:22.050390 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-30 21:00:22.376628 | instance | ok: Runtime: 0:00:00.004984
2026-07-30 21:00:22.381984 | 
2026-07-30 21:00:22.382064 | TASK [helm-release-status : Downloads logs to executor]
2026-07-30 21:00:22.914991 | instance | changed:
2026-07-30 21:00:22.915195 | instance | cd+++++++++ helm/
2026-07-30 21:00:22.915238 | instance | cd+++++++++ helm/releases/
2026-07-30 21:00:22.915271 | instance | cd+++++++++ helm/values/
2026-07-30 21:00:22.932272 | 
2026-07-30 21:00:22.932473 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-30 21:00:23.147083 | instance | changed
2026-07-30 21:00:23.152933 | 
2026-07-30 21:00:23.153023 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-30 21:00:23.380282 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 21:00:23.380512 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 21:00:23.384576 | instance | environment: line 1: kubectl: command not found
2026-07-30 21:00:23.385849 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 21:00:23.387028 | instance | environment: line 1: kubectl: command not found
2026-07-30 21:00:23.387771 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 21:00:23.390112 | instance | environment: line 1: kubectl: command not found
2026-07-30 21:00:23.391334 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 21:00:23.391694 | instance | environment: line 1: kubectl: command not found
2026-07-30 21:00:23.393065 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 21:00:23.394527 | instance | environment: line 1: kubectl: command not found
2026-07-30 21:00:23.395461 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 21:00:23.693234 | instance | ok: Runtime: 0:00:00.024265
2026-07-30 21:00:23.709934 | 
2026-07-30 21:00:23.710102 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-30 21:00:23.927595 | instance | changed
2026-07-30 21:00:23.934472 | 
2026-07-30 21:00:23.934565 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-30 21:00:24.140674 | instance | environment: line 5: kubectl: command not found
2026-07-30 21:00:24.141333 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 21:00:24.141663 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 21:00:24.141858 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 21:00:24.469860 | instance | ok: Runtime: 0:00:00.008219
2026-07-30 21:00:24.477289 | 
2026-07-30 21:00:24.477369 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-30 21:00:24.986075 | instance | changed:
2026-07-30 21:00:24.986261 | instance | cd+++++++++ objects/
2026-07-30 21:00:24.986301 | instance | cd+++++++++ objects/cluster/
2026-07-30 21:00:24.986332 | instance | cd+++++++++ objects/namespaced/
2026-07-30 21:00:24.997931 | 
2026-07-30 21:00:24.997995 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-30 21:00:25.204736 | instance | changed
2026-07-30 21:00:25.209680 | 
2026-07-30 21:00:25.209743 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-30 21:00:25.407750 | instance | changed
2026-07-30 21:00:25.415687 | 
2026-07-30 21:00:25.415756 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-30 21:00:25.630411 | instance | environment: line 3: kubectl: command not found
2026-07-30 21:00:25.954358 | instance | ok: Runtime: 0:00:00.008583
2026-07-30 21:00:25.963002 | 
2026-07-30 21:00:25.963066 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-30 21:00:26.452025 | instance | changed:
2026-07-30 21:00:26.452219 | instance | cd+++++++++ pod-logs/
2026-07-30 21:00:26.452259 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-30 21:00:26.463862 | 
2026-07-30 21:00:26.463923 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-30 21:00:26.669284 | instance | changed
2026-07-30 21:00:26.677322 | 
2026-07-30 21:00:26.677413 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-30 21:00:26.882787 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-30 21:00:27.213419 | instance | ok: Runtime: 0:00:00.037772
2026-07-30 21:00:27.221205 | 
2026-07-30 21:00:27.221300 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-30 21:00:27.446469 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-30 21:00:27.480468 | instance | ceph-mgr endpoints:
2026-07-30 21:00:27.761125 | instance | ok: Runtime: 0:00:00.040218
2026-07-30 21:00:27.768177 | 
2026-07-30 21:00:27.768323 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-30 21:00:28.030420 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-30 21:00:28.343861 | instance | ok: Runtime: 0:00:00.037296
2026-07-30 21:00:28.353110 | 
2026-07-30 21:00:28.353185 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-30 21:00:28.850435 | instance | changed: cd+++++++++ prometheus/
2026-07-30 21:00:28.864335 | 
2026-07-30 21:00:28.864479 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-30 21:00:29.073441 | instance | changed
2026-07-30 21:00:29.078654 | 
2026-07-30 21:00:29.078802 | TASK [gather-selenium-data : Get selenium data]
2026-07-30 21:00:29.325919 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-30 21:00:29.327503 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-30 21:00:29.615517 | instance | ERROR
2026-07-30 21:00:29.615745 | instance | {
2026-07-30 21:00:29.615793 | instance |   "delta": "0:00:00.005838",
2026-07-30 21:00:29.615822 | instance |   "end": "2026-07-30 21:00:29.327810",
2026-07-30 21:00:29.615849 | instance |   "msg": "non-zero return code",
2026-07-30 21:00:29.615874 | instance |   "rc": 1,
2026-07-30 21:00:29.615900 | instance |   "start": "2026-07-30 21:00:29.321972"
2026-07-30 21:00:29.615925 | instance | }
2026-07-30 21:00:29.615957 | instance | ERROR: Ignoring Errors
2026-07-30 21:00:29.622802 | 
2026-07-30 21:00:29.622889 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-30 21:00:30.111918 | instance | changed: cd+++++++++ selenium/
2026-07-30 21:00:30.121788 | 
2026-07-30 21:00:30.121902 | PLAY RECAP
2026-07-30 21:00:30.121976 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-30 21:00:30.122019 | 
2026-07-30 21:00:30.267380 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-30 21:00:30.473282 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-30 21:00:31.154494 | 
2026-07-30 21:00:31.154685 | PLAY [all]
2026-07-30 21:00:31.168672 | 
2026-07-30 21:00:31.168812 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-30 21:00:31.224176 | instance | skipping: Conditional result was False
2026-07-30 21:00:31.235635 | 
2026-07-30 21:00:31.235815 | TASK [fetch-output : Set log path for single node]
2026-07-30 21:00:31.281299 | instance | ok
2026-07-30 21:00:31.286935 | 
2026-07-30 21:00:31.287024 | LOOP [fetch-output : Ensure local output dirs]
2026-07-30 21:00:31.742933 | instance -> localhost | ok: "/var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/work/logs"
2026-07-30 21:00:31.972292 | instance -> localhost | changed: "/var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/work/artifacts"
2026-07-30 21:00:32.195987 | instance -> localhost | changed: "/var/lib/zuul/builds/4e31f735c7b14e26856e5a11b9c33b0c/work/docs"
2026-07-30 21:00:32.216873 | 
2026-07-30 21:00:32.217073 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-30 21:00:32.894772 | instance | changed: .d..t...... ./
2026-07-30 21:00:32.895000 | instance | changed: All items complete
2026-07-30 21:00:32.895033 | 
2026-07-30 21:00:33.341154 | instance | changed: .d..t...... ./
2026-07-30 21:00:33.790818 | instance | changed: .d..t...... ./
2026-07-30 21:00:33.817645 | 
2026-07-30 21:00:33.817785 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-30 21:00:34.257496 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006957
2026-07-30 21:00:34.482139 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009097
2026-07-30 21:00:34.499866 | 
2026-07-30 21:00:34.499990 | PLAY [all]
2026-07-30 21:00:34.510621 | 
2026-07-30 21:00:34.510760 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-30 21:00:34.921220 | instance | changed
2026-07-30 21:00:34.927992 | 
2026-07-30 21:00:34.928040 | PLAY RECAP
2026-07-30 21:00:34.928084 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-30 21:00:34.928106 | 
2026-07-30 21:00:35.095027 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-30 21:00:35.104526 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-30 21:00:35.800298 | 
2026-07-30 21:00:35.800426 | PLAY [localhost]
2026-07-30 21:00:35.810462 | 
2026-07-30 21:00:35.810564 | TASK [Generate Zuul manifest]
2026-07-30 21:00:35.833510 | localhost | ok
2026-07-30 21:00:35.850804 | 
2026-07-30 21:00:35.850920 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-30 21:00:36.268648 | localhost | changed
2026-07-30 21:00:36.300906 | 
2026-07-30 21:00:36.301047 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-30 21:00:36.333259 | localhost | ok
2026-07-30 21:00:36.343596 | 
2026-07-30 21:00:36.343679 | TASK [Upload logs]
2026-07-30 21:00:36.362997 | localhost | ok
2026-07-30 21:00:36.465561 | 
2026-07-30 21:00:36.465678 | TASK [Set zuul-log-path fact]
2026-07-30 21:00:36.487334 | localhost | ok
2026-07-30 21:00:36.501747 | 
2026-07-30 21:00:36.501824 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-30 21:00:36.533486 | localhost | ok
2026-07-30 21:00:36.543508 | 
2026-07-30 21:00:36.543620 | TASK [upload-logs : Create log directories]
2026-07-30 21:00:36.942466 | localhost | changed
2026-07-30 21:00:36.948486 | 
2026-07-30 21:00:36.948562 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-30 21:00:37.303774 | localhost -> localhost | ok: Runtime: 0:00:00.004852
2026-07-30 21:00:37.311242 | 
2026-07-30 21:00:37.311317 | TASK [upload-logs : Upload logs to log server]
2026-07-30 21:00:37.778318 | localhost | Output suppressed because no_log was given
2026-07-30 21:00:37.784720 | 
2026-07-30 21:00:37.784835 | LOOP [upload-logs : Compress console log and json output]
2026-07-30 21:00:37.833889 | localhost | skipping: Conditional result was False
2026-07-30 21:00:37.846204 | localhost | skipping: Conditional result was False
2026-07-30 21:00:37.860712 | 
2026-07-30 21:00:37.860846 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-30 21:00:37.905105 | localhost | skipping: Conditional result was False
2026-07-30 21:00:37.905482 | 
2026-07-30 21:00:37.923823 | localhost | skipping: Conditional result was False
2026-07-30 21:00:37.938548 | 
2026-07-30 21:00:37.938684 | LOOP [upload-logs : Upload console log and json output]