2026-08-26 00:16:13.517874 | Job console starting
2026-08-26 00:16:13.532176 | Updating git repos
2026-08-26 00:16:18.278441 | Cloning repos into workspace
2026-08-26 00:16:34.279533 | Restoring repo states
2026-08-26 00:16:39.681845 | Merging changes
2026-08-26 00:16:41.559242 | Checking out repos
2026-08-26 00:16:45.877377 | Preparing playbooks
2026-08-26 00:16:54.103968 | Running Ansible setup
2026-08-26 00:16:58.354096 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 00:16:58.963300 | 
2026-08-26 00:16:58.963417 | PLAY [localhost]
2026-08-26 00:16:58.970948 | 
2026-08-26 00:16:58.971016 | TASK [Gathering Facts]
2026-08-26 00:16:59.911750 | localhost | ok
2026-08-26 00:16:59.921588 | 
2026-08-26 00:16:59.921757 | TASK [Setup log path fact]
2026-08-26 00:16:59.944131 | localhost | ok
2026-08-26 00:16:59.955515 | 
2026-08-26 00:16:59.955643 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 00:16:59.987065 | localhost | ok
2026-08-26 00:16:59.996854 | 
2026-08-26 00:16:59.996995 | TASK [emit-job-header : Print job information]
2026-08-26 00:17:00.039180 | # Job Information
2026-08-26 00:17:00.039563 | Ansible Version: 2.16.18
2026-08-26 00:17:00.039645 | Job: atmosphere-molecule-csi-rbd
2026-08-26 00:17:00.039694 | Pipeline: check
2026-08-26 00:17:00.039731 | Executor: 2d72f0692154
2026-08-26 00:17:00.039763 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4095
2026-08-26 00:17:00.039796 | Event ID: c975a720-a0e2-11f1-9d25-f7cceabdc49a
2026-08-26 00:17:00.044090 | 
2026-08-26 00:17:00.044181 | LOOP [emit-job-header : Print node information]
2026-08-26 00:17:00.166015 | localhost | ok:
2026-08-26 00:17:00.166403 | localhost | # Node Information
2026-08-26 00:17:00.166531 | localhost | Inventory Hostname: instance
2026-08-26 00:17:00.166581 | localhost | Hostname: np0000191108
2026-08-26 00:17:00.166621 | localhost | Username: zuul
2026-08-26 00:17:00.166672 | localhost | Distro: Ubuntu 22.04
2026-08-26 00:17:00.166897 | localhost | Provider: yul1
2026-08-26 00:17:00.166957 | localhost | Region: ca-ymq-1
2026-08-26 00:17:00.167000 | localhost | Label: ubuntu-jammy
2026-08-26 00:17:00.167041 | localhost | Product Name: OpenStack Nova
2026-08-26 00:17:00.167086 | localhost | Interface IP: 162.253.55.104
2026-08-26 00:17:00.185110 | 
2026-08-26 00:17:00.185249 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 00:17:00.621521 | localhost -> localhost | changed
2026-08-26 00:17:00.633931 | 
2026-08-26 00:17:00.634011 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 00:17:01.521061 | localhost -> localhost | changed
2026-08-26 00:17:01.532005 | 
2026-08-26 00:17:01.532080 | PLAY [all]
2026-08-26 00:17:01.540899 | 
2026-08-26 00:17:01.540987 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 00:17:01.757836 | instance -> localhost | ok
2026-08-26 00:17:01.770346 | 
2026-08-26 00:17:01.770480 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 00:17:01.805753 | instance | ok
2026-08-26 00:17:01.839324 | instance | included: /var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 00:17:01.845511 | 
2026-08-26 00:17:01.845578 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 00:17:02.822736 | instance -> localhost | Generating public/private rsa key pair.
2026-08-26 00:17:02.822896 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/work/ad1bd17bff1d409aa9beb61afed91381_id_rsa
2026-08-26 00:17:02.822926 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/work/ad1bd17bff1d409aa9beb61afed91381_id_rsa.pub
2026-08-26 00:17:02.822950 | instance -> localhost | The key fingerprint is:
2026-08-26 00:17:02.822971 | instance -> localhost | SHA256:WObFP8czRSQUjx85pNDowZzihkQEwGWyCC599MscSFA zuul-build-sshkey
2026-08-26 00:17:02.823006 | instance -> localhost | The key's randomart image is:
2026-08-26 00:17:02.823028 | instance -> localhost | +---[RSA 3072]----+
2026-08-26 00:17:02.823055 | instance -> localhost | |. o=E++o  o.+.++o|
2026-08-26 00:17:02.823078 | instance -> localhost | |o..++o . o *..o=.|
2026-08-26 00:17:02.823099 | instance -> localhost | |.o..o + = = ...+o|
2026-08-26 00:17:02.823118 | instance -> localhost | |.  . o O + o . oo|
2026-08-26 00:17:02.823138 | instance -> localhost | |      = S   o = .|
2026-08-26 00:17:02.823157 | instance -> localhost | |             o o |
2026-08-26 00:17:02.823176 | instance -> localhost | |                 |
2026-08-26 00:17:02.823195 | instance -> localhost | |                 |
2026-08-26 00:17:02.823217 | instance -> localhost | |                 |
2026-08-26 00:17:02.823237 | instance -> localhost | +----[SHA256]-----+
2026-08-26 00:17:02.823281 | instance -> localhost | ok: Runtime: 0:00:00.518682
2026-08-26 00:17:02.829292 | 
2026-08-26 00:17:02.829351 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 00:17:02.849196 | instance | ok
2026-08-26 00:17:02.857312 | instance | included: /var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 00:17:02.864584 | 
2026-08-26 00:17:02.864643 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 00:17:02.888973 | instance | skipping: Conditional result was False
2026-08-26 00:17:02.894307 | 
2026-08-26 00:17:02.894372 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 00:17:03.331710 | instance | changed
2026-08-26 00:17:03.339481 | 
2026-08-26 00:17:03.339570 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 00:17:03.523527 | instance | ok
2026-08-26 00:17:03.531035 | 
2026-08-26 00:17:03.531129 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 00:17:04.012192 | instance | changed
2026-08-26 00:17:04.026328 | 
2026-08-26 00:17:04.026497 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 00:17:04.482532 | instance | changed
2026-08-26 00:17:04.490618 | 
2026-08-26 00:17:04.490728 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 00:17:04.516229 | instance | skipping: Conditional result was False
2026-08-26 00:17:04.525670 | 
2026-08-26 00:17:04.525765 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 00:17:05.026203 | instance -> localhost | changed
2026-08-26 00:17:05.042166 | 
2026-08-26 00:17:05.042254 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 00:17:05.353126 | instance -> localhost | Identity added: /var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/work/ad1bd17bff1d409aa9beb61afed91381_id_rsa (zuul-build-sshkey)
2026-08-26 00:17:05.353304 | instance -> localhost | ok: Runtime: 0:00:00.012286
2026-08-26 00:17:05.358316 | 
2026-08-26 00:17:05.358383 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 00:17:05.683270 | instance | ok
2026-08-26 00:17:05.689538 | 
2026-08-26 00:17:05.689626 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 00:17:05.714795 | instance | skipping: Conditional result was False
2026-08-26 00:17:05.729542 | 
2026-08-26 00:17:05.729633 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 00:17:06.078345 | instance | ok
2026-08-26 00:17:06.085726 | 
2026-08-26 00:17:06.085800 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 00:17:09.666729 | instance | Output suppressed because no_log was given
2026-08-26 00:17:09.690418 | 
2026-08-26 00:17:09.690499 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 00:17:09.918814 | instance | ok: "logs"
2026-08-26 00:17:09.919090 | instance | ok: All items complete
2026-08-26 00:17:09.919119 | 
2026-08-26 00:17:10.072356 | instance | ok: "artifacts"
2026-08-26 00:17:10.239598 | instance | ok: "docs"
2026-08-26 00:17:10.272181 | 
2026-08-26 00:17:10.272361 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 00:17:10.470538 | instance | changed: "logs"
2026-08-26 00:17:10.622757 | instance | changed: "artifacts"
2026-08-26 00:17:10.788646 | instance | changed: "docs"
2026-08-26 00:17:10.805421 | 
2026-08-26 00:17:10.805557 | PLAY RECAP
2026-08-26 00:17:10.805611 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 00:17:10.805640 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 00:17:10.805663 | 
2026-08-26 00:17:10.950436 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 00:17:10.953640 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 00:17:11.581147 | 
2026-08-26 00:17:11.581302 | PLAY [all]
2026-08-26 00:17:11.592764 | 
2026-08-26 00:17:11.592890 | TASK [setup-uv : Extract archive]
2026-08-26 00:17:14.860635 | instance | changed
2026-08-26 00:17:15.750854 | 
2026-08-26 00:17:15.751008 | TASK [setup-uv : Print version]
2026-08-26 00:17:16.124126 | instance | uv 0.8.13
2026-08-26 00:17:16.295692 | instance | ok: Runtime: 0:00:00.011669
2026-08-26 00:17:16.307038 | 
2026-08-26 00:17:16.346886 | TASK [Install Ansible collection siblings]
2026-08-26 00:17:30.517760 | instance | changed:
2026-08-26 00:17:30.518014 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-26 00:17:30.518067 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-26 00:17:30.518102 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-26 00:17:30.518131 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-26 00:17:30.518157 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-26 00:17:30.518185 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-26 00:17:30.518210 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-26 00:17:30.518237 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-26 00:17:30.518263 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-26 00:17:30.518292 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-26 00:17:30.518322 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-26 00:17:30.518354 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-26 00:17:30.518386 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-08-26 00:17:30.518418 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-26 00:17:30.518450 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-08-26 00:17:30.518482 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-26 00:17:30.518514 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-26 00:17:30.518546 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-26 00:17:30.518576 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-26 00:17:30.518606 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-08-26 00:17:30.518636 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-08-26 00:17:30.518667 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-08-26 00:17:30.518701 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-26 00:17:30.518746 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-26 00:17:30.518781 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-26 00:17:30.518812 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-26 00:17:30.518844 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-26 00:17:30.518873 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-26 00:17:30.518901 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-26 00:17:30.518927 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-26 00:17:30.518951 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-26 00:17:30.518975 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-26 00:17:30.518998 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-26 00:17:30.519091 | instance | Checked out ansible.netcommon to v8.6.2
2026-08-26 00:17:30.519131 | 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-08-26 00:17:30.519174 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.519206 | instance | Process install dependency map
2026-08-26 00:17:30.519239 | instance | Starting collection install process
2026-08-26 00:17:30.519271 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-26 00:17:30.519303 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-26 00:17:30.519336 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-08-26 00:17:30.519367 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-26 00:17:30.519398 | instance | Creating virtual environment at: .venv
2026-08-26 00:17:30.519430 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-26 00:17:30.519463 | instance | Downloading ansible-core (2.1MiB)
2026-08-26 00:17:30.519502 | instance | Downloading netaddr (2.2MiB)
2026-08-26 00:17:30.519535 | instance | Downloading setuptools (1.1MiB)
2026-08-26 00:17:30.519568 | instance | Downloading openstacksdk (1.7MiB)
2026-08-26 00:17:30.519601 | instance | Downloading pygments (1.2MiB)
2026-08-26 00:17:30.519633 | instance | Downloading cryptography (4.2MiB)
2026-08-26 00:17:30.519662 | instance | Downloading kubernetes (1.9MiB)
2026-08-26 00:17:30.519689 | instance | Downloading rjsonnet (1.2MiB)
2026-08-26 00:17:30.519713 | instance |  Downloading rjsonnet
2026-08-26 00:17:30.519741 | instance |    Building pyperclip==1.9.0
2026-08-26 00:17:30.519765 | instance |  Downloading netaddr
2026-08-26 00:17:30.519789 | instance |  Downloading pygments
2026-08-26 00:17:30.519812 | instance |  Downloading cryptography
2026-08-26 00:17:30.519835 | instance |  Downloading setuptools
2026-08-26 00:17:30.519859 | instance |  Downloading kubernetes
2026-08-26 00:17:30.519885 | instance |  Downloading ansible-core
2026-08-26 00:17:30.519913 | instance |  Downloading openstacksdk
2026-08-26 00:17:30.519942 | instance |       Built pyperclip==1.9.0
2026-08-26 00:17:30.519972 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-26 00:17:30.520005 | instance | Installed 79 packages in 536ms
2026-08-26 00:17:30.520036 | instance | Checked out ansible.posix to 1.6.0
2026-08-26 00:17:30.520066 | 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-08-26 00:17:30.520099 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.520130 | instance | Process install dependency map
2026-08-26 00:17:30.520168 | instance | Starting collection install process
2026-08-26 00:17:30.520202 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-26 00:17:30.520230 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-26 00:17:30.520260 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-26 00:17:30.520293 | instance | Checked out ansible.utils to v6.1.0
2026-08-26 00:17:30.520351 | 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-08-26 00:17:30.520383 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.520413 | instance | Process install dependency map
2026-08-26 00:17:30.520443 | instance | Starting collection install process
2026-08-26 00:17:30.520474 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-26 00:17:30.520505 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-26 00:17:30.520534 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-26 00:17:30.520563 | instance | Checked out atmosphere.common to v0.6.0
2026-08-26 00:17:30.520590 | 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-08-26 00:17:30.520616 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.520640 | instance | Process install dependency map
2026-08-26 00:17:30.520663 | instance | Starting collection install process
2026-08-26 00:17:30.520686 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-26 00:17:30.520709 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-26 00:17:30.520732 | instance | atmosphere.common:0.6.0 was installed successfully
2026-08-26 00:17:30.520759 | instance | Checked out community.crypto to 2.2.3
2026-08-26 00:17:30.520788 | 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-08-26 00:17:30.520817 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.520849 | instance | Process install dependency map
2026-08-26 00:17:30.520879 | instance | Starting collection install process
2026-08-26 00:17:30.520910 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-26 00:17:30.520941 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-26 00:17:30.520998 | instance | community.crypto:2.2.3 was installed successfully
2026-08-26 00:17:30.521033 | instance | Checked out community.general to 7.3.0
2026-08-26 00:17:30.521065 | 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-08-26 00:17:30.521096 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.521126 | instance | Process install dependency map
2026-08-26 00:17:30.521158 | instance | Starting collection install process
2026-08-26 00:17:30.521189 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-26 00:17:30.521221 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-26 00:17:30.521251 | instance | community.general:7.3.0 was installed successfully
2026-08-26 00:17:30.521291 | instance | Checked out community.mysql to 3.6.0
2026-08-26 00:17:30.521324 | 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-08-26 00:17:30.521355 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.521387 | instance | Process install dependency map
2026-08-26 00:17:30.521419 | instance | Starting collection install process
2026-08-26 00:17:30.521451 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-26 00:17:30.521483 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-26 00:17:30.521514 | instance | community.mysql:3.6.0 was installed successfully
2026-08-26 00:17:30.521544 | instance | Checked out kubernetes.core to 2.4.0
2026-08-26 00:17:30.521573 | 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-08-26 00:17:30.521600 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.521626 | instance | Process install dependency map
2026-08-26 00:17:30.521653 | instance | Starting collection install process
2026-08-26 00:17:30.521678 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-26 00:17:30.521701 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-26 00:17:30.521725 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-26 00:17:30.521751 | instance | Checked out openstack.cloud to 1.7.0
2026-08-26 00:17:30.521779 | 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-08-26 00:17:30.521808 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.521837 | instance | Process install dependency map
2026-08-26 00:17:30.521871 | instance | Starting collection install process
2026-08-26 00:17:30.521903 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-26 00:17:30.521935 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-26 00:17:30.521966 | instance | openstack.cloud:1.7.0 was installed successfully
2026-08-26 00:17:30.521997 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-26 00:17:30.522029 | 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-08-26 00:17:30.522061 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.522093 | instance | Process install dependency map
2026-08-26 00:17:30.522123 | instance | Starting collection install process
2026-08-26 00:17:30.522154 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-26 00:17:30.522185 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-26 00:17:30.522216 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-26 00:17:30.522247 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-26 00:17:30.522276 | 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-08-26 00:17:30.522303 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.522337 | instance | Process install dependency map
2026-08-26 00:17:30.522362 | instance | Starting collection install process
2026-08-26 00:17:30.522386 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-26 00:17:30.522409 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-26 00:17:30.522431 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-26 00:17:30.522454 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-26 00:17:30.522481 | 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-08-26 00:17:30.522508 | instance | Starting galaxy collection install process
2026-08-26 00:17:30.522537 | instance | Process install dependency map
2026-08-26 00:17:30.522567 | instance | Starting collection install process
2026-08-26 00:17:30.522597 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-26 00:17:30.522628 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-26 00:17:30.522662 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-26 00:17:30.530032 | 
2026-08-26 00:17:30.530094 | PLAY RECAP
2026-08-26 00:17:30.530160 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 00:17:30.530198 | 
2026-08-26 00:17:30.673625 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 00:17:31.372155 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-26 00:17:32.014193 | 
2026-08-26 00:17:32.014331 | PLAY [all]
2026-08-26 00:17:32.026445 | 
2026-08-26 00:17:32.026543 | TASK [Install "jq" for log collection]
2026-08-26 00:17:42.749378 | instance | changed
2026-08-26 00:17:42.755715 | 
2026-08-26 00:17:42.755837 | PLAY RECAP
2026-08-26 00:17:42.755892 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 00:17:42.755944 | 
2026-08-26 00:17:42.912607 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-26 00:17:42.920913 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-26 00:17:43.528828 | 
2026-08-26 00:17:43.528968 | PLAY [all]
2026-08-26 00:17:43.540537 | 
2026-08-26 00:17:43.540615 | LOOP [Check inventory directories]
2026-08-26 00:17:43.914922 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-26 00:17:44.069804 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-26 00:17:44.220788 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-26 00:17:44.232508 | 
2026-08-26 00:17:44.232729 | LOOP [Copy inventory file for Zuul]
2026-08-26 00:17:45.027414 | instance | changed:
2026-08-26 00:17:45.027587 | instance | {
2026-08-26 00:17:45.027615 | instance |   "ansible_loop_var": "item",
2026-08-26 00:17:45.027638 | instance |   "changed": false,
2026-08-26 00:17:45.027658 | instance |   "failed": false,
2026-08-26 00:17:45.027680 | instance |   "invocation": {
2026-08-26 00:17:45.027699 | instance |     "module_args": {
2026-08-26 00:17:45.027718 | instance |       "checksum_algorithm": "sha1",
2026-08-26 00:17:45.027737 | instance |       "follow": true,
2026-08-26 00:17:45.027755 | instance |       "get_attributes": true,
2026-08-26 00:17:45.027774 | instance |       "get_checksum": true,
2026-08-26 00:17:45.027793 | instance |       "get_mime": true,
2026-08-26 00:17:45.027811 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-26 00:17:45.027841 | instance |     }
2026-08-26 00:17:45.027861 | instance |   },
2026-08-26 00:17:45.027878 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-26 00:17:45.027896 | instance |   "stat": {
2026-08-26 00:17:45.027915 | instance |     "atime": 1787703441.719423,
2026-08-26 00:17:45.027933 | instance |     "attr_flags": "e",
2026-08-26 00:17:45.027950 | instance |     "attributes": [
2026-08-26 00:17:45.027967 | instance |       "extents"
2026-08-26 00:17:45.027985 | instance |     ],
2026-08-26 00:17:45.028002 | instance |     "block_size": 4096,
2026-08-26 00:17:45.028036 | instance |     "blocks": 8,
2026-08-26 00:17:45.028054 | instance |     "charset": "unknown",
2026-08-26 00:17:45.028073 | instance |     "ctime": 1787703440.475458,
2026-08-26 00:17:45.028091 | instance |     "dev": 64513,
2026-08-26 00:17:45.028109 | instance |     "device_type": 0,
2026-08-26 00:17:45.028128 | instance |     "executable": true,
2026-08-26 00:17:45.028150 | instance |     "exists": true,
2026-08-26 00:17:45.028172 | instance |     "gid": 1000,
2026-08-26 00:17:45.028190 | instance |     "gr_name": "zuul",
2026-08-26 00:17:45.028210 | instance |     "inode": 1048698,
2026-08-26 00:17:45.028230 | instance |     "isblk": false,
2026-08-26 00:17:45.028247 | instance |     "ischr": false,
2026-08-26 00:17:45.028265 | instance |     "isdir": true,
2026-08-26 00:17:45.028282 | instance |     "isfifo": false,
2026-08-26 00:17:45.028299 | instance |     "isgid": false,
2026-08-26 00:17:45.028317 | instance |     "islnk": false,
2026-08-26 00:17:45.028335 | instance |     "isreg": false,
2026-08-26 00:17:45.028352 | instance |     "issock": false,
2026-08-26 00:17:45.028369 | instance |     "isuid": false,
2026-08-26 00:17:45.028386 | instance |     "mimetype": "unknown",
2026-08-26 00:17:45.028403 | instance |     "mode": "0755",
2026-08-26 00:17:45.028420 | instance |     "mtime": 1787703440.475458,
2026-08-26 00:17:45.028437 | instance |     "nlink": 21,
2026-08-26 00:17:45.028454 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-26 00:17:45.028471 | instance |     "pw_name": "zuul",
2026-08-26 00:17:45.028490 | instance |     "readable": true,
2026-08-26 00:17:45.028508 | instance |     "rgrp": true,
2026-08-26 00:17:45.028526 | instance |     "roth": true,
2026-08-26 00:17:45.028544 | instance |     "rusr": true,
2026-08-26 00:17:45.028561 | instance |     "size": 4096,
2026-08-26 00:17:45.028579 | instance |     "uid": 1000,
2026-08-26 00:17:45.028596 | instance |     "version": "2125837278",
2026-08-26 00:17:45.028614 | instance |     "wgrp": false,
2026-08-26 00:17:45.028633 | instance |     "woth": false,
2026-08-26 00:17:45.028650 | instance |     "writeable": true,
2026-08-26 00:17:45.028668 | instance |     "wusr": true,
2026-08-26 00:17:45.028686 | instance |     "xgrp": true,
2026-08-26 00:17:45.028704 | instance |     "xoth": true,
2026-08-26 00:17:45.028721 | instance |     "xusr": true
2026-08-26 00:17:45.028739 | instance |   }
2026-08-26 00:17:45.028756 | instance | }
2026-08-26 00:17:45.028788 | instance | ok: All items complete
2026-08-26 00:17:45.028810 | 
2026-08-26 00:17:45.030057 | instance | skipping: Conditional result was False
2026-08-26 00:17:45.034243 | instance | skipping: Conditional result was False
2026-08-26 00:17:45.042245 | 
2026-08-26 00:17:45.042308 | LOOP [Switch "ansible_host" to private IP]
2026-08-26 00:17:45.390835 | instance | changed: 1 replacements made
2026-08-26 00:17:45.392854 | instance | skipping: Conditional result was False
2026-08-26 00:17:45.396938 | instance | skipping: Conditional result was False
2026-08-26 00:17:45.412265 | 
2026-08-26 00:17:45.412357 | TASK [Run Molecule scenario]
2026-08-26 00:17:46.331907 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-26 00:17:46.722592 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-26 00:17:46.722672 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-26 00:17:58.121030 | instance | INFO     [csi > dependency] Executing
2026-08-26 00:17:58.121177 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-26 00:17:58.121416 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-26 00:17:58.121530 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-26 00:17:58.129317 | instance | INFO     [csi > cleanup] Executing
2026-08-26 00:17:58.129652 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-26 00:17:58.137815 | instance | INFO     [csi > destroy] Executing
2026-08-26 00:17:58.137854 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-26 00:17:58.137941 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-26 00:17:58.145883 | instance | INFO     [csi > syntax] Executing
2026-08-26 00:18:00.149330 | instance |
2026-08-26 00:18:00.149851 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-26 00:18:00.208739 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-26 00:18:00.222145 | instance | INFO     [csi > create] Executing
2026-08-26 00:18:00.224200 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-26 00:18:00.232450 | instance | INFO     [csi > prepare] Executing
2026-08-26 00:18:01.070479 | instance |
2026-08-26 00:18:01.070647 | instance | PLAY [Prepare] *****************************************************************
2026-08-26 00:18:01.070904 | instance |
2026-08-26 00:18:01.071145 | instance | TASK [Gathering Facts] *********************************************************
2026-08-26 00:18:01.071316 | instance | Wednesday 26 August 2026  00:18:01 +0000 (0:00:00.030)       0:00:00.030 ******
2026-08-26 00:18:02.292107 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-26 00:18:02.292396 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-26 00:18:02.292748 | instance | interpreter could change the meaning of that path. See
2026-08-26 00:18:02.293060 | instance | https://docs.ansible.com/ansible-
2026-08-26 00:18:02.293389 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-26 00:18:02.300429 | instance | ok: [instance]
2026-08-26 00:18:02.300716 | instance |
2026-08-26 00:18:02.301009 | instance | TASK [Configure short hostname] ************************************************
2026-08-26 00:18:02.301306 | instance | Wednesday 26 August 2026  00:18:02 +0000 (0:00:01.231)       0:00:01.262 ******
2026-08-26 00:18:02.967163 | instance | changed: [instance]
2026-08-26 00:18:02.967400 | instance |
2026-08-26 00:18:02.967714 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-26 00:18:02.968052 | instance | Wednesday 26 August 2026  00:18:02 +0000 (0:00:00.666)       0:00:01.928 ******
2026-08-26 00:18:03.231749 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-26 00:18:03.231992 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-26 00:18:03.232288 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-26 00:18:03.239287 | instance | changed: [instance]
2026-08-26 00:18:03.239566 | instance |
2026-08-26 00:18:03.239898 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-26 00:18:03.240187 | instance | Wednesday 26 August 2026  00:18:03 +0000 (0:00:00.272)       0:00:02.201 ******
2026-08-26 00:18:04.017548 | instance | ok: [instance]
2026-08-26 00:18:04.017624 | instance |
2026-08-26 00:18:04.017757 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-26 00:18:04.017876 | instance |
2026-08-26 00:18:04.018000 | instance | TASK [Gathering Facts] *********************************************************
2026-08-26 00:18:04.018126 | instance | Wednesday 26 August 2026  00:18:04 +0000 (0:00:00.778)       0:00:02.979 ******
2026-08-26 00:18:04.747414 | instance | ok: [instance]
2026-08-26 00:18:04.747591 | instance |
2026-08-26 00:18:04.747877 | instance | TASK [Install depedencies] *****************************************************
2026-08-26 00:18:04.748155 | instance | Wednesday 26 August 2026  00:18:04 +0000 (0:00:00.728)       0:00:03.708 ******
2026-08-26 00:18:39.559695 | instance | changed: [instance]
2026-08-26 00:18:39.559948 | instance |
2026-08-26 00:18:39.560327 | instance | TASK [Start up service] ********************************************************
2026-08-26 00:18:39.560699 | instance | Wednesday 26 August 2026  00:18:39 +0000 (0:00:34.812)       0:00:38.521 ******
2026-08-26 00:18:40.124245 | instance | ok: [instance]
2026-08-26 00:18:40.124522 | instance |
2026-08-26 00:18:40.124839 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-26 00:18:40.125151 | instance | Wednesday 26 August 2026  00:18:40 +0000 (0:00:00.564)       0:00:39.085 ******
2026-08-26 00:18:40.417436 | instance | ok: [instance]
2026-08-26 00:18:40.417645 | instance |
2026-08-26 00:18:40.417931 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-26 00:18:40.418212 | instance | Wednesday 26 August 2026  00:18:40 +0000 (0:00:00.293)       0:00:39.378 ******
2026-08-26 00:18:41.101227 | instance | changed: [instance]
2026-08-26 00:18:41.101664 | instance |
2026-08-26 00:18:41.102032 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-26 00:18:41.102370 | instance | Wednesday 26 August 2026  00:18:41 +0000 (0:00:00.683)       0:00:40.062 ******
2026-08-26 00:18:41.311052 | instance | ok: [instance]
2026-08-26 00:18:41.311142 | instance |
2026-08-26 00:18:41.311250 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-26 00:18:41.311526 | instance | Wednesday 26 August 2026  00:18:41 +0000 (0:00:00.210)       0:00:40.273 ******
2026-08-26 00:18:41.341984 | instance | skipping: [instance]
2026-08-26 00:18:41.342158 | instance |
2026-08-26 00:18:41.342421 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-26 00:18:41.342637 | instance | Wednesday 26 August 2026  00:18:41 +0000 (0:00:00.028)       0:00:40.301 ******
2026-08-26 00:18:41.934625 | instance | changed: [instance] => (item=osd0)
2026-08-26 00:18:41.935691 | instance | changed: [instance] => (item=osd1)
2026-08-26 00:18:41.935915 | instance | changed: [instance] => (item=osd2)
2026-08-26 00:18:41.935926 | instance |
2026-08-26 00:18:41.935934 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-26 00:18:41.935942 | instance | Wednesday 26 August 2026  00:18:41 +0000 (0:00:00.594)       0:00:40.895 ******
2026-08-26 00:18:42.582951 | instance | changed: [instance] => (item=osd0)
2026-08-26 00:18:42.583064 | instance | changed: [instance] => (item=osd1)
2026-08-26 00:18:42.583903 | instance | changed: [instance] => (item=osd2)
2026-08-26 00:18:42.583961 | instance |
2026-08-26 00:18:42.583970 | instance | TASK [Start loop devices] ******************************************************
2026-08-26 00:18:42.583977 | instance | Wednesday 26 August 2026  00:18:42 +0000 (0:00:00.648)       0:00:41.544 ******
2026-08-26 00:18:43.304621 | instance | changed: [instance] => (item=osd0)
2026-08-26 00:18:43.305682 | instance | changed: [instance] => (item=osd1)
2026-08-26 00:18:43.305795 | instance | changed: [instance] => (item=osd2)
2026-08-26 00:18:43.305811 | instance |
2026-08-26 00:18:43.305834 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-26 00:18:43.306244 | instance | Wednesday 26 August 2026  00:18:43 +0000 (0:00:00.720)       0:00:42.265 ******
2026-08-26 00:18:46.623671 | instance | changed: [instance] => (item=osd0)
2026-08-26 00:18:46.623730 | instance | changed: [instance] => (item=osd1)
2026-08-26 00:18:46.623737 | instance | changed: [instance] => (item=osd2)
2026-08-26 00:18:46.623744 | instance |
2026-08-26 00:18:46.623750 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-26 00:18:46.623757 | instance | Wednesday 26 August 2026  00:18:46 +0000 (0:00:03.318)       0:00:45.584 ******
2026-08-26 00:18:48.613421 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-26 00:18:48.613492 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-26 00:18:48.613525 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-26 00:18:48.613534 | instance |
2026-08-26 00:18:48.613544 | instance | PLAY RECAP *********************************************************************
2026-08-26 00:18:48.613554 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-26 00:18:48.613563 | instance |
2026-08-26 00:18:48.613572 | instance | Wednesday 26 August 2026  00:18:48 +0000 (0:00:01.988)       0:00:47.573 ******
2026-08-26 00:18:48.613635 | instance | ===============================================================================
2026-08-26 00:18:48.613975 | instance | Install depedencies ---------------------------------------------------- 34.81s
2026-08-26 00:18:48.614248 | instance | Create a volume group for each loop device ------------------------------ 3.32s
2026-08-26 00:18:48.614416 | instance | Create a logical volume for each loop device ---------------------------- 1.99s
2026-08-26 00:18:48.614570 | instance | Gathering Facts --------------------------------------------------------- 1.23s
2026-08-26 00:18:48.614734 | instance | Purge "snapd" package --------------------------------------------------- 0.78s
2026-08-26 00:18:48.614905 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-08-26 00:18:48.615062 | instance | Start loop devices ------------------------------------------------------ 0.72s
2026-08-26 00:18:48.615216 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.68s
2026-08-26 00:18:48.615378 | instance | Configure short hostname ------------------------------------------------ 0.67s
2026-08-26 00:18:48.615541 | instance | Set permissions on loopback devices ------------------------------------- 0.65s
2026-08-26 00:18:48.615703 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-08-26 00:18:48.615863 | instance | Start up service -------------------------------------------------------- 0.56s
2026-08-26 00:18:48.616024 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s
2026-08-26 00:18:48.616226 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s
2026-08-26 00:18:48.616393 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-08-26 00:18:48.616562 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-08-26 00:18:48.683557 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-26 00:18:48.695290 | instance | INFO     [csi > converge] Executing
2026-08-26 00:18:50.227133 | instance |
2026-08-26 00:18:50.227401 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-26 00:18:50.227662 | instance |
2026-08-26 00:18:50.227958 | instance | TASK [Gathering Facts] *********************************************************
2026-08-26 00:18:50.228243 | instance | Wednesday 26 August 2026  00:18:50 +0000 (0:00:00.008)       0:00:00.008 ******
2026-08-26 00:18:51.241524 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-26 00:18:51.241977 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-26 00:18:51.242283 | instance | interpreter could change the meaning of that path. See
2026-08-26 00:18:51.242599 | instance | https://docs.ansible.com/ansible-
2026-08-26 00:18:51.252282 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-26 00:18:51.253237 | instance | ok: [instance]
2026-08-26 00:18:51.253555 | instance |
2026-08-26 00:18:51.253840 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-26 00:18:51.254122 | instance | Wednesday 26 August 2026  00:18:51 +0000 (0:00:01.026)       0:00:01.034 ******
2026-08-26 00:18:51.304589 | instance | ok: [instance] => {
2026-08-26 00:18:51.304880 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-26 00:18:51.305159 | instance | }
2026-08-26 00:18:51.305494 | instance |
2026-08-26 00:18:51.305813 | instance | PLAY [all] *********************************************************************
2026-08-26 00:18:51.306087 | instance |
2026-08-26 00:18:51.306372 | instance | TASK [Gathering Facts] *********************************************************
2026-08-26 00:18:51.306708 | instance | Wednesday 26 August 2026  00:18:51 +0000 (0:00:00.051)       0:00:01.086 ******
2026-08-26 00:18:52.243257 | instance | ok: [instance]
2026-08-26 00:18:52.243524 | instance |
2026-08-26 00:18:52.243851 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-26 00:18:52.244150 | instance | Wednesday 26 August 2026  00:18:52 +0000 (0:00:00.938)       0:00:02.024 ******
2026-08-26 00:18:52.440409 | instance | ok: [instance]
2026-08-26 00:18:52.440820 | instance |
2026-08-26 00:18:52.441187 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-26 00:18:52.441571 | instance |
2026-08-26 00:18:52.441928 | instance | TASK [Gathering Facts] *********************************************************
2026-08-26 00:18:52.442280 | instance | Wednesday 26 August 2026  00:18:52 +0000 (0:00:00.197)       0:00:02.221 ******
2026-08-26 00:18:53.386020 | instance | ok: [instance]
2026-08-26 00:18:53.386114 | instance |
2026-08-26 00:18:53.386312 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-26 00:18:53.386595 | instance | Wednesday 26 August 2026  00:18:53 +0000 (0:00:00.942)       0:00:03.164 ******
2026-08-26 00:18:53.673864 | instance | ok: [instance]
2026-08-26 00:18:53.674157 | instance |
2026-08-26 00:18:53.674319 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-26 00:18:53.674497 | instance | Wednesday 26 August 2026  00:18:53 +0000 (0:00:00.289)       0:00:03.453 ******
2026-08-26 00:18:53.717929 | instance | skipping: [instance]
2026-08-26 00:18:53.718015 | instance |
2026-08-26 00:18:53.718081 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-26 00:18:53.718248 | instance | Wednesday 26 August 2026  00:18:53 +0000 (0:00:00.045)       0:00:03.499 ******
2026-08-26 00:18:54.081959 | instance | changed: [instance]
2026-08-26 00:18:54.082051 | instance |
2026-08-26 00:18:54.082145 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-26 00:18:54.082399 | instance | Wednesday 26 August 2026  00:18:54 +0000 (0:00:00.362)       0:00:03.861 ******
2026-08-26 00:18:54.161947 | instance | ok: [instance] => {
2026-08-26 00:18:54.162041 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-26 00:18:54.162160 | instance | }
2026-08-26 00:18:54.162355 | instance |
2026-08-26 00:18:54.162510 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-26 00:18:54.162664 | instance | Wednesday 26 August 2026  00:18:54 +0000 (0:00:00.077)       0:00:03.939 ******
2026-08-26 00:18:54.958298 | instance | changed: [instance]
2026-08-26 00:18:54.958700 | instance |
2026-08-26 00:18:54.959059 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-26 00:18:54.959409 | instance | Wednesday 26 August 2026  00:18:54 +0000 (0:00:00.799)       0:00:04.738 ******
2026-08-26 00:18:55.013966 | instance | skipping: [instance]
2026-08-26 00:18:55.014090 | instance |
2026-08-26 00:18:55.014315 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-26 00:18:55.014478 | instance | Wednesday 26 August 2026  00:18:55 +0000 (0:00:00.054)       0:00:04.792 ******
2026-08-26 00:18:55.069461 | instance | skipping: [instance]
2026-08-26 00:18:55.069730 | instance |
2026-08-26 00:18:55.069942 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-26 00:18:55.070177 | instance | Wednesday 26 August 2026  00:18:55 +0000 (0:00:00.056)       0:00:04.849 ******
2026-08-26 00:18:55.293531 | instance | ok: [instance]
2026-08-26 00:18:55.293933 | instance |
2026-08-26 00:18:55.294276 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-26 00:18:55.294605 | instance | Wednesday 26 August 2026  00:18:55 +0000 (0:00:00.225)       0:00:05.074 ******
2026-08-26 00:18:56.863248 | instance | ok: [instance]
2026-08-26 00:18:56.863509 | instance |
2026-08-26 00:18:56.863728 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-26 00:18:56.863935 | instance | Wednesday 26 August 2026  00:18:56 +0000 (0:00:01.569)       0:00:06.644 ******
2026-08-26 00:18:56.936548 | instance | ok: [instance] => {
2026-08-26 00:18:56.936989 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-26 00:18:56.937376 | instance | }
2026-08-26 00:18:56.937689 | instance |
2026-08-26 00:18:56.938060 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-26 00:18:56.938400 | instance | Wednesday 26 August 2026  00:18:56 +0000 (0:00:00.073)       0:00:06.717 ******
2026-08-26 00:18:57.749779 | instance | changed: [instance]
2026-08-26 00:18:57.750031 | instance |
2026-08-26 00:18:57.750205 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-26 00:18:57.750367 | instance | Wednesday 26 August 2026  00:18:57 +0000 (0:00:00.813)       0:00:07.530 ******
2026-08-26 00:19:01.702759 | instance | changed: [instance]
2026-08-26 00:19:01.703406 | instance |
2026-08-26 00:19:01.703774 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-26 00:19:01.704225 | instance | Wednesday 26 August 2026  00:19:01 +0000 (0:00:03.952)       0:00:11.483 ******
2026-08-26 00:19:01.738009 | instance | skipping: [instance]
2026-08-26 00:19:01.738115 | instance |
2026-08-26 00:19:01.738266 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-26 00:19:01.738547 | instance | Wednesday 26 August 2026  00:19:01 +0000 (0:00:00.033)       0:00:11.517 ******
2026-08-26 00:19:01.773993 | instance | skipping: [instance]
2026-08-26 00:19:01.774245 | instance |
2026-08-26 00:19:01.774722 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-26 00:19:01.774942 | instance | Wednesday 26 August 2026  00:19:01 +0000 (0:00:00.037)       0:00:11.555 ******
2026-08-26 00:19:01.806243 | instance | skipping: [instance]
2026-08-26 00:19:01.806655 | instance |
2026-08-26 00:19:01.807192 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-26 00:19:01.807559 | instance | Wednesday 26 August 2026  00:19:01 +0000 (0:00:00.031)       0:00:11.586 ******
2026-08-26 00:19:09.897715 | instance | changed: [instance]
2026-08-26 00:19:09.897802 | instance |
2026-08-26 00:19:09.897899 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-26 00:19:09.898048 | instance | Wednesday 26 August 2026  00:19:09 +0000 (0:00:08.092)       0:00:19.679 ******
2026-08-26 00:19:10.547674 | instance | changed: [instance]
2026-08-26 00:19:10.547762 | instance |
2026-08-26 00:19:10.547853 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-26 00:19:10.547978 | instance | Wednesday 26 August 2026  00:19:10 +0000 (0:00:00.649)       0:00:20.328 ******
2026-08-26 00:19:11.479508 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-26 00:19:11.479760 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-26 00:19:11.480041 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-26 00:19:11.480359 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-26 00:19:11.480643 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-26 00:19:11.480900 | instance |
2026-08-26 00:19:11.481186 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-26 00:19:11.481499 | instance | Wednesday 26 August 2026  00:19:11 +0000 (0:00:00.931)       0:00:21.260 ******
2026-08-26 00:19:12.102525 | instance | changed: [instance]
2026-08-26 00:19:12.102784 | instance |
2026-08-26 00:19:12.103103 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-26 00:19:12.103449 | instance | Wednesday 26 August 2026  00:19:12 +0000 (0:00:00.605)       0:00:21.866 ******
2026-08-26 00:19:12.103763 | instance |
2026-08-26 00:19:12.104071 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-26 00:19:12.104516 | instance | Wednesday 26 August 2026  00:19:12 +0000 (0:00:00.017)       0:00:21.883 ******
2026-08-26 00:19:13.002236 | instance | ok: [instance]
2026-08-26 00:19:13.002474 | instance |
2026-08-26 00:19:13.002773 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-26 00:19:13.003164 | instance | Wednesday 26 August 2026  00:19:12 +0000 (0:00:00.899)       0:00:22.782 ******
2026-08-26 00:19:13.463672 | instance | changed: [instance]
2026-08-26 00:19:13.463793 | instance |
2026-08-26 00:19:13.464079 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-26 00:19:13.464097 | instance | Wednesday 26 August 2026  00:19:13 +0000 (0:00:00.462)       0:00:23.245 ******
2026-08-26 00:19:14.027643 | instance | changed: [instance]
2026-08-26 00:19:14.027722 | instance |
2026-08-26 00:19:14.028030 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-26 00:19:14.028095 | instance | Wednesday 26 August 2026  00:19:14 +0000 (0:00:00.563)       0:00:23.809 ******
2026-08-26 00:19:14.236385 | instance | ok: [instance]
2026-08-26 00:19:14.236514 | instance |
2026-08-26 00:19:14.236526 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-26 00:19:14.236703 | instance | Wednesday 26 August 2026  00:19:14 +0000 (0:00:00.208)       0:00:24.017 ******
2026-08-26 00:19:14.304844 | instance | ok: [instance] => {
2026-08-26 00:19:14.305496 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-26 00:19:14.305611 | instance | }
2026-08-26 00:19:14.305631 | instance |
2026-08-26 00:19:14.305680 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-26 00:19:14.305701 | instance | Wednesday 26 August 2026  00:19:14 +0000 (0:00:00.068)       0:00:24.086 ******
2026-08-26 00:19:15.144570 | instance | changed: [instance]
2026-08-26 00:19:15.144664 | instance |
2026-08-26 00:19:15.144968 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-26 00:19:15.145037 | instance | Wednesday 26 August 2026  00:19:15 +0000 (0:00:00.839)       0:00:24.925 ******
2026-08-26 00:19:19.672949 | instance | changed: [instance]
2026-08-26 00:19:19.673059 | instance |
2026-08-26 00:19:19.673376 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-26 00:19:19.673418 | instance | Wednesday 26 August 2026  00:19:19 +0000 (0:00:04.528)       0:00:29.454 ******
2026-08-26 00:19:20.835316 | instance | ok: [instance]
2026-08-26 00:19:20.835402 | instance |
2026-08-26 00:19:20.835716 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-26 00:19:20.835781 | instance | Wednesday 26 August 2026  00:19:20 +0000 (0:00:01.161)       0:00:30.616 ******
2026-08-26 00:19:21.218936 | instance | changed: [instance]
2026-08-26 00:19:21.219046 | instance |
2026-08-26 00:19:21.219292 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-26 00:19:21.219345 | instance | Wednesday 26 August 2026  00:19:21 +0000 (0:00:00.383)       0:00:31.000 ******
2026-08-26 00:19:21.745627 | instance | changed: [instance]
2026-08-26 00:19:21.745703 | instance |
2026-08-26 00:19:21.745991 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-26 00:19:21.746158 | instance | Wednesday 26 August 2026  00:19:21 +0000 (0:00:00.526)       0:00:31.527 ******
2026-08-26 00:19:22.343776 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-26 00:19:22.343852 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-26 00:19:22.344368 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-26 00:19:22.344409 | instance |
2026-08-26 00:19:22.344415 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-26 00:19:22.344420 | instance | Wednesday 26 August 2026  00:19:22 +0000 (0:00:00.598)       0:00:32.125 ******
2026-08-26 00:19:22.804792 | instance | changed: [instance]
2026-08-26 00:19:22.804866 | instance |
2026-08-26 00:19:22.805179 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-26 00:19:22.805326 | instance | Wednesday 26 August 2026  00:19:22 +0000 (0:00:00.461)       0:00:32.586 ******
2026-08-26 00:19:23.325171 | instance | changed: [instance]
2026-08-26 00:19:23.325276 | instance |
2026-08-26 00:19:23.325938 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-26 00:19:23.326007 | instance | Wednesday 26 August 2026  00:19:23 +0000 (0:00:00.501)       0:00:33.087 ******
2026-08-26 00:19:23.326012 | instance |
2026-08-26 00:19:23.326017 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-26 00:19:23.326021 | instance | Wednesday 26 August 2026  00:19:23 +0000 (0:00:00.018)       0:00:33.106 ******
2026-08-26 00:19:24.016918 | instance | ok: [instance]
2026-08-26 00:19:24.017033 | instance |
2026-08-26 00:19:24.017398 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-26 00:19:24.017514 | instance | Wednesday 26 August 2026  00:19:24 +0000 (0:00:00.691)       0:00:33.798 ******
2026-08-26 00:19:26.805028 | instance | changed: [instance]
2026-08-26 00:19:26.805148 | instance |
2026-08-26 00:19:26.805215 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-26 00:19:26.805428 | instance | Wednesday 26 August 2026  00:19:26 +0000 (0:00:02.788)       0:00:36.586 ******
2026-08-26 00:19:27.426908 | instance | changed: [instance]
2026-08-26 00:19:27.426961 | instance |
2026-08-26 00:19:27.426968 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-26 00:19:27.426975 | instance | Wednesday 26 August 2026  00:19:27 +0000 (0:00:00.619)       0:00:37.206 ******
2026-08-26 00:19:27.483636 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-26 00:19:27.483706 | instance |
2026-08-26 00:19:27.484015 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-26 00:19:27.484083 | instance | Wednesday 26 August 2026  00:19:27 +0000 (0:00:00.058)       0:00:37.264 ******
2026-08-26 00:19:34.530636 | instance | changed: [instance]
2026-08-26 00:19:34.530895 | instance |
2026-08-26 00:19:34.531410 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-26 00:19:34.531473 | instance | Wednesday 26 August 2026  00:19:34 +0000 (0:00:07.046)       0:00:44.311 ******
2026-08-26 00:19:35.199720 | instance | ok: [instance] => (item=chronyd)
2026-08-26 00:19:35.199806 | instance | ok: [instance] => (item=sshd)
2026-08-26 00:19:35.200180 | instance |
2026-08-26 00:19:35.200221 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-26 00:19:35.200227 | instance | Wednesday 26 August 2026  00:19:35 +0000 (0:00:00.669)       0:00:44.981 ******
2026-08-26 00:19:35.528835 | instance | changed: [instance]
2026-08-26 00:19:35.528907 | instance |
2026-08-26 00:19:35.529189 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-26 00:19:35.529237 | instance | Wednesday 26 August 2026  00:19:35 +0000 (0:00:00.328)       0:00:45.310 ******
2026-08-26 00:19:35.735707 | instance | ok: [instance]
2026-08-26 00:19:35.735788 | instance |
2026-08-26 00:19:35.736210 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-26 00:19:35.736293 | instance | Wednesday 26 August 2026  00:19:35 +0000 (0:00:00.206)       0:00:45.516 ******
2026-08-26 00:19:36.214764 | instance | changed: [instance]
2026-08-26 00:19:36.214875 | instance |
2026-08-26 00:19:36.215130 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-26 00:19:36.215184 | instance | Wednesday 26 August 2026  00:19:36 +0000 (0:00:00.479)       0:00:45.996 ******
2026-08-26 00:19:36.573825 | instance | changed: [instance]
2026-08-26 00:19:36.574438 | instance |
2026-08-26 00:19:36.574492 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-26 00:19:36.574501 | instance | Wednesday 26 August 2026  00:19:36 +0000 (0:00:00.358)       0:00:46.355 ******
2026-08-26 00:19:36.730627 | instance | ok: [instance]
2026-08-26 00:19:36.730707 | instance |
2026-08-26 00:19:36.731063 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-26 00:19:36.731106 | instance | Wednesday 26 August 2026  00:19:36 +0000 (0:00:00.156)       0:00:46.511 ******
2026-08-26 00:19:36.962598 | instance | ok: [instance] => (item=instance)
2026-08-26 00:19:36.962705 | instance |
2026-08-26 00:19:36.962769 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-26 00:19:36.962941 | instance | Wednesday 26 August 2026  00:19:36 +0000 (0:00:00.232)       0:00:46.744 ******
2026-08-26 00:19:37.031558 | instance | ok: [instance]
2026-08-26 00:19:37.031671 | instance |
2026-08-26 00:19:37.032181 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-26 00:19:37.032266 | instance | Wednesday 26 August 2026  00:19:37 +0000 (0:00:00.067)       0:00:46.812 ******
2026-08-26 00:19:37.123280 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-26 00:19:37.123394 | instance |
2026-08-26 00:19:37.123648 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-26 00:19:37.123715 | instance | Wednesday 26 August 2026  00:19:37 +0000 (0:00:00.092)       0:00:46.904 ******
2026-08-26 00:19:37.460072 | instance | changed: [instance]
2026-08-26 00:19:37.460185 | instance |
2026-08-26 00:19:37.460249 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-26 00:19:37.460418 | instance | Wednesday 26 August 2026  00:19:37 +0000 (0:00:00.336)       0:00:47.241 ******
2026-08-26 00:19:38.214339 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-26 00:19:38.214420 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-26 00:19:38.214947 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-26 00:19:38.215001 | instance |
2026-08-26 00:19:38.215008 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-26 00:19:38.215014 | instance | Wednesday 26 August 2026  00:19:38 +0000 (0:00:00.754)       0:00:47.995 ******
2026-08-26 00:21:36.628354 | instance | ok: [instance]
2026-08-26 00:21:36.628446 | instance |
2026-08-26 00:21:36.628523 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-26 00:21:36.628660 | instance | Wednesday 26 August 2026  00:21:36 +0000 (0:01:58.413)       0:02:46.409 ******
2026-08-26 00:21:36.918438 | instance | changed: [instance]
2026-08-26 00:21:36.918780 | instance |
2026-08-26 00:21:36.919255 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-26 00:21:36.919632 | instance | Wednesday 26 August 2026  00:21:36 +0000 (0:00:00.290)       0:02:46.699 ******
2026-08-26 00:21:36.985562 | instance | ok: [instance]
2026-08-26 00:21:37.001199 | instance |
2026-08-26 00:21:37.001228 | instance | TASK [Install Ceph host] *******************************************************
2026-08-26 00:21:37.001235 | instance | Wednesday 26 August 2026  00:21:36 +0000 (0:00:00.067)       0:02:46.767 ******
2026-08-26 00:21:37.063997 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-26 00:21:37.064212 | instance |
2026-08-26 00:21:37.064491 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-26 00:21:37.064784 | instance | Wednesday 26 August 2026  00:21:37 +0000 (0:00:00.078)       0:02:46.845 ******
2026-08-26 00:21:38.739933 | instance | ok: [instance]
2026-08-26 00:21:38.740082 | instance |
2026-08-26 00:21:38.740270 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-26 00:21:38.740446 | instance | Wednesday 26 August 2026  00:21:38 +0000 (0:00:01.676)       0:02:48.521 ******
2026-08-26 00:21:38.799436 | instance | ok: [instance] => (item=instance)
2026-08-26 00:21:38.799555 | instance |
2026-08-26 00:21:38.799734 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-26 00:21:38.799926 | instance | Wednesday 26 August 2026  00:21:38 +0000 (0:00:00.059)       0:02:48.581 ******
2026-08-26 00:21:39.169607 | instance | ok: [instance]
2026-08-26 00:21:39.169943 | instance |
2026-08-26 00:21:39.170244 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-26 00:21:39.170669 | instance | Wednesday 26 August 2026  00:21:39 +0000 (0:00:00.369)       0:02:48.950 ******
2026-08-26 00:21:41.492633 | instance | ok: [instance]
2026-08-26 00:21:41.492984 | instance |
2026-08-26 00:21:41.493012 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-26 00:21:41.493023 | instance | Wednesday 26 August 2026  00:21:41 +0000 (0:00:02.323)       0:02:51.274 ******
2026-08-26 00:22:54.491194 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-26 00:22:54.491310 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n  File \"<stdin>\", line 107, in <module>\n  File \"<stdin>\", line 99, in _ansiballz_main\n  File \"<stdin>\", line 47, in invoke_module\n  File \"/usr/lib/python3.10/runpy.py\", line 224, in run_module\n    return _run_module_code(code, init_globals, run_name, mod_spec)\n  File \"/usr/lib/python3.10/runpy.py\", line 96, in _run_module_code\n    _run_code(code, mod_globals, init_globals,\n  File \"/usr/lib/python3.10/runpy.py\", line 86, in _run_code\n    exec(code, run_globals)\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_rimtz5jz/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in <module>\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_rimtz5jz/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 198, in main\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_rimtz5jz/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 177, in run_module\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_rimtz5jz/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-08-26T00:22:51.853+0000 7f68b1bf5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBdMY5qmtYlACAAR7d4lHQDs4cJXGogxlhbDe45Bxjj30wTa5I60/pJQK8=: Malformed input [buffer:3]\n2026-08-26T00:22:51.853+0000 7f68b1bf5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-26T00:22:51.853+0000 7f68b1bf5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBdMY5qmtYlACAAR7d4lHQDs4cJXGogxlhbDe45Bxjj30wTa5I60/pJQK8=: Malformed input [buffer:3]\n2026-08-26T00:22:51.853+0000 7f68b1bf5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-26T00:22:51.853+0000 7f68b1bf5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBdMY5qmtYlACAAR7d4lHQDs4cJXGogxlhbDe45Bxjj30wTa5I60/pJQK8=: Malformed input [buffer:3]\n2026-08-26T00:22:51.853+0000 7f68b1bf5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-26T00:22:51.853+0000 7f68b1bf5640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-26 00:22:54.492468 | instance |
2026-08-26 00:22:54.493502 | instance | PLAY RECAP *********************************************************************
2026-08-26 00:22:54.493547 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-26 00:22:54.493559 | instance |
2026-08-26 00:22:54.493569 | instance | Wednesday 26 August 2026  00:22:54 +0000 (0:01:13.000)       0:04:04.274 ******
2026-08-26 00:22:54.493578 | instance | ===============================================================================
2026-08-26 00:22:54.493587 | instance | vexxhost.ceph.mon : Run Bootstrap command ----------------------------- 118.41s
2026-08-26 00:22:54.493600 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 73.00s
2026-08-26 00:22:54.494551 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 8.09s
2026-08-26 00:22:54.494614 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 7.05s
2026-08-26 00:22:54.494619 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.53s
2026-08-26 00:22:54.494624 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.95s
2026-08-26 00:22:54.494628 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 2.79s
2026-08-26 00:22:54.494637 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.32s
2026-08-26 00:22:54.494759 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.68s
2026-08-26 00:22:54.495002 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.59s
2026-08-26 00:22:54.495207 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.57s
2026-08-26 00:22:54.495411 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.16s
2026-08-26 00:22:54.495613 | instance | Gathering Facts --------------------------------------------------------- 1.03s
2026-08-26 00:22:54.495815 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-08-26 00:22:54.496016 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-08-26 00:22:54.496220 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.93s
2026-08-26 00:22:54.496491 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.84s
2026-08-26 00:22:54.496703 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.81s
2026-08-26 00:22:54.496915 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.80s
2026-08-26 00:22:54.497124 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.75s
2026-08-26 00:22:54.658442 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-26 00:22:54.658742 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-26 00:22:54.658933 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-26 00:22:55.038929 | instance | ERROR
2026-08-26 00:22:55.039479 | instance | {
2026-08-26 00:22:55.039619 | instance |   "delta": "0:05:08.948412",
2026-08-26 00:22:55.039654 | instance |   "end": "2026-08-26 00:22:54.716803",
2026-08-26 00:22:55.039680 | instance |   "msg": "non-zero return code",
2026-08-26 00:22:55.039705 | instance |   "rc": 2,
2026-08-26 00:22:55.039730 | instance |   "start": "2026-08-26 00:17:45.768391"
2026-08-26 00:22:55.039754 | instance | }
failure
2026-08-26 00:22:55.046877 | 
2026-08-26 00:22:55.046948 | PLAY RECAP
2026-08-26 00:22:55.047013 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-26 00:22:55.047044 | 
2026-08-26 00:22:55.218086 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-26 00:22:55.223584 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-26 00:22:55.841328 | 
2026-08-26 00:22:55.841472 | PLAY [all]
2026-08-26 00:22:55.856339 | 
2026-08-26 00:22:55.856427 | TASK [gather-host-logs : creating directory for system status]
2026-08-26 00:22:56.203151 | instance | changed
2026-08-26 00:22:56.210305 | 
2026-08-26 00:22:56.210412 | TASK [gather-host-logs : Get logs for each host]
2026-08-26 00:22:56.563243 | instance | + systemd-cgls --full --all --no-pager
2026-08-26 00:22:56.585489 | instance | + ip addr
2026-08-26 00:22:56.586936 | instance | + ip route
2026-08-26 00:22:56.589631 | instance | + lsblk
2026-08-26 00:22:56.594976 | instance | + mount
2026-08-26 00:22:56.598600 | instance | + docker images
2026-08-26 00:22:56.617352 | instance | + brctl show
2026-08-26 00:22:56.617855 | instance | /bin/bash: line 8: brctl: command not found
2026-08-26 00:22:56.618069 | instance | + ps aux --sort=-%mem
2026-08-26 00:22:56.658431 | instance | + dpkg -l
2026-08-26 00:22:56.670029 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-26 00:22:56.670753 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-26 00:22:56.688613 | instance | + '[' '!' -z '' ']'
2026-08-26 00:22:56.745251 | instance | ok: Runtime: 0:00:00.131976
2026-08-26 00:22:56.752491 | 
2026-08-26 00:22:56.752563 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-26 00:22:57.396218 | instance | changed:
2026-08-26 00:22:57.396445 | instance | created directory /var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/work/logs/instance
2026-08-26 00:22:57.396491 | instance | cd+++++++++ system/
2026-08-26 00:22:57.396528 | instance | >f+++++++++ system/brctl-show.txt
2026-08-26 00:22:57.396563 | instance | >f+++++++++ system/docker-images.txt
2026-08-26 00:22:57.396598 | instance | >f+++++++++ system/ip-addr.txt
2026-08-26 00:22:57.396637 | instance | >f+++++++++ system/ip-route.txt
2026-08-26 00:22:57.396673 | instance | >f+++++++++ system/lsblk.txt
2026-08-26 00:22:57.396709 | instance | >f+++++++++ system/mount.txt
2026-08-26 00:22:57.396743 | instance | >f+++++++++ system/packages.txt
2026-08-26 00:22:57.396776 | instance | >f+++++++++ system/ps.txt
2026-08-26 00:22:57.396812 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-26 00:22:57.408368 | 
2026-08-26 00:22:57.408461 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-26 00:22:57.613888 | instance | changed: "values"
2026-08-26 00:22:57.783524 | instance | changed: "releases"
2026-08-26 00:22:57.794923 | 
2026-08-26 00:22:57.795025 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-26 00:22:58.003725 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-26 00:22:58.334536 | instance | ok: Runtime: 0:00:00.006005
2026-08-26 00:22:58.340216 | 
2026-08-26 00:22:58.340288 | TASK [helm-release-status : Downloads logs to executor]
2026-08-26 00:22:58.808291 | instance | changed:
2026-08-26 00:22:58.808450 | instance | cd+++++++++ helm/
2026-08-26 00:22:58.808482 | instance | cd+++++++++ helm/releases/
2026-08-26 00:22:58.808503 | instance | cd+++++++++ helm/values/
2026-08-26 00:22:58.817691 | 
2026-08-26 00:22:58.817763 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-26 00:22:59.033865 | instance | changed
2026-08-26 00:22:59.049227 | 
2026-08-26 00:22:59.049301 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-26 00:22:59.296236 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 00:22:59.298098 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 00:22:59.300812 | instance | environment: line 1: kubectl: command not found
2026-08-26 00:22:59.301574 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 00:22:59.304729 | instance | environment: line 1: kubectl: command not found
2026-08-26 00:22:59.305869 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 00:22:59.306152 | instance | environment: line 1: kubectl: command not found
2026-08-26 00:22:59.308181 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 00:22:59.308614 | instance | environment: line 1: kubectl: command not found
2026-08-26 00:22:59.310532 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 00:22:59.312324 | instance | environment: line 1: kubectl: command not found
2026-08-26 00:22:59.313090 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-26 00:22:59.595206 | instance | ok: Runtime: 0:00:00.026911
2026-08-26 00:22:59.601964 | 
2026-08-26 00:22:59.602054 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-26 00:22:59.809116 | instance | changed
2026-08-26 00:22:59.872192 | 
2026-08-26 00:22:59.872335 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-26 00:23:00.090943 | instance | environment: line 5: kubectl: command not found
2026-08-26 00:23:00.092124 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 00:23:00.092248 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 00:23:00.092263 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-26 00:23:00.410353 | instance | ok: Runtime: 0:00:00.009079
2026-08-26 00:23:00.419088 | 
2026-08-26 00:23:00.419187 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-26 00:23:00.942383 | instance | changed:
2026-08-26 00:23:00.942563 | instance | cd+++++++++ objects/
2026-08-26 00:23:00.942591 | instance | cd+++++++++ objects/cluster/
2026-08-26 00:23:00.942615 | instance | cd+++++++++ objects/namespaced/
2026-08-26 00:23:00.951373 | 
2026-08-26 00:23:00.951438 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-26 00:23:01.166424 | instance | changed
2026-08-26 00:23:01.172099 | 
2026-08-26 00:23:01.172166 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-26 00:23:01.379605 | instance | changed
2026-08-26 00:23:01.386507 | 
2026-08-26 00:23:01.386610 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-26 00:23:01.600552 | instance | environment: line 3: kubectl: command not found
2026-08-26 00:23:01.922368 | instance | ok: Runtime: 0:00:00.008314
2026-08-26 00:23:01.927962 | 
2026-08-26 00:23:01.928028 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-26 00:23:02.416096 | instance | changed:
2026-08-26 00:23:02.416314 | instance | cd+++++++++ pod-logs/
2026-08-26 00:23:02.416353 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-26 00:23:02.428217 | 
2026-08-26 00:23:02.428285 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-26 00:23:02.636542 | instance | changed
2026-08-26 00:23:02.643412 | 
2026-08-26 00:23:02.643506 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-26 00:23:02.859436 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-26 00:23:03.182071 | instance | ok: Runtime: 0:00:00.035379
2026-08-26 00:23:03.188634 | 
2026-08-26 00:23:03.188736 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-26 00:23:03.404579 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-26 00:23:03.434222 | instance | ceph-mgr endpoints:
2026-08-26 00:23:03.728214 | instance | ok: Runtime: 0:00:00.037405
2026-08-26 00:23:03.735526 | 
2026-08-26 00:23:03.735623 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-26 00:23:03.970644 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-26 00:23:04.272610 | instance | ok: Runtime: 0:00:00.036841
2026-08-26 00:23:04.279550 | 
2026-08-26 00:23:04.279640 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-26 00:23:04.758008 | instance | changed: cd+++++++++ prometheus/
2026-08-26 00:23:04.775134 | 
2026-08-26 00:23:04.775367 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-26 00:23:04.975857 | instance | changed
2026-08-26 00:23:04.982637 | 
2026-08-26 00:23:04.982771 | TASK [gather-selenium-data : Get selenium data]
2026-08-26 00:23:05.242059 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-26 00:23:05.243505 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-26 00:23:05.513802 | instance | ERROR
2026-08-26 00:23:05.513982 | instance | {
2026-08-26 00:23:05.514014 | instance |   "delta": "0:00:00.007164",
2026-08-26 00:23:05.514035 | instance |   "end": "2026-08-26 00:23:05.243908",
2026-08-26 00:23:05.514056 | instance |   "msg": "non-zero return code",
2026-08-26 00:23:05.514076 | instance |   "rc": 1,
2026-08-26 00:23:05.514093 | instance |   "start": "2026-08-26 00:23:05.236744"
2026-08-26 00:23:05.514111 | instance | }
2026-08-26 00:23:05.514134 | instance | ERROR: Ignoring Errors
2026-08-26 00:23:05.518923 | 
2026-08-26 00:23:05.518986 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-26 00:23:06.000421 | instance | changed: cd+++++++++ selenium/
2026-08-26 00:23:06.007907 | 
2026-08-26 00:23:06.007963 | PLAY RECAP
2026-08-26 00:23:06.008010 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-26 00:23:06.008031 | 
2026-08-26 00:23:06.142527 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-26 00:23:06.147422 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 00:23:06.713219 | 
2026-08-26 00:23:06.713696 | PLAY [all]
2026-08-26 00:23:06.725011 | 
2026-08-26 00:23:06.725087 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-26 00:23:06.759492 | instance | skipping: Conditional result was False
2026-08-26 00:23:06.769707 | 
2026-08-26 00:23:06.769869 | TASK [fetch-output : Set log path for single node]
2026-08-26 00:23:06.813653 | instance | ok
2026-08-26 00:23:06.819272 | 
2026-08-26 00:23:06.819339 | LOOP [fetch-output : Ensure local output dirs]
2026-08-26 00:23:07.199299 | instance -> localhost | ok: "/var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/work/logs"
2026-08-26 00:23:07.411641 | instance -> localhost | changed: "/var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/work/artifacts"
2026-08-26 00:23:07.669702 | instance -> localhost | changed: "/var/lib/zuul/builds/ad1bd17bff1d409aa9beb61afed91381/work/docs"
2026-08-26 00:23:07.685756 | 
2026-08-26 00:23:07.685955 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-26 00:23:08.323755 | instance | changed: .d..t...... ./
2026-08-26 00:23:08.324022 | instance | changed: All items complete
2026-08-26 00:23:08.324075 | 
2026-08-26 00:23:08.792354 | instance | changed: .d..t...... ./
2026-08-26 00:23:09.238507 | instance | changed: .d..t...... ./
2026-08-26 00:23:09.255226 | 
2026-08-26 00:23:09.255373 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-26 00:23:09.660059 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008150
2026-08-26 00:23:09.880410 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007340
2026-08-26 00:23:09.893423 | 
2026-08-26 00:23:09.893551 | PLAY [all]
2026-08-26 00:23:09.899955 | 
2026-08-26 00:23:09.900020 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-26 00:23:10.317619 | instance | changed
2026-08-26 00:23:10.323879 | 
2026-08-26 00:23:10.323950 | PLAY RECAP
2026-08-26 00:23:10.323998 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 00:23:10.324021 | 
2026-08-26 00:23:10.471708 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 00:23:10.474059 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-26 00:23:11.120724 | 
2026-08-26 00:23:11.120906 | PLAY [localhost]
2026-08-26 00:23:11.132114 | 
2026-08-26 00:23:11.132234 | TASK [Generate Zuul manifest]
2026-08-26 00:23:11.152704 | localhost | ok
2026-08-26 00:23:11.170066 | 
2026-08-26 00:23:11.170152 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-26 00:23:11.546695 | localhost | changed
2026-08-26 00:23:11.559897 | 
2026-08-26 00:23:11.560035 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-26 00:23:11.593527 | localhost | ok
2026-08-26 00:23:11.602903 | 
2026-08-26 00:23:11.602991 | TASK [Upload logs]
2026-08-26 00:23:11.624659 | localhost | ok
2026-08-26 00:23:11.686265 | 
2026-08-26 00:23:11.686403 | TASK [Set zuul-log-path fact]
2026-08-26 00:23:11.706389 | localhost | ok
2026-08-26 00:23:11.720180 | 
2026-08-26 00:23:11.720263 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 00:23:11.752546 | localhost | ok
2026-08-26 00:23:11.763950 | 
2026-08-26 00:23:11.764022 | TASK [upload-logs : Create log directories]
2026-08-26 00:23:12.170033 | localhost | changed
2026-08-26 00:23:12.176325 | 
2026-08-26 00:23:12.176394 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-26 00:23:12.582349 | localhost -> localhost | ok: Runtime: 0:00:00.006186
2026-08-26 00:23:12.589399 | 
2026-08-26 00:23:12.589485 | TASK [upload-logs : Upload logs to log server]
2026-08-26 00:23:13.052286 | localhost | Output suppressed because no_log was given
2026-08-26 00:23:13.088247 | 
2026-08-26 00:23:13.088381 | LOOP [upload-logs : Compress console log and json output]
2026-08-26 00:23:13.134999 | localhost | skipping: Conditional result was False
2026-08-26 00:23:13.141857 | localhost | skipping: Conditional result was False
2026-08-26 00:23:13.156162 | 
2026-08-26 00:23:13.156327 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-26 00:23:13.192490 | localhost | skipping: Conditional result was False
2026-08-26 00:23:13.193222 | 
2026-08-26 00:23:13.196027 | localhost | skipping: Conditional result was False
2026-08-26 00:23:13.211329 | 
2026-08-26 00:23:13.211515 | LOOP [upload-logs : Upload console log and json output]