2026-09-15 22:14:23.439120 | Job console starting
2026-09-15 22:14:23.460677 | Updating git repos
2026-09-15 22:14:25.807148 | Cloning repos into workspace
2026-09-15 22:14:41.930484 | Restoring repo states
2026-09-15 22:14:51.684732 | Merging changes
2026-09-15 22:14:52.958697 | Checking out repos
2026-09-15 22:14:54.749111 | Preparing playbooks
2026-09-15 22:14:59.104201 | Running Ansible setup
2026-09-15 22:15:03.612069 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 22:15:04.253288 | 
2026-09-15 22:15:04.253463 | PLAY [localhost]
2026-09-15 22:15:04.261367 | 
2026-09-15 22:15:04.261484 | TASK [Gathering Facts]
2026-09-15 22:15:05.252306 | localhost | ok
2026-09-15 22:15:05.265484 | 
2026-09-15 22:15:05.265660 | TASK [Setup log path fact]
2026-09-15 22:15:05.311445 | localhost | ok
2026-09-15 22:15:05.339216 | 
2026-09-15 22:15:05.339364 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 22:15:05.370159 | localhost | ok
2026-09-15 22:15:05.379696 | 
2026-09-15 22:15:05.379784 | TASK [emit-job-header : Print job information]
2026-09-15 22:15:05.426199 | # Job Information
2026-09-15 22:15:05.426460 | Ansible Version: 2.16.18
2026-09-15 22:15:05.426527 | Job: atmosphere-molecule-csi-rbd
2026-09-15 22:15:05.426572 | Pipeline: check
2026-09-15 22:15:05.426643 | Executor: 2d72f0692154
2026-09-15 22:15:05.426684 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4091
2026-09-15 22:15:05.426734 | Event ID: 231b43a0-b152-11f1-9b92-cddb73fcc3dc
2026-09-15 22:15:05.431748 | 
2026-09-15 22:15:05.431836 | LOOP [emit-job-header : Print node information]
2026-09-15 22:15:05.546804 | localhost | ok:
2026-09-15 22:15:05.546977 | localhost | # Node Information
2026-09-15 22:15:05.547004 | localhost | Inventory Hostname: instance
2026-09-15 22:15:05.547023 | localhost | Hostname: np0000192868
2026-09-15 22:15:05.547040 | localhost | Username: zuul
2026-09-15 22:15:05.547060 | localhost | Distro: Ubuntu 22.04
2026-09-15 22:15:05.547077 | localhost | Provider: yul1
2026-09-15 22:15:05.547094 | localhost | Region: ca-ymq-1
2026-09-15 22:15:05.547111 | localhost | Label: ubuntu-jammy
2026-09-15 22:15:05.547127 | localhost | Product Name: OpenStack Nova
2026-09-15 22:15:05.547143 | localhost | Interface IP: 162.253.55.77
2026-09-15 22:15:05.571771 | 
2026-09-15 22:15:05.572057 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 22:15:06.034931 | localhost -> localhost | changed
2026-09-15 22:15:06.042334 | 
2026-09-15 22:15:06.042471 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 22:15:07.011310 | localhost -> localhost | changed
2026-09-15 22:15:07.025888 | 
2026-09-15 22:15:07.026046 | PLAY [all]
2026-09-15 22:15:07.041779 | 
2026-09-15 22:15:07.041971 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 22:15:07.340181 | instance -> localhost | ok
2026-09-15 22:15:07.346023 | 
2026-09-15 22:15:07.346136 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 22:15:07.376105 | instance | ok
2026-09-15 22:15:07.393031 | instance | included: /var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 22:15:07.400853 | 
2026-09-15 22:15:07.400955 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 22:15:08.557028 | instance -> localhost | Generating public/private rsa key pair.
2026-09-15 22:15:08.557186 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/work/c60e72ba38cd458292f355ccdca8b759_id_rsa
2026-09-15 22:15:08.557213 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/work/c60e72ba38cd458292f355ccdca8b759_id_rsa.pub
2026-09-15 22:15:08.557233 | instance -> localhost | The key fingerprint is:
2026-09-15 22:15:08.557252 | instance -> localhost | SHA256:y9hmGzvi3qFHFFcRcBJ0f3fSedecM4q8IUK/SKSi4XM zuul-build-sshkey
2026-09-15 22:15:08.557290 | instance -> localhost | The key's randomart image is:
2026-09-15 22:15:08.557310 | instance -> localhost | +---[RSA 3072]----+
2026-09-15 22:15:08.557332 | instance -> localhost | |         .=+*o   |
2026-09-15 22:15:08.557351 | instance -> localhost | |        . .+ . o+|
2026-09-15 22:15:08.557369 | instance -> localhost | |       o o    o=X|
2026-09-15 22:15:08.557386 | instance -> localhost | |      + o . . .oB|
2026-09-15 22:15:08.557403 | instance -> localhost | | . . . +So + .   |
2026-09-15 22:15:08.557420 | instance -> localhost | |. o . .++.o o    |
2026-09-15 22:15:08.557437 | instance -> localhost | | + E  .oO. .     |
2026-09-15 22:15:08.557455 | instance -> localhost | |  o   .=o=       |
2026-09-15 22:15:08.557474 | instance -> localhost | |     o+o+.       |
2026-09-15 22:15:08.557492 | instance -> localhost | +----[SHA256]-----+
2026-09-15 22:15:08.557537 | instance -> localhost | ok: Runtime: 0:00:00.733723
2026-09-15 22:15:08.562798 | 
2026-09-15 22:15:08.562869 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 22:15:08.592728 | instance | ok
2026-09-15 22:15:08.603829 | instance | included: /var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 22:15:08.612763 | 
2026-09-15 22:15:08.612825 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 22:15:08.636801 | instance | skipping: Conditional result was False
2026-09-15 22:15:08.644097 | 
2026-09-15 22:15:08.644189 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 22:15:09.159400 | instance | changed
2026-09-15 22:15:09.166492 | 
2026-09-15 22:15:09.166559 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 22:15:09.364853 | instance | ok
2026-09-15 22:15:09.376697 | 
2026-09-15 22:15:09.376838 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 22:15:09.879404 | instance | changed
2026-09-15 22:15:09.897239 | 
2026-09-15 22:15:09.897456 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 22:15:10.377346 | instance | changed
2026-09-15 22:15:10.389210 | 
2026-09-15 22:15:10.389381 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 22:15:10.419987 | instance | skipping: Conditional result was False
2026-09-15 22:15:10.439494 | 
2026-09-15 22:15:10.439782 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 22:15:10.901128 | instance -> localhost | changed
2026-09-15 22:15:10.921282 | 
2026-09-15 22:15:10.921434 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 22:15:11.230915 | instance -> localhost | Identity added: /var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/work/c60e72ba38cd458292f355ccdca8b759_id_rsa (zuul-build-sshkey)
2026-09-15 22:15:11.231202 | instance -> localhost | ok: Runtime: 0:00:00.012174
2026-09-15 22:15:11.238994 | 
2026-09-15 22:15:11.239140 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 22:15:11.612912 | instance | ok
2026-09-15 22:15:11.641184 | 
2026-09-15 22:15:11.641531 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 22:15:11.680928 | instance | skipping: Conditional result was False
2026-09-15 22:15:11.700962 | 
2026-09-15 22:15:11.701095 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 22:15:12.051863 | instance | ok
2026-09-15 22:15:12.063617 | 
2026-09-15 22:15:12.063869 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 22:15:15.779337 | instance | Output suppressed because no_log was given
2026-09-15 22:15:15.797298 | 
2026-09-15 22:15:15.797440 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 22:15:16.054239 | instance | ok: "logs"
2026-09-15 22:15:16.054502 | instance | ok: All items complete
2026-09-15 22:15:16.054545 | 
2026-09-15 22:15:16.219759 | instance | ok: "artifacts"
2026-09-15 22:15:16.416766 | instance | ok: "docs"
2026-09-15 22:15:16.437350 | 
2026-09-15 22:15:16.437547 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 22:15:16.649902 | instance | changed: "logs"
2026-09-15 22:15:16.821222 | instance | changed: "artifacts"
2026-09-15 22:15:16.982748 | instance | changed: "docs"
2026-09-15 22:15:17.002096 | 
2026-09-15 22:15:17.002249 | PLAY RECAP
2026-09-15 22:15:17.002304 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 22:15:17.002331 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:15:17.002352 | 
2026-09-15 22:15:17.184430 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 22:15:17.188189 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 22:15:17.843066 | 
2026-09-15 22:15:17.843214 | PLAY [all]
2026-09-15 22:15:17.854106 | 
2026-09-15 22:15:17.854190 | TASK [setup-uv : Extract archive]
2026-09-15 22:15:20.148897 | instance | changed
2026-09-15 22:15:20.160343 | 
2026-09-15 22:15:20.160561 | TASK [setup-uv : Print version]
2026-09-15 22:15:20.570207 | instance | uv 0.8.13
2026-09-15 22:15:20.705431 | instance | ok: Runtime: 0:00:00.009474
2026-09-15 22:15:20.731745 | 
2026-09-15 22:15:20.731952 | TASK [Install Ansible collection siblings]
2026-09-15 22:15:30.873739 | instance | changed:
2026-09-15 22:15:30.873986 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 22:15:30.874045 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 22:15:30.874087 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 22:15:30.874126 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 22:15:30.874164 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 22:15:30.874207 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 22:15:30.874247 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 22:15:30.874289 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 22:15:30.874327 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 22:15:30.874364 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 22:15:30.874400 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 22:15:30.874436 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 22:15:30.874471 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-15 22:15:30.874507 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-15 22:15:30.874542 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-15 22:15:30.874600 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-15 22:15:30.874638 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-15 22:15:30.874674 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-15 22:15:30.874708 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-15 22:15:30.874742 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-15 22:15:30.874777 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-15 22:15:30.874811 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-15 22:15:30.874849 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 22:15:30.874903 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-15 22:15:30.874941 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-15 22:15:30.874975 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 22:15:30.875010 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 22:15:30.875044 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 22:15:30.875078 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-15 22:15:30.875112 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-15 22:15:30.875146 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 22:15:30.875181 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 22:15:30.875216 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-15 22:15:30.875251 | 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-09-15 22:15:30.875295 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.875329 | instance | Process install dependency map
2026-09-15 22:15:30.875363 | instance | Starting collection install process
2026-09-15 22:15:30.875398 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-15 22:15:30.875433 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-15 22:15:30.875468 | instance | ansible.netcommon:* was installed successfully
2026-09-15 22:15:30.875502 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-15 22:15:30.875536 | instance | Creating virtual environment at: .venv
2026-09-15 22:15:30.875590 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 22:15:30.875627 | instance | Downloading openstacksdk (1.7MiB)
2026-09-15 22:15:30.875662 | instance | Downloading pygments (1.2MiB)
2026-09-15 22:15:30.875701 | instance | Downloading netaddr (2.2MiB)
2026-09-15 22:15:30.875736 | instance | Downloading setuptools (1.1MiB)
2026-09-15 22:15:30.875771 | instance | Downloading kubernetes (1.9MiB)
2026-09-15 22:15:30.875805 | instance | Downloading cryptography (4.2MiB)
2026-09-15 22:15:30.875840 | instance | Downloading rjsonnet (1.2MiB)
2026-09-15 22:15:30.875875 | instance | Downloading ansible-core (2.1MiB)
2026-09-15 22:15:30.875910 | instance |  Downloading rjsonnet
2026-09-15 22:15:30.875945 | instance |    Building pyperclip==1.9.0
2026-09-15 22:15:30.875982 | instance |  Downloading netaddr
2026-09-15 22:15:30.876019 | instance |  Downloading pygments
2026-09-15 22:15:30.876056 | instance |  Downloading cryptography
2026-09-15 22:15:30.876091 | instance |  Downloading setuptools
2026-09-15 22:15:30.876125 | instance |  Downloading kubernetes
2026-09-15 22:15:30.876159 | instance |  Downloading ansible-core
2026-09-15 22:15:30.876193 | instance |  Downloading openstacksdk
2026-09-15 22:15:30.876227 | instance |       Built pyperclip==1.9.0
2026-09-15 22:15:30.876262 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 22:15:30.876297 | instance | Installed 79 packages in 111ms
2026-09-15 22:15:30.876332 | instance | Checked out ansible.posix to 1.6.0
2026-09-15 22:15:30.876367 | 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-09-15 22:15:30.876401 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.876435 | instance | Process install dependency map
2026-09-15 22:15:30.876469 | instance | Starting collection install process
2026-09-15 22:15:30.876536 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 22:15:30.876602 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-15 22:15:30.876640 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-15 22:15:30.876675 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.876709 | instance | 2.17.12
2026-09-15 22:15:30.876745 | instance | Checked out ansible.utils to v6.1.0
2026-09-15 22:15:30.876779 | 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-09-15 22:15:30.876814 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.876848 | instance | Process install dependency map
2026-09-15 22:15:30.876882 | instance | Starting collection install process
2026-09-15 22:15:30.876915 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-15 22:15:30.876949 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-15 22:15:30.876983 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-15 22:15:30.877018 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.877053 | instance | 2.17.12
2026-09-15 22:15:30.877089 | instance | Checked out atmosphere.common to v0.7.0
2026-09-15 22:15:30.877125 | 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-09-15 22:15:30.877159 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.877193 | instance | Process install dependency map
2026-09-15 22:15:30.877227 | instance | Starting collection install process
2026-09-15 22:15:30.877262 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-15 22:15:30.877297 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-15 22:15:30.877332 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-15 22:15:30.877366 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.877401 | instance | 2.17.12
2026-09-15 22:15:30.877435 | instance | Checked out community.crypto to 2.2.3
2026-09-15 22:15:30.877470 | 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-09-15 22:15:30.877505 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.877540 | instance | Process install dependency map
2026-09-15 22:15:30.877593 | instance | Starting collection install process
2026-09-15 22:15:30.877655 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 22:15:30.877690 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-15 22:15:30.877724 | instance | community.crypto:2.2.3 was installed successfully
2026-09-15 22:15:30.877759 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.877793 | instance | 2.17.12
2026-09-15 22:15:30.877827 | instance | Checked out community.general to 7.3.0
2026-09-15 22:15:30.877862 | 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-09-15 22:15:30.877898 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.877942 | instance | Process install dependency map
2026-09-15 22:15:30.877978 | instance | Starting collection install process
2026-09-15 22:15:30.878012 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 22:15:30.878047 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-15 22:15:30.878081 | instance | community.general:7.3.0 was installed successfully
2026-09-15 22:15:30.878115 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.878150 | instance | 2.17.12
2026-09-15 22:15:30.878186 | instance | Checked out community.mysql to 3.6.0
2026-09-15 22:15:30.878222 | 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-09-15 22:15:30.878258 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.878297 | instance | Process install dependency map
2026-09-15 22:15:30.878333 | instance | Starting collection install process
2026-09-15 22:15:30.878368 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-15 22:15:30.878403 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-15 22:15:30.878438 | instance | community.mysql:3.6.0 was installed successfully
2026-09-15 22:15:30.878472 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.878507 | instance | 2.17.12
2026-09-15 22:15:30.878542 | instance | Checked out kubernetes.core to 2.4.0
2026-09-15 22:15:30.878596 | 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-09-15 22:15:30.878634 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.878669 | instance | Process install dependency map
2026-09-15 22:15:30.878703 | instance | Starting collection install process
2026-09-15 22:15:30.878737 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 22:15:30.878771 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-15 22:15:30.878805 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-15 22:15:30.878839 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.878872 | instance | 2.17.12
2026-09-15 22:15:30.878907 | instance | Checked out openstack.cloud to 2.6.0
2026-09-15 22:15:30.878941 | 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-09-15 22:15:30.878977 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.879010 | instance | Process install dependency map
2026-09-15 22:15:30.879044 | instance | Starting collection install process
2026-09-15 22:15:30.879077 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-15 22:15:30.879109 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-15 22:15:30.879143 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-15 22:15:30.879176 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.879439 | instance | 2.17.12
2026-09-15 22:15:30.879482 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-15 22:15:30.879519 | 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-09-15 22:15:30.879587 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.879626 | instance | Process install dependency map
2026-09-15 22:15:30.879661 | instance | Starting collection install process
2026-09-15 22:15:30.879695 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-15 22:15:30.879729 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-15 22:15:30.879763 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-15 22:15:30.879797 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.879831 | instance | 2.17.12
2026-09-15 22:15:30.879865 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-15 22:15:30.879900 | 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-09-15 22:15:30.879934 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.879968 | instance | Process install dependency map
2026-09-15 22:15:30.880003 | instance | Starting collection install process
2026-09-15 22:15:30.880037 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-15 22:15:30.880072 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-15 22:15:30.880107 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-15 22:15:30.880141 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.880175 | instance | 2.17.12
2026-09-15 22:15:30.880210 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-15 22:15:30.880246 | 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-09-15 22:15:30.880281 | instance | Starting galaxy collection install process
2026-09-15 22:15:30.880316 | instance | Process install dependency map
2026-09-15 22:15:30.880350 | instance | Starting collection install process
2026-09-15 22:15:30.880384 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-15 22:15:30.880418 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-15 22:15:30.880452 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-15 22:15:30.880487 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:30.880553 | instance | 2.17.12
2026-09-15 22:15:30.890827 | 
2026-09-15 22:15:30.890922 | PLAY RECAP
2026-09-15 22:15:30.891004 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:15:30.891045 | 
2026-09-15 22:15:31.105988 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 22:15:31.123466 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 22:15:31.788949 | 
2026-09-15 22:15:31.789096 | PLAY [all]
2026-09-15 22:15:31.799499 | 
2026-09-15 22:15:31.799586 | TASK [Install "jq" for log collection]
2026-09-15 22:15:41.354140 | instance | changed
2026-09-15 22:15:41.357194 | 
2026-09-15 22:15:41.357283 | PLAY RECAP
2026-09-15 22:15:41.357353 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:15:41.357418 | 
2026-09-15 22:15:41.514164 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 22:15:41.516141 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 22:15:42.106917 | 
2026-09-15 22:15:42.107045 | PLAY [all]
2026-09-15 22:15:42.117418 | 
2026-09-15 22:15:42.117496 | LOOP [Check inventory directories]
2026-09-15 22:15:42.471167 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-15 22:15:42.649475 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-15 22:15:42.804652 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-15 22:15:42.824136 | 
2026-09-15 22:15:42.824478 | LOOP [Copy inventory file for Zuul]
2026-09-15 22:15:43.672347 | instance | changed:
2026-09-15 22:15:43.672699 | instance | {
2026-09-15 22:15:43.672757 | instance |   "ansible_loop_var": "item",
2026-09-15 22:15:43.672802 | instance |   "changed": false,
2026-09-15 22:15:43.672840 | instance |   "failed": false,
2026-09-15 22:15:43.672881 | instance |   "invocation": {
2026-09-15 22:15:43.672917 | instance |     "module_args": {
2026-09-15 22:15:43.672953 | instance |       "checksum_algorithm": "sha1",
2026-09-15 22:15:43.672987 | instance |       "follow": true,
2026-09-15 22:15:43.673020 | instance |       "get_attributes": true,
2026-09-15 22:15:43.673056 | instance |       "get_checksum": true,
2026-09-15 22:15:43.673090 | instance |       "get_mime": true,
2026-09-15 22:15:43.673124 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-15 22:15:43.673157 | instance |     }
2026-09-15 22:15:43.673190 | instance |   },
2026-09-15 22:15:43.673223 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-15 22:15:43.673256 | instance |   "stat": {
2026-09-15 22:15:43.673289 | instance |     "atime": 1789510522.6345725,
2026-09-15 22:15:43.673323 | instance |     "attr_flags": "e",
2026-09-15 22:15:43.673356 | instance |     "attributes": [
2026-09-15 22:15:43.673389 | instance |       "extents"
2026-09-15 22:15:43.673420 | instance |     ],
2026-09-15 22:15:43.673452 | instance |     "block_size": 4096,
2026-09-15 22:15:43.673484 | instance |     "blocks": 8,
2026-09-15 22:15:43.673517 | instance |     "charset": "unknown",
2026-09-15 22:15:43.673572 | instance |     "ctime": 1789510521.6065702,
2026-09-15 22:15:43.673609 | instance |     "dev": 64513,
2026-09-15 22:15:43.673642 | instance |     "device_type": 0,
2026-09-15 22:15:43.673674 | instance |     "executable": true,
2026-09-15 22:15:43.673706 | instance |     "exists": true,
2026-09-15 22:15:43.673738 | instance |     "gid": 1000,
2026-09-15 22:15:43.673770 | instance |     "gr_name": "zuul",
2026-09-15 22:15:43.673802 | instance |     "inode": 1049077,
2026-09-15 22:15:43.673835 | instance |     "isblk": false,
2026-09-15 22:15:43.673869 | instance |     "ischr": false,
2026-09-15 22:15:43.673902 | instance |     "isdir": true,
2026-09-15 22:15:43.673934 | instance |     "isfifo": false,
2026-09-15 22:15:43.673965 | instance |     "isgid": false,
2026-09-15 22:15:43.673998 | instance |     "islnk": false,
2026-09-15 22:15:43.674032 | instance |     "isreg": false,
2026-09-15 22:15:43.674064 | instance |     "issock": false,
2026-09-15 22:15:43.674096 | instance |     "isuid": false,
2026-09-15 22:15:43.674128 | instance |     "mimetype": "unknown",
2026-09-15 22:15:43.674160 | instance |     "mode": "0755",
2026-09-15 22:15:43.674192 | instance |     "mtime": 1789510521.6065702,
2026-09-15 22:15:43.674224 | instance |     "nlink": 21,
2026-09-15 22:15:43.674255 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-15 22:15:43.674287 | instance |     "pw_name": "zuul",
2026-09-15 22:15:43.674324 | instance |     "readable": true,
2026-09-15 22:15:43.674368 | instance |     "rgrp": true,
2026-09-15 22:15:43.674408 | instance |     "roth": true,
2026-09-15 22:15:43.674448 | instance |     "rusr": true,
2026-09-15 22:15:43.674486 | instance |     "size": 4096,
2026-09-15 22:15:43.674525 | instance |     "uid": 1000,
2026-09-15 22:15:43.674656 | instance |     "version": "3849269488",
2026-09-15 22:15:43.674712 | instance |     "wgrp": false,
2026-09-15 22:15:43.674752 | instance |     "woth": false,
2026-09-15 22:15:43.674786 | instance |     "writeable": true,
2026-09-15 22:15:43.674819 | instance |     "wusr": true,
2026-09-15 22:15:43.674852 | instance |     "xgrp": true,
2026-09-15 22:15:43.674884 | instance |     "xoth": true,
2026-09-15 22:15:43.674915 | instance |     "xusr": true
2026-09-15 22:15:43.674947 | instance |   }
2026-09-15 22:15:43.674980 | instance | }
2026-09-15 22:15:43.675046 | instance | ok: All items complete
2026-09-15 22:15:43.675088 | 
2026-09-15 22:15:43.677502 | instance | skipping: Conditional result was False
2026-09-15 22:15:43.679040 | instance | skipping: Conditional result was False
2026-09-15 22:15:43.696841 | 
2026-09-15 22:15:43.696992 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 22:15:44.087285 | instance | changed: 1 replacements made
2026-09-15 22:15:44.096813 | instance | skipping: Conditional result was False
2026-09-15 22:15:44.098529 | instance | skipping: Conditional result was False
2026-09-15 22:15:44.117999 | 
2026-09-15 22:15:44.118169 | TASK [Run Molecule scenario]
2026-09-15 22:15:45.124631 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-15 22:15:45.509455 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 22:15:45.509589 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-15 22:15:56.451942 | instance | INFO     [csi > dependency] Executing
2026-09-15 22:15:56.452123 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-15 22:15:56.452380 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-15 22:15:56.452549 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-15 22:15:56.461813 | instance | INFO     [csi > cleanup] Executing
2026-09-15 22:15:56.462204 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 22:15:56.473362 | instance | INFO     [csi > destroy] Executing
2026-09-15 22:15:56.473395 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-15 22:15:56.473490 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-15 22:15:56.480365 | instance | INFO     [csi > syntax] Executing
2026-09-15 22:15:58.007402 | instance |
2026-09-15 22:15:58.007752 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 22:15:58.094120 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-15 22:15:58.109917 | instance | INFO     [csi > create] Executing
2026-09-15 22:15:58.113370 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 22:15:58.128328 | instance | INFO     [csi > prepare] Executing
2026-09-15 22:15:58.954342 | instance |
2026-09-15 22:15:58.954428 | instance | PLAY [Prepare] *****************************************************************
2026-09-15 22:15:58.954496 | instance |
2026-09-15 22:15:58.954632 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:15:58.954755 | instance | Tuesday 15 September 2026  22:15:58 +0000 (0:00:00.026)       0:00:00.026 *****
2026-09-15 22:16:00.269929 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 22:16:00.270201 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 22:16:00.270623 | instance | interpreter could change the meaning of that path. See
2026-09-15 22:16:00.271010 | instance | https://docs.ansible.com/ansible-
2026-09-15 22:16:00.271404 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 22:16:00.280528 | instance | ok: [instance]
2026-09-15 22:16:00.280599 | instance |
2026-09-15 22:16:00.280762 | instance | TASK [Configure short hostname] ************************************************
2026-09-15 22:16:00.280825 | instance | Tuesday 15 September 2026  22:16:00 +0000 (0:00:01.326)       0:00:01.352 *****
2026-09-15 22:16:01.061995 | instance | changed: [instance]
2026-09-15 22:16:01.062169 | instance |
2026-09-15 22:16:01.062456 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-15 22:16:01.062734 | instance | Tuesday 15 September 2026  22:16:01 +0000 (0:00:00.780)       0:00:02.133 *****
2026-09-15 22:16:01.350007 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-15 22:16:01.350108 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-15 22:16:01.350140 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-15 22:16:01.354823 | instance | changed: [instance]
2026-09-15 22:16:01.354905 | instance |
2026-09-15 22:16:01.355025 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-15 22:16:01.355143 | instance | Tuesday 15 September 2026  22:16:01 +0000 (0:00:00.294)       0:00:02.427 *****
2026-09-15 22:16:02.276048 | instance | ok: [instance]
2026-09-15 22:16:02.276250 | instance |
2026-09-15 22:16:02.276611 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-15 22:16:02.276752 | instance |
2026-09-15 22:16:02.276997 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:02.277261 | instance | Tuesday 15 September 2026  22:16:02 +0000 (0:00:00.917)       0:00:03.345 *****
2026-09-15 22:16:03.047414 | instance | ok: [instance]
2026-09-15 22:16:03.047581 | instance |
2026-09-15 22:16:03.047823 | instance | TASK [Install depedencies] *****************************************************
2026-09-15 22:16:03.048059 | instance | Tuesday 15 September 2026  22:16:03 +0000 (0:00:00.773)       0:00:04.118 *****
2026-09-15 22:16:27.244305 | instance | changed: [instance]
2026-09-15 22:16:27.244574 | instance |
2026-09-15 22:16:27.244889 | instance | TASK [Start up service] ********************************************************
2026-09-15 22:16:27.245128 | instance | Tuesday 15 September 2026  22:16:27 +0000 (0:00:24.196)       0:00:28.315 *****
2026-09-15 22:16:27.838538 | instance | ok: [instance]
2026-09-15 22:16:27.838802 | instance |
2026-09-15 22:16:27.839096 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-15 22:16:27.839471 | instance | Tuesday 15 September 2026  22:16:27 +0000 (0:00:00.594)       0:00:28.910 *****
2026-09-15 22:16:28.177281 | instance | ok: [instance]
2026-09-15 22:16:28.177562 | instance |
2026-09-15 22:16:28.177843 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-15 22:16:28.178135 | instance | Tuesday 15 September 2026  22:16:28 +0000 (0:00:00.339)       0:00:29.249 *****
2026-09-15 22:16:28.883931 | instance | changed: [instance]
2026-09-15 22:16:28.883998 | instance |
2026-09-15 22:16:28.884012 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-15 22:16:28.884022 | instance | Tuesday 15 September 2026  22:16:28 +0000 (0:00:00.705)       0:00:29.955 *****
2026-09-15 22:16:29.079632 | instance | ok: [instance]
2026-09-15 22:16:29.080503 | instance |
2026-09-15 22:16:29.080571 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-15 22:16:29.080583 | instance | Tuesday 15 September 2026  22:16:29 +0000 (0:00:00.196)       0:00:30.151 *****
2026-09-15 22:16:29.110080 | instance | skipping: [instance]
2026-09-15 22:16:29.110990 | instance |
2026-09-15 22:16:29.111076 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-15 22:16:29.111094 | instance | Tuesday 15 September 2026  22:16:29 +0000 (0:00:00.030)       0:00:30.181 *****
2026-09-15 22:16:29.750701 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:29.750768 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:29.750849 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:29.750991 | instance |
2026-09-15 22:16:29.751129 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-15 22:16:29.751281 | instance | Tuesday 15 September 2026  22:16:29 +0000 (0:00:00.641)       0:00:30.823 *****
2026-09-15 22:16:30.458748 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:30.458849 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:30.459475 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:30.459537 | instance |
2026-09-15 22:16:30.459547 | instance | TASK [Start loop devices] ******************************************************
2026-09-15 22:16:30.459557 | instance | Tuesday 15 September 2026  22:16:30 +0000 (0:00:00.707)       0:00:31.530 *****
2026-09-15 22:16:31.279321 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:31.279375 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:31.279381 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:31.280252 | instance |
2026-09-15 22:16:31.280341 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-15 22:16:31.280358 | instance | Tuesday 15 September 2026  22:16:31 +0000 (0:00:00.819)       0:00:32.350 *****
2026-09-15 22:16:34.683895 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:34.685273 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:34.685317 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:34.685332 | instance |
2026-09-15 22:16:34.685345 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-15 22:16:34.685358 | instance | Tuesday 15 September 2026  22:16:34 +0000 (0:00:03.404)       0:00:35.755 *****
2026-09-15 22:16:36.741373 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-15 22:16:36.741477 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-15 22:16:36.741517 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-15 22:16:36.741533 | instance |
2026-09-15 22:16:36.741549 | instance | PLAY RECAP *********************************************************************
2026-09-15 22:16:36.741564 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-15 22:16:36.741578 | instance |
2026-09-15 22:16:36.741591 | instance | Tuesday 15 September 2026  22:16:36 +0000 (0:00:02.055)       0:00:37.811 *****
2026-09-15 22:16:36.741605 | instance | ===============================================================================
2026-09-15 22:16:36.741619 | instance | Install depedencies ---------------------------------------------------- 24.20s
2026-09-15 22:16:36.741633 | instance | Create a volume group for each loop device ------------------------------ 3.40s
2026-09-15 22:16:36.741646 | instance | Create a logical volume for each loop device ---------------------------- 2.06s
2026-09-15 22:16:36.741671 | instance | Gathering Facts --------------------------------------------------------- 1.33s
2026-09-15 22:16:36.741682 | instance | Purge "snapd" package --------------------------------------------------- 0.92s
2026-09-15 22:16:36.744299 | instance | Start loop devices ------------------------------------------------------ 0.82s
2026-09-15 22:16:36.744376 | instance | Configure short hostname ------------------------------------------------ 0.78s
2026-09-15 22:16:36.744387 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-15 22:16:36.744397 | instance | Set permissions on loopback devices ------------------------------------- 0.71s
2026-09-15 22:16:36.744406 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.71s
2026-09-15 22:16:36.744414 | instance | Create devices for Ceph ------------------------------------------------- 0.64s
2026-09-15 22:16:36.744423 | instance | Start up service -------------------------------------------------------- 0.59s
2026-09-15 22:16:36.744432 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s
2026-09-15 22:16:36.744442 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-15 22:16:36.744451 | instance | Get list of all loopback devices ---------------------------------------- 0.20s
2026-09-15 22:16:36.744460 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-15 22:16:36.855048 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-15 22:16:36.872536 | instance | INFO     [csi > converge] Executing
2026-09-15 22:16:38.463181 | instance |
2026-09-15 22:16:38.463637 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-15 22:16:38.463945 | instance |
2026-09-15 22:16:38.464280 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:38.464592 | instance | Tuesday 15 September 2026  22:16:38 +0000 (0:00:00.009)       0:00:00.009 *****
2026-09-15 22:16:39.553662 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 22:16:39.553960 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 22:16:39.554194 | instance | interpreter could change the meaning of that path. See
2026-09-15 22:16:39.554409 | instance | https://docs.ansible.com/ansible-
2026-09-15 22:16:39.554617 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 22:16:39.568849 | instance | ok: [instance]
2026-09-15 22:16:39.569268 | instance |
2026-09-15 22:16:39.569597 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-15 22:16:39.569898 | instance | Tuesday 15 September 2026  22:16:39 +0000 (0:00:01.105)       0:00:01.114 *****
2026-09-15 22:16:39.640063 | instance | ok: [instance] => {
2026-09-15 22:16:39.640350 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-15 22:16:39.640542 | instance | }
2026-09-15 22:16:39.640748 | instance |
2026-09-15 22:16:39.640935 | instance | PLAY [all] *********************************************************************
2026-09-15 22:16:39.641137 | instance |
2026-09-15 22:16:39.641369 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:39.641588 | instance | Tuesday 15 September 2026  22:16:39 +0000 (0:00:00.071)       0:00:01.186 *****
2026-09-15 22:16:40.596593 | instance | ok: [instance]
2026-09-15 22:16:40.596986 | instance |
2026-09-15 22:16:40.597376 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-15 22:16:40.597699 | instance | Tuesday 15 September 2026  22:16:40 +0000 (0:00:00.955)       0:00:02.142 *****
2026-09-15 22:16:40.817308 | instance | ok: [instance]
2026-09-15 22:16:40.817592 | instance |
2026-09-15 22:16:40.817821 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-15 22:16:40.818032 | instance |
2026-09-15 22:16:40.818282 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:40.818505 | instance | Tuesday 15 September 2026  22:16:40 +0000 (0:00:00.221)       0:00:02.363 *****
2026-09-15 22:16:41.826323 | instance | ok: [instance]
2026-09-15 22:16:41.826745 | instance |
2026-09-15 22:16:41.827277 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 22:16:41.827643 | instance | Tuesday 15 September 2026  22:16:41 +0000 (0:00:01.008)       0:00:03.371 *****
2026-09-15 22:16:42.152063 | instance | ok: [instance]
2026-09-15 22:16:42.152130 | instance |
2026-09-15 22:16:42.152140 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 22:16:42.152148 | instance | Tuesday 15 September 2026  22:16:42 +0000 (0:00:00.325)       0:00:03.696 *****
2026-09-15 22:16:42.196294 | instance | skipping: [instance]
2026-09-15 22:16:42.196349 | instance |
2026-09-15 22:16:42.196356 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-15 22:16:42.196362 | instance | Tuesday 15 September 2026  22:16:42 +0000 (0:00:00.045)       0:00:03.742 *****
2026-09-15 22:16:42.561846 | instance | changed: [instance]
2026-09-15 22:16:42.562700 | instance |
2026-09-15 22:16:42.562784 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 22:16:42.562800 | instance | Tuesday 15 September 2026  22:16:42 +0000 (0:00:00.365)       0:00:04.107 *****
2026-09-15 22:16:42.654057 | instance | ok: [instance] => {
2026-09-15 22:16:42.654166 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-15 22:16:42.654841 | instance | }
2026-09-15 22:16:42.654908 | instance |
2026-09-15 22:16:42.654917 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 22:16:42.654925 | instance | Tuesday 15 September 2026  22:16:42 +0000 (0:00:00.092)       0:00:04.199 *****
2026-09-15 22:16:43.686725 | instance | changed: [instance]
2026-09-15 22:16:43.686794 | instance |
2026-09-15 22:16:43.687102 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 22:16:43.687148 | instance | Tuesday 15 September 2026  22:16:43 +0000 (0:00:01.033)       0:00:05.233 *****
2026-09-15 22:16:43.727828 | instance | skipping: [instance]
2026-09-15 22:16:43.727911 | instance |
2026-09-15 22:16:43.728192 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 22:16:43.728251 | instance | Tuesday 15 September 2026  22:16:43 +0000 (0:00:00.040)       0:00:05.274 *****
2026-09-15 22:16:43.776993 | instance | skipping: [instance]
2026-09-15 22:16:43.777097 | instance |
2026-09-15 22:16:43.777151 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 22:16:43.777312 | instance | Tuesday 15 September 2026  22:16:43 +0000 (0:00:00.049)       0:00:05.323 *****
2026-09-15 22:16:44.039639 | instance | ok: [instance]
2026-09-15 22:16:44.039755 | instance |
2026-09-15 22:16:44.039963 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 22:16:44.040259 | instance | Tuesday 15 September 2026  22:16:44 +0000 (0:00:00.261)       0:00:05.585 *****
2026-09-15 22:16:45.833036 | instance | ok: [instance]
2026-09-15 22:16:45.833152 | instance |
2026-09-15 22:16:45.833172 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 22:16:45.833188 | instance | Tuesday 15 September 2026  22:16:45 +0000 (0:00:01.791)       0:00:07.377 *****
2026-09-15 22:16:45.926429 | instance | ok: [instance] => {
2026-09-15 22:16:45.926555 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-15 22:16:45.927752 | instance | }
2026-09-15 22:16:45.927829 | instance |
2026-09-15 22:16:45.927842 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 22:16:45.927852 | instance | Tuesday 15 September 2026  22:16:45 +0000 (0:00:00.094)       0:00:07.471 *****
2026-09-15 22:16:46.628746 | instance | changed: [instance]
2026-09-15 22:16:46.628839 | instance |
2026-09-15 22:16:46.629048 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 22:16:46.629517 | instance | Tuesday 15 September 2026  22:16:46 +0000 (0:00:00.702)       0:00:08.174 *****
2026-09-15 22:16:49.669322 | instance | changed: [instance]
2026-09-15 22:16:49.669397 | instance |
2026-09-15 22:16:49.669409 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-15 22:16:49.669455 | instance | Tuesday 15 September 2026  22:16:49 +0000 (0:00:03.040)       0:00:11.215 *****
2026-09-15 22:16:49.713899 | instance | skipping: [instance]
2026-09-15 22:16:49.713977 | instance |
2026-09-15 22:16:49.713985 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-15 22:16:49.713993 | instance | Tuesday 15 September 2026  22:16:49 +0000 (0:00:00.044)       0:00:11.259 *****
2026-09-15 22:16:49.762342 | instance | skipping: [instance]
2026-09-15 22:16:49.762445 | instance |
2026-09-15 22:16:49.763284 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-15 22:16:49.763361 | instance | Tuesday 15 September 2026  22:16:49 +0000 (0:00:00.048)       0:00:11.308 *****
2026-09-15 22:16:49.815092 | instance | skipping: [instance]
2026-09-15 22:16:49.815204 | instance |
2026-09-15 22:16:49.815437 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-15 22:16:49.815868 | instance | Tuesday 15 September 2026  22:16:49 +0000 (0:00:00.052)       0:00:11.360 *****
2026-09-15 22:16:57.347363 | instance | changed: [instance]
2026-09-15 22:16:57.347507 | instance |
2026-09-15 22:16:57.347682 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-15 22:16:57.347995 | instance | Tuesday 15 September 2026  22:16:57 +0000 (0:00:07.532)       0:00:18.893 *****
2026-09-15 22:16:58.093074 | instance | changed: [instance]
2026-09-15 22:16:58.093290 | instance |
2026-09-15 22:16:58.093960 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-15 22:16:58.094037 | instance | Tuesday 15 September 2026  22:16:58 +0000 (0:00:00.744)       0:00:19.638 *****
2026-09-15 22:16:59.135650 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-15 22:16:59.137373 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-15 22:16:59.137468 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-15 22:16:59.137477 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-15 22:16:59.137485 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-15 22:16:59.137493 | instance |
2026-09-15 22:16:59.137521 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-15 22:16:59.137642 | instance | Tuesday 15 September 2026  22:16:59 +0000 (0:00:01.043)       0:00:20.681 *****
2026-09-15 22:16:59.726466 | instance | changed: [instance]
2026-09-15 22:16:59.726527 | instance |
2026-09-15 22:16:59.726535 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-15 22:16:59.726542 | instance | Tuesday 15 September 2026  22:16:59 +0000 (0:00:00.573)       0:00:21.254 *****
2026-09-15 22:16:59.726548 | instance |
2026-09-15 22:16:59.726554 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 22:16:59.726560 | instance | Tuesday 15 September 2026  22:16:59 +0000 (0:00:00.017)       0:00:21.271 *****
2026-09-15 22:17:00.755928 | instance | ok: [instance]
2026-09-15 22:17:00.756096 | instance |
2026-09-15 22:17:00.756387 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-15 22:17:00.756791 | instance | Tuesday 15 September 2026  22:17:00 +0000 (0:00:01.029)       0:00:22.301 *****
2026-09-15 22:17:01.254549 | instance | changed: [instance]
2026-09-15 22:17:01.254712 | instance |
2026-09-15 22:17:01.254801 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-15 22:17:01.254972 | instance | Tuesday 15 September 2026  22:17:01 +0000 (0:00:00.499)       0:00:22.800 *****
2026-09-15 22:17:01.829331 | instance | changed: [instance]
2026-09-15 22:17:01.829416 | instance |
2026-09-15 22:17:01.829900 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 22:17:01.829965 | instance | Tuesday 15 September 2026  22:17:01 +0000 (0:00:00.574)       0:00:23.375 *****
2026-09-15 22:17:02.067288 | instance | ok: [instance]
2026-09-15 22:17:02.067409 | instance |
2026-09-15 22:17:02.067974 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 22:17:02.068045 | instance | Tuesday 15 September 2026  22:17:02 +0000 (0:00:00.237)       0:00:23.613 *****
2026-09-15 22:17:02.147822 | instance | ok: [instance] => {
2026-09-15 22:17:02.149019 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-15 22:17:02.150381 | instance | }
2026-09-15 22:17:02.151591 | instance |
2026-09-15 22:17:02.152582 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 22:17:02.153555 | instance | Tuesday 15 September 2026  22:17:02 +0000 (0:00:00.077)       0:00:23.691 *****
2026-09-15 22:17:05.118154 | instance | changed: [instance]
2026-09-15 22:17:05.118268 | instance |
2026-09-15 22:17:05.118550 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 22:17:05.119149 | instance | Tuesday 15 September 2026  22:17:05 +0000 (0:00:02.972)       0:00:26.663 *****
2026-09-15 22:17:09.591513 | instance | changed: [instance]
2026-09-15 22:17:09.591770 | instance |
2026-09-15 22:17:09.592034 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-15 22:17:09.592289 | instance | Tuesday 15 September 2026  22:17:09 +0000 (0:00:04.471)       0:00:31.135 *****
2026-09-15 22:17:10.886139 | instance | ok: [instance]
2026-09-15 22:17:10.886253 | instance |
2026-09-15 22:17:10.886416 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-15 22:17:10.886571 | instance | Tuesday 15 September 2026  22:17:10 +0000 (0:00:01.296)       0:00:32.432 *****
2026-09-15 22:17:11.300632 | instance | changed: [instance]
2026-09-15 22:17:11.300711 | instance |
2026-09-15 22:17:11.300893 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-15 22:17:11.301044 | instance | Tuesday 15 September 2026  22:17:11 +0000 (0:00:00.414)       0:00:32.846 *****
2026-09-15 22:17:11.822121 | instance | changed: [instance]
2026-09-15 22:17:11.822199 | instance |
2026-09-15 22:17:11.822394 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-15 22:17:11.822552 | instance | Tuesday 15 September 2026  22:17:11 +0000 (0:00:00.521)       0:00:33.368 *****
2026-09-15 22:17:12.468276 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-15 22:17:12.468348 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-15 22:17:12.468354 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-15 22:17:12.468358 | instance |
2026-09-15 22:17:12.468363 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-15 22:17:12.468367 | instance | Tuesday 15 September 2026  22:17:12 +0000 (0:00:00.645)       0:00:34.013 *****
2026-09-15 22:17:12.966833 | instance | changed: [instance]
2026-09-15 22:17:12.966917 | instance |
2026-09-15 22:17:12.967129 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-15 22:17:12.967184 | instance | Tuesday 15 September 2026  22:17:12 +0000 (0:00:00.500)       0:00:34.513 *****
2026-09-15 22:17:13.486164 | instance | changed: [instance]
2026-09-15 22:17:13.486245 | instance |
2026-09-15 22:17:13.486802 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-15 22:17:13.486868 | instance | Tuesday 15 September 2026  22:17:13 +0000 (0:00:00.501)       0:00:35.015 *****
2026-09-15 22:17:13.486874 | instance |
2026-09-15 22:17:13.486880 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 22:17:13.486884 | instance | Tuesday 15 September 2026  22:17:13 +0000 (0:00:00.017)       0:00:35.032 *****
2026-09-15 22:17:14.246760 | instance | ok: [instance]
2026-09-15 22:17:14.246819 | instance |
2026-09-15 22:17:14.246825 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-15 22:17:14.246838 | instance | Tuesday 15 September 2026  22:17:14 +0000 (0:00:00.760)       0:00:35.792 *****
2026-09-15 22:17:15.256974 | instance | changed: [instance]
2026-09-15 22:17:15.257391 | instance |
2026-09-15 22:17:15.257836 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-15 22:17:15.257920 | instance | Tuesday 15 September 2026  22:17:15 +0000 (0:00:01.009)       0:00:36.802 *****
2026-09-15 22:17:15.860243 | instance | changed: [instance]
2026-09-15 22:17:15.860360 | instance |
2026-09-15 22:17:15.860692 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-15 22:17:15.860746 | instance | Tuesday 15 September 2026  22:17:15 +0000 (0:00:00.603)       0:00:37.406 *****
2026-09-15 22:17:15.921705 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-15 22:17:15.921828 | instance |
2026-09-15 22:17:15.921840 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-15 22:17:15.921972 | instance | Tuesday 15 September 2026  22:17:15 +0000 (0:00:00.061)       0:00:37.467 *****
2026-09-15 22:17:22.261181 | instance | changed: [instance]
2026-09-15 22:17:22.261298 | instance |
2026-09-15 22:17:22.261310 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-15 22:17:22.261321 | instance | Tuesday 15 September 2026  22:17:22 +0000 (0:00:06.338)       0:00:43.805 *****
2026-09-15 22:17:22.968325 | instance | ok: [instance] => (item=chronyd)
2026-09-15 22:17:22.969449 | instance | ok: [instance] => (item=sshd)
2026-09-15 22:17:22.969570 | instance |
2026-09-15 22:17:22.969585 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-15 22:17:22.969611 | instance | Tuesday 15 September 2026  22:17:22 +0000 (0:00:00.707)       0:00:44.513 *****
2026-09-15 22:17:33.356036 | instance | fatal: [instance]: FAILED! => {"changed": false, "dest": "/usr/bin/cephadm", "elapsed": 10, "msg": "Request failed: <urlopen error _ssl.c:990: The handshake operation timed out>", "url": "https://download.ceph.com/rpm-20.2.3/el9/noarch/cephadm"}
2026-09-15 22:17:33.359518 | instance |
2026-09-15 22:17:33.361323 | instance | PLAY RECAP *********************************************************************
2026-09-15 22:17:33.361404 | instance | instance                   : ok=37   changed=20   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-15 22:17:33.361417 | instance |
2026-09-15 22:17:33.361428 | instance | Tuesday 15 September 2026  22:17:33 +0000 (0:00:10.392)       0:00:54.906 *****
2026-09-15 22:17:33.361437 | instance | ===============================================================================
2026-09-15 22:17:33.361446 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 10.39s
2026-09-15 22:17:33.361455 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 7.53s
2026-09-15 22:17:33.361472 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.34s
2026-09-15 22:17:33.365315 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.47s
2026-09-15 22:17:33.365393 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.04s
2026-09-15 22:17:33.365400 | instance | vexxhost.containers.download_artifact : Download item ------------------- 2.97s
2026-09-15 22:17:33.365406 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.79s
2026-09-15 22:17:33.365412 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.79s
2026-09-15 22:17:33.365417 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.30s
2026-09-15 22:17:33.365423 | instance | Gathering Facts --------------------------------------------------------- 1.11s
2026-09-15 22:17:33.365428 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.04s
2026-09-15 22:17:33.365433 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.03s
2026-09-15 22:17:33.365458 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.01s
2026-09-15 22:17:33.365463 | instance | Gathering Facts --------------------------------------------------------- 1.01s
2026-09-15 22:17:33.365469 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-09-15 22:17:33.365474 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.74s
2026-09-15 22:17:33.365480 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.71s
2026-09-15 22:17:33.365486 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.70s
2026-09-15 22:17:33.365498 | instance | vexxhost.containers.docker : Create folders for configuration ----------- 0.65s
2026-09-15 22:17:33.365618 | instance | vexxhost.containers.docker : Enable and start service ------------------- 0.60s
2026-09-15 22:17:33.557937 | 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-09-15 22:17:33.558293 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-15 22:17:33.558498 | 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-09-15 22:17:33.840837 | instance | ERROR
2026-09-15 22:17:33.841132 | instance | {
2026-09-15 22:17:33.841189 | instance |   "delta": "0:01:49.149542",
2026-09-15 22:17:33.841228 | instance |   "end": "2026-09-15 22:17:33.647433",
2026-09-15 22:17:33.841263 | instance |   "msg": "non-zero return code",
2026-09-15 22:17:33.841297 | instance |   "rc": 2,
2026-09-15 22:17:33.841330 | instance |   "start": "2026-09-15 22:15:44.497891"
2026-09-15 22:17:33.841363 | instance | }
failure
2026-09-15 22:17:33.849759 | 
2026-09-15 22:17:33.849848 | PLAY RECAP
2026-09-15 22:17:33.849929 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:17:33.849970 | 
2026-09-15 22:17:34.015069 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 22:17:34.017517 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 22:17:34.716699 | 
2026-09-15 22:17:34.716907 | PLAY [all]
2026-09-15 22:17:34.733203 | 
2026-09-15 22:17:34.733363 | TASK [gather-host-logs : creating directory for system status]
2026-09-15 22:17:35.151781 | instance | changed
2026-09-15 22:17:35.159385 | 
2026-09-15 22:17:35.159547 | TASK [gather-host-logs : Get logs for each host]
2026-09-15 22:17:35.543524 | instance | + systemd-cgls --full --all --no-pager
2026-09-15 22:17:35.558162 | instance | + ip addr
2026-09-15 22:17:35.560950 | instance | + ip route
2026-09-15 22:17:35.563977 | instance | + lsblk
2026-09-15 22:17:35.569004 | instance | + mount
2026-09-15 22:17:35.572421 | instance | + docker images
2026-09-15 22:17:35.597501 | instance | + brctl show
2026-09-15 22:17:35.598176 | instance | /bin/bash: line 8: brctl: command not found
2026-09-15 22:17:35.598555 | instance | + ip neigh
2026-09-15 22:17:35.601133 | instance | + bridge fdb show
2026-09-15 22:17:35.605706 | instance | + ps aux --sort=-%mem
2026-09-15 22:17:35.624275 | instance | + dpkg -l
2026-09-15 22:17:35.636363 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-15 22:17:35.637196 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-15 22:17:35.663865 | instance | + '[' '!' -z '' ']'
2026-09-15 22:17:35.710057 | instance | ok: Runtime: 0:00:00.125646
2026-09-15 22:17:35.718380 | 
2026-09-15 22:17:35.718541 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-15 22:17:36.435504 | instance | changed:
2026-09-15 22:17:36.435768 | instance | created directory /var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/work/logs/instance
2026-09-15 22:17:36.435828 | instance | cd+++++++++ system/
2026-09-15 22:17:36.435873 | instance | >f+++++++++ system/brctl-show.txt
2026-09-15 22:17:36.435914 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-15 22:17:36.435954 | instance | >f+++++++++ system/docker-images.txt
2026-09-15 22:17:36.436001 | instance | >f+++++++++ system/ip-addr.txt
2026-09-15 22:17:36.436045 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-15 22:17:36.436085 | instance | >f+++++++++ system/ip-route.txt
2026-09-15 22:17:36.436129 | instance | >f+++++++++ system/lsblk.txt
2026-09-15 22:17:36.436168 | instance | >f+++++++++ system/mount.txt
2026-09-15 22:17:36.436206 | instance | >f+++++++++ system/packages.txt
2026-09-15 22:17:36.436243 | instance | >f+++++++++ system/ps.txt
2026-09-15 22:17:36.436281 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-15 22:17:36.457560 | 
2026-09-15 22:17:36.457861 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 22:17:36.681359 | instance | changed: "values"
2026-09-15 22:17:36.865114 | instance | changed: "releases"
2026-09-15 22:17:36.883214 | 
2026-09-15 22:17:36.883383 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 22:17:37.126438 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-15 22:17:37.436719 | instance | ok: Runtime: 0:00:00.005479
2026-09-15 22:17:37.446884 | 
2026-09-15 22:17:37.447114 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 22:17:37.963470 | instance | changed:
2026-09-15 22:17:37.963853 | instance | cd+++++++++ helm/
2026-09-15 22:17:37.963966 | instance | cd+++++++++ helm/releases/
2026-09-15 22:17:37.964044 | instance | cd+++++++++ helm/values/
2026-09-15 22:17:37.986061 | 
2026-09-15 22:17:37.986205 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 22:17:38.242483 | instance | changed
2026-09-15 22:17:38.250739 | 
2026-09-15 22:17:38.251151 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 22:17:38.543951 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:38.544398 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:38.550427 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:38.551641 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:38.551740 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:38.553538 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:38.556516 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:38.557441 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:38.558229 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:38.559838 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:38.561852 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:38.563210 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:38.790872 | instance | ok: Runtime: 0:00:00.028579
2026-09-15 22:17:38.797709 | 
2026-09-15 22:17:38.797804 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 22:17:39.014421 | instance | changed
2026-09-15 22:17:39.024107 | 
2026-09-15 22:17:39.024265 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 22:17:39.275583 | instance | environment: line 5: kubectl: command not found
2026-09-15 22:17:39.276790 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:39.276923 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:39.277028 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:39.576157 | instance | ok: Runtime: 0:00:00.009180
2026-09-15 22:17:39.590365 | 
2026-09-15 22:17:39.590558 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 22:17:40.176742 | instance | changed:
2026-09-15 22:17:40.177294 | instance | cd+++++++++ objects/
2026-09-15 22:17:40.177377 | instance | cd+++++++++ objects/cluster/
2026-09-15 22:17:40.177463 | instance | cd+++++++++ objects/namespaced/
2026-09-15 22:17:40.198154 | 
2026-09-15 22:17:40.198291 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 22:17:40.448384 | instance | changed
2026-09-15 22:17:40.458721 | 
2026-09-15 22:17:40.458861 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 22:17:40.722565 | instance | changed
2026-09-15 22:17:40.734172 | 
2026-09-15 22:17:40.734337 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 22:17:40.984816 | instance | environment: line 3: kubectl: command not found
2026-09-15 22:17:41.286135 | instance | ok: Runtime: 0:00:00.011246
2026-09-15 22:17:41.298990 | 
2026-09-15 22:17:41.299220 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 22:17:41.853577 | instance | changed:
2026-09-15 22:17:41.853864 | instance | cd+++++++++ pod-logs/
2026-09-15 22:17:41.853922 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-15 22:17:41.874934 | 
2026-09-15 22:17:41.875128 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-15 22:17:42.093869 | instance | changed
2026-09-15 22:17:42.104054 | 
2026-09-15 22:17:42.104199 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-15 22:17:42.360888 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 22:17:42.652052 | instance | ok: Runtime: 0:00:00.048393
2026-09-15 22:17:42.663310 | 
2026-09-15 22:17:42.663489 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-15 22:17:42.937490 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 22:17:42.983418 | instance | ceph-mgr endpoints:
2026-09-15 22:17:43.215555 | instance | ok: Runtime: 0:00:00.053080
2026-09-15 22:17:43.233188 | 
2026-09-15 22:17:43.233397 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-15 22:17:43.489735 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-15 22:17:43.774941 | instance | ok: Runtime: 0:00:00.053769
2026-09-15 22:17:43.798078 | 
2026-09-15 22:17:43.798346 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-15 22:17:44.374837 | instance | changed: cd+++++++++ prometheus/
2026-09-15 22:17:44.396729 | 
2026-09-15 22:17:44.396963 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-15 22:17:44.637766 | instance | changed
2026-09-15 22:17:44.658728 | 
2026-09-15 22:17:44.658932 | TASK [gather-selenium-data : Get selenium data]
2026-09-15 22:17:44.961378 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-15 22:17:44.962922 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-15 22:17:45.204362 | instance | ERROR
2026-09-15 22:17:45.204751 | instance | {
2026-09-15 22:17:45.204817 | instance |   "delta": "0:00:00.007045",
2026-09-15 22:17:45.204858 | instance |   "end": "2026-09-15 22:17:44.963316",
2026-09-15 22:17:45.204894 | instance |   "msg": "non-zero return code",
2026-09-15 22:17:45.204929 | instance |   "rc": 1,
2026-09-15 22:17:45.204962 | instance |   "start": "2026-09-15 22:17:44.956271"
2026-09-15 22:17:45.204995 | instance | }
2026-09-15 22:17:45.205040 | instance | ERROR: Ignoring Errors
2026-09-15 22:17:45.216108 | 
2026-09-15 22:17:45.216281 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-15 22:17:45.758297 | instance | changed: cd+++++++++ selenium/
2026-09-15 22:17:45.769646 | 
2026-09-15 22:17:45.769753 | PLAY RECAP
2026-09-15 22:17:45.769839 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-15 22:17:45.769881 | 
2026-09-15 22:17:45.971683 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 22:17:45.977284 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 22:17:46.629947 | 
2026-09-15 22:17:46.630120 | PLAY [all]
2026-09-15 22:17:46.641790 | 
2026-09-15 22:17:46.641883 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 22:17:46.695882 | instance | skipping: Conditional result was False
2026-09-15 22:17:46.705641 | 
2026-09-15 22:17:46.705750 | TASK [fetch-output : Set log path for single node]
2026-09-15 22:17:46.748105 | instance | ok
2026-09-15 22:17:46.756789 | 
2026-09-15 22:17:46.756920 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 22:17:47.215136 | instance -> localhost | ok: "/var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/work/logs"
2026-09-15 22:17:47.455413 | instance -> localhost | changed: "/var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/work/artifacts"
2026-09-15 22:17:47.661571 | instance -> localhost | changed: "/var/lib/zuul/builds/c60e72ba38cd458292f355ccdca8b759/work/docs"
2026-09-15 22:17:47.689528 | 
2026-09-15 22:17:47.689761 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 22:17:48.420267 | instance | changed: .d..t...... ./
2026-09-15 22:17:48.420483 | instance | changed: All items complete
2026-09-15 22:17:48.420567 | 
2026-09-15 22:17:48.931723 | instance | changed: .d..t...... ./
2026-09-15 22:17:49.457245 | instance | changed: .d..t...... ./
2026-09-15 22:17:49.582149 | 
2026-09-15 22:17:49.582399 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 22:17:50.056911 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008242
2026-09-15 22:17:50.318108 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006887
2026-09-15 22:17:50.338339 | 
2026-09-15 22:17:50.338723 | PLAY [all]
2026-09-15 22:17:50.350046 | 
2026-09-15 22:17:50.350179 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 22:17:50.840740 | instance | changed
2026-09-15 22:17:50.851141 | 
2026-09-15 22:17:50.851239 | PLAY RECAP
2026-09-15 22:17:50.851325 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 22:17:50.851388 | 
2026-09-15 22:17:50.995126 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 22:17:51.000651 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 22:17:51.561652 | 
2026-09-15 22:17:51.561794 | PLAY [localhost]
2026-09-15 22:17:51.571305 | 
2026-09-15 22:17:51.571392 | TASK [Generate Zuul manifest]
2026-09-15 22:17:51.589792 | localhost | ok
2026-09-15 22:17:51.605870 | 
2026-09-15 22:17:51.606016 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 22:17:52.143468 | localhost | changed
2026-09-15 22:17:52.164471 | 
2026-09-15 22:17:52.164663 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 22:17:52.206182 | localhost | ok
2026-09-15 22:17:52.219163 | 
2026-09-15 22:17:52.219295 | TASK [Upload logs]
2026-09-15 22:17:52.244983 | localhost | ok
2026-09-15 22:17:52.317716 | 
2026-09-15 22:17:52.317861 | TASK [Set zuul-log-path fact]
2026-09-15 22:17:52.334085 | localhost | ok
2026-09-15 22:17:52.344355 | 
2026-09-15 22:17:52.344440 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 22:17:52.382122 | localhost | ok
2026-09-15 22:17:52.390274 | 
2026-09-15 22:17:52.390366 | TASK [upload-logs : Create log directories]
2026-09-15 22:17:52.846052 | localhost | changed
2026-09-15 22:17:52.850258 | 
2026-09-15 22:17:52.850333 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 22:17:53.306045 | localhost -> localhost | ok: Runtime: 0:00:00.006570
2026-09-15 22:17:53.325071 | 
2026-09-15 22:17:53.325277 | TASK [upload-logs : Upload logs to log server]
2026-09-15 22:17:53.892140 | localhost | Output suppressed because no_log was given
2026-09-15 22:17:53.940688 | 
2026-09-15 22:17:53.941236 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 22:17:53.987846 | localhost | skipping: Conditional result was False
2026-09-15 22:17:53.997314 | localhost | skipping: Conditional result was False
2026-09-15 22:17:54.011801 | 
2026-09-15 22:17:54.011966 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 22:17:54.052567 | localhost | skipping: Conditional result was False
2026-09-15 22:17:54.052922 | 
2026-09-15 22:17:54.056039 | localhost | skipping: Conditional result was False
2026-09-15 22:17:54.063862 | 
2026-09-15 22:17:54.064066 | LOOP [upload-logs : Upload console log and json output]