2026-09-15 22:14:26.728949 | Job console starting
2026-09-15 22:14:26.738912 | Updating git repos
2026-09-15 22:14:34.600932 | Cloning repos into workspace
2026-09-15 22:14:50.540851 | Restoring repo states
2026-09-15 22:14:55.508444 | Merging changes
2026-09-15 22:14:58.105932 | Checking out repos
2026-09-15 22:15:01.406582 | Preparing playbooks
2026-09-15 22:15:04.117186 | Running Ansible setup
2026-09-15 22:15:07.928540 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 22:15:08.566133 | 
2026-09-15 22:15:08.566297 | PLAY [localhost]
2026-09-15 22:15:08.574324 | 
2026-09-15 22:15:08.574411 | TASK [Gathering Facts]
2026-09-15 22:15:09.547850 | localhost | ok
2026-09-15 22:15:09.557016 | 
2026-09-15 22:15:09.557117 | TASK [Setup log path fact]
2026-09-15 22:15:09.575913 | localhost | ok
2026-09-15 22:15:09.588046 | 
2026-09-15 22:15:09.588114 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 22:15:09.617193 | localhost | ok
2026-09-15 22:15:09.625634 | 
2026-09-15 22:15:09.625753 | TASK [emit-job-header : Print job information]
2026-09-15 22:15:09.678213 | # Job Information
2026-09-15 22:15:09.678490 | Ansible Version: 2.16.18
2026-09-15 22:15:09.678557 | Job: atmosphere-molecule-csi-rbd
2026-09-15 22:15:09.678623 | Pipeline: check
2026-09-15 22:15:09.678666 | Executor: 2d72f0692154
2026-09-15 22:15:09.678704 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4093
2026-09-15 22:15:09.678750 | Event ID: 3c76cf90-b152-11f1-964c-53d438f63770
2026-09-15 22:15:09.684919 | 
2026-09-15 22:15:09.685104 | LOOP [emit-job-header : Print node information]
2026-09-15 22:15:09.790469 | localhost | ok:
2026-09-15 22:15:09.790765 | localhost | # Node Information
2026-09-15 22:15:09.790823 | localhost | Inventory Hostname: instance
2026-09-15 22:15:09.790866 | localhost | Hostname: np0000192873
2026-09-15 22:15:09.790908 | localhost | Username: zuul
2026-09-15 22:15:09.790954 | localhost | Distro: Ubuntu 22.04
2026-09-15 22:15:09.790993 | localhost | Provider: yul1
2026-09-15 22:15:09.791031 | localhost | Region: ca-ymq-1
2026-09-15 22:15:09.791067 | localhost | Label: ubuntu-jammy
2026-09-15 22:15:09.791104 | localhost | Product Name: OpenStack Nova
2026-09-15 22:15:09.791140 | localhost | Interface IP: 162.253.55.104
2026-09-15 22:15:09.807041 | 
2026-09-15 22:15:09.807414 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 22:15:10.210731 | localhost -> localhost | changed
2026-09-15 22:15:10.225296 | 
2026-09-15 22:15:10.225478 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 22:15:11.208196 | localhost -> localhost | changed
2026-09-15 22:15:11.221094 | 
2026-09-15 22:15:11.221220 | PLAY [all]
2026-09-15 22:15:11.238214 | 
2026-09-15 22:15:11.238370 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 22:15:11.634937 | instance -> localhost | ok
2026-09-15 22:15:11.639977 | 
2026-09-15 22:15:11.640040 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 22:15:11.677825 | instance | ok
2026-09-15 22:15:11.689924 | instance | included: /var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 22:15:11.695391 | 
2026-09-15 22:15:11.695453 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 22:15:12.609656 | instance -> localhost | Generating public/private rsa key pair.
2026-09-15 22:15:12.609928 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/work/8ea68a13f636487b8de0d4253f4d6c3a_id_rsa
2026-09-15 22:15:12.609987 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/work/8ea68a13f636487b8de0d4253f4d6c3a_id_rsa.pub
2026-09-15 22:15:12.610030 | instance -> localhost | The key fingerprint is:
2026-09-15 22:15:12.610072 | instance -> localhost | SHA256:qgtgBY+CPAlxxP5tevNcv1zzrU/V9IvkshBBMlqv+gQ zuul-build-sshkey
2026-09-15 22:15:12.610549 | instance -> localhost | The key's randomart image is:
2026-09-15 22:15:12.610615 | instance -> localhost | +---[RSA 3072]----+
2026-09-15 22:15:12.610665 | instance -> localhost | |o=o    + .       |
2026-09-15 22:15:12.610709 | instance -> localhost | |+.*   o =        |
2026-09-15 22:15:12.610749 | instance -> localhost | |o* o .   o      .|
2026-09-15 22:15:12.610787 | instance -> localhost | |. +     . .    .o|
2026-09-15 22:15:12.610825 | instance -> localhost | |.. . E .S.   .  +|
2026-09-15 22:15:12.610863 | instance -> localhost | |..  . =.  . o . o|
2026-09-15 22:15:12.610900 | instance -> localhost | |  .  +.. ... oo..|
2026-09-15 22:15:12.610937 | instance -> localhost | |   ...=. ..oo. +.|
2026-09-15 22:15:12.610981 | instance -> localhost | |    oo +o  .+..o+|
2026-09-15 22:15:12.611019 | instance -> localhost | +----[SHA256]-----+
2026-09-15 22:15:12.611113 | instance -> localhost | ok: Runtime: 0:00:00.394810
2026-09-15 22:15:12.622230 | 
2026-09-15 22:15:12.622365 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 22:15:12.656731 | instance | ok
2026-09-15 22:15:12.672096 | instance | included: /var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 22:15:12.684069 | 
2026-09-15 22:15:12.684176 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 22:15:12.709062 | instance | skipping: Conditional result was False
2026-09-15 22:15:12.719812 | 
2026-09-15 22:15:12.719941 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 22:15:13.165294 | instance | changed
2026-09-15 22:15:13.171126 | 
2026-09-15 22:15:13.171201 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 22:15:13.378144 | instance | ok
2026-09-15 22:15:13.386998 | 
2026-09-15 22:15:13.387068 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 22:15:13.925004 | instance | changed
2026-09-15 22:15:13.934284 | 
2026-09-15 22:15:13.934411 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 22:15:14.469511 | instance | changed
2026-09-15 22:15:14.484677 | 
2026-09-15 22:15:14.485673 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 22:15:14.510117 | instance | skipping: Conditional result was False
2026-09-15 22:15:14.517387 | 
2026-09-15 22:15:14.517486 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 22:15:14.937235 | instance -> localhost | changed
2026-09-15 22:15:14.957960 | 
2026-09-15 22:15:14.958161 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 22:15:15.260969 | instance -> localhost | Identity added: /var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/work/8ea68a13f636487b8de0d4253f4d6c3a_id_rsa (zuul-build-sshkey)
2026-09-15 22:15:15.261197 | instance -> localhost | ok: Runtime: 0:00:00.010384
2026-09-15 22:15:15.267961 | 
2026-09-15 22:15:15.268045 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 22:15:15.621762 | instance | ok
2026-09-15 22:15:15.630544 | 
2026-09-15 22:15:15.630700 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 22:15:15.656115 | instance | skipping: Conditional result was False
2026-09-15 22:15:15.671976 | 
2026-09-15 22:15:15.672138 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 22:15:16.053568 | instance | ok
2026-09-15 22:15:16.068138 | 
2026-09-15 22:15:16.068361 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 22:15:19.875628 | instance | Output suppressed because no_log was given
2026-09-15 22:15:19.892649 | 
2026-09-15 22:15:19.892783 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 22:15:20.111510 | instance | ok: "logs"
2026-09-15 22:15:20.111901 | instance | ok: All items complete
2026-09-15 22:15:20.111957 | 
2026-09-15 22:15:20.260972 | instance | ok: "artifacts"
2026-09-15 22:15:20.415884 | instance | ok: "docs"
2026-09-15 22:15:20.432118 | 
2026-09-15 22:15:20.432314 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 22:15:20.655672 | instance | changed: "logs"
2026-09-15 22:15:20.828154 | instance | changed: "artifacts"
2026-09-15 22:15:21.013727 | instance | changed: "docs"
2026-09-15 22:15:21.034270 | 
2026-09-15 22:15:21.034439 | PLAY RECAP
2026-09-15 22:15:21.034532 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 22:15:21.034618 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:15:21.034665 | 
2026-09-15 22:15:21.181678 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 22:15:21.186905 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 22:15:21.793871 | 
2026-09-15 22:15:21.794057 | PLAY [all]
2026-09-15 22:15:21.805302 | 
2026-09-15 22:15:21.805475 | TASK [setup-uv : Extract archive]
2026-09-15 22:15:24.150152 | instance | changed
2026-09-15 22:15:24.161753 | 
2026-09-15 22:15:24.161917 | TASK [setup-uv : Print version]
2026-09-15 22:15:24.517708 | instance | uv 0.8.13
2026-09-15 22:15:24.701313 | instance | ok: Runtime: 0:00:00.013609
2026-09-15 22:15:24.719401 | 
2026-09-15 22:15:24.719618 | TASK [Install Ansible collection siblings]
2026-09-15 22:15:34.427840 | instance | changed:
2026-09-15 22:15:34.428120 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 22:15:34.428176 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 22:15:34.428218 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 22:15:34.428256 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 22:15:34.428293 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 22:15:34.428334 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 22:15:34.428373 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 22:15:34.428414 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 22:15:34.428451 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 22:15:34.428490 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 22:15:34.428604 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 22:15:34.428646 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 22:15:34.428682 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-15 22:15:34.428717 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-15 22:15:34.428753 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-15 22:15:34.428790 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-15 22:15:34.428825 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-15 22:15:34.428860 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-15 22:15:34.428895 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-15 22:15:34.428929 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-15 22:15:34.428962 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-15 22:15:34.428996 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-15 22:15:34.429034 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 22:15:34.429086 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-15 22:15:34.429122 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-15 22:15:34.429156 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 22:15:34.429191 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 22:15:34.429225 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 22:15:34.429259 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-15 22:15:34.429293 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-15 22:15:34.429326 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 22:15:34.429361 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 22:15:34.429395 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-15 22:15:34.429429 | 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:34.429475 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.429510 | instance | Process install dependency map
2026-09-15 22:15:34.429544 | instance | Starting collection install process
2026-09-15 22:15:34.429594 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-15 22:15:34.429629 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-15 22:15:34.429663 | instance | ansible.netcommon:* was installed successfully
2026-09-15 22:15:34.429696 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-15 22:15:34.429730 | instance | Creating virtual environment at: .venv
2026-09-15 22:15:34.429764 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 22:15:34.429798 | instance | Downloading pygments (1.2MiB)
2026-09-15 22:15:34.429831 | instance | Downloading cryptography (4.2MiB)
2026-09-15 22:15:34.429869 | instance | Downloading rjsonnet (1.2MiB)
2026-09-15 22:15:34.429903 | instance | Downloading ansible-core (2.1MiB)
2026-09-15 22:15:34.429937 | instance | Downloading openstacksdk (1.7MiB)
2026-09-15 22:15:34.429972 | instance | Downloading netaddr (2.2MiB)
2026-09-15 22:15:34.430006 | instance | Downloading kubernetes (1.9MiB)
2026-09-15 22:15:34.430041 | instance | Downloading setuptools (1.1MiB)
2026-09-15 22:15:34.430075 | instance |  Downloading rjsonnet
2026-09-15 22:15:34.430108 | instance |    Building pyperclip==1.9.0
2026-09-15 22:15:34.430146 | instance |  Downloading netaddr
2026-09-15 22:15:34.430181 | instance |  Downloading pygments
2026-09-15 22:15:34.430215 | instance |  Downloading cryptography
2026-09-15 22:15:34.430249 | instance |  Downloading setuptools
2026-09-15 22:15:34.430283 | instance |  Downloading kubernetes
2026-09-15 22:15:34.430315 | instance |  Downloading ansible-core
2026-09-15 22:15:34.430348 | instance |  Downloading openstacksdk
2026-09-15 22:15:34.430381 | instance |       Built pyperclip==1.9.0
2026-09-15 22:15:34.430415 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 22:15:34.430449 | instance | Installed 79 packages in 150ms
2026-09-15 22:15:34.430484 | instance | Checked out ansible.posix to 1.6.0
2026-09-15 22:15:34.430519 | 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:34.430553 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.430607 | instance | Process install dependency map
2026-09-15 22:15:34.430891 | instance | Starting collection install process
2026-09-15 22:15:34.430962 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 22:15:34.431013 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-15 22:15:34.431050 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-15 22:15:34.431085 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.431119 | instance | 2.17.12
2026-09-15 22:15:34.431154 | instance | Checked out ansible.utils to v6.1.0
2026-09-15 22:15:34.431188 | 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:34.431223 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.431255 | instance | Process install dependency map
2026-09-15 22:15:34.431288 | instance | Starting collection install process
2026-09-15 22:15:34.431321 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-15 22:15:34.431354 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-15 22:15:34.431388 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-15 22:15:34.431421 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.431455 | instance | 2.17.12
2026-09-15 22:15:34.431489 | instance | Checked out atmosphere.common to v0.7.0
2026-09-15 22:15:34.431523 | 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:34.431557 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.431613 | instance | Process install dependency map
2026-09-15 22:15:34.431647 | instance | Starting collection install process
2026-09-15 22:15:34.431680 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-15 22:15:34.431714 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-15 22:15:34.431747 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-15 22:15:34.431781 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.431814 | instance | 2.17.12
2026-09-15 22:15:34.431848 | instance | Checked out community.crypto to 2.2.3
2026-09-15 22:15:34.431882 | 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:34.431916 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.431950 | instance | Process install dependency map
2026-09-15 22:15:34.431984 | instance | Starting collection install process
2026-09-15 22:15:34.432017 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 22:15:34.432050 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-15 22:15:34.432084 | instance | community.crypto:2.2.3 was installed successfully
2026-09-15 22:15:34.432117 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.432150 | instance | 2.17.12
2026-09-15 22:15:34.432183 | instance | Checked out community.general to 7.3.0
2026-09-15 22:15:34.432215 | 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:34.432249 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.432295 | instance | Process install dependency map
2026-09-15 22:15:34.432329 | instance | Starting collection install process
2026-09-15 22:15:34.432363 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 22:15:34.432396 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-15 22:15:34.432429 | instance | community.general:7.3.0 was installed successfully
2026-09-15 22:15:34.432462 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.432525 | instance | 2.17.12
2026-09-15 22:15:34.432608 | instance | Checked out community.mysql to 3.6.0
2026-09-15 22:15:34.432650 | 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:34.432686 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.432725 | instance | Process install dependency map
2026-09-15 22:15:34.432761 | instance | Starting collection install process
2026-09-15 22:15:34.432796 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-15 22:15:34.432830 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-15 22:15:34.432865 | instance | community.mysql:3.6.0 was installed successfully
2026-09-15 22:15:34.432899 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.432932 | instance | 2.17.12
2026-09-15 22:15:34.432966 | instance | Checked out kubernetes.core to 2.4.0
2026-09-15 22:15:34.433000 | 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:34.433034 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.433069 | instance | Process install dependency map
2026-09-15 22:15:34.433103 | instance | Starting collection install process
2026-09-15 22:15:34.433136 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 22:15:34.433171 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-15 22:15:34.433205 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-15 22:15:34.433238 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.433272 | instance | 2.17.12
2026-09-15 22:15:34.433305 | instance | Checked out openstack.cloud to 2.6.0
2026-09-15 22:15:34.433338 | 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:34.433373 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.433405 | instance | Process install dependency map
2026-09-15 22:15:34.433439 | instance | Starting collection install process
2026-09-15 22:15:34.433540 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-15 22:15:34.433662 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-15 22:15:34.433706 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-15 22:15:34.433743 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.433778 | instance | 2.17.12
2026-09-15 22:15:34.433812 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-15 22:15:34.433847 | 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:34.433895 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.433930 | instance | Process install dependency map
2026-09-15 22:15:34.433964 | instance | Starting collection install process
2026-09-15 22:15:34.433997 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-15 22:15:34.434030 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-15 22:15:34.434062 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-15 22:15:34.434095 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.434128 | instance | 2.17.12
2026-09-15 22:15:34.434161 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-15 22:15:34.434195 | 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:34.434227 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.434260 | instance | Process install dependency map
2026-09-15 22:15:34.434293 | instance | Starting collection install process
2026-09-15 22:15:34.434326 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-15 22:15:34.434359 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-15 22:15:34.434392 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-15 22:15:34.434426 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.434459 | instance | 2.17.12
2026-09-15 22:15:34.434492 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-15 22:15:34.434526 | 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:34.434560 | instance | Starting galaxy collection install process
2026-09-15 22:15:34.434624 | instance | Process install dependency map
2026-09-15 22:15:34.434658 | instance | Starting collection install process
2026-09-15 22:15:34.434691 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-15 22:15:34.434725 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-15 22:15:34.434758 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-15 22:15:34.434792 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 22:15:34.434825 | instance | 2.17.12
2026-09-15 22:15:34.445634 | 
2026-09-15 22:15:34.445736 | PLAY RECAP
2026-09-15 22:15:34.445817 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:15:34.445856 | 
2026-09-15 22:15:34.612816 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 22:15:34.617010 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 22:15:35.167676 | 
2026-09-15 22:15:35.167797 | PLAY [all]
2026-09-15 22:15:35.177752 | 
2026-09-15 22:15:35.177819 | TASK [Install "jq" for log collection]
2026-09-15 22:15:44.834765 | instance | changed
2026-09-15 22:15:44.837254 | 
2026-09-15 22:15:44.837333 | PLAY RECAP
2026-09-15 22:15:44.837403 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:15:44.837466 | 
2026-09-15 22:15:45.009660 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 22:15:45.012851 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 22:15:45.601379 | 
2026-09-15 22:15:45.601531 | PLAY [all]
2026-09-15 22:15:45.612204 | 
2026-09-15 22:15:45.612293 | LOOP [Check inventory directories]
2026-09-15 22:15:45.986285 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-15 22:15:46.157489 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-15 22:15:46.321446 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-15 22:15:46.329830 | 
2026-09-15 22:15:46.330059 | LOOP [Copy inventory file for Zuul]
2026-09-15 22:15:47.210285 | instance | changed:
2026-09-15 22:15:47.210486 | instance | {
2026-09-15 22:15:47.210544 | instance |   "ansible_loop_var": "item",
2026-09-15 22:15:47.210588 | instance |   "changed": false,
2026-09-15 22:15:47.210609 | instance |   "failed": false,
2026-09-15 22:15:47.210633 | instance |   "invocation": {
2026-09-15 22:15:47.210652 | instance |     "module_args": {
2026-09-15 22:15:47.210670 | instance |       "checksum_algorithm": "sha1",
2026-09-15 22:15:47.210688 | instance |       "follow": true,
2026-09-15 22:15:47.210707 | instance |       "get_attributes": true,
2026-09-15 22:15:47.210726 | instance |       "get_checksum": true,
2026-09-15 22:15:47.210743 | instance |       "get_mime": true,
2026-09-15 22:15:47.210761 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-15 22:15:47.210778 | instance |     }
2026-09-15 22:15:47.211008 | instance |   },
2026-09-15 22:15:47.211031 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-15 22:15:47.211048 | instance |   "stat": {
2026-09-15 22:15:47.211065 | instance |     "atime": 1789510526.6183245,
2026-09-15 22:15:47.211083 | instance |     "attr_flags": "e",
2026-09-15 22:15:47.211100 | instance |     "attributes": [
2026-09-15 22:15:47.211117 | instance |       "extents"
2026-09-15 22:15:47.211133 | instance |     ],
2026-09-15 22:15:47.211149 | instance |     "block_size": 4096,
2026-09-15 22:15:47.211166 | instance |     "blocks": 8,
2026-09-15 22:15:47.211184 | instance |     "charset": "unknown",
2026-09-15 22:15:47.211233 | instance |     "ctime": 1789510525.582232,
2026-09-15 22:15:47.211252 | instance |     "dev": 64513,
2026-09-15 22:15:47.211269 | instance |     "device_type": 0,
2026-09-15 22:15:47.211286 | instance |     "executable": true,
2026-09-15 22:15:47.211303 | instance |     "exists": true,
2026-09-15 22:15:47.211501 | instance |     "gid": 1000,
2026-09-15 22:15:47.211737 | instance |     "gr_name": "zuul",
2026-09-15 22:15:47.211761 | instance |     "inode": 1048097,
2026-09-15 22:15:47.211819 | instance |     "isblk": false,
2026-09-15 22:15:47.211841 | instance |     "ischr": false,
2026-09-15 22:15:47.211858 | instance |     "isdir": true,
2026-09-15 22:15:47.211875 | instance |     "isfifo": false,
2026-09-15 22:15:47.211891 | instance |     "isgid": false,
2026-09-15 22:15:47.211907 | instance |     "islnk": false,
2026-09-15 22:15:47.211924 | instance |     "isreg": false,
2026-09-15 22:15:47.211940 | instance |     "issock": false,
2026-09-15 22:15:47.211956 | instance |     "isuid": false,
2026-09-15 22:15:47.211972 | instance |     "mimetype": "unknown",
2026-09-15 22:15:47.211988 | instance |     "mode": "0755",
2026-09-15 22:15:47.212005 | instance |     "mtime": 1789510525.582232,
2026-09-15 22:15:47.212062 | instance |     "nlink": 22,
2026-09-15 22:15:47.212083 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-15 22:15:47.212101 | instance |     "pw_name": "zuul",
2026-09-15 22:15:47.212119 | instance |     "readable": true,
2026-09-15 22:15:47.212283 | instance |     "rgrp": true,
2026-09-15 22:15:47.212570 | instance |     "roth": true,
2026-09-15 22:15:47.212595 | instance |     "rusr": true,
2026-09-15 22:15:47.212613 | instance |     "size": 4096,
2026-09-15 22:15:47.213150 | instance |     "uid": 1000,
2026-09-15 22:15:47.213707 | instance |     "version": "4037113866",
2026-09-15 22:15:47.213737 | instance |     "wgrp": false,
2026-09-15 22:15:47.213758 | instance |     "woth": false,
2026-09-15 22:15:47.213777 | instance |     "writeable": true,
2026-09-15 22:15:47.213795 | instance |     "wusr": true,
2026-09-15 22:15:47.213812 | instance |     "xgrp": true,
2026-09-15 22:15:47.213828 | instance |     "xoth": true,
2026-09-15 22:15:47.213844 | instance |     "xusr": true
2026-09-15 22:15:47.213861 | instance |   }
2026-09-15 22:15:47.213877 | instance | }
2026-09-15 22:15:47.213915 | instance | ok: All items complete
2026-09-15 22:15:47.213936 | 
2026-09-15 22:15:47.216187 | instance | skipping: Conditional result was False
2026-09-15 22:15:47.217071 | instance | skipping: Conditional result was False
2026-09-15 22:15:47.228934 | 
2026-09-15 22:15:47.229107 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 22:15:47.637604 | instance | changed: 1 replacements made
2026-09-15 22:15:47.640222 | instance | skipping: Conditional result was False
2026-09-15 22:15:47.641434 | instance | skipping: Conditional result was False
2026-09-15 22:15:47.655236 | 
2026-09-15 22:15:47.655410 | TASK [Run Molecule scenario]
2026-09-15 22:15:48.656475 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-15 22:15:49.076532 | 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:49.076611 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-15 22:15:59.868010 | instance | INFO     [csi > dependency] Executing
2026-09-15 22:15:59.868326 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-15 22:15:59.868856 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-15 22:15:59.869078 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-15 22:15:59.883872 | instance | INFO     [csi > cleanup] Executing
2026-09-15 22:15:59.884489 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 22:15:59.897791 | instance | INFO     [csi > destroy] Executing
2026-09-15 22:15:59.897857 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-15 22:15:59.897941 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-15 22:15:59.908077 | instance | INFO     [csi > syntax] Executing
2026-09-15 22:16:01.418973 | instance |
2026-09-15 22:16:01.419195 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 22:16:01.503216 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-15 22:16:01.520413 | instance | INFO     [csi > create] Executing
2026-09-15 22:16:01.523906 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 22:16:01.534172 | instance | INFO     [csi > prepare] Executing
2026-09-15 22:16:02.238816 | instance |
2026-09-15 22:16:02.238901 | instance | PLAY [Prepare] *****************************************************************
2026-09-15 22:16:02.238913 | instance |
2026-09-15 22:16:02.238999 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:02.239101 | instance | Tuesday 15 September 2026  22:16:02 +0000 (0:00:00.024)       0:00:00.024 *****
2026-09-15 22:16:03.536384 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 22:16:03.536503 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 22:16:03.536674 | instance | interpreter could change the meaning of that path. See
2026-09-15 22:16:03.536834 | instance | https://docs.ansible.com/ansible-
2026-09-15 22:16:03.537000 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 22:16:03.546393 | instance | ok: [instance]
2026-09-15 22:16:03.546536 | instance |
2026-09-15 22:16:03.546691 | instance | TASK [Configure short hostname] ************************************************
2026-09-15 22:16:03.546852 | instance | Tuesday 15 September 2026  22:16:03 +0000 (0:00:01.308)       0:00:01.332 *****
2026-09-15 22:16:04.301821 | instance | changed: [instance]
2026-09-15 22:16:04.302030 | instance |
2026-09-15 22:16:04.302279 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-15 22:16:04.302574 | instance | Tuesday 15 September 2026  22:16:04 +0000 (0:00:00.754)       0:00:02.087 *****
2026-09-15 22:16:04.564509 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-15 22:16:04.564752 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-15 22:16:04.565027 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-15 22:16:04.571683 | instance | changed: [instance]
2026-09-15 22:16:04.571965 | instance |
2026-09-15 22:16:04.572283 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-15 22:16:04.572590 | instance | Tuesday 15 September 2026  22:16:04 +0000 (0:00:00.270)       0:00:02.358 *****
2026-09-15 22:16:05.387671 | instance | ok: [instance]
2026-09-15 22:16:05.387819 | instance |
2026-09-15 22:16:05.388076 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-15 22:16:05.388300 | instance |
2026-09-15 22:16:05.388609 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:05.388836 | instance | Tuesday 15 September 2026  22:16:05 +0000 (0:00:00.815)       0:00:03.174 *****
2026-09-15 22:16:06.088915 | instance | ok: [instance]
2026-09-15 22:16:06.089074 | instance |
2026-09-15 22:16:06.089461 | instance | TASK [Install depedencies] *****************************************************
2026-09-15 22:16:06.089708 | instance | Tuesday 15 September 2026  22:16:06 +0000 (0:00:00.700)       0:00:03.874 *****
2026-09-15 22:16:30.659708 | instance | changed: [instance]
2026-09-15 22:16:30.659823 | instance |
2026-09-15 22:16:30.659987 | instance | TASK [Start up service] ********************************************************
2026-09-15 22:16:30.660170 | instance | Tuesday 15 September 2026  22:16:30 +0000 (0:00:24.570)       0:00:28.445 *****
2026-09-15 22:16:31.226322 | instance | ok: [instance]
2026-09-15 22:16:31.226456 | instance |
2026-09-15 22:16:31.226640 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-15 22:16:31.226916 | instance | Tuesday 15 September 2026  22:16:31 +0000 (0:00:00.566)       0:00:29.012 *****
2026-09-15 22:16:31.552643 | instance | ok: [instance]
2026-09-15 22:16:31.552821 | instance |
2026-09-15 22:16:31.553166 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-15 22:16:31.553474 | instance | Tuesday 15 September 2026  22:16:31 +0000 (0:00:00.326)       0:00:29.338 *****
2026-09-15 22:16:32.259432 | instance | changed: [instance]
2026-09-15 22:16:32.259590 | instance |
2026-09-15 22:16:32.259845 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-15 22:16:32.260078 | instance | Tuesday 15 September 2026  22:16:32 +0000 (0:00:00.706)       0:00:30.045 *****
2026-09-15 22:16:32.460072 | instance | ok: [instance]
2026-09-15 22:16:32.460313 | instance |
2026-09-15 22:16:32.460581 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-15 22:16:32.460838 | instance | Tuesday 15 September 2026  22:16:32 +0000 (0:00:00.200)       0:00:30.245 *****
2026-09-15 22:16:32.482806 | instance | skipping: [instance]
2026-09-15 22:16:32.483139 | instance |
2026-09-15 22:16:32.483478 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-15 22:16:32.483741 | instance | Tuesday 15 September 2026  22:16:32 +0000 (0:00:00.023)       0:00:30.269 *****
2026-09-15 22:16:33.112349 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:33.112569 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:33.112864 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:33.113135 | instance |
2026-09-15 22:16:33.113485 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-15 22:16:33.113750 | instance | Tuesday 15 September 2026  22:16:33 +0000 (0:00:00.629)       0:00:30.898 *****
2026-09-15 22:16:33.770782 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:33.771005 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:33.771272 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:33.771485 | instance |
2026-09-15 22:16:33.771725 | instance | TASK [Start loop devices] ******************************************************
2026-09-15 22:16:33.771940 | instance | Tuesday 15 September 2026  22:16:33 +0000 (0:00:00.658)       0:00:31.557 *****
2026-09-15 22:16:34.521800 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:34.522009 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:34.522288 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:34.522512 | instance |
2026-09-15 22:16:34.522750 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-15 22:16:34.523018 | instance | Tuesday 15 September 2026  22:16:34 +0000 (0:00:00.750)       0:00:32.307 *****
2026-09-15 22:16:37.828723 | instance | changed: [instance] => (item=osd0)
2026-09-15 22:16:37.828812 | instance | changed: [instance] => (item=osd1)
2026-09-15 22:16:37.828821 | instance | changed: [instance] => (item=osd2)
2026-09-15 22:16:37.828827 | instance |
2026-09-15 22:16:37.828834 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-15 22:16:37.828841 | instance | Tuesday 15 September 2026  22:16:37 +0000 (0:00:03.305)       0:00:35.613 *****
2026-09-15 22:16:39.731679 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-15 22:16:39.731779 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-15 22:16:39.732724 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-15 22:16:39.732749 | instance |
2026-09-15 22:16:39.732759 | instance | PLAY RECAP *********************************************************************
2026-09-15 22:16:39.732770 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-15 22:16:39.733402 | instance |
2026-09-15 22:16:39.733489 | instance | Tuesday 15 September 2026  22:16:39 +0000 (0:00:01.905)       0:00:37.518 *****
2026-09-15 22:16:39.733507 | instance | ===============================================================================
2026-09-15 22:16:39.733526 | instance | Install depedencies ---------------------------------------------------- 24.57s
2026-09-15 22:16:39.733935 | instance | Create a volume group for each loop device ------------------------------ 3.31s
2026-09-15 22:16:39.733960 | instance | Create a logical volume for each loop device ---------------------------- 1.91s
2026-09-15 22:16:39.735997 | instance | Gathering Facts --------------------------------------------------------- 1.31s
2026-09-15 22:16:39.736021 | instance | Purge "snapd" package --------------------------------------------------- 0.82s
2026-09-15 22:16:39.736029 | instance | Configure short hostname ------------------------------------------------ 0.75s
2026-09-15 22:16:39.736036 | instance | Start loop devices ------------------------------------------------------ 0.75s
2026-09-15 22:16:39.736042 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.71s
2026-09-15 22:16:39.736048 | instance | Gathering Facts --------------------------------------------------------- 0.70s
2026-09-15 22:16:39.736054 | instance | Set permissions on loopback devices ------------------------------------- 0.66s
2026-09-15 22:16:39.736060 | instance | Create devices for Ceph ------------------------------------------------- 0.63s
2026-09-15 22:16:39.736065 | instance | Start up service -------------------------------------------------------- 0.57s
2026-09-15 22:16:39.736071 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s
2026-09-15 22:16:39.736077 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s
2026-09-15 22:16:39.736105 | instance | Get list of all loopback devices ---------------------------------------- 0.20s
2026-09-15 22:16:39.736112 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-15 22:16:39.890324 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-15 22:16:39.904867 | instance | INFO     [csi > converge] Executing
2026-09-15 22:16:41.350362 | instance |
2026-09-15 22:16:41.350820 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-15 22:16:41.351188 | instance |
2026-09-15 22:16:41.351524 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:41.351901 | instance | Tuesday 15 September 2026  22:16:41 +0000 (0:00:00.009)       0:00:00.009 *****
2026-09-15 22:16:42.326212 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 22:16:42.326580 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 22:16:42.326885 | instance | interpreter could change the meaning of that path. See
2026-09-15 22:16:42.327212 | instance | https://docs.ansible.com/ansible-
2026-09-15 22:16:42.327551 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 22:16:42.352115 | instance | ok: [instance]
2026-09-15 22:16:42.352516 | instance |
2026-09-15 22:16:42.352838 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-15 22:16:42.353184 | instance | Tuesday 15 September 2026  22:16:42 +0000 (0:00:00.999)       0:00:01.009 *****
2026-09-15 22:16:42.413586 | instance | ok: [instance] => {
2026-09-15 22:16:42.413824 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-15 22:16:42.414015 | instance | }
2026-09-15 22:16:42.414216 | instance |
2026-09-15 22:16:42.414404 | instance | PLAY [all] *********************************************************************
2026-09-15 22:16:42.414580 | instance |
2026-09-15 22:16:42.414767 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:42.414973 | instance | Tuesday 15 September 2026  22:16:42 +0000 (0:00:00.063)       0:00:01.072 *****
2026-09-15 22:16:43.384007 | instance | ok: [instance]
2026-09-15 22:16:43.384061 | instance |
2026-09-15 22:16:43.384072 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-15 22:16:43.384100 | instance | Tuesday 15 September 2026  22:16:43 +0000 (0:00:00.968)       0:00:02.041 *****
2026-09-15 22:16:43.587509 | instance | ok: [instance]
2026-09-15 22:16:43.587585 | instance |
2026-09-15 22:16:43.587602 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-15 22:16:43.587616 | instance |
2026-09-15 22:16:43.587628 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 22:16:43.587640 | instance | Tuesday 15 September 2026  22:16:43 +0000 (0:00:00.203)       0:00:02.245 *****
2026-09-15 22:16:44.523258 | instance | ok: [instance]
2026-09-15 22:16:44.523498 | instance |
2026-09-15 22:16:44.523929 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 22:16:44.524294 | instance | Tuesday 15 September 2026  22:16:44 +0000 (0:00:00.936)       0:00:03.181 *****
2026-09-15 22:16:44.866488 | instance | ok: [instance]
2026-09-15 22:16:44.866535 | instance |
2026-09-15 22:16:44.866545 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 22:16:44.866555 | instance | Tuesday 15 September 2026  22:16:44 +0000 (0:00:00.342)       0:00:03.524 *****
2026-09-15 22:16:44.918219 | instance | skipping: [instance]
2026-09-15 22:16:44.918253 | instance |
2026-09-15 22:16:44.918258 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-15 22:16:44.918265 | instance | Tuesday 15 September 2026  22:16:44 +0000 (0:00:00.052)       0:00:03.577 *****
2026-09-15 22:16:45.221563 | instance | changed: [instance]
2026-09-15 22:16:45.221630 | instance |
2026-09-15 22:16:45.221894 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 22:16:45.221942 | instance | Tuesday 15 September 2026  22:16:45 +0000 (0:00:00.303)       0:00:03.881 *****
2026-09-15 22:16:45.313798 | instance | ok: [instance] => {
2026-09-15 22:16:45.315059 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-15 22:16:45.315136 | instance | }
2026-09-15 22:16:45.315148 | instance |
2026-09-15 22:16:45.315157 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 22:16:45.315167 | instance | Tuesday 15 September 2026  22:16:45 +0000 (0:00:00.091)       0:00:03.972 *****
2026-09-15 22:16:46.043097 | instance | changed: [instance]
2026-09-15 22:16:46.043873 | instance |
2026-09-15 22:16:46.043930 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 22:16:46.043942 | instance | Tuesday 15 September 2026  22:16:46 +0000 (0:00:00.729)       0:00:04.701 *****
2026-09-15 22:16:46.112158 | instance | skipping: [instance]
2026-09-15 22:16:46.112222 | instance |
2026-09-15 22:16:46.112593 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 22:16:46.112634 | instance | Tuesday 15 September 2026  22:16:46 +0000 (0:00:00.069)       0:00:04.771 *****
2026-09-15 22:16:46.170357 | instance | skipping: [instance]
2026-09-15 22:16:46.170444 | instance |
2026-09-15 22:16:46.170747 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 22:16:46.170794 | instance | Tuesday 15 September 2026  22:16:46 +0000 (0:00:00.057)       0:00:04.829 *****
2026-09-15 22:16:46.411673 | instance | ok: [instance]
2026-09-15 22:16:46.412360 | instance |
2026-09-15 22:16:46.412416 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 22:16:46.412428 | instance | Tuesday 15 September 2026  22:16:46 +0000 (0:00:00.241)       0:00:05.070 *****
2026-09-15 22:16:47.669059 | instance | ok: [instance]
2026-09-15 22:16:47.669259 | instance |
2026-09-15 22:16:47.669644 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 22:16:47.669941 | instance | Tuesday 15 September 2026  22:16:47 +0000 (0:00:01.257)       0:00:06.328 *****
2026-09-15 22:16:47.750620 | instance | ok: [instance] => {
2026-09-15 22:16:47.750828 | 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:47.751213 | instance | }
2026-09-15 22:16:47.751501 | instance |
2026-09-15 22:16:47.751803 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 22:16:47.752123 | instance | Tuesday 15 September 2026  22:16:47 +0000 (0:00:00.081)       0:00:06.409 *****
2026-09-15 22:16:48.505851 | instance | changed: [instance]
2026-09-15 22:16:48.506227 | instance |
2026-09-15 22:16:48.506428 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 22:16:48.506582 | instance | Tuesday 15 September 2026  22:16:48 +0000 (0:00:00.753)       0:00:07.162 *****
2026-09-15 22:16:51.515355 | instance | changed: [instance]
2026-09-15 22:16:51.515403 | instance |
2026-09-15 22:16:51.515411 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-15 22:16:51.515418 | instance | Tuesday 15 September 2026  22:16:51 +0000 (0:00:03.011)       0:00:10.174 *****
2026-09-15 22:16:51.556623 | instance | skipping: [instance]
2026-09-15 22:16:51.556670 | instance |
2026-09-15 22:16:51.556681 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-15 22:16:51.556694 | instance | Tuesday 15 September 2026  22:16:51 +0000 (0:00:00.040)       0:00:10.214 *****
2026-09-15 22:16:51.597461 | instance | skipping: [instance]
2026-09-15 22:16:51.597523 | instance |
2026-09-15 22:16:51.597540 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-15 22:16:51.597830 | instance | Tuesday 15 September 2026  22:16:51 +0000 (0:00:00.040)       0:00:10.255 *****
2026-09-15 22:16:51.638231 | instance | skipping: [instance]
2026-09-15 22:16:51.638285 | instance |
2026-09-15 22:16:51.638296 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-15 22:16:51.638306 | instance | Tuesday 15 September 2026  22:16:51 +0000 (0:00:00.040)       0:00:10.296 *****
2026-09-15 22:16:57.382551 | instance | changed: [instance]
2026-09-15 22:16:57.382665 | instance |
2026-09-15 22:16:57.382948 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-15 22:16:57.382985 | instance | Tuesday 15 September 2026  22:16:57 +0000 (0:00:05.745)       0:00:16.041 *****
2026-09-15 22:16:58.039546 | instance | changed: [instance]
2026-09-15 22:16:58.039668 | instance |
2026-09-15 22:16:58.040267 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-15 22:16:58.040332 | instance | Tuesday 15 September 2026  22:16:58 +0000 (0:00:00.657)       0:00:16.698 *****
2026-09-15 22:16:59.007766 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-15 22:16:59.007818 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-15 22:16:59.007830 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-15 22:16:59.007839 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-15 22:16:59.007849 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-15 22:16:59.007858 | instance |
2026-09-15 22:16:59.007875 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-15 22:16:59.007885 | instance | Tuesday 15 September 2026  22:16:59 +0000 (0:00:00.966)       0:00:17.664 *****
2026-09-15 22:16:59.617870 | instance | changed: [instance]
2026-09-15 22:16:59.617967 | instance |
2026-09-15 22:16:59.618752 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-15 22:16:59.618793 | instance | Tuesday 15 September 2026  22:16:59 +0000 (0:00:00.583)       0:00:18.248 *****
2026-09-15 22:16:59.618801 | instance |
2026-09-15 22:16:59.618807 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 22:16:59.618813 | instance | Tuesday 15 September 2026  22:16:59 +0000 (0:00:00.028)       0:00:18.277 *****
2026-09-15 22:17:00.517339 | instance | ok: [instance]
2026-09-15 22:17:00.518331 | instance |
2026-09-15 22:17:00.518385 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-15 22:17:00.518396 | instance | Tuesday 15 September 2026  22:17:00 +0000 (0:00:00.898)       0:00:19.175 *****
2026-09-15 22:17:01.026111 | instance | changed: [instance]
2026-09-15 22:17:01.026834 | instance |
2026-09-15 22:17:01.026885 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-15 22:17:01.026897 | instance | Tuesday 15 September 2026  22:17:01 +0000 (0:00:00.509)       0:00:19.684 *****
2026-09-15 22:17:01.647735 | instance | changed: [instance]
2026-09-15 22:17:01.648248 | instance |
2026-09-15 22:17:01.648273 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 22:17:01.648282 | instance | Tuesday 15 September 2026  22:17:01 +0000 (0:00:00.622)       0:00:20.307 *****
2026-09-15 22:17:01.879126 | instance | ok: [instance]
2026-09-15 22:17:01.879203 | instance |
2026-09-15 22:17:01.879543 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 22:17:01.879582 | instance | Tuesday 15 September 2026  22:17:01 +0000 (0:00:00.231)       0:00:20.538 *****
2026-09-15 22:17:01.926940 | instance | ok: [instance] => {
2026-09-15 22:17:01.926999 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-15 22:17:01.927241 | instance | }
2026-09-15 22:17:01.927355 | instance |
2026-09-15 22:17:01.927364 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 22:17:01.927471 | instance | Tuesday 15 September 2026  22:17:01 +0000 (0:00:00.047)       0:00:20.586 *****
2026-09-15 22:17:02.843156 | instance | changed: [instance]
2026-09-15 22:17:02.844029 | instance |
2026-09-15 22:17:02.844243 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 22:17:02.844258 | instance | Tuesday 15 September 2026  22:17:02 +0000 (0:00:00.915)       0:00:21.501 *****
2026-09-15 22:17:07.210397 | instance | changed: [instance]
2026-09-15 22:17:07.211096 | instance |
2026-09-15 22:17:07.211173 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-15 22:17:07.211186 | instance | Tuesday 15 September 2026  22:17:07 +0000 (0:00:04.367)       0:00:25.869 *****
2026-09-15 22:17:08.352375 | instance | ok: [instance]
2026-09-15 22:17:08.353068 | instance |
2026-09-15 22:17:08.353135 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-15 22:17:08.353147 | instance | Tuesday 15 September 2026  22:17:08 +0000 (0:00:01.141)       0:00:27.011 *****
2026-09-15 22:17:08.706435 | instance | changed: [instance]
2026-09-15 22:17:08.707173 | instance |
2026-09-15 22:17:08.707230 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-15 22:17:08.707245 | instance | Tuesday 15 September 2026  22:17:08 +0000 (0:00:00.353)       0:00:27.365 *****
2026-09-15 22:17:09.195492 | instance | changed: [instance]
2026-09-15 22:17:09.196207 | instance |
2026-09-15 22:17:09.196262 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-15 22:17:09.196274 | instance | Tuesday 15 September 2026  22:17:09 +0000 (0:00:00.488)       0:00:27.854 *****
2026-09-15 22:17:09.790504 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-15 22:17:09.791956 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-15 22:17:09.791992 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-15 22:17:09.792003 | instance |
2026-09-15 22:17:09.792013 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-15 22:17:09.792022 | instance | Tuesday 15 September 2026  22:17:09 +0000 (0:00:00.595)       0:00:28.449 *****
2026-09-15 22:17:10.280914 | instance | changed: [instance]
2026-09-15 22:17:10.281019 | instance |
2026-09-15 22:17:10.281408 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-15 22:17:10.281478 | instance | Tuesday 15 September 2026  22:17:10 +0000 (0:00:00.491)       0:00:28.940 *****
2026-09-15 22:17:10.724182 | instance | changed: [instance]
2026-09-15 22:17:10.724289 | instance |
2026-09-15 22:17:10.724351 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-15 22:17:10.724788 | instance | Tuesday 15 September 2026  22:17:10 +0000 (0:00:00.427)       0:00:29.368 *****
2026-09-15 22:17:10.724825 | instance |
2026-09-15 22:17:10.724831 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 22:17:10.724835 | instance | Tuesday 15 September 2026  22:17:10 +0000 (0:00:00.015)       0:00:29.383 *****
2026-09-15 22:17:11.462726 | instance | ok: [instance]
2026-09-15 22:17:11.463220 | instance |
2026-09-15 22:17:11.463265 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-15 22:17:11.463280 | instance | Tuesday 15 September 2026  22:17:11 +0000 (0:00:00.738)       0:00:30.122 *****
2026-09-15 22:17:12.471468 | instance | changed: [instance]
2026-09-15 22:17:12.472094 | instance |
2026-09-15 22:17:12.472118 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-15 22:17:12.472145 | instance | Tuesday 15 September 2026  22:17:12 +0000 (0:00:01.008)       0:00:31.130 *****
2026-09-15 22:17:13.120672 | instance | changed: [instance]
2026-09-15 22:17:13.120715 | instance |
2026-09-15 22:17:13.120725 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-15 22:17:13.120733 | instance | Tuesday 15 September 2026  22:17:13 +0000 (0:00:00.648)       0:00:31.779 *****
2026-09-15 22:17:13.204383 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-15 22:17:13.204435 | instance |
2026-09-15 22:17:13.204448 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-15 22:17:13.204458 | instance | Tuesday 15 September 2026  22:17:13 +0000 (0:00:00.083)       0:00:31.862 *****
2026-09-15 22:17:18.612938 | instance | changed: [instance]
2026-09-15 22:17:18.613118 | instance |
2026-09-15 22:17:18.613418 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-15 22:17:18.613460 | instance | Tuesday 15 September 2026  22:17:18 +0000 (0:00:05.409)       0:00:37.272 *****
2026-09-15 22:17:19.304420 | instance | ok: [instance] => (item=chronyd)
2026-09-15 22:17:19.305074 | instance | ok: [instance] => (item=sshd)
2026-09-15 22:17:19.305093 | instance |
2026-09-15 22:17:19.305100 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-15 22:17:19.305106 | instance | Tuesday 15 September 2026  22:17:19 +0000 (0:00:00.691)       0:00:37.963 *****
2026-09-15 22:17:52.839177 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: TimeoutError: The read operation timed out
2026-09-15 22:17:52.839993 | instance | fatal: [instance]: FAILED! => {"changed": false, "elapsed": 0, "msg": "failed to create temporary content file: The read operation timed out"}
2026-09-15 22:17:52.842746 | instance |
2026-09-15 22:17:52.845466 | instance | PLAY RECAP *********************************************************************
2026-09-15 22:17:52.845556 | instance | instance                   : ok=37   changed=20   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-15 22:17:52.845570 | instance |
2026-09-15 22:17:52.845580 | instance | Tuesday 15 September 2026  22:17:52 +0000 (0:00:33.538)       0:01:11.502 *****
2026-09-15 22:17:52.845591 | instance | ===============================================================================
2026-09-15 22:17:52.845600 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 33.54s
2026-09-15 22:17:52.845610 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.75s
2026-09-15 22:17:52.845620 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.41s
2026-09-15 22:17:52.845631 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.37s
2026-09-15 22:17:52.845640 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.01s
2026-09-15 22:17:52.845649 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.64s
2026-09-15 22:17:52.845660 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.26s
2026-09-15 22:17:52.845669 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.14s
2026-09-15 22:17:52.845679 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.01s
2026-09-15 22:17:52.845689 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-09-15 22:17:52.845698 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-15 22:17:52.845734 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.97s
2026-09-15 22:17:52.845743 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-09-15 22:17:52.845752 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.92s
2026-09-15 22:17:52.845760 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.75s
2026-09-15 22:17:52.845769 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.73s
2026-09-15 22:17:52.845777 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.69s
2026-09-15 22:17:52.845786 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.66s
2026-09-15 22:17:52.845795 | instance | vexxhost.containers.docker : Enable and start service ------------------- 0.65s
2026-09-15 22:17:52.845813 | instance | vexxhost.containers.containerd : Enable and start service --------------- 0.62s
2026-09-15 22:17:52.973375 | 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:52.973432 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-15 22:17:52.973438 | 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:53.367882 | instance | ERROR
2026-09-15 22:17:53.368112 | instance | {
2026-09-15 22:17:53.368150 | instance |   "delta": "0:02:04.950846",
2026-09-15 22:17:53.368177 | instance |   "end": "2026-09-15 22:17:53.041953",
2026-09-15 22:17:53.368200 | instance |   "msg": "non-zero return code",
2026-09-15 22:17:53.368223 | instance |   "rc": 2,
2026-09-15 22:17:53.368246 | instance |   "start": "2026-09-15 22:15:48.091107"
2026-09-15 22:17:53.368269 | instance | }
failure
2026-09-15 22:17:53.373387 | 
2026-09-15 22:17:53.373482 | PLAY RECAP
2026-09-15 22:17:53.373544 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 22:17:53.373579 | 
2026-09-15 22:17:53.524311 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 22:17:53.528482 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 22:17:54.181319 | 
2026-09-15 22:17:54.181493 | PLAY [all]
2026-09-15 22:17:54.197083 | 
2026-09-15 22:17:54.197242 | TASK [gather-host-logs : creating directory for system status]
2026-09-15 22:17:54.575469 | instance | changed
2026-09-15 22:17:54.586375 | 
2026-09-15 22:17:54.586558 | TASK [gather-host-logs : Get logs for each host]
2026-09-15 22:17:54.996082 | instance | + systemd-cgls --full --all --no-pager
2026-09-15 22:17:55.009721 | instance | + ip addr
2026-09-15 22:17:55.012294 | instance | + ip route
2026-09-15 22:17:55.015185 | instance | + lsblk
2026-09-15 22:17:55.020284 | instance | + mount
2026-09-15 22:17:55.023115 | instance | + docker images
2026-09-15 22:17:55.046472 | instance | + brctl show
2026-09-15 22:17:55.046963 | instance | /bin/bash: line 8: brctl: command not found
2026-09-15 22:17:55.047241 | instance | + ip neigh
2026-09-15 22:17:55.050086 | instance | + bridge fdb show
2026-09-15 22:17:55.054011 | instance | + ps aux --sort=-%mem
2026-09-15 22:17:55.071342 | instance | + dpkg -l
2026-09-15 22:17:55.084409 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-15 22:17:55.084953 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-15 22:17:55.107565 | instance | + '[' '!' -z '' ']'
2026-09-15 22:17:55.167354 | instance | ok: Runtime: 0:00:00.116779
2026-09-15 22:17:55.181046 | 
2026-09-15 22:17:55.181461 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-15 22:17:55.925718 | instance | changed:
2026-09-15 22:17:55.925920 | instance | created directory /var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/work/logs/instance
2026-09-15 22:17:55.925958 | instance | cd+++++++++ system/
2026-09-15 22:17:55.925980 | instance | >f+++++++++ system/brctl-show.txt
2026-09-15 22:17:55.926000 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-15 22:17:55.926018 | instance | >f+++++++++ system/docker-images.txt
2026-09-15 22:17:55.926039 | instance | >f+++++++++ system/ip-addr.txt
2026-09-15 22:17:55.926058 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-15 22:17:55.926076 | instance | >f+++++++++ system/ip-route.txt
2026-09-15 22:17:55.926097 | instance | >f+++++++++ system/lsblk.txt
2026-09-15 22:17:55.926114 | instance | >f+++++++++ system/mount.txt
2026-09-15 22:17:55.926132 | instance | >f+++++++++ system/packages.txt
2026-09-15 22:17:55.926151 | instance | >f+++++++++ system/ps.txt
2026-09-15 22:17:55.926178 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-15 22:17:55.934990 | 
2026-09-15 22:17:55.989097 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 22:17:56.216884 | instance | changed: "values"
2026-09-15 22:17:56.412347 | instance | changed: "releases"
2026-09-15 22:17:56.496087 | 
2026-09-15 22:17:56.496344 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 22:17:56.799621 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-15 22:17:57.052563 | instance | ok: Runtime: 0:00:00.005604
2026-09-15 22:17:57.060019 | 
2026-09-15 22:17:57.071677 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 22:17:57.586042 | instance | changed:
2026-09-15 22:17:57.622544 | instance | cd+++++++++ helm/
2026-09-15 22:17:57.622654 | instance | cd+++++++++ helm/releases/
2026-09-15 22:17:57.622687 | instance | cd+++++++++ helm/values/
2026-09-15 22:17:57.631081 | 
2026-09-15 22:17:57.631148 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 22:17:57.842931 | instance | changed
2026-09-15 22:17:57.850916 | 
2026-09-15 22:17:57.851046 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 22:17:58.132461 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:58.132561 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:58.138138 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:58.139264 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:58.139292 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:58.140633 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:58.142513 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:58.143408 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:58.145127 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:58.146343 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:58.147509 | instance | environment: line 1: kubectl: command not found
2026-09-15 22:17:58.149175 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 22:17:58.383828 | instance | ok: Runtime: 0:00:00.025090
2026-09-15 22:17:58.388021 | 
2026-09-15 22:17:58.388080 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 22:17:58.613910 | instance | changed
2026-09-15 22:17:58.624280 | 
2026-09-15 22:17:58.624461 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 22:17:58.896889 | instance | environment: line 5: kubectl: command not found
2026-09-15 22:17:58.897458 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:58.897781 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:58.898426 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 22:17:59.169744 | instance | ok: Runtime: 0:00:00.007772
2026-09-15 22:17:59.179988 | 
2026-09-15 22:17:59.180116 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 22:17:59.727211 | instance | changed:
2026-09-15 22:17:59.727663 | instance | cd+++++++++ objects/
2026-09-15 22:17:59.727719 | instance | cd+++++++++ objects/cluster/
2026-09-15 22:17:59.727748 | instance | cd+++++++++ objects/namespaced/
2026-09-15 22:17:59.747347 | 
2026-09-15 22:17:59.747461 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 22:17:59.942867 | instance | changed
2026-09-15 22:18:00.079801 | 
2026-09-15 22:18:00.080011 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 22:18:00.301737 | instance | changed
2026-09-15 22:18:00.311921 | 
2026-09-15 22:18:00.312064 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 22:18:00.583760 | instance | environment: line 3: kubectl: command not found
2026-09-15 22:18:00.880320 | instance | ok: Runtime: 0:00:00.006238
2026-09-15 22:18:00.915951 | 
2026-09-15 22:18:00.916100 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 22:18:01.479258 | instance | changed:
2026-09-15 22:18:01.479558 | instance | cd+++++++++ pod-logs/
2026-09-15 22:18:01.479617 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-15 22:18:01.499738 | 
2026-09-15 22:18:01.499925 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-15 22:18:01.713938 | instance | changed
2026-09-15 22:18:01.721004 | 
2026-09-15 22:18:01.721089 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-15 22:18:01.973151 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 22:18:02.263809 | instance | ok: Runtime: 0:00:00.053366
2026-09-15 22:18:02.275448 | 
2026-09-15 22:18:02.275683 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-15 22:18:02.529636 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 22:18:02.576173 | instance | ceph-mgr endpoints:
2026-09-15 22:18:02.827765 | instance | ok: Runtime: 0:00:00.052727
2026-09-15 22:18:02.837601 | 
2026-09-15 22:18:02.837741 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-15 22:18:03.105998 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-15 22:18:03.387353 | instance | ok: Runtime: 0:00:00.056176
2026-09-15 22:18:03.397441 | 
2026-09-15 22:18:03.397580 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-15 22:18:03.891437 | instance | changed: cd+++++++++ prometheus/
2026-09-15 22:18:03.913913 | 
2026-09-15 22:18:03.914131 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-15 22:18:04.133271 | instance | changed
2026-09-15 22:18:04.141359 | 
2026-09-15 22:18:04.141595 | TASK [gather-selenium-data : Get selenium data]
2026-09-15 22:18:04.449596 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-15 22:18:04.451092 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-15 22:18:04.685759 | instance | ERROR
2026-09-15 22:18:04.686029 | instance | {
2026-09-15 22:18:04.686088 | instance |   "delta": "0:00:00.006080",
2026-09-15 22:18:04.686129 | instance |   "end": "2026-09-15 22:18:04.451458",
2026-09-15 22:18:04.686167 | instance |   "msg": "non-zero return code",
2026-09-15 22:18:04.686203 | instance |   "rc": 1,
2026-09-15 22:18:04.686238 | instance |   "start": "2026-09-15 22:18:04.445378"
2026-09-15 22:18:04.686272 | instance | }
2026-09-15 22:18:04.686317 | instance | ERROR: Ignoring Errors
2026-09-15 22:18:04.695862 | 
2026-09-15 22:18:04.696004 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-15 22:18:05.227920 | instance | changed: cd+++++++++ selenium/
2026-09-15 22:18:05.239454 | 
2026-09-15 22:18:05.239592 | PLAY RECAP
2026-09-15 22:18:05.239710 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-15 22:18:05.239777 | 
2026-09-15 22:18:05.378881 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 22:18:05.382456 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 22:18:06.008334 | 
2026-09-15 22:18:06.008474 | PLAY [all]
2026-09-15 22:18:06.019419 | 
2026-09-15 22:18:06.019523 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 22:18:06.083343 | instance | skipping: Conditional result was False
2026-09-15 22:18:06.089927 | 
2026-09-15 22:18:06.090034 | TASK [fetch-output : Set log path for single node]
2026-09-15 22:18:06.134310 | instance | ok
2026-09-15 22:18:06.143107 | 
2026-09-15 22:18:06.143245 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 22:18:06.708751 | instance -> localhost | ok: "/var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/work/logs"
2026-09-15 22:18:06.937229 | instance -> localhost | changed: "/var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/work/artifacts"
2026-09-15 22:18:07.211873 | instance -> localhost | changed: "/var/lib/zuul/builds/8ea68a13f636487b8de0d4253f4d6c3a/work/docs"
2026-09-15 22:18:07.231977 | 
2026-09-15 22:18:07.232175 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 22:18:08.065336 | instance | changed: .d..t...... ./
2026-09-15 22:18:08.065920 | instance | changed: All items complete
2026-09-15 22:18:08.065997 | 
2026-09-15 22:18:08.509135 | instance | changed: .d..t...... ./
2026-09-15 22:18:09.033247 | instance | changed: .d..t...... ./
2026-09-15 22:18:09.141526 | 
2026-09-15 22:18:09.141642 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 22:18:09.625682 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008971
2026-09-15 22:18:09.993837 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008690
2026-09-15 22:18:10.019502 | 
2026-09-15 22:18:10.019694 | PLAY [all]
2026-09-15 22:18:10.029726 | 
2026-09-15 22:18:10.029839 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 22:18:10.472660 | instance | changed
2026-09-15 22:18:10.483114 | 
2026-09-15 22:18:10.483250 | PLAY RECAP
2026-09-15 22:18:10.483340 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 22:18:10.483383 | 
2026-09-15 22:18:10.634604 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 22:18:10.639131 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 22:18:11.370452 | 
2026-09-15 22:18:11.370568 | PLAY [localhost]
2026-09-15 22:18:11.379709 | 
2026-09-15 22:18:11.379779 | TASK [Generate Zuul manifest]
2026-09-15 22:18:11.395767 | localhost | ok
2026-09-15 22:18:11.411382 | 
2026-09-15 22:18:11.411502 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 22:18:11.853899 | localhost | changed
2026-09-15 22:18:11.863815 | 
2026-09-15 22:18:11.863936 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 22:18:11.902934 | localhost | ok
2026-09-15 22:18:11.911875 | 
2026-09-15 22:18:11.911995 | TASK [Upload logs]
2026-09-15 22:18:11.935197 | localhost | ok
2026-09-15 22:18:11.995304 | 
2026-09-15 22:18:12.177479 | TASK [Set zuul-log-path fact]
2026-09-15 22:18:12.199706 | localhost | ok
2026-09-15 22:18:12.224049 | 
2026-09-15 22:18:12.224246 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 22:18:12.266699 | localhost | ok
2026-09-15 22:18:12.280686 | 
2026-09-15 22:18:12.280896 | TASK [upload-logs : Create log directories]
2026-09-15 22:18:12.800579 | localhost | changed
2026-09-15 22:18:12.814288 | 
2026-09-15 22:18:12.814543 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 22:18:13.269676 | localhost -> localhost | ok: Runtime: 0:00:00.005203
2026-09-15 22:18:13.281662 | 
2026-09-15 22:18:13.281874 | TASK [upload-logs : Upload logs to log server]
2026-09-15 22:18:14.019914 | localhost | Output suppressed because no_log was given
2026-09-15 22:18:14.086593 | 
2026-09-15 22:18:14.086841 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 22:18:14.160837 | localhost | skipping: Conditional result was False
2026-09-15 22:18:14.168753 | localhost | skipping: Conditional result was False
2026-09-15 22:18:14.178315 | 
2026-09-15 22:18:14.178498 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 22:18:14.234481 | localhost | skipping: Conditional result was False
2026-09-15 22:18:14.234826 | 
2026-09-15 22:18:14.241392 | localhost | skipping: Conditional result was False
2026-09-15 22:18:14.248929 | 
2026-09-15 22:18:14.249099 | LOOP [upload-logs : Upload console log and json output]