2026-08-22 18:41:52.502825 | Job console starting
2026-08-22 18:41:52.511094 | Updating git repos
2026-08-22 18:41:54.680875 | Cloning repos into workspace
2026-08-22 18:42:08.525220 | Restoring repo states
2026-08-22 18:42:21.712626 | Merging changes
2026-08-22 18:42:23.046675 | Checking out repos
2026-08-22 18:42:25.389931 | Preparing playbooks
2026-08-22 18:42:28.954323 | Running Ansible setup
2026-08-22 18:42:33.682104 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 18:42:34.281149 | 
2026-08-22 18:42:34.281256 | PLAY [localhost]
2026-08-22 18:42:34.288165 | 
2026-08-22 18:42:34.288236 | TASK [Gathering Facts]
2026-08-22 18:42:35.145820 | localhost | ok
2026-08-22 18:42:35.155722 | 
2026-08-22 18:42:35.155796 | TASK [Setup log path fact]
2026-08-22 18:42:35.174954 | localhost | ok
2026-08-22 18:42:35.188002 | 
2026-08-22 18:42:35.188070 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 18:42:35.216491 | localhost | ok
2026-08-22 18:42:35.226477 | 
2026-08-22 18:42:35.226549 | TASK [emit-job-header : Print job information]
2026-08-22 18:42:35.265471 | # Job Information
2026-08-22 18:42:35.265696 | Ansible Version: 2.16.18
2026-08-22 18:42:35.265765 | Job: atmosphere-molecule-csi-rbd
2026-08-22 18:42:35.265813 | Pipeline: check
2026-08-22 18:42:35.265857 | Executor: 2d72f0692154
2026-08-22 18:42:35.265900 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4095
2026-08-22 18:42:35.265950 | Event ID: a23ec790-9e58-11f1-870d-a8eac275495b
2026-08-22 18:42:35.269747 | 
2026-08-22 18:42:35.269818 | LOOP [emit-job-header : Print node information]
2026-08-22 18:42:35.367832 | localhost | ok:
2026-08-22 18:42:35.368069 | localhost | # Node Information
2026-08-22 18:42:35.368136 | localhost | Inventory Hostname: instance
2026-08-22 18:42:35.368185 | localhost | Hostname: np0000190732
2026-08-22 18:42:35.368229 | localhost | Username: zuul
2026-08-22 18:42:35.368278 | localhost | Distro: Ubuntu 22.04
2026-08-22 18:42:35.368321 | localhost | Provider: yul1
2026-08-22 18:42:35.368364 | localhost | Region: ca-ymq-1
2026-08-22 18:42:35.368405 | localhost | Label: ubuntu-jammy
2026-08-22 18:42:35.368445 | localhost | Product Name: OpenStack Nova
2026-08-22 18:42:35.368486 | localhost | Interface IP: 199.19.213.170
2026-08-22 18:42:35.385413 | 
2026-08-22 18:42:35.385566 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 18:42:35.777264 | localhost -> localhost | changed
2026-08-22 18:42:35.782146 | 
2026-08-22 18:42:35.782216 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 18:42:36.578609 | localhost -> localhost | changed
2026-08-22 18:42:36.589004 | 
2026-08-22 18:42:36.589069 | PLAY [all]
2026-08-22 18:42:36.597021 | 
2026-08-22 18:42:36.597097 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 18:42:36.804643 | instance -> localhost | ok
2026-08-22 18:42:36.813615 | 
2026-08-22 18:42:36.813704 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 18:42:36.846878 | instance | ok
2026-08-22 18:42:36.860154 | instance | included: /var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 18:42:36.865853 | 
2026-08-22 18:42:36.865930 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 18:42:37.761613 | instance -> localhost | Generating public/private rsa key pair.
2026-08-22 18:42:37.761812 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/work/9cf007c8be1e43cd8c41c0516fdd7e5f_id_rsa
2026-08-22 18:42:37.761860 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/work/9cf007c8be1e43cd8c41c0516fdd7e5f_id_rsa.pub
2026-08-22 18:42:37.761896 | instance -> localhost | The key fingerprint is:
2026-08-22 18:42:37.761929 | instance -> localhost | SHA256:CppnmNPvAbP28q4kyDL9yeR9bPbt50TuMvz29NjMITY zuul-build-sshkey
2026-08-22 18:42:37.761978 | instance -> localhost | The key's randomart image is:
2026-08-22 18:42:37.762012 | instance -> localhost | +---[RSA 3072]----+
2026-08-22 18:42:37.762052 | instance -> localhost | |                 |
2026-08-22 18:42:37.762086 | instance -> localhost | |                 |
2026-08-22 18:42:37.762117 | instance -> localhost | |                 |
2026-08-22 18:42:37.762149 | instance -> localhost | |                 |
2026-08-22 18:42:37.762181 | instance -> localhost | |    +   S     .  |
2026-08-22 18:42:37.762211 | instance -> localhost | |.o * = .     o   |
2026-08-22 18:42:37.762242 | instance -> localhost | |+.O O o.   . Eo..|
2026-08-22 18:42:37.762274 | instance -> localhost | |.. %.= .=  .=o=*o|
2026-08-22 18:42:37.762317 | instance -> localhost | |    **B+ ...oB=.*|
2026-08-22 18:42:37.762364 | instance -> localhost | +----[SHA256]-----+
2026-08-22 18:42:37.762464 | instance -> localhost | ok: Runtime: 0:00:00.528648
2026-08-22 18:42:37.770046 | 
2026-08-22 18:42:37.770137 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 18:42:37.802104 | instance | ok
2026-08-22 18:42:37.813715 | instance | included: /var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 18:42:37.824172 | 
2026-08-22 18:42:37.824304 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 18:42:37.848560 | instance | skipping: Conditional result was False
2026-08-22 18:42:37.858383 | 
2026-08-22 18:42:37.858486 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 18:42:38.255696 | instance | changed
2026-08-22 18:42:38.263416 | 
2026-08-22 18:42:38.263486 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 18:42:38.450151 | instance | ok
2026-08-22 18:42:38.457242 | 
2026-08-22 18:42:38.457309 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 18:42:38.912784 | instance | changed
2026-08-22 18:42:38.919243 | 
2026-08-22 18:42:38.919307 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 18:42:39.360868 | instance | changed
2026-08-22 18:42:39.367036 | 
2026-08-22 18:42:39.367100 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 18:42:39.391106 | instance | skipping: Conditional result was False
2026-08-22 18:42:39.402470 | 
2026-08-22 18:42:39.402535 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 18:42:39.735162 | instance -> localhost | changed
2026-08-22 18:42:39.754338 | 
2026-08-22 18:42:39.755081 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 18:42:40.014114 | instance -> localhost | Identity added: /var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/work/9cf007c8be1e43cd8c41c0516fdd7e5f_id_rsa (zuul-build-sshkey)
2026-08-22 18:42:40.014325 | instance -> localhost | ok: Runtime: 0:00:00.013028
2026-08-22 18:42:40.020765 | 
2026-08-22 18:42:40.020825 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 18:42:40.322303 | instance | ok
2026-08-22 18:42:40.327575 | 
2026-08-22 18:42:40.327683 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 18:42:40.354047 | instance | skipping: Conditional result was False
2026-08-22 18:42:40.369609 | 
2026-08-22 18:42:40.369677 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 18:42:40.682802 | instance | ok
2026-08-22 18:42:40.689542 | 
2026-08-22 18:42:40.689609 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 18:42:44.998528 | instance | Output suppressed because no_log was given
2026-08-22 18:42:45.007556 | 
2026-08-22 18:42:45.007627 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 18:42:45.198779 | instance | ok: "logs"
2026-08-22 18:42:45.198976 | instance | ok: All items complete
2026-08-22 18:42:45.199004 | 
2026-08-22 18:42:45.349131 | instance | ok: "artifacts"
2026-08-22 18:42:45.504512 | instance | ok: "docs"
2026-08-22 18:42:45.523834 | 
2026-08-22 18:42:45.523953 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 18:42:45.705208 | instance | changed: "logs"
2026-08-22 18:42:45.868831 | instance | changed: "artifacts"
2026-08-22 18:42:46.041407 | instance | changed: "docs"
2026-08-22 18:42:46.062044 | 
2026-08-22 18:42:46.062147 | PLAY RECAP
2026-08-22 18:42:46.062196 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 18:42:46.062226 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:42:46.062248 | 
2026-08-22 18:42:46.243400 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 18:42:46.245460 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 18:42:46.831410 | 
2026-08-22 18:42:46.831535 | PLAY [all]
2026-08-22 18:42:46.843021 | 
2026-08-22 18:42:46.843137 | TASK [setup-uv : Extract archive]
2026-08-22 18:42:49.041454 | instance | changed
2026-08-22 18:42:49.046926 | 
2026-08-22 18:42:49.046999 | TASK [setup-uv : Print version]
2026-08-22 18:42:49.381928 | instance | uv 0.8.13
2026-08-22 18:42:49.585430 | instance | ok: Runtime: 0:00:00.013592
2026-08-22 18:42:49.598229 | 
2026-08-22 18:42:49.598325 | TASK [Install Ansible collection siblings]
2026-08-22 18:42:58.879776 | instance | changed:
2026-08-22 18:42:58.879966 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-22 18:42:58.880010 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-22 18:42:58.880042 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-22 18:42:58.880072 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-22 18:42:58.880100 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-22 18:42:58.880133 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-22 18:42:58.880164 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-22 18:42:58.880195 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-22 18:42:58.880224 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-22 18:42:58.880252 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-22 18:42:58.880280 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-22 18:42:58.880307 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-22 18:42:58.880334 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-08-22 18:42:58.880362 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-22 18:42:58.880389 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-08-22 18:42:58.880418 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-22 18:42:58.880446 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-22 18:42:58.880473 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-22 18:42:58.880500 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-22 18:42:58.880526 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-08-22 18:42:58.880554 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-08-22 18:42:58.880581 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-08-22 18:42:58.880610 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 18:42:58.880653 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-22 18:42:58.880681 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 18:42:58.880708 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 18:42:58.880950 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-22 18:42:58.881003 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-22 18:42:58.881033 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 18:42:58.881061 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-22 18:42:58.881088 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-22 18:42:58.881116 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 18:42:58.881144 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 18:42:58.881171 | instance | Checked out ansible.netcommon to v8.6.2
2026-08-22 18:42:58.881198 | 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-22 18:42:58.881233 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.881260 | instance | Process install dependency map
2026-08-22 18:42:58.881286 | instance | Starting collection install process
2026-08-22 18:42:58.881313 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-22 18:42:58.881339 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-22 18:42:58.881365 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-08-22 18:42:58.881435 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-22 18:42:58.881462 | instance | Creating virtual environment at: .venv
2026-08-22 18:42:58.881488 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 18:42:58.881514 | instance | Downloading cryptography (4.2MiB)
2026-08-22 18:42:58.881546 | instance | Downloading netaddr (2.2MiB)
2026-08-22 18:42:58.881572 | instance | Downloading ansible-core (2.1MiB)
2026-08-22 18:42:58.881599 | instance | Downloading kubernetes (1.9MiB)
2026-08-22 18:42:58.881625 | instance | Downloading pygments (1.2MiB)
2026-08-22 18:42:58.881652 | instance | Downloading setuptools (1.1MiB)
2026-08-22 18:42:58.881678 | instance | Downloading rjsonnet (1.2MiB)
2026-08-22 18:42:58.881704 | instance | Downloading openstacksdk (1.7MiB)
2026-08-22 18:42:58.881730 | instance |    Building pyperclip==1.9.0
2026-08-22 18:42:58.881759 | instance |  Downloading rjsonnet
2026-08-22 18:42:58.881787 | instance |  Downloading netaddr
2026-08-22 18:42:58.881813 | instance |  Downloading pygments
2026-08-22 18:42:58.881838 | instance |  Downloading cryptography
2026-08-22 18:42:58.881858 | instance |  Downloading setuptools
2026-08-22 18:42:58.881877 | instance |  Downloading kubernetes
2026-08-22 18:42:58.881894 | instance |  Downloading ansible-core
2026-08-22 18:42:58.881912 | instance |  Downloading openstacksdk
2026-08-22 18:42:58.881929 | instance |       Built pyperclip==1.9.0
2026-08-22 18:42:58.881946 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 18:42:58.881964 | instance | Installed 79 packages in 176ms
2026-08-22 18:42:58.881982 | instance | Checked out ansible.posix to 1.6.0
2026-08-22 18:42:58.882000 | 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-22 18:42:58.882018 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.882035 | instance | Process install dependency map
2026-08-22 18:42:58.882058 | instance | Starting collection install process
2026-08-22 18:42:58.882077 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-22 18:42:58.882094 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-22 18:42:58.882111 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-22 18:42:58.882129 | instance | Checked out ansible.utils to v6.1.0
2026-08-22 18:42:58.882147 | 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-22 18:42:58.882164 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.882181 | instance | Process install dependency map
2026-08-22 18:42:58.882198 | instance | Starting collection install process
2026-08-22 18:42:58.882215 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-22 18:42:58.882233 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-22 18:42:58.882250 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-22 18:42:58.882268 | instance | Checked out atmosphere.common to v0.6.0
2026-08-22 18:42:58.882286 | 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-22 18:42:58.882304 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.882322 | instance | Process install dependency map
2026-08-22 18:42:58.882339 | instance | Starting collection install process
2026-08-22 18:42:58.882357 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-22 18:42:58.882375 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-22 18:42:58.882393 | instance | atmosphere.common:0.6.0 was installed successfully
2026-08-22 18:42:58.882410 | instance | Checked out community.crypto to 2.2.3
2026-08-22 18:42:58.882428 | 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-22 18:42:58.882446 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.882463 | instance | Process install dependency map
2026-08-22 18:42:58.882480 | instance | Starting collection install process
2026-08-22 18:42:58.882498 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-22 18:42:58.882515 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-22 18:42:58.882533 | instance | community.crypto:2.2.3 was installed successfully
2026-08-22 18:42:58.882551 | instance | Checked out community.general to 7.3.0
2026-08-22 18:42:58.882568 | 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-22 18:42:58.882586 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.882603 | instance | Process install dependency map
2026-08-22 18:42:58.882621 | instance | Starting collection install process
2026-08-22 18:42:58.882638 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-22 18:42:58.882656 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-22 18:42:58.882673 | instance | community.general:7.3.0 was installed successfully
2026-08-22 18:42:58.882695 | instance | Checked out community.mysql to 3.6.0
2026-08-22 18:42:58.882713 | 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-22 18:42:58.882730 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.882747 | instance | Process install dependency map
2026-08-22 18:42:58.882764 | instance | Starting collection install process
2026-08-22 18:42:58.882782 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-22 18:42:58.882799 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-22 18:42:58.882816 | instance | community.mysql:3.6.0 was installed successfully
2026-08-22 18:42:58.882833 | instance | Checked out kubernetes.core to 2.4.0
2026-08-22 18:42:58.882851 | 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-22 18:42:58.882869 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.882886 | instance | Process install dependency map
2026-08-22 18:42:58.882906 | instance | Starting collection install process
2026-08-22 18:42:58.882924 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-22 18:42:58.882943 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-22 18:42:58.882961 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-22 18:42:58.882978 | instance | Checked out openstack.cloud to 1.7.0
2026-08-22 18:42:58.882996 | 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-22 18:42:58.883014 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.883032 | instance | Process install dependency map
2026-08-22 18:42:58.883050 | instance | Starting collection install process
2026-08-22 18:42:58.883068 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-22 18:42:58.883085 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-22 18:42:58.883103 | instance | openstack.cloud:1.7.0 was installed successfully
2026-08-22 18:42:58.883121 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-22 18:42:58.883139 | 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-22 18:42:58.883157 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.883175 | instance | Process install dependency map
2026-08-22 18:42:58.883193 | instance | Starting collection install process
2026-08-22 18:42:58.883210 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-22 18:42:58.883228 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-22 18:42:58.883245 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-22 18:42:58.883263 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-22 18:42:58.883280 | 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-22 18:42:58.883297 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.883319 | instance | Process install dependency map
2026-08-22 18:42:58.883337 | instance | Starting collection install process
2026-08-22 18:42:58.883354 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-22 18:42:58.883372 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-22 18:42:58.883389 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-22 18:42:58.883407 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-22 18:42:58.883424 | 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-22 18:42:58.883441 | instance | Starting galaxy collection install process
2026-08-22 18:42:58.883458 | instance | Process install dependency map
2026-08-22 18:42:58.883475 | instance | Starting collection install process
2026-08-22 18:42:58.883492 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-22 18:42:58.883509 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-22 18:42:58.883527 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-22 18:42:58.889210 | 
2026-08-22 18:42:58.889265 | PLAY RECAP
2026-08-22 18:42:58.889320 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:42:58.889345 | 
2026-08-22 18:42:59.005049 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 18:42:59.007983 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 18:42:59.575135 | 
2026-08-22 18:42:59.575532 | PLAY [all]
2026-08-22 18:42:59.586080 | 
2026-08-22 18:42:59.586156 | TASK [Install "jq" for log collection]
2026-08-22 18:43:09.003577 | instance | changed
2026-08-22 18:43:09.005228 | 
2026-08-22 18:43:09.005281 | PLAY RECAP
2026-08-22 18:43:09.005328 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:43:09.005377 | 
2026-08-22 18:43:09.105035 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 18:43:09.107027 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 18:43:09.678056 | 
2026-08-22 18:43:09.678159 | PLAY [all]
2026-08-22 18:43:09.688629 | 
2026-08-22 18:43:09.688701 | LOOP [Check inventory directories]
2026-08-22 18:43:09.998354 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-22 18:43:10.161874 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-22 18:43:10.316714 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-22 18:43:10.334283 | 
2026-08-22 18:43:10.334479 | LOOP [Copy inventory file for Zuul]
2026-08-22 18:43:11.059973 | instance | changed:
2026-08-22 18:43:11.060193 | instance | {
2026-08-22 18:43:11.060232 | instance |   "ansible_loop_var": "item",
2026-08-22 18:43:11.060262 | instance |   "changed": false,
2026-08-22 18:43:11.060286 | instance |   "failed": false,
2026-08-22 18:43:11.060315 | instance |   "invocation": {
2026-08-22 18:43:11.060339 | instance |     "module_args": {
2026-08-22 18:43:11.060363 | instance |       "checksum_algorithm": "sha1",
2026-08-22 18:43:11.060386 | instance |       "follow": true,
2026-08-22 18:43:11.060409 | instance |       "get_attributes": true,
2026-08-22 18:43:11.060433 | instance |       "get_checksum": true,
2026-08-22 18:43:11.060456 | instance |       "get_mime": true,
2026-08-22 18:43:11.060479 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-22 18:43:11.060835 | instance |     }
2026-08-22 18:43:11.060882 | instance |   },
2026-08-22 18:43:11.060902 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-22 18:43:11.060921 | instance |   "stat": {
2026-08-22 18:43:11.060939 | instance |     "atime": 1787424171.2675683,
2026-08-22 18:43:11.060974 | instance |     "attr_flags": "e",
2026-08-22 18:43:11.061001 | instance |     "attributes": [
2026-08-22 18:43:11.061020 | instance |       "extents"
2026-08-22 18:43:11.061037 | instance |     ],
2026-08-22 18:43:11.061054 | instance |     "block_size": 4096,
2026-08-22 18:43:11.061071 | instance |     "blocks": 8,
2026-08-22 18:43:11.061089 | instance |     "charset": "unknown",
2026-08-22 18:43:11.061107 | instance |     "ctime": 1787424170.3915381,
2026-08-22 18:43:11.061125 | instance |     "dev": 64513,
2026-08-22 18:43:11.061142 | instance |     "device_type": 0,
2026-08-22 18:43:11.061160 | instance |     "executable": true,
2026-08-22 18:43:11.061176 | instance |     "exists": true,
2026-08-22 18:43:11.061193 | instance |     "gid": 1000,
2026-08-22 18:43:11.061209 | instance |     "gr_name": "zuul",
2026-08-22 18:43:11.061226 | instance |     "inode": 1048827,
2026-08-22 18:43:11.061243 | instance |     "isblk": false,
2026-08-22 18:43:11.061260 | instance |     "ischr": false,
2026-08-22 18:43:11.061277 | instance |     "isdir": true,
2026-08-22 18:43:11.061294 | instance |     "isfifo": false,
2026-08-22 18:43:11.061310 | instance |     "isgid": false,
2026-08-22 18:43:11.061327 | instance |     "islnk": false,
2026-08-22 18:43:11.061344 | instance |     "isreg": false,
2026-08-22 18:43:11.061360 | instance |     "issock": false,
2026-08-22 18:43:11.061377 | instance |     "isuid": false,
2026-08-22 18:43:11.061393 | instance |     "mimetype": "unknown",
2026-08-22 18:43:11.061410 | instance |     "mode": "0755",
2026-08-22 18:43:11.061427 | instance |     "mtime": 1787424170.3915381,
2026-08-22 18:43:11.061443 | instance |     "nlink": 21,
2026-08-22 18:43:11.061459 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-22 18:43:11.061476 | instance |     "pw_name": "zuul",
2026-08-22 18:43:11.061498 | instance |     "readable": true,
2026-08-22 18:43:11.061516 | instance |     "rgrp": true,
2026-08-22 18:43:11.061533 | instance |     "roth": true,
2026-08-22 18:43:11.061550 | instance |     "rusr": true,
2026-08-22 18:43:11.061567 | instance |     "size": 4096,
2026-08-22 18:43:11.061584 | instance |     "uid": 1000,
2026-08-22 18:43:11.061601 | instance |     "version": "649859980",
2026-08-22 18:43:11.061617 | instance |     "wgrp": false,
2026-08-22 18:43:11.061636 | instance |     "woth": false,
2026-08-22 18:43:11.061653 | instance |     "writeable": true,
2026-08-22 18:43:11.061670 | instance |     "wusr": true,
2026-08-22 18:43:11.061687 | instance |     "xgrp": true,
2026-08-22 18:43:11.061704 | instance |     "xoth": true,
2026-08-22 18:43:11.061721 | instance |     "xusr": true
2026-08-22 18:43:11.061737 | instance |   }
2026-08-22 18:43:11.061754 | instance | }
2026-08-22 18:43:11.061786 | instance | ok: All items complete
2026-08-22 18:43:11.061806 | 
2026-08-22 18:43:11.063132 | instance | skipping: Conditional result was False
2026-08-22 18:43:11.063710 | instance | skipping: Conditional result was False
2026-08-22 18:43:11.085747 | 
2026-08-22 18:43:11.085951 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 18:43:11.403307 | instance | changed: 1 replacements made
2026-08-22 18:43:11.405126 | instance | skipping: Conditional result was False
2026-08-22 18:43:11.406036 | instance | skipping: Conditional result was False
2026-08-22 18:43:11.419303 | 
2026-08-22 18:43:11.419452 | TASK [Run Molecule scenario]
2026-08-22 18:43:12.309328 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-22 18:43:12.717134 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 18:43:12.717238 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-22 18:43:24.329200 | instance | INFO     [csi > dependency] Executing
2026-08-22 18:43:24.329371 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-22 18:43:24.329581 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-22 18:43:24.329697 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 18:43:24.337912 | instance | INFO     [csi > cleanup] Executing
2026-08-22 18:43:24.338242 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 18:43:24.346104 | instance | INFO     [csi > destroy] Executing
2026-08-22 18:43:24.346140 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-22 18:43:24.346229 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-22 18:43:24.353617 | instance | INFO     [csi > syntax] Executing
2026-08-22 18:43:26.066153 | instance |
2026-08-22 18:43:26.066486 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 18:43:26.148798 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-22 18:43:26.160923 | instance | INFO     [csi > create] Executing
2026-08-22 18:43:26.162955 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 18:43:26.170984 | instance | INFO     [csi > prepare] Executing
2026-08-22 18:43:27.045469 | instance |
2026-08-22 18:43:27.045712 | instance | PLAY [Prepare] *****************************************************************
2026-08-22 18:43:27.046047 | instance |
2026-08-22 18:43:27.046538 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:43:27.046804 | instance | Saturday 22 August 2026  18:43:27 +0000 (0:00:00.027)       0:00:00.027 *******
2026-08-22 18:43:28.210789 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 18:43:28.211563 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 18:43:28.211611 | instance | interpreter could change the meaning of that path. See
2026-08-22 18:43:28.211620 | instance | https://docs.ansible.com/ansible-
2026-08-22 18:43:28.211626 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 18:43:28.218711 | instance | ok: [instance]
2026-08-22 18:43:28.218923 | instance |
2026-08-22 18:43:28.219140 | instance | TASK [Configure short hostname] ************************************************
2026-08-22 18:43:28.219351 | instance | Saturday 22 August 2026  18:43:28 +0000 (0:00:01.174)       0:00:01.202 *******
2026-08-22 18:43:28.917166 | instance | changed: [instance]
2026-08-22 18:43:28.917544 | instance |
2026-08-22 18:43:28.917949 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 18:43:28.918302 | instance | Saturday 22 August 2026  18:43:28 +0000 (0:00:00.697)       0:00:01.900 *******
2026-08-22 18:43:29.182521 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 18:43:29.182936 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 18:43:29.183282 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 18:43:29.190836 | instance | changed: [instance]
2026-08-22 18:43:29.191174 | instance |
2026-08-22 18:43:29.191514 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-22 18:43:29.191849 | instance | Saturday 22 August 2026  18:43:29 +0000 (0:00:00.273)       0:00:02.174 *******
2026-08-22 18:43:30.025698 | instance | ok: [instance]
2026-08-22 18:43:30.026185 | instance |
2026-08-22 18:43:30.026599 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-22 18:43:30.026939 | instance |
2026-08-22 18:43:30.027273 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:43:30.027635 | instance | Saturday 22 August 2026  18:43:30 +0000 (0:00:00.834)       0:00:03.008 *******
2026-08-22 18:43:30.758297 | instance | ok: [instance]
2026-08-22 18:43:30.758712 | instance |
2026-08-22 18:43:30.759119 | instance | TASK [Install depedencies] *****************************************************
2026-08-22 18:43:30.759599 | instance | Saturday 22 August 2026  18:43:30 +0000 (0:00:00.732)       0:00:03.741 *******
2026-08-22 18:43:51.335636 | instance | changed: [instance]
2026-08-22 18:43:51.335725 | instance |
2026-08-22 18:43:51.335739 | instance | TASK [Start up service] ********************************************************
2026-08-22 18:43:51.335754 | instance | Saturday 22 August 2026  18:43:51 +0000 (0:00:20.576)       0:00:24.317 *******
2026-08-22 18:43:51.906067 | instance | ok: [instance]
2026-08-22 18:43:51.906332 | instance |
2026-08-22 18:43:51.906496 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-22 18:43:51.906789 | instance | Saturday 22 August 2026  18:43:51 +0000 (0:00:00.571)       0:00:24.888 *******
2026-08-22 18:43:52.190646 | instance | ok: [instance]
2026-08-22 18:43:52.190757 | instance |
2026-08-22 18:43:52.190939 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 18:43:52.191125 | instance | Saturday 22 August 2026  18:43:52 +0000 (0:00:00.284)       0:00:25.173 *******
2026-08-22 18:43:52.854012 | instance | changed: [instance]
2026-08-22 18:43:52.854291 | instance |
2026-08-22 18:43:52.854574 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-22 18:43:52.855017 | instance | Saturday 22 August 2026  18:43:52 +0000 (0:00:00.663)       0:00:25.836 *******
2026-08-22 18:43:53.042745 | instance | ok: [instance]
2026-08-22 18:43:53.042989 | instance |
2026-08-22 18:43:53.043268 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 18:43:53.043542 | instance | Saturday 22 August 2026  18:43:53 +0000 (0:00:00.189)       0:00:26.025 *******
2026-08-22 18:43:53.065626 | instance | skipping: [instance]
2026-08-22 18:43:53.065929 | instance |
2026-08-22 18:43:53.066204 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-22 18:43:53.066479 | instance | Saturday 22 August 2026  18:43:53 +0000 (0:00:00.023)       0:00:26.049 *******
2026-08-22 18:43:53.600286 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:43:53.600565 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:43:53.600842 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:43:53.601096 | instance |
2026-08-22 18:43:53.601372 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-22 18:43:53.601641 | instance | Saturday 22 August 2026  18:43:53 +0000 (0:00:00.534)       0:00:26.583 *******
2026-08-22 18:43:54.197998 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:43:54.198080 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:43:54.198091 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:43:54.198101 | instance |
2026-08-22 18:43:54.198111 | instance | TASK [Start loop devices] ******************************************************
2026-08-22 18:43:54.198347 | instance | Saturday 22 August 2026  18:43:54 +0000 (0:00:00.596)       0:00:27.179 *******
2026-08-22 18:43:54.899609 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:43:54.900459 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:43:54.900510 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:43:54.900518 | instance |
2026-08-22 18:43:54.900524 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-22 18:43:54.900531 | instance | Saturday 22 August 2026  18:43:54 +0000 (0:00:00.703)       0:00:27.882 *******
2026-08-22 18:43:58.185516 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:43:58.185615 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:43:58.186015 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:43:58.186128 | instance |
2026-08-22 18:43:58.186141 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 18:43:58.186337 | instance | Saturday 22 August 2026  18:43:58 +0000 (0:00:03.285)       0:00:31.168 *******
2026-08-22 18:44:00.025583 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-22 18:44:00.025668 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-22 18:44:00.025723 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-22 18:44:00.025763 | instance |
2026-08-22 18:44:00.025786 | instance | PLAY RECAP *********************************************************************
2026-08-22 18:44:00.025801 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 18:44:00.025816 | instance |
2026-08-22 18:44:00.025829 | instance | Saturday 22 August 2026  18:44:00 +0000 (0:00:01.837)       0:00:33.006 *******
2026-08-22 18:44:00.025843 | instance | ===============================================================================
2026-08-22 18:44:00.025856 | instance | Install depedencies ---------------------------------------------------- 20.58s
2026-08-22 18:44:00.025869 | instance | Create a volume group for each loop device ------------------------------ 3.29s
2026-08-22 18:44:00.025913 | instance | Create a logical volume for each loop device ---------------------------- 1.84s
2026-08-22 18:44:00.025924 | instance | Gathering Facts --------------------------------------------------------- 1.17s
2026-08-22 18:44:00.025943 | instance | Purge "snapd" package --------------------------------------------------- 0.83s
2026-08-22 18:44:00.025952 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-08-22 18:44:00.028286 | instance | Start loop devices ------------------------------------------------------ 0.70s
2026-08-22 18:44:00.028359 | instance | Configure short hostname ------------------------------------------------ 0.70s
2026-08-22 18:44:00.028368 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.66s
2026-08-22 18:44:00.028376 | instance | Set permissions on loopback devices ------------------------------------- 0.60s
2026-08-22 18:44:00.028383 | instance | Start up service -------------------------------------------------------- 0.57s
2026-08-22 18:44:00.028392 | instance | Create devices for Ceph ------------------------------------------------- 0.53s
2026-08-22 18:44:00.028399 | instance | Generate lvm.conf ------------------------------------------------------- 0.28s
2026-08-22 18:44:00.028408 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s
2026-08-22 18:44:00.028416 | instance | Get list of all loopback devices ---------------------------------------- 0.19s
2026-08-22 18:44:00.028424 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-22 18:44:00.111323 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-22 18:44:00.122098 | instance | INFO     [csi > converge] Executing
2026-08-22 18:44:01.673633 | instance |
2026-08-22 18:44:01.674123 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-22 18:44:01.674452 | instance |
2026-08-22 18:44:01.674785 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:44:01.675117 | instance | Saturday 22 August 2026  18:44:01 +0000 (0:00:00.009)       0:00:00.009 *******
2026-08-22 18:44:02.661853 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 18:44:02.661969 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 18:44:02.661984 | instance | interpreter could change the meaning of that path. See
2026-08-22 18:44:02.661997 | instance | https://docs.ansible.com/ansible-
2026-08-22 18:44:02.662010 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 18:44:02.672758 | instance | ok: [instance]
2026-08-22 18:44:02.672795 | instance |
2026-08-22 18:44:02.672807 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-22 18:44:02.672818 | instance | Saturday 22 August 2026  18:44:02 +0000 (0:00:00.998)       0:00:01.008 *******
2026-08-22 18:44:02.722316 | instance | ok: [instance] => {
2026-08-22 18:44:02.722727 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-22 18:44:02.723054 | instance | }
2026-08-22 18:44:02.723366 | instance |
2026-08-22 18:44:02.723693 | instance | PLAY [all] *********************************************************************
2026-08-22 18:44:02.724002 | instance |
2026-08-22 18:44:02.724325 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:44:02.724682 | instance | Saturday 22 August 2026  18:44:02 +0000 (0:00:00.049)       0:00:01.058 *******
2026-08-22 18:44:03.675258 | instance | ok: [instance]
2026-08-22 18:44:03.675594 | instance |
2026-08-22 18:44:03.675870 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-22 18:44:03.676138 | instance | Saturday 22 August 2026  18:44:03 +0000 (0:00:00.952)       0:00:02.011 *******
2026-08-22 18:44:03.855533 | instance | ok: [instance]
2026-08-22 18:44:03.856101 | instance |
2026-08-22 18:44:03.856612 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 18:44:03.857081 | instance |
2026-08-22 18:44:03.857590 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:44:03.858264 | instance | Saturday 22 August 2026  18:44:03 +0000 (0:00:00.180)       0:00:02.191 *******
2026-08-22 18:44:04.786431 | instance | ok: [instance]
2026-08-22 18:44:04.786628 | instance |
2026-08-22 18:44:04.786790 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 18:44:04.786965 | instance | Saturday 22 August 2026  18:44:04 +0000 (0:00:00.927)       0:00:03.118 *******
2026-08-22 18:44:05.067885 | instance | ok: [instance]
2026-08-22 18:44:05.068298 | instance |
2026-08-22 18:44:05.068669 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 18:44:05.069024 | instance | Saturday 22 August 2026  18:44:05 +0000 (0:00:00.285)       0:00:03.403 *******
2026-08-22 18:44:05.116096 | instance | skipping: [instance]
2026-08-22 18:44:05.116505 | instance |
2026-08-22 18:44:05.116880 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 18:44:05.117246 | instance | Saturday 22 August 2026  18:44:05 +0000 (0:00:00.048)       0:00:03.452 *******
2026-08-22 18:44:05.482476 | instance | changed: [instance]
2026-08-22 18:44:05.483449 | instance |
2026-08-22 18:44:05.483817 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 18:44:05.484169 | instance | Saturday 22 August 2026  18:44:05 +0000 (0:00:00.365)       0:00:03.818 *******
2026-08-22 18:44:05.553905 | instance | ok: [instance] => {
2026-08-22 18:44:05.554295 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 18:44:05.554631 | instance | }
2026-08-22 18:44:05.555016 | instance |
2026-08-22 18:44:05.555360 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 18:44:05.555698 | instance | Saturday 22 August 2026  18:44:05 +0000 (0:00:00.071)       0:00:03.890 *******
2026-08-22 18:44:06.313113 | instance | changed: [instance]
2026-08-22 18:44:06.313268 | instance |
2026-08-22 18:44:06.313277 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 18:44:06.313283 | instance | Saturday 22 August 2026  18:44:06 +0000 (0:00:00.756)       0:00:04.647 *******
2026-08-22 18:44:06.364099 | instance | skipping: [instance]
2026-08-22 18:44:06.364504 | instance |
2026-08-22 18:44:06.364553 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 18:44:06.364559 | instance | Saturday 22 August 2026  18:44:06 +0000 (0:00:00.053)       0:00:04.700 *******
2026-08-22 18:44:06.416501 | instance | skipping: [instance]
2026-08-22 18:44:06.416568 | instance |
2026-08-22 18:44:06.416817 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 18:44:06.416832 | instance | Saturday 22 August 2026  18:44:06 +0000 (0:00:00.052)       0:00:04.752 *******
2026-08-22 18:44:06.640647 | instance | ok: [instance]
2026-08-22 18:44:06.640687 | instance |
2026-08-22 18:44:06.640692 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 18:44:06.640697 | instance | Saturday 22 August 2026  18:44:06 +0000 (0:00:00.223)       0:00:04.976 *******
2026-08-22 18:44:08.050463 | instance | ok: [instance]
2026-08-22 18:44:08.050734 | instance |
2026-08-22 18:44:08.050748 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 18:44:08.050814 | instance | Saturday 22 August 2026  18:44:08 +0000 (0:00:01.410)       0:00:06.386 *******
2026-08-22 18:44:08.125292 | instance | ok: [instance] => {
2026-08-22 18:44:08.125349 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 18:44:08.125370 | instance | }
2026-08-22 18:44:08.125375 | instance |
2026-08-22 18:44:08.125380 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 18:44:08.125384 | instance | Saturday 22 August 2026  18:44:08 +0000 (0:00:00.073)       0:00:06.460 *******
2026-08-22 18:44:08.886493 | instance | changed: [instance]
2026-08-22 18:44:08.886551 | instance |
2026-08-22 18:44:08.886557 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 18:44:08.886562 | instance | Saturday 22 August 2026  18:44:08 +0000 (0:00:00.761)       0:00:07.221 *******
2026-08-22 18:44:11.763678 | instance | changed: [instance]
2026-08-22 18:44:11.763795 | instance |
2026-08-22 18:44:11.764084 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 18:44:11.764146 | instance | Saturday 22 August 2026  18:44:11 +0000 (0:00:02.878)       0:00:10.099 *******
2026-08-22 18:44:11.805186 | instance | skipping: [instance]
2026-08-22 18:44:11.805280 | instance |
2026-08-22 18:44:11.805589 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 18:44:11.805632 | instance | Saturday 22 August 2026  18:44:11 +0000 (0:00:00.041)       0:00:10.141 *******
2026-08-22 18:44:11.840082 | instance | skipping: [instance]
2026-08-22 18:44:11.840195 | instance |
2026-08-22 18:44:11.840816 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 18:44:11.840886 | instance | Saturday 22 August 2026  18:44:11 +0000 (0:00:00.034)       0:00:10.176 *******
2026-08-22 18:44:11.873595 | instance | skipping: [instance]
2026-08-22 18:44:11.874187 | instance |
2026-08-22 18:44:11.874298 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 18:44:11.874311 | instance | Saturday 22 August 2026  18:44:11 +0000 (0:00:00.033)       0:00:10.209 *******
2026-08-22 18:44:17.903948 | instance | changed: [instance]
2026-08-22 18:44:17.904049 | instance |
2026-08-22 18:44:17.904360 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 18:44:17.904405 | instance | Saturday 22 August 2026  18:44:17 +0000 (0:00:06.030)       0:00:16.240 *******
2026-08-22 18:44:18.513800 | instance | changed: [instance]
2026-08-22 18:44:18.513922 | instance |
2026-08-22 18:44:18.514036 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 18:44:18.514189 | instance | Saturday 22 August 2026  18:44:18 +0000 (0:00:00.609)       0:00:16.849 *******
2026-08-22 18:44:19.509123 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-22 18:44:19.509379 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 18:44:19.509652 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 18:44:19.509981 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 18:44:19.510286 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 18:44:19.510539 | instance |
2026-08-22 18:44:19.510840 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 18:44:19.511104 | instance | Saturday 22 August 2026  18:44:19 +0000 (0:00:00.995)       0:00:17.845 *******
2026-08-22 18:44:20.112589 | instance | changed: [instance]
2026-08-22 18:44:20.112796 | instance |
2026-08-22 18:44:20.113088 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 18:44:20.113368 | instance | Saturday 22 August 2026  18:44:20 +0000 (0:00:00.581)       0:00:18.426 *******
2026-08-22 18:44:20.113641 | instance |
2026-08-22 18:44:20.113956 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 18:44:20.114231 | instance | Saturday 22 August 2026  18:44:20 +0000 (0:00:00.021)       0:00:18.448 *******
2026-08-22 18:44:21.100745 | instance | ok: [instance]
2026-08-22 18:44:21.100797 | instance |
2026-08-22 18:44:21.100808 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 18:44:21.100818 | instance | Saturday 22 August 2026  18:44:21 +0000 (0:00:00.987)       0:00:19.435 *******
2026-08-22 18:44:21.549416 | instance | changed: [instance]
2026-08-22 18:44:21.549523 | instance |
2026-08-22 18:44:21.549786 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 18:44:21.549834 | instance | Saturday 22 August 2026  18:44:21 +0000 (0:00:00.449)       0:00:19.885 *******
2026-08-22 18:44:22.140584 | instance | changed: [instance]
2026-08-22 18:44:22.140665 | instance |
2026-08-22 18:44:22.140942 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 18:44:22.140989 | instance | Saturday 22 August 2026  18:44:22 +0000 (0:00:00.591)       0:00:20.476 *******
2026-08-22 18:44:22.362243 | instance | ok: [instance]
2026-08-22 18:44:22.362322 | instance |
2026-08-22 18:44:22.362604 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 18:44:22.362650 | instance | Saturday 22 August 2026  18:44:22 +0000 (0:00:00.221)       0:00:20.698 *******
2026-08-22 18:44:22.423781 | instance | ok: [instance] => {
2026-08-22 18:44:22.423887 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 18:44:22.424398 | instance | }
2026-08-22 18:44:22.424444 | instance |
2026-08-22 18:44:22.424449 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 18:44:22.424454 | instance | Saturday 22 August 2026  18:44:22 +0000 (0:00:00.061)       0:00:20.760 *******
2026-08-22 18:44:23.167719 | instance | changed: [instance]
2026-08-22 18:44:23.167788 | instance |
2026-08-22 18:44:23.167801 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 18:44:23.167814 | instance | Saturday 22 August 2026  18:44:23 +0000 (0:00:00.742)       0:00:21.503 *******
2026-08-22 18:44:27.629667 | instance | changed: [instance]
2026-08-22 18:44:27.629773 | instance |
2026-08-22 18:44:27.629794 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 18:44:27.629985 | instance | Saturday 22 August 2026  18:44:27 +0000 (0:00:04.462)       0:00:25.965 *******
2026-08-22 18:44:28.748486 | instance | ok: [instance]
2026-08-22 18:44:28.748582 | instance |
2026-08-22 18:44:28.748998 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 18:44:28.749055 | instance | Saturday 22 August 2026  18:44:28 +0000 (0:00:01.119)       0:00:27.084 *******
2026-08-22 18:44:29.107980 | instance | changed: [instance]
2026-08-22 18:44:29.108185 | instance |
2026-08-22 18:44:29.108196 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 18:44:29.108352 | instance | Saturday 22 August 2026  18:44:29 +0000 (0:00:00.359)       0:00:27.444 *******
2026-08-22 18:44:29.607815 | instance | changed: [instance]
2026-08-22 18:44:29.608264 | instance |
2026-08-22 18:44:29.608312 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 18:44:29.608318 | instance | Saturday 22 August 2026  18:44:29 +0000 (0:00:00.499)       0:00:27.944 *******
2026-08-22 18:44:30.187103 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-22 18:44:30.187176 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 18:44:30.187744 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 18:44:30.187813 | instance |
2026-08-22 18:44:30.187820 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 18:44:30.187825 | instance | Saturday 22 August 2026  18:44:30 +0000 (0:00:00.579)       0:00:28.523 *******
2026-08-22 18:44:30.685456 | instance | changed: [instance]
2026-08-22 18:44:30.685555 | instance |
2026-08-22 18:44:30.685898 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 18:44:30.685961 | instance | Saturday 22 August 2026  18:44:30 +0000 (0:00:00.498)       0:00:29.021 *******
2026-08-22 18:44:31.184592 | instance | changed: [instance]
2026-08-22 18:44:31.184685 | instance |
2026-08-22 18:44:31.185322 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 18:44:31.185375 | instance | Saturday 22 August 2026  18:44:31 +0000 (0:00:00.482)       0:00:29.504 *******
2026-08-22 18:44:31.185382 | instance |
2026-08-22 18:44:31.185387 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 18:44:31.185391 | instance | Saturday 22 August 2026  18:44:31 +0000 (0:00:00.016)       0:00:29.520 *******
2026-08-22 18:44:31.949024 | instance | ok: [instance]
2026-08-22 18:44:31.949134 | instance |
2026-08-22 18:44:31.949562 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 18:44:31.949631 | instance | Saturday 22 August 2026  18:44:31 +0000 (0:00:00.764)       0:00:30.285 *******
2026-08-22 18:44:32.955633 | instance | changed: [instance]
2026-08-22 18:44:32.955743 | instance |
2026-08-22 18:44:32.955991 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 18:44:32.956043 | instance | Saturday 22 August 2026  18:44:32 +0000 (0:00:01.006)       0:00:31.292 *******
2026-08-22 18:44:33.520937 | instance | changed: [instance]
2026-08-22 18:44:33.521024 | instance |
2026-08-22 18:44:33.521312 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 18:44:33.521359 | instance | Saturday 22 August 2026  18:44:33 +0000 (0:00:00.565)       0:00:31.857 *******
2026-08-22 18:44:33.584862 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-22 18:44:33.585714 | instance |
2026-08-22 18:44:33.585818 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 18:44:33.585838 | instance | Saturday 22 August 2026  18:44:33 +0000 (0:00:00.062)       0:00:31.920 *******
2026-08-22 18:44:39.096687 | instance | changed: [instance]
2026-08-22 18:44:39.096927 | instance |
2026-08-22 18:44:39.096940 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 18:44:39.096996 | instance | Saturday 22 August 2026  18:44:39 +0000 (0:00:05.512)       0:00:37.433 *******
2026-08-22 18:44:39.795880 | instance | ok: [instance] => (item=chronyd)
2026-08-22 18:44:39.795953 | instance | ok: [instance] => (item=sshd)
2026-08-22 18:44:39.796355 | instance |
2026-08-22 18:44:39.796419 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 18:44:39.796426 | instance | Saturday 22 August 2026  18:44:39 +0000 (0:00:00.698)       0:00:38.131 *******
2026-08-22 18:44:40.757409 | instance | changed: [instance]
2026-08-22 18:44:40.757854 | instance |
2026-08-22 18:44:40.758028 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 18:44:40.758035 | instance | Saturday 22 August 2026  18:44:40 +0000 (0:00:00.961)       0:00:39.093 *******
2026-08-22 18:44:40.964589 | instance | ok: [instance]
2026-08-22 18:44:40.964694 | instance |
2026-08-22 18:44:40.965082 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 18:44:40.965136 | instance | Saturday 22 August 2026  18:44:40 +0000 (0:00:00.207)       0:00:39.300 *******
2026-08-22 18:44:41.439670 | instance | changed: [instance]
2026-08-22 18:44:41.439769 | instance |
2026-08-22 18:44:41.440002 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 18:44:41.440069 | instance | Saturday 22 August 2026  18:44:41 +0000 (0:00:00.475)       0:00:39.776 *******
2026-08-22 18:44:41.785557 | instance | changed: [instance]
2026-08-22 18:44:41.785636 | instance |
2026-08-22 18:44:41.785919 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 18:44:41.785939 | instance | Saturday 22 August 2026  18:44:41 +0000 (0:00:00.346)       0:00:40.122 *******
2026-08-22 18:44:41.899113 | instance | ok: [instance]
2026-08-22 18:44:41.899185 | instance |
2026-08-22 18:44:41.899449 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 18:44:41.899465 | instance | Saturday 22 August 2026  18:44:41 +0000 (0:00:00.113)       0:00:40.235 *******
2026-08-22 18:44:42.138965 | instance | ok: [instance] => (item=instance)
2026-08-22 18:44:42.139037 | instance |
2026-08-22 18:44:42.139345 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 18:44:42.139403 | instance | Saturday 22 August 2026  18:44:42 +0000 (0:00:00.239)       0:00:40.475 *******
2026-08-22 18:44:42.186815 | instance | ok: [instance]
2026-08-22 18:44:42.186907 | instance |
2026-08-22 18:44:42.187165 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 18:44:42.187215 | instance | Saturday 22 August 2026  18:44:42 +0000 (0:00:00.047)       0:00:40.523 *******
2026-08-22 18:44:42.263743 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-22 18:44:42.264132 | instance |
2026-08-22 18:44:42.264187 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 18:44:42.264193 | instance | Saturday 22 August 2026  18:44:42 +0000 (0:00:00.076)       0:00:40.600 *******
2026-08-22 18:44:42.634003 | instance | changed: [instance]
2026-08-22 18:44:42.634428 | instance |
2026-08-22 18:44:42.634495 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 18:44:42.634502 | instance | Saturday 22 August 2026  18:44:42 +0000 (0:00:00.369)       0:00:40.969 *******
2026-08-22 18:44:43.397328 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-22 18:44:43.397387 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 18:44:43.397393 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 18:44:43.397399 | instance |
2026-08-22 18:44:43.397405 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 18:44:43.397409 | instance | Saturday 22 August 2026  18:44:43 +0000 (0:00:00.762)       0:00:41.732 *******
2026-08-22 18:46:13.603585 | instance | ok: [instance]
2026-08-22 18:46:13.603661 | instance |
2026-08-22 18:46:13.603669 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 18:46:13.603676 | instance | Saturday 22 August 2026  18:46:13 +0000 (0:01:30.204)       0:02:11.937 *******
2026-08-22 18:46:13.834359 | instance | changed: [instance]
2026-08-22 18:46:13.834463 | instance |
2026-08-22 18:46:13.834626 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 18:46:13.834822 | instance | Saturday 22 August 2026  18:46:13 +0000 (0:00:00.233)       0:02:12.170 *******
2026-08-22 18:46:13.942975 | instance | ok: [instance]
2026-08-22 18:46:13.943108 | instance |
2026-08-22 18:46:13.943296 | instance | TASK [Install Ceph host] *******************************************************
2026-08-22 18:46:13.943454 | instance | Saturday 22 August 2026  18:46:13 +0000 (0:00:00.108)       0:02:12.279 *******
2026-08-22 18:46:14.023101 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-22 18:46:14.023189 | instance |
2026-08-22 18:46:14.023322 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 18:46:14.023446 | instance | Saturday 22 August 2026  18:46:14 +0000 (0:00:00.080)       0:02:12.359 *******
2026-08-22 18:46:15.665158 | instance | ok: [instance]
2026-08-22 18:46:15.665253 | instance |
2026-08-22 18:46:15.665530 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 18:46:15.665594 | instance | Saturday 22 August 2026  18:46:15 +0000 (0:00:01.642)       0:02:14.001 *******
2026-08-22 18:46:15.732295 | instance | ok: [instance] => (item=instance)
2026-08-22 18:46:15.732396 | instance |
2026-08-22 18:46:15.732412 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 18:46:15.732551 | instance | Saturday 22 August 2026  18:46:15 +0000 (0:00:00.066)       0:02:14.067 *******
2026-08-22 18:46:16.123383 | instance | ok: [instance]
2026-08-22 18:46:16.123470 | instance |
2026-08-22 18:46:16.123590 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 18:46:16.123752 | instance | Saturday 22 August 2026  18:46:16 +0000 (0:00:00.392)       0:02:14.459 *******
2026-08-22 18:46:18.518568 | instance | ok: [instance]
2026-08-22 18:46:18.518662 | instance |
2026-08-22 18:46:18.518935 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 18:46:18.519007 | instance | Saturday 22 August 2026  18:46:18 +0000 (0:00:02.394)       0:02:16.854 *******
2026-08-22 18:47:06.401287 | 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-22 18:47:06.402434 | 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_ho3luulr/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_ho3luulr/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_ho3luulr/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_ho3luulr/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-22T18:47:06.208+0000 7f5ad2ce4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBJ7olqTtMYGyAASnuSsv2cC56KWIrikaWSi2d7nv4DvfSO+/77DLzKUKk=: Malformed input [buffer:3]\n2026-08-22T18:47:06.208+0000 7f5ad2ce4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T18:47:06.212+0000 7f5ad2ce4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBJ7olqTtMYGyAASnuSsv2cC56KWIrikaWSi2d7nv4DvfSO+/77DLzKUKk=: Malformed input [buffer:3]\n2026-08-22T18:47:06.212+0000 7f5ad2ce4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T18:47:06.212+0000 7f5ad2ce4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBJ7olqTtMYGyAASnuSsv2cC56KWIrikaWSi2d7nv4DvfSO+/77DLzKUKk=: Malformed input [buffer:3]\n2026-08-22T18:47:06.212+0000 7f5ad2ce4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T18:47:06.212+0000 7f5ad2ce4640 -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-22 18:47:06.405640 | instance |
2026-08-22 18:47:06.405820 | instance | PLAY RECAP *********************************************************************
2026-08-22 18:47:06.410056 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-22 18:47:06.410141 | instance |
2026-08-22 18:47:06.410154 | instance | Saturday 22 August 2026  18:47:06 +0000 (0:00:47.887)       0:03:04.742 *******
2026-08-22 18:47:06.410165 | instance | ===============================================================================
2026-08-22 18:47:06.410174 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 90.20s
2026-08-22 18:47:06.410183 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 47.89s
2026-08-22 18:47:06.410192 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.03s
2026-08-22 18:47:06.410201 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.51s
2026-08-22 18:47:06.410210 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.46s
2026-08-22 18:47:06.410219 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.88s
2026-08-22 18:47:06.410228 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.39s
2026-08-22 18:47:06.410237 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.75s
2026-08-22 18:47:06.410266 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.64s
2026-08-22 18:47:06.410275 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.41s
2026-08-22 18:47:06.410284 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.12s
2026-08-22 18:47:06.410293 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.01s
2026-08-22 18:47:06.410302 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-08-22 18:47:06.410311 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.00s
2026-08-22 18:47:06.410319 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 0.96s
2026-08-22 18:47:06.410328 | instance | Gathering Facts --------------------------------------------------------- 0.95s
2026-08-22 18:47:06.410347 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-08-22 18:47:06.410356 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.76s
2026-08-22 18:47:06.410365 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.76s
2026-08-22 18:47:06.410383 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.76s
2026-08-22 18:47:06.558849 | 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-22 18:47:06.559008 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-22 18:47:06.559156 | 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-22 18:47:06.808938 | instance | ERROR
2026-08-22 18:47:06.809181 | instance | {
2026-08-22 18:47:06.809211 | instance |   "delta": "0:03:54.895881",
2026-08-22 18:47:06.809233 | instance |   "end": "2026-08-22 18:47:06.624969",
2026-08-22 18:47:06.809251 | instance |   "msg": "non-zero return code",
2026-08-22 18:47:06.809270 | instance |   "rc": 2,
2026-08-22 18:47:06.809289 | instance |   "start": "2026-08-22 18:43:11.729088"
2026-08-22 18:47:06.809307 | instance | }
failure
2026-08-22 18:47:06.813823 | 
2026-08-22 18:47:06.813870 | PLAY RECAP
2026-08-22 18:47:06.813914 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:47:06.813937 | 
2026-08-22 18:47:06.953832 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 18:47:06.957944 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 18:47:07.619449 | 
2026-08-22 18:47:07.619568 | PLAY [all]
2026-08-22 18:47:07.635538 | 
2026-08-22 18:47:07.635634 | TASK [gather-host-logs : creating directory for system status]
2026-08-22 18:47:07.960070 | instance | changed
2026-08-22 18:47:07.967098 | 
2026-08-22 18:47:07.967202 | TASK [gather-host-logs : Get logs for each host]
2026-08-22 18:47:08.297162 | instance | + systemd-cgls --full --all --no-pager
2026-08-22 18:47:08.310024 | instance | + ip addr
2026-08-22 18:47:08.311737 | instance | + ip route
2026-08-22 18:47:08.314349 | instance | + lsblk
2026-08-22 18:47:08.319605 | instance | + mount
2026-08-22 18:47:08.322223 | instance | + docker images
2026-08-22 18:47:08.340998 | instance | + brctl show
2026-08-22 18:47:08.341561 | instance | /bin/bash: line 8: brctl: command not found
2026-08-22 18:47:08.342004 | instance | + ps aux --sort=-%mem
2026-08-22 18:47:08.357552 | instance | + dpkg -l
2026-08-22 18:47:08.369663 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-22 18:47:08.370521 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-22 18:47:08.388933 | instance | + '[' '!' -z '' ']'
2026-08-22 18:47:08.502349 | instance | ok: Runtime: 0:00:00.097610
2026-08-22 18:47:08.509696 | 
2026-08-22 18:47:08.509761 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-22 18:47:09.120060 | instance | changed:
2026-08-22 18:47:09.120229 | instance | created directory /var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/work/logs/instance
2026-08-22 18:47:09.120267 | instance | cd+++++++++ system/
2026-08-22 18:47:09.120297 | instance | >f+++++++++ system/brctl-show.txt
2026-08-22 18:47:09.120326 | instance | >f+++++++++ system/docker-images.txt
2026-08-22 18:47:09.120353 | instance | >f+++++++++ system/ip-addr.txt
2026-08-22 18:47:09.120383 | instance | >f+++++++++ system/ip-route.txt
2026-08-22 18:47:09.120410 | instance | >f+++++++++ system/lsblk.txt
2026-08-22 18:47:09.120437 | instance | >f+++++++++ system/mount.txt
2026-08-22 18:47:09.120463 | instance | >f+++++++++ system/packages.txt
2026-08-22 18:47:09.120489 | instance | >f+++++++++ system/ps.txt
2026-08-22 18:47:09.120517 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-22 18:47:09.130765 | 
2026-08-22 18:47:09.130831 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 18:47:09.327197 | instance | changed: "values"
2026-08-22 18:47:09.505879 | instance | changed: "releases"
2026-08-22 18:47:09.516366 | 
2026-08-22 18:47:09.516485 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 18:47:09.718189 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-22 18:47:10.052432 | instance | ok: Runtime: 0:00:00.006818
2026-08-22 18:47:10.060282 | 
2026-08-22 18:47:10.060410 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 18:47:10.528337 | instance | changed:
2026-08-22 18:47:10.528518 | instance | cd+++++++++ helm/
2026-08-22 18:47:10.528557 | instance | cd+++++++++ helm/releases/
2026-08-22 18:47:10.528588 | instance | cd+++++++++ helm/values/
2026-08-22 18:47:10.539850 | 
2026-08-22 18:47:10.539931 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 18:47:10.767741 | instance | changed
2026-08-22 18:47:10.774294 | 
2026-08-22 18:47:10.774385 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 18:47:11.021183 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:47:11.021302 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:47:11.027784 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:47:11.028338 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:47:11.030244 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:47:11.031619 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:47:11.032927 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:47:11.033655 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:47:11.034329 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:47:11.035371 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:47:11.036135 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:47:11.037696 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:47:11.322198 | instance | ok: Runtime: 0:00:00.026311
2026-08-22 18:47:11.327214 | 
2026-08-22 18:47:11.327281 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 18:47:11.545751 | instance | changed
2026-08-22 18:47:11.550910 | 
2026-08-22 18:47:11.551003 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 18:47:11.760086 | instance | environment: line 5: kubectl: command not found
2026-08-22 18:47:11.761092 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:47:11.761127 | instance |
2026-08-22 18:47:11.761648 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:47:12.091656 | instance | ok: Runtime: 0:00:00.009023
2026-08-22 18:47:12.098149 | 
2026-08-22 18:47:12.098212 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 18:47:12.587846 | instance | changed:
2026-08-22 18:47:12.588034 | instance | cd+++++++++ objects/
2026-08-22 18:47:12.588073 | instance | cd+++++++++ objects/cluster/
2026-08-22 18:47:12.588102 | instance | cd+++++++++ objects/namespaced/
2026-08-22 18:47:12.598829 | 
2026-08-22 18:47:12.598894 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 18:47:12.813804 | instance | changed
2026-08-22 18:47:12.820352 | 
2026-08-22 18:47:12.820422 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 18:47:13.017620 | instance | changed
2026-08-22 18:47:13.023948 | 
2026-08-22 18:47:13.024024 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 18:47:13.230586 | instance | environment: line 3: kubectl: command not found
2026-08-22 18:47:13.557292 | instance | ok: Runtime: 0:00:00.008907
2026-08-22 18:47:13.564066 | 
2026-08-22 18:47:13.564150 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 18:47:14.036755 | instance | changed:
2026-08-22 18:47:14.036907 | instance | cd+++++++++ pod-logs/
2026-08-22 18:47:14.036935 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-22 18:47:14.046906 | 
2026-08-22 18:47:14.047001 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-22 18:47:14.274057 | instance | changed
2026-08-22 18:47:14.280637 | 
2026-08-22 18:47:14.280769 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-22 18:47:14.491591 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 18:47:14.822547 | instance | ok: Runtime: 0:00:00.040315
2026-08-22 18:47:14.830280 | 
2026-08-22 18:47:14.830482 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-22 18:47:15.033548 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 18:47:15.062916 | instance | ceph-mgr endpoints:
2026-08-22 18:47:15.369622 | instance | ok: Runtime: 0:00:00.037311
2026-08-22 18:47:15.376673 | 
2026-08-22 18:47:15.376762 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-22 18:47:15.577678 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-22 18:47:15.913296 | instance | ok: Runtime: 0:00:00.033664
2026-08-22 18:47:15.918666 | 
2026-08-22 18:47:15.918728 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-22 18:47:16.386125 | instance | changed: cd+++++++++ prometheus/
2026-08-22 18:47:16.395254 | 
2026-08-22 18:47:16.395317 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-22 18:47:16.591189 | instance | changed
2026-08-22 18:47:16.597498 | 
2026-08-22 18:47:16.597595 | TASK [gather-selenium-data : Get selenium data]
2026-08-22 18:47:16.831353 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-22 18:47:16.832826 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-22 18:47:17.133042 | instance | ERROR
2026-08-22 18:47:17.133413 | instance | {
2026-08-22 18:47:17.133456 | instance |   "delta": "0:00:00.006348",
2026-08-22 18:47:17.133484 | instance |   "end": "2026-08-22 18:47:16.833236",
2026-08-22 18:47:17.133514 | instance |   "msg": "non-zero return code",
2026-08-22 18:47:17.133540 | instance |   "rc": 1,
2026-08-22 18:47:17.133566 | instance |   "start": "2026-08-22 18:47:16.826888"
2026-08-22 18:47:17.133591 | instance | }
2026-08-22 18:47:17.133624 | instance | ERROR: Ignoring Errors
2026-08-22 18:47:17.139827 | 
2026-08-22 18:47:17.139891 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-22 18:47:17.621879 | instance | changed: cd+++++++++ selenium/
2026-08-22 18:47:17.629264 | 
2026-08-22 18:47:17.629314 | PLAY RECAP
2026-08-22 18:47:17.629358 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-22 18:47:17.629380 | 
2026-08-22 18:47:17.736917 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 18:47:17.741315 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 18:47:18.306180 | 
2026-08-22 18:47:18.306295 | PLAY [all]
2026-08-22 18:47:18.317793 | 
2026-08-22 18:47:18.317892 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 18:47:18.362195 | instance | skipping: Conditional result was False
2026-08-22 18:47:18.372326 | 
2026-08-22 18:47:18.372411 | TASK [fetch-output : Set log path for single node]
2026-08-22 18:47:18.415116 | instance | ok
2026-08-22 18:47:18.421994 | 
2026-08-22 18:47:18.422094 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 18:47:18.822789 | instance -> localhost | ok: "/var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/work/logs"
2026-08-22 18:47:19.024594 | instance -> localhost | changed: "/var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/work/artifacts"
2026-08-22 18:47:19.249811 | instance -> localhost | changed: "/var/lib/zuul/builds/9cf007c8be1e43cd8c41c0516fdd7e5f/work/docs"
2026-08-22 18:47:19.268240 | 
2026-08-22 18:47:19.268360 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 18:47:19.867597 | instance | changed: .d..t...... ./
2026-08-22 18:47:19.867817 | instance | changed: All items complete
2026-08-22 18:47:19.867856 | 
2026-08-22 18:47:20.321014 | instance | changed: .d..t...... ./
2026-08-22 18:47:20.756458 | instance | changed: .d..t...... ./
2026-08-22 18:47:20.779198 | 
2026-08-22 18:47:20.779314 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 18:47:21.174745 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008367
2026-08-22 18:47:21.392186 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007632
2026-08-22 18:47:21.405902 | 
2026-08-22 18:47:21.405995 | PLAY [all]
2026-08-22 18:47:21.411556 | 
2026-08-22 18:47:21.411616 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 18:47:21.821757 | instance | changed
2026-08-22 18:47:21.828401 | 
2026-08-22 18:47:21.828456 | PLAY RECAP
2026-08-22 18:47:21.828505 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 18:47:21.828527 | 
2026-08-22 18:47:22.003961 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 18:47:22.008255 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 18:47:22.565280 | 
2026-08-22 18:47:22.565392 | PLAY [localhost]
2026-08-22 18:47:22.575135 | 
2026-08-22 18:47:22.575208 | TASK [Generate Zuul manifest]
2026-08-22 18:47:22.591908 | localhost | ok
2026-08-22 18:47:22.620503 | 
2026-08-22 18:47:22.620652 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 18:47:22.997083 | localhost | changed
2026-08-22 18:47:23.008172 | 
2026-08-22 18:47:23.008245 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 18:47:23.039500 | localhost | ok
2026-08-22 18:47:23.049228 | 
2026-08-22 18:47:23.049293 | TASK [Upload logs]
2026-08-22 18:47:23.073328 | localhost | ok
2026-08-22 18:47:23.131145 | 
2026-08-22 18:47:23.131287 | TASK [Set zuul-log-path fact]
2026-08-22 18:47:23.153824 | localhost | ok
2026-08-22 18:47:23.168396 | 
2026-08-22 18:47:23.168491 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 18:47:23.201008 | localhost | ok
2026-08-22 18:47:23.210706 | 
2026-08-22 18:47:23.210808 | TASK [upload-logs : Create log directories]
2026-08-22 18:47:23.620704 | localhost | changed
2026-08-22 18:47:23.626743 | 
2026-08-22 18:47:23.626816 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 18:47:24.012219 | localhost -> localhost | ok: Runtime: 0:00:00.005156
2026-08-22 18:47:24.018864 | 
2026-08-22 18:47:24.018951 | TASK [upload-logs : Upload logs to log server]
2026-08-22 18:47:24.504720 | localhost | Output suppressed because no_log was given
2026-08-22 18:47:24.538632 | 
2026-08-22 18:47:24.538776 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 18:47:24.579376 | localhost | skipping: Conditional result was False
2026-08-22 18:47:24.586969 | localhost | skipping: Conditional result was False
2026-08-22 18:47:24.602200 | 
2026-08-22 18:47:24.602424 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 18:47:24.647339 | localhost | skipping: Conditional result was False
2026-08-22 18:47:24.647698 | 
2026-08-22 18:47:24.651635 | localhost | skipping: Conditional result was False
2026-08-22 18:47:24.668100 | 
2026-08-22 18:47:24.668249 | LOOP [upload-logs : Upload console log and json output]