2026-09-07 10:40:57.971638 | Job console starting
2026-09-07 10:40:57.986043 | Updating git repos
2026-09-07 10:41:04.434727 | Cloning repos into workspace
2026-09-07 10:41:21.444934 | Restoring repo states
2026-09-07 10:41:34.028915 | Merging changes
2026-09-07 10:41:35.646005 | Checking out repos
2026-09-07 10:41:38.794520 | Preparing playbooks
2026-09-07 10:41:49.741014 | Running Ansible setup
2026-09-07 10:41:53.128548 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 10:41:53.662432 | 
2026-09-07 10:41:53.662555 | PLAY [localhost]
2026-09-07 10:41:53.669682 | 
2026-09-07 10:41:53.669768 | TASK [Gathering Facts]
2026-09-07 10:41:54.592924 | localhost | ok
2026-09-07 10:41:54.617443 | 
2026-09-07 10:41:54.617642 | TASK [Setup log path fact]
2026-09-07 10:41:54.666122 | localhost | ok
2026-09-07 10:41:54.691957 | 
2026-09-07 10:41:54.692121 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 10:41:54.731266 | localhost | ok
2026-09-07 10:41:54.740680 | 
2026-09-07 10:41:54.740893 | TASK [emit-job-header : Print job information]
2026-09-07 10:41:54.789107 | # Job Information
2026-09-07 10:41:54.789258 | Ansible Version: 2.16.18
2026-09-07 10:41:54.789295 | Job: atmosphere-molecule-csi-rbd
2026-09-07 10:41:54.789322 | Pipeline: check
2026-09-07 10:41:54.789342 | Executor: 2d72f0692154
2026-09-07 10:41:54.789364 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4095
2026-09-07 10:41:54.789386 | Event ID: 1b65a570-aaa8-11f1-8ba3-90f03bcde550
2026-09-07 10:41:54.792004 | 
2026-09-07 10:41:54.792082 | LOOP [emit-job-header : Print node information]
2026-09-07 10:41:54.890306 | localhost | ok:
2026-09-07 10:41:54.890576 | localhost | # Node Information
2026-09-07 10:41:54.890639 | localhost | Inventory Hostname: instance
2026-09-07 10:41:54.890687 | localhost | Hostname: np0000192132
2026-09-07 10:41:54.890760 | localhost | Username: zuul
2026-09-07 10:41:54.890813 | localhost | Distro: Ubuntu 22.04
2026-09-07 10:41:54.890854 | localhost | Provider: yul1
2026-09-07 10:41:54.890893 | localhost | Region: ca-ymq-1
2026-09-07 10:41:54.890933 | localhost | Label: ubuntu-jammy
2026-09-07 10:41:54.890971 | localhost | Product Name: OpenStack Nova
2026-09-07 10:41:54.891009 | localhost | Interface IP: 162.253.55.173
2026-09-07 10:41:54.903377 | 
2026-09-07 10:41:54.903483 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 10:41:55.289651 | localhost -> localhost | changed
2026-09-07 10:41:55.294220 | 
2026-09-07 10:41:55.294281 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 10:41:56.163248 | localhost -> localhost | changed
2026-09-07 10:41:56.173396 | 
2026-09-07 10:41:56.173515 | PLAY [all]
2026-09-07 10:41:56.183061 | 
2026-09-07 10:41:56.183165 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 10:41:56.439959 | instance -> localhost | ok
2026-09-07 10:41:56.450385 | 
2026-09-07 10:41:56.450510 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 10:41:56.484350 | instance | ok
2026-09-07 10:41:56.512588 | instance | included: /var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 10:41:56.522485 | 
2026-09-07 10:41:56.522589 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 10:41:57.262901 | instance -> localhost | Generating public/private rsa key pair.
2026-09-07 10:41:57.263130 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/work/fecb4a075cff488685b4a95552a3816d_id_rsa
2026-09-07 10:41:57.263189 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/work/fecb4a075cff488685b4a95552a3816d_id_rsa.pub
2026-09-07 10:41:57.263234 | instance -> localhost | The key fingerprint is:
2026-09-07 10:41:57.263276 | instance -> localhost | SHA256:g5WDbEbOlnfECdsAT/YsDvJB6UgMoM8YnET65DqJJLk zuul-build-sshkey
2026-09-07 10:41:57.263640 | instance -> localhost | The key's randomart image is:
2026-09-07 10:41:57.263739 | instance -> localhost | +---[RSA 3072]----+
2026-09-07 10:41:57.263800 | instance -> localhost | |o+.o  +o=o..     |
2026-09-07 10:41:57.263844 | instance -> localhost | |= . o*.* Oo      |
2026-09-07 10:41:57.263883 | instance -> localhost | |+o...o% O =      |
2026-09-07 10:41:57.263922 | instance -> localhost | | X  .*.B +       |
2026-09-07 10:41:57.263960 | instance -> localhost | |+.=   o S        |
2026-09-07 10:41:57.263997 | instance -> localhost | |++       .       |
2026-09-07 10:41:57.264035 | instance -> localhost | |E                |
2026-09-07 10:41:57.264073 | instance -> localhost | | .               |
2026-09-07 10:41:57.264117 | instance -> localhost | |                 |
2026-09-07 10:41:57.264155 | instance -> localhost | +----[SHA256]-----+
2026-09-07 10:41:57.264240 | instance -> localhost | ok: Runtime: 0:00:00.320114
2026-09-07 10:41:57.275370 | 
2026-09-07 10:41:57.275491 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 10:41:57.298435 | instance | ok
2026-09-07 10:41:57.314381 | instance | included: /var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 10:41:57.324978 | 
2026-09-07 10:41:57.325082 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 10:41:57.338676 | instance | skipping: Conditional result was False
2026-09-07 10:41:57.349188 | 
2026-09-07 10:41:57.349316 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 10:41:57.789703 | instance | changed
2026-09-07 10:41:57.800774 | 
2026-09-07 10:41:57.800905 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 10:41:58.025578 | instance | ok
2026-09-07 10:41:58.035444 | 
2026-09-07 10:41:58.035568 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 10:41:58.556134 | instance | changed
2026-09-07 10:41:58.565974 | 
2026-09-07 10:41:58.566110 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 10:41:59.098116 | instance | changed
2026-09-07 10:41:59.113748 | 
2026-09-07 10:41:59.113927 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 10:41:59.138568 | instance | skipping: Conditional result was False
2026-09-07 10:41:59.150553 | 
2026-09-07 10:41:59.150745 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 10:41:59.562961 | instance -> localhost | changed
2026-09-07 10:41:59.581834 | 
2026-09-07 10:41:59.582018 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 10:41:59.930460 | instance -> localhost | Identity added: /var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/work/fecb4a075cff488685b4a95552a3816d_id_rsa (zuul-build-sshkey)
2026-09-07 10:41:59.930773 | instance -> localhost | ok: Runtime: 0:00:00.009776
2026-09-07 10:41:59.940196 | 
2026-09-07 10:41:59.940320 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 10:42:00.269540 | instance | ok
2026-09-07 10:42:00.276824 | 
2026-09-07 10:42:00.276917 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 10:42:00.310319 | instance | skipping: Conditional result was False
2026-09-07 10:42:00.327638 | 
2026-09-07 10:42:00.327792 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 10:42:00.674670 | instance | ok
2026-09-07 10:42:00.686363 | 
2026-09-07 10:42:00.686548 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 10:42:04.839101 | instance | Output suppressed because no_log was given
2026-09-07 10:42:04.846433 | 
2026-09-07 10:42:04.846491 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 10:42:05.027073 | instance | ok: "logs"
2026-09-07 10:42:05.027340 | instance | ok: All items complete
2026-09-07 10:42:05.027383 | 
2026-09-07 10:42:05.168664 | instance | ok: "artifacts"
2026-09-07 10:42:05.317171 | instance | ok: "docs"
2026-09-07 10:42:05.339427 | 
2026-09-07 10:42:05.339653 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 10:42:05.531312 | instance | changed: "logs"
2026-09-07 10:42:05.663521 | instance | changed: "artifacts"
2026-09-07 10:42:05.809352 | instance | changed: "docs"
2026-09-07 10:42:05.828309 | 
2026-09-07 10:42:05.828751 | PLAY RECAP
2026-09-07 10:42:05.829173 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 10:42:05.829215 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:42:05.829271 | 
2026-09-07 10:42:06.112966 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 10:42:06.117159 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 10:42:06.756034 | 
2026-09-07 10:42:06.756161 | PLAY [all]
2026-09-07 10:42:06.766385 | 
2026-09-07 10:42:06.766490 | TASK [setup-uv : Extract archive]
2026-09-07 10:42:08.960320 | instance | changed
2026-09-07 10:42:08.972100 | 
2026-09-07 10:42:08.972261 | TASK [setup-uv : Print version]
2026-09-07 10:42:09.316614 | instance | uv 0.8.13
2026-09-07 10:42:09.521090 | instance | ok: Runtime: 0:00:00.012160
2026-09-07 10:42:09.543609 | 
2026-09-07 10:42:09.543906 | TASK [Install Ansible collection siblings]
2026-09-07 10:42:19.231270 | instance | changed:
2026-09-07 10:42:19.231404 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-07 10:42:19.231431 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-07 10:42:19.231450 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-07 10:42:19.231468 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-07 10:42:19.231485 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-07 10:42:19.231505 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-07 10:42:19.231523 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-07 10:42:19.231542 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-07 10:42:19.231559 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-07 10:42:19.231576 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-07 10:42:19.231592 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-07 10:42:19.231608 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-07 10:42:19.231624 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-07 10:42:19.231640 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-07 10:42:19.231656 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-07 10:42:19.231673 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-07 10:42:19.231689 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-07 10:42:19.231705 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-07 10:42:19.231735 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-07 10:42:19.231752 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-07 10:42:19.231768 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-07 10:42:19.231783 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-07 10:42:19.231800 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-07 10:42:19.231826 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-07 10:42:19.231843 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-07 10:42:19.231860 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-07 10:42:19.231875 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-07 10:42:19.231891 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-07 10:42:19.231906 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-07 10:42:19.231922 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-07 10:42:19.231937 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-07 10:42:19.231953 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-07 10:42:19.231969 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-07 10:42:19.231985 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-07 10:42:19.232001 | 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-07 10:42:19.232022 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.232038 | instance | Process install dependency map
2026-09-07 10:42:19.232054 | instance | Starting collection install process
2026-09-07 10:42:19.232069 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-07 10:42:19.232085 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-07 10:42:19.232101 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-07 10:42:19.232116 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-07 10:42:19.232131 | instance | Creating virtual environment at: .venv
2026-09-07 10:42:19.232147 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-07 10:42:19.232163 | instance | Downloading openstacksdk (1.7MiB)
2026-09-07 10:42:19.232180 | instance | Downloading pygments (1.2MiB)
2026-09-07 10:42:19.232196 | instance | Downloading netaddr (2.2MiB)
2026-09-07 10:42:19.232212 | instance | Downloading rjsonnet (1.2MiB)
2026-09-07 10:42:19.232228 | instance | Downloading kubernetes (1.9MiB)
2026-09-07 10:42:19.232244 | instance | Downloading cryptography (4.2MiB)
2026-09-07 10:42:19.232259 | instance | Downloading ansible-core (2.1MiB)
2026-09-07 10:42:19.232275 | instance | Downloading setuptools (1.1MiB)
2026-09-07 10:42:19.232291 | instance |    Building pyperclip==1.9.0
2026-09-07 10:42:19.232308 | instance |  Downloading rjsonnet
2026-09-07 10:42:19.232324 | instance |  Downloading netaddr
2026-09-07 10:42:19.232340 | instance |  Downloading pygments
2026-09-07 10:42:19.232356 | instance |  Downloading cryptography
2026-09-07 10:42:19.232371 | instance |  Downloading setuptools
2026-09-07 10:42:19.232386 | instance |  Downloading kubernetes
2026-09-07 10:42:19.232401 | instance |  Downloading ansible-core
2026-09-07 10:42:19.232417 | instance |  Downloading openstacksdk
2026-09-07 10:42:19.232432 | instance |       Built pyperclip==1.9.0
2026-09-07 10:42:19.232448 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-07 10:42:19.232464 | instance | Installed 79 packages in 82ms
2026-09-07 10:42:19.232480 | instance | Checked out ansible.posix to 1.6.0
2026-09-07 10:42:19.232540 | 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-07 10:42:19.232703 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.232812 | instance | Process install dependency map
2026-09-07 10:42:19.233509 | instance | Starting collection install process
2026-09-07 10:42:19.233676 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 10:42:19.233764 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-07 10:42:19.233798 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-07 10:42:19.233913 | instance | Checked out ansible.utils to v6.1.0
2026-09-07 10:42:19.233979 | 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-07 10:42:19.234125 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.234162 | instance | Process install dependency map
2026-09-07 10:42:19.234191 | instance | Starting collection install process
2026-09-07 10:42:19.234213 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-07 10:42:19.234267 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-07 10:42:19.234300 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-07 10:42:19.234395 | instance | Checked out atmosphere.common to v0.6.0
2026-09-07 10:42:19.234427 | 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-07 10:42:19.234475 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.234503 | instance | Process install dependency map
2026-09-07 10:42:19.234526 | instance | Starting collection install process
2026-09-07 10:42:19.234605 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-07 10:42:19.234737 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-07 10:42:19.234775 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-07 10:42:19.234802 | instance | Checked out community.crypto to 2.2.3
2026-09-07 10:42:19.234829 | 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-07 10:42:19.234856 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.234882 | instance | Process install dependency map
2026-09-07 10:42:19.234908 | instance | Starting collection install process
2026-09-07 10:42:19.234934 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-07 10:42:19.234956 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-07 10:42:19.234981 | instance | community.crypto:2.2.3 was installed successfully
2026-09-07 10:42:19.235006 | instance | Checked out community.general to 7.3.0
2026-09-07 10:42:19.235032 | 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-07 10:42:19.235055 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.235136 | instance | Process install dependency map
2026-09-07 10:42:19.235247 | instance | Starting collection install process
2026-09-07 10:42:19.235342 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 10:42:19.235375 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-07 10:42:19.235598 | instance | community.general:7.3.0 was installed successfully
2026-09-07 10:42:19.235701 | instance | Checked out community.mysql to 3.6.0
2026-09-07 10:42:19.235776 | 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-07 10:42:19.235894 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.236071 | instance | Process install dependency map
2026-09-07 10:42:19.236331 | instance | Starting collection install process
2026-09-07 10:42:19.236396 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-07 10:42:19.236426 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-07 10:42:19.236450 | instance | community.mysql:3.6.0 was installed successfully
2026-09-07 10:42:19.236471 | instance | Checked out kubernetes.core to 2.4.0
2026-09-07 10:42:19.236511 | 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-07 10:42:19.236537 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.236558 | instance | Process install dependency map
2026-09-07 10:42:19.236604 | instance | Starting collection install process
2026-09-07 10:42:19.236640 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-07 10:42:19.236765 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-07 10:42:19.236806 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-07 10:42:19.236836 | instance | Checked out openstack.cloud to 1.7.0
2026-09-07 10:42:19.236864 | 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-07 10:42:19.236892 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.236949 | instance | Process install dependency map
2026-09-07 10:42:19.237010 | instance | Starting collection install process
2026-09-07 10:42:19.237070 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-07 10:42:19.237101 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-07 10:42:19.237128 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-07 10:42:19.237150 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-07 10:42:19.237218 | 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-07 10:42:19.237314 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.237398 | instance | Process install dependency map
2026-09-07 10:42:19.237470 | instance | Starting collection install process
2026-09-07 10:42:19.237668 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-07 10:42:19.237845 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-07 10:42:19.237881 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-07 10:42:19.237906 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-07 10:42:19.237926 | 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-07 10:42:19.237947 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.237980 | instance | Process install dependency map
2026-09-07 10:42:19.238008 | instance | Starting collection install process
2026-09-07 10:42:19.238036 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-07 10:42:19.238963 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-07 10:42:19.239138 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-07 10:42:19.239194 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-07 10:42:19.239261 | 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-07 10:42:19.239332 | instance | Starting galaxy collection install process
2026-09-07 10:42:19.239402 | instance | Process install dependency map
2026-09-07 10:42:19.239459 | instance | Starting collection install process
2026-09-07 10:42:19.239488 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-07 10:42:19.239564 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-07 10:42:19.239617 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-07 10:42:19.253928 | 
2026-09-07 10:42:19.254094 | PLAY RECAP
2026-09-07 10:42:19.254203 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:42:19.254270 | 
2026-09-07 10:42:19.396877 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 10:42:19.425162 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-07 10:42:20.068708 | 
2026-09-07 10:42:20.068850 | PLAY [all]
2026-09-07 10:42:20.081404 | 
2026-09-07 10:42:20.081483 | TASK [Install "jq" for log collection]
2026-09-07 10:42:31.115515 | instance | changed
2026-09-07 10:42:31.118897 | 
2026-09-07 10:42:31.119018 | PLAY RECAP
2026-09-07 10:42:31.119120 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:42:31.119223 | 
2026-09-07 10:42:31.289527 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-07 10:42:31.293479 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 10:42:31.949979 | 
2026-09-07 10:42:31.950280 | PLAY [all]
2026-09-07 10:42:31.972678 | 
2026-09-07 10:42:31.972874 | LOOP [Check inventory directories]
2026-09-07 10:42:32.436704 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-07 10:42:32.628259 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-07 10:42:32.807503 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-07 10:42:32.824947 | 
2026-09-07 10:42:32.825587 | LOOP [Copy inventory file for Zuul]
2026-09-07 10:42:33.784713 | instance | changed:
2026-09-07 10:42:33.785033 | instance | {
2026-09-07 10:42:33.785090 | instance |   "ansible_loop_var": "item",
2026-09-07 10:42:33.785137 | instance |   "changed": false,
2026-09-07 10:42:33.785180 | instance |   "failed": false,
2026-09-07 10:42:33.785225 | instance |   "invocation": {
2026-09-07 10:42:33.785266 | instance |     "module_args": {
2026-09-07 10:42:33.785303 | instance |       "checksum_algorithm": "sha1",
2026-09-07 10:42:33.785340 | instance |       "follow": true,
2026-09-07 10:42:33.785377 | instance |       "get_attributes": true,
2026-09-07 10:42:33.785415 | instance |       "get_checksum": true,
2026-09-07 10:42:33.785452 | instance |       "get_mime": true,
2026-09-07 10:42:33.785487 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-07 10:42:33.785679 | instance |     }
2026-09-07 10:42:33.785726 | instance |   },
2026-09-07 10:42:33.785777 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-07 10:42:33.785804 | instance |   "stat": {
2026-09-07 10:42:33.785828 | instance |     "atime": 1788777731.892571,
2026-09-07 10:42:33.785852 | instance |     "attr_flags": "e",
2026-09-07 10:42:33.785877 | instance |     "attributes": [
2026-09-07 10:42:33.785900 | instance |       "extents"
2026-09-07 10:42:33.785922 | instance |     ],
2026-09-07 10:42:33.785945 | instance |     "block_size": 4096,
2026-09-07 10:42:33.785968 | instance |     "blocks": 8,
2026-09-07 10:42:33.785990 | instance |     "charset": "unknown",
2026-09-07 10:42:33.786016 | instance |     "ctime": 1788777730.412508,
2026-09-07 10:42:33.786040 | instance |     "dev": 64513,
2026-09-07 10:42:33.786064 | instance |     "device_type": 0,
2026-09-07 10:42:33.786088 | instance |     "executable": true,
2026-09-07 10:42:33.786111 | instance |     "exists": true,
2026-09-07 10:42:33.786134 | instance |     "gid": 1000,
2026-09-07 10:42:33.786157 | instance |     "gr_name": "zuul",
2026-09-07 10:42:33.786181 | instance |     "inode": 1047746,
2026-09-07 10:42:33.786206 | instance |     "isblk": false,
2026-09-07 10:42:33.786229 | instance |     "ischr": false,
2026-09-07 10:42:33.786253 | instance |     "isdir": true,
2026-09-07 10:42:33.786276 | instance |     "isfifo": false,
2026-09-07 10:42:33.786297 | instance |     "isgid": false,
2026-09-07 10:42:33.786320 | instance |     "islnk": false,
2026-09-07 10:42:33.786343 | instance |     "isreg": false,
2026-09-07 10:42:33.786366 | instance |     "issock": false,
2026-09-07 10:42:33.786389 | instance |     "isuid": false,
2026-09-07 10:42:33.786411 | instance |     "mimetype": "unknown",
2026-09-07 10:42:33.786433 | instance |     "mode": "0755",
2026-09-07 10:42:33.786457 | instance |     "mtime": 1788777730.412508,
2026-09-07 10:42:33.786480 | instance |     "nlink": 21,
2026-09-07 10:42:33.786503 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-07 10:42:33.786527 | instance |     "pw_name": "zuul",
2026-09-07 10:42:33.786550 | instance |     "readable": true,
2026-09-07 10:42:33.786574 | instance |     "rgrp": true,
2026-09-07 10:42:33.786603 | instance |     "roth": true,
2026-09-07 10:42:33.786639 | instance |     "rusr": true,
2026-09-07 10:42:33.786678 | instance |     "size": 4096,
2026-09-07 10:42:33.786710 | instance |     "uid": 1000,
2026-09-07 10:42:33.786753 | instance |     "version": "2392531648",
2026-09-07 10:42:33.786780 | instance |     "wgrp": false,
2026-09-07 10:42:33.786805 | instance |     "woth": false,
2026-09-07 10:42:33.786830 | instance |     "writeable": true,
2026-09-07 10:42:33.786854 | instance |     "wusr": true,
2026-09-07 10:42:33.786877 | instance |     "xgrp": true,
2026-09-07 10:42:33.786900 | instance |     "xoth": true,
2026-09-07 10:42:33.786922 | instance |     "xusr": true
2026-09-07 10:42:33.786946 | instance |   }
2026-09-07 10:42:33.786971 | instance | }
2026-09-07 10:42:33.787024 | instance | ok: All items complete
2026-09-07 10:42:33.787526 | 
2026-09-07 10:42:33.789663 | instance | skipping: Conditional result was False
2026-09-07 10:42:33.790583 | instance | skipping: Conditional result was False
2026-09-07 10:42:33.809557 | 
2026-09-07 10:42:33.809775 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 10:42:34.169430 | instance | changed: 1 replacements made
2026-09-07 10:42:34.173928 | instance | skipping: Conditional result was False
2026-09-07 10:42:34.178706 | instance | skipping: Conditional result was False
2026-09-07 10:42:34.199247 | 
2026-09-07 10:42:34.199455 | TASK [Run Molecule scenario]
2026-09-07 10:42:35.125478 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-07 10:42:35.537079 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-07 10:42:35.537142 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-07 10:42:46.653988 | instance | INFO     [csi > dependency] Executing
2026-09-07 10:42:46.654510 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-07 10:42:46.655004 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-07 10:42:46.655289 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-07 10:42:46.671045 | instance | INFO     [csi > cleanup] Executing
2026-09-07 10:42:46.671774 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-07 10:42:46.683803 | instance | INFO     [csi > destroy] Executing
2026-09-07 10:42:46.683897 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-07 10:42:46.684004 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-07 10:42:46.693270 | instance | INFO     [csi > syntax] Executing
2026-09-07 10:42:48.280996 | instance |
2026-09-07 10:42:48.281228 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-07 10:42:48.346856 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-07 10:42:48.365562 | instance | INFO     [csi > create] Executing
2026-09-07 10:42:48.370461 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-07 10:42:48.390058 | instance | INFO     [csi > prepare] Executing
2026-09-07 10:42:49.309023 | instance |
2026-09-07 10:42:49.309296 | instance | PLAY [Prepare] *****************************************************************
2026-09-07 10:42:49.309679 | instance |
2026-09-07 10:42:49.310067 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:42:49.310496 | instance | Monday 07 September 2026  10:42:49 +0000 (0:00:00.026)       0:00:00.026 ******
2026-09-07 10:42:50.673264 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-07 10:42:50.673377 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-07 10:42:50.673472 | instance | interpreter could change the meaning of that path. See
2026-09-07 10:42:50.673595 | instance | https://docs.ansible.com/ansible-
2026-09-07 10:42:50.673726 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-07 10:42:50.681986 | instance | ok: [instance]
2026-09-07 10:42:50.682077 | instance |
2026-09-07 10:42:50.682209 | instance | TASK [Configure short hostname] ************************************************
2026-09-07 10:42:50.682501 | instance | Monday 07 September 2026  10:42:50 +0000 (0:00:01.374)       0:00:01.400 ******
2026-09-07 10:42:51.466083 | instance | changed: [instance]
2026-09-07 10:42:51.466257 | instance |
2026-09-07 10:42:51.466634 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-07 10:42:51.466907 | instance | Monday 07 September 2026  10:42:51 +0000 (0:00:00.783)       0:00:02.184 ******
2026-09-07 10:42:51.787929 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-07 10:42:51.788034 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-07 10:42:51.788049 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-07 10:42:51.798397 | instance | changed: [instance]
2026-09-07 10:42:51.798472 | instance |
2026-09-07 10:42:51.798606 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-07 10:42:51.798710 | instance | Monday 07 September 2026  10:42:51 +0000 (0:00:00.332)       0:00:02.516 ******
2026-09-07 10:42:52.620146 | instance | ok: [instance]
2026-09-07 10:42:52.620264 | instance |
2026-09-07 10:42:52.620345 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-07 10:42:52.620523 | instance |
2026-09-07 10:42:52.620698 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:42:52.620851 | instance | Monday 07 September 2026  10:42:52 +0000 (0:00:00.821)       0:00:03.338 ******
2026-09-07 10:42:53.422686 | instance | ok: [instance]
2026-09-07 10:42:53.422888 | instance |
2026-09-07 10:42:53.423149 | instance | TASK [Install depedencies] *****************************************************
2026-09-07 10:42:53.423395 | instance | Monday 07 September 2026  10:42:53 +0000 (0:00:00.802)       0:00:04.140 ******
2026-09-07 10:43:18.678387 | instance | changed: [instance]
2026-09-07 10:43:18.678915 | instance |
2026-09-07 10:43:18.678930 | instance | TASK [Start up service] ********************************************************
2026-09-07 10:43:18.678938 | instance | Monday 07 September 2026  10:43:18 +0000 (0:00:25.256)       0:00:29.396 ******
2026-09-07 10:43:19.242250 | instance | ok: [instance]
2026-09-07 10:43:19.243582 | instance |
2026-09-07 10:43:19.243723 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-07 10:43:19.243744 | instance | Monday 07 September 2026  10:43:19 +0000 (0:00:00.563)       0:00:29.959 ******
2026-09-07 10:43:19.581137 | instance | ok: [instance]
2026-09-07 10:43:19.581242 | instance |
2026-09-07 10:43:19.581584 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-07 10:43:19.581642 | instance | Monday 07 September 2026  10:43:19 +0000 (0:00:00.339)       0:00:30.299 ******
2026-09-07 10:43:20.285928 | instance | changed: [instance]
2026-09-07 10:43:20.286351 | instance |
2026-09-07 10:43:20.286474 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-07 10:43:20.286778 | instance | Monday 07 September 2026  10:43:20 +0000 (0:00:00.704)       0:00:31.004 ******
2026-09-07 10:43:20.468597 | instance | ok: [instance]
2026-09-07 10:43:20.468674 | instance |
2026-09-07 10:43:20.468901 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-07 10:43:20.468916 | instance | Monday 07 September 2026  10:43:20 +0000 (0:00:00.183)       0:00:31.187 ******
2026-09-07 10:43:20.493740 | instance | skipping: [instance]
2026-09-07 10:43:20.494247 | instance |
2026-09-07 10:43:20.494266 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-07 10:43:20.494274 | instance | Monday 07 September 2026  10:43:20 +0000 (0:00:00.024)       0:00:31.212 ******
2026-09-07 10:43:21.043115 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:43:21.043988 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:43:21.044037 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:43:21.044045 | instance |
2026-09-07 10:43:21.044053 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-07 10:43:21.044059 | instance | Monday 07 September 2026  10:43:21 +0000 (0:00:00.549)       0:00:31.761 ******
2026-09-07 10:43:21.700897 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:43:21.701719 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:43:21.701765 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:43:21.701773 | instance |
2026-09-07 10:43:21.701780 | instance | TASK [Start loop devices] ******************************************************
2026-09-07 10:43:21.701787 | instance | Monday 07 September 2026  10:43:21 +0000 (0:00:00.657)       0:00:32.419 ******
2026-09-07 10:43:22.452365 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:43:22.452531 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:43:22.453538 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:43:22.453603 | instance |
2026-09-07 10:43:22.453614 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-07 10:43:22.453625 | instance | Monday 07 September 2026  10:43:22 +0000 (0:00:00.751)       0:00:33.170 ******
2026-09-07 10:43:25.702944 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:43:25.704234 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:43:25.704264 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:43:25.704275 | instance |
2026-09-07 10:43:25.704285 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-07 10:43:25.704295 | instance | Monday 07 September 2026  10:43:25 +0000 (0:00:03.250)       0:00:36.421 ******
2026-09-07 10:43:27.651101 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-07 10:43:27.651198 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-07 10:43:27.651227 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-07 10:43:27.651237 | instance |
2026-09-07 10:43:27.651246 | instance | PLAY RECAP *********************************************************************
2026-09-07 10:43:27.651263 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-07 10:43:27.651719 | instance |
2026-09-07 10:43:27.651768 | instance | Monday 07 September 2026  10:43:27 +0000 (0:00:01.947)       0:00:38.369 ******
2026-09-07 10:43:27.654429 | instance | ===============================================================================
2026-09-07 10:43:27.654525 | instance | Install depedencies ---------------------------------------------------- 25.26s
2026-09-07 10:43:27.654536 | instance | Create a volume group for each loop device ------------------------------ 3.25s
2026-09-07 10:43:27.654545 | instance | Create a logical volume for each loop device ---------------------------- 1.95s
2026-09-07 10:43:27.654556 | instance | Gathering Facts --------------------------------------------------------- 1.37s
2026-09-07 10:43:27.654564 | instance | Purge "snapd" package --------------------------------------------------- 0.82s
2026-09-07 10:43:27.654573 | instance | Gathering Facts --------------------------------------------------------- 0.80s
2026-09-07 10:43:27.654582 | instance | Configure short hostname ------------------------------------------------ 0.78s
2026-09-07 10:43:27.654591 | instance | Start loop devices ------------------------------------------------------ 0.75s
2026-09-07 10:43:27.654599 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.70s
2026-09-07 10:43:27.654608 | instance | Set permissions on loopback devices ------------------------------------- 0.66s
2026-09-07 10:43:27.654625 | instance | Start up service -------------------------------------------------------- 0.56s
2026-09-07 10:43:27.654755 | instance | Create devices for Ceph ------------------------------------------------- 0.55s
2026-09-07 10:43:27.654976 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s
2026-09-07 10:43:27.655204 | instance | Ensure hostname inside hosts file --------------------------------------- 0.33s
2026-09-07 10:43:27.655426 | instance | Get list of all loopback devices ---------------------------------------- 0.18s
2026-09-07 10:43:27.655683 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-07 10:43:27.741426 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-07 10:43:27.755671 | instance | INFO     [csi > converge] Executing
2026-09-07 10:43:29.044087 | instance |
2026-09-07 10:43:29.044329 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-07 10:43:29.044567 | instance |
2026-09-07 10:43:29.044799 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:43:29.045023 | instance | Monday 07 September 2026  10:43:29 +0000 (0:00:00.006)       0:00:00.006 ******
2026-09-07 10:43:29.996032 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-07 10:43:29.996127 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-07 10:43:29.996263 | instance | interpreter could change the meaning of that path. See
2026-09-07 10:43:29.996389 | instance | https://docs.ansible.com/ansible-
2026-09-07 10:43:29.996547 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-07 10:43:30.010199 | instance | ok: [instance]
2026-09-07 10:43:30.010325 | instance |
2026-09-07 10:43:30.010465 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-07 10:43:30.010618 | instance | Monday 07 September 2026  10:43:30 +0000 (0:00:00.965)       0:00:00.972 ******
2026-09-07 10:43:30.070801 | instance | ok: [instance] => {
2026-09-07 10:43:30.070912 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-07 10:43:30.070927 | instance | }
2026-09-07 10:43:30.071054 | instance |
2026-09-07 10:43:30.071186 | instance | PLAY [all] *********************************************************************
2026-09-07 10:43:30.071275 | instance |
2026-09-07 10:43:30.071388 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:43:30.071506 | instance | Monday 07 September 2026  10:43:30 +0000 (0:00:00.060)       0:00:01.033 ******
2026-09-07 10:43:31.059344 | instance | ok: [instance]
2026-09-07 10:43:31.059580 | instance |
2026-09-07 10:43:31.059835 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-07 10:43:31.060078 | instance | Monday 07 September 2026  10:43:31 +0000 (0:00:00.987)       0:00:02.021 ******
2026-09-07 10:43:31.284002 | instance | ok: [instance]
2026-09-07 10:43:31.284123 | instance |
2026-09-07 10:43:31.284222 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-07 10:43:31.284396 | instance |
2026-09-07 10:43:31.284557 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:43:31.284718 | instance | Monday 07 September 2026  10:43:31 +0000 (0:00:00.225)       0:00:02.246 ******
2026-09-07 10:43:32.282189 | instance | ok: [instance]
2026-09-07 10:43:32.282392 | instance |
2026-09-07 10:43:32.282683 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-07 10:43:32.282947 | instance | Monday 07 September 2026  10:43:32 +0000 (0:00:00.996)       0:00:03.243 ******
2026-09-07 10:43:32.586598 | instance | ok: [instance]
2026-09-07 10:43:32.587070 | instance |
2026-09-07 10:43:32.587449 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-07 10:43:32.587987 | instance | Monday 07 September 2026  10:43:32 +0000 (0:00:00.304)       0:00:03.548 ******
2026-09-07 10:43:32.626393 | instance | skipping: [instance]
2026-09-07 10:43:32.626598 | instance |
2026-09-07 10:43:32.626761 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-07 10:43:32.626908 | instance | Monday 07 September 2026  10:43:32 +0000 (0:00:00.038)       0:00:03.586 ******
2026-09-07 10:43:33.010860 | instance | changed: [instance]
2026-09-07 10:43:33.011223 | instance |
2026-09-07 10:43:33.011570 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-07 10:43:33.011892 | instance | Monday 07 September 2026  10:43:33 +0000 (0:00:00.385)       0:00:03.972 ******
2026-09-07 10:43:33.108888 | instance | ok: [instance] => {
2026-09-07 10:43:33.109140 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-07 10:43:33.109325 | instance | }
2026-09-07 10:43:33.109514 | instance |
2026-09-07 10:43:33.109700 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-07 10:43:33.109931 | instance | Monday 07 September 2026  10:43:33 +0000 (0:00:00.098)       0:00:04.070 ******
2026-09-07 10:43:33.869239 | instance | changed: [instance]
2026-09-07 10:43:33.869609 | instance |
2026-09-07 10:43:33.869927 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-07 10:43:33.870236 | instance | Monday 07 September 2026  10:43:33 +0000 (0:00:00.760)       0:00:04.831 ******
2026-09-07 10:43:33.936144 | instance | skipping: [instance]
2026-09-07 10:43:33.936523 | instance |
2026-09-07 10:43:33.936855 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-07 10:43:33.937296 | instance | Monday 07 September 2026  10:43:33 +0000 (0:00:00.067)       0:00:04.898 ******
2026-09-07 10:43:33.992746 | instance | skipping: [instance]
2026-09-07 10:43:33.993101 | instance |
2026-09-07 10:43:33.993418 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-07 10:43:33.993787 | instance | Monday 07 September 2026  10:43:33 +0000 (0:00:00.056)       0:00:04.955 ******
2026-09-07 10:43:34.239163 | instance | ok: [instance]
2026-09-07 10:43:34.239215 | instance |
2026-09-07 10:43:34.239227 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-07 10:43:34.239236 | instance | Monday 07 September 2026  10:43:34 +0000 (0:00:00.245)       0:00:05.200 ******
2026-09-07 10:43:35.550378 | instance | ok: [instance]
2026-09-07 10:43:35.551334 | instance |
2026-09-07 10:43:35.551372 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-07 10:43:35.551389 | instance | Monday 07 September 2026  10:43:35 +0000 (0:00:01.311)       0:00:06.512 ******
2026-09-07 10:43:35.647553 | instance | ok: [instance] => {
2026-09-07 10:43:35.647765 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-07 10:43:35.648399 | instance | }
2026-09-07 10:43:35.648727 | instance |
2026-09-07 10:43:35.649042 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-07 10:43:35.649343 | instance | Monday 07 September 2026  10:43:35 +0000 (0:00:00.096)       0:00:06.608 ******
2026-09-07 10:43:36.603721 | instance | changed: [instance]
2026-09-07 10:43:36.603835 | instance |
2026-09-07 10:43:36.603981 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-07 10:43:36.604239 | instance | Monday 07 September 2026  10:43:36 +0000 (0:00:00.957)       0:00:07.566 ******
2026-09-07 10:43:39.428806 | instance | changed: [instance]
2026-09-07 10:43:39.428852 | instance |
2026-09-07 10:43:39.428860 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-07 10:43:39.428867 | instance | Monday 07 September 2026  10:43:39 +0000 (0:00:02.824)       0:00:10.390 ******
2026-09-07 10:43:39.469860 | instance | skipping: [instance]
2026-09-07 10:43:39.469906 | instance |
2026-09-07 10:43:39.469917 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-07 10:43:39.469927 | instance | Monday 07 September 2026  10:43:39 +0000 (0:00:00.040)       0:00:10.431 ******
2026-09-07 10:43:39.509759 | instance | skipping: [instance]
2026-09-07 10:43:39.509808 | instance |
2026-09-07 10:43:39.509819 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-07 10:43:39.509829 | instance | Monday 07 September 2026  10:43:39 +0000 (0:00:00.039)       0:00:10.471 ******
2026-09-07 10:43:39.546209 | instance | skipping: [instance]
2026-09-07 10:43:39.546251 | instance |
2026-09-07 10:43:39.546259 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-07 10:43:39.546266 | instance | Monday 07 September 2026  10:43:39 +0000 (0:00:00.037)       0:00:10.508 ******
2026-09-07 10:43:45.335599 | instance | changed: [instance]
2026-09-07 10:43:45.335715 | instance |
2026-09-07 10:43:45.336079 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-07 10:43:45.336122 | instance | Monday 07 September 2026  10:43:45 +0000 (0:00:05.789)       0:00:16.298 ******
2026-09-07 10:43:45.977775 | instance | changed: [instance]
2026-09-07 10:43:45.978464 | instance |
2026-09-07 10:43:45.978555 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-07 10:43:45.978926 | instance | Monday 07 September 2026  10:43:45 +0000 (0:00:00.641)       0:00:16.940 ******
2026-09-07 10:43:46.931548 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-07 10:43:46.931685 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-07 10:43:46.932848 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-07 10:43:46.932898 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-07 10:43:46.932907 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-07 10:43:46.932916 | instance |
2026-09-07 10:43:46.932928 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-07 10:43:46.932935 | instance | Monday 07 September 2026  10:43:46 +0000 (0:00:00.954)       0:00:17.894 ******
2026-09-07 10:43:47.537617 | instance | changed: [instance]
2026-09-07 10:43:47.537729 | instance |
2026-09-07 10:43:47.538485 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-07 10:43:47.538558 | instance | Monday 07 September 2026  10:43:47 +0000 (0:00:00.580)       0:00:18.474 ******
2026-09-07 10:43:47.538571 | instance |
2026-09-07 10:43:47.538581 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-07 10:43:47.538596 | instance | Monday 07 September 2026  10:43:47 +0000 (0:00:00.025)       0:00:18.500 ******
2026-09-07 10:43:48.484095 | instance | ok: [instance]
2026-09-07 10:43:48.484850 | instance |
2026-09-07 10:43:48.484881 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-07 10:43:48.484892 | instance | Monday 07 September 2026  10:43:48 +0000 (0:00:00.946)       0:00:19.446 ******
2026-09-07 10:43:48.959479 | instance | changed: [instance]
2026-09-07 10:43:48.960199 | instance |
2026-09-07 10:43:48.960272 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-07 10:43:48.960284 | instance | Monday 07 September 2026  10:43:48 +0000 (0:00:00.475)       0:00:19.921 ******
2026-09-07 10:43:49.554464 | instance | changed: [instance]
2026-09-07 10:43:49.554534 | instance |
2026-09-07 10:43:49.554549 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-07 10:43:49.554682 | instance | Monday 07 September 2026  10:43:49 +0000 (0:00:00.594)       0:00:20.516 ******
2026-09-07 10:43:49.793897 | instance | ok: [instance]
2026-09-07 10:43:49.794402 | instance |
2026-09-07 10:43:49.794437 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-07 10:43:49.794448 | instance | Monday 07 September 2026  10:43:49 +0000 (0:00:00.240)       0:00:20.756 ******
2026-09-07 10:43:49.848449 | instance | ok: [instance] => {
2026-09-07 10:43:49.848503 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-07 10:43:49.848938 | instance | }
2026-09-07 10:43:49.848974 | instance |
2026-09-07 10:43:49.848979 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-07 10:43:49.848984 | instance | Monday 07 September 2026  10:43:49 +0000 (0:00:00.054)       0:00:20.811 ******
2026-09-07 10:43:50.769543 | instance | changed: [instance]
2026-09-07 10:43:50.770175 | instance |
2026-09-07 10:43:50.770206 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-07 10:43:50.770390 | instance | Monday 07 September 2026  10:43:50 +0000 (0:00:00.920)       0:00:21.731 ******
2026-09-07 10:43:55.032794 | instance | changed: [instance]
2026-09-07 10:43:55.032921 | instance |
2026-09-07 10:43:55.033560 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-07 10:43:55.033627 | instance | Monday 07 September 2026  10:43:55 +0000 (0:00:04.262)       0:00:25.994 ******
2026-09-07 10:43:56.471007 | instance | ok: [instance]
2026-09-07 10:43:56.471900 | instance |
2026-09-07 10:43:56.471983 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-07 10:43:56.471996 | instance | Monday 07 September 2026  10:43:56 +0000 (0:00:01.437)       0:00:27.432 ******
2026-09-07 10:43:56.855505 | instance | changed: [instance]
2026-09-07 10:43:56.855576 | instance |
2026-09-07 10:43:56.855586 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-07 10:43:56.855594 | instance | Monday 07 September 2026  10:43:56 +0000 (0:00:00.384)       0:00:27.817 ******
2026-09-07 10:43:57.393888 | instance | changed: [instance]
2026-09-07 10:43:57.394475 | instance |
2026-09-07 10:43:57.394896 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-07 10:43:57.394919 | instance | Monday 07 September 2026  10:43:57 +0000 (0:00:00.538)       0:00:28.355 ******
2026-09-07 10:43:57.980908 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-07 10:43:57.981025 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-07 10:43:57.981035 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-07 10:43:57.981395 | instance |
2026-09-07 10:43:57.981444 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-07 10:43:57.981451 | instance | Monday 07 September 2026  10:43:57 +0000 (0:00:00.588)       0:00:28.943 ******
2026-09-07 10:43:58.458371 | instance | changed: [instance]
2026-09-07 10:43:58.458780 | instance |
2026-09-07 10:43:58.458798 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-07 10:43:58.458805 | instance | Monday 07 September 2026  10:43:58 +0000 (0:00:00.477)       0:00:29.421 ******
2026-09-07 10:43:58.979305 | instance | changed: [instance]
2026-09-07 10:43:58.979919 | instance |
2026-09-07 10:43:58.979936 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-07 10:43:58.979942 | instance | Monday 07 September 2026  10:43:58 +0000 (0:00:00.504)       0:00:29.925 ******
2026-09-07 10:43:58.979946 | instance |
2026-09-07 10:43:58.979950 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-07 10:43:58.979954 | instance | Monday 07 September 2026  10:43:58 +0000 (0:00:00.016)       0:00:29.942 ******
2026-09-07 10:43:59.719521 | instance | ok: [instance]
2026-09-07 10:43:59.720278 | instance |
2026-09-07 10:43:59.720333 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-07 10:43:59.720351 | instance | Monday 07 September 2026  10:43:59 +0000 (0:00:00.739)       0:00:30.681 ******
2026-09-07 10:44:00.603844 | instance | changed: [instance]
2026-09-07 10:44:00.604566 | instance |
2026-09-07 10:44:00.604618 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-07 10:44:00.604630 | instance | Monday 07 September 2026  10:44:00 +0000 (0:00:00.884)       0:00:31.566 ******
2026-09-07 10:44:01.194403 | instance | changed: [instance]
2026-09-07 10:44:01.194862 | instance |
2026-09-07 10:44:01.194900 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-07 10:44:01.194908 | instance | Monday 07 September 2026  10:44:01 +0000 (0:00:00.590)       0:00:32.157 ******
2026-09-07 10:44:01.243730 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-07 10:44:01.243853 | instance |
2026-09-07 10:44:01.244169 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-07 10:44:01.244234 | instance | Monday 07 September 2026  10:44:01 +0000 (0:00:00.049)       0:00:32.206 ******
2026-09-07 10:44:06.758395 | instance | changed: [instance]
2026-09-07 10:44:06.759218 | instance |
2026-09-07 10:44:06.759300 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-07 10:44:06.759314 | instance | Monday 07 September 2026  10:44:06 +0000 (0:00:05.513)       0:00:37.720 ******
2026-09-07 10:44:07.445308 | instance | ok: [instance] => (item=chronyd)
2026-09-07 10:44:07.445430 | instance | ok: [instance] => (item=sshd)
2026-09-07 10:44:07.445443 | instance |
2026-09-07 10:44:07.445673 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-07 10:44:07.445840 | instance | Monday 07 September 2026  10:44:07 +0000 (0:00:00.687)       0:00:38.408 ******
2026-09-07 10:44:12.963852 | instance | changed: [instance]
2026-09-07 10:44:12.964561 | instance |
2026-09-07 10:44:12.964595 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-07 10:44:12.964607 | instance | Monday 07 September 2026  10:44:12 +0000 (0:00:05.517)       0:00:43.925 ******
2026-09-07 10:44:13.226401 | instance | ok: [instance]
2026-09-07 10:44:13.227128 | instance |
2026-09-07 10:44:13.227161 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-07 10:44:13.227173 | instance | Monday 07 September 2026  10:44:13 +0000 (0:00:00.262)       0:00:44.188 ******
2026-09-07 10:44:13.722514 | instance | changed: [instance]
2026-09-07 10:44:13.722601 | instance |
2026-09-07 10:44:13.722617 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-07 10:44:13.722630 | instance | Monday 07 September 2026  10:44:13 +0000 (0:00:00.495)       0:00:44.683 ******
2026-09-07 10:44:14.145879 | instance | changed: [instance]
2026-09-07 10:44:14.145927 | instance |
2026-09-07 10:44:14.145934 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-07 10:44:14.145941 | instance | Monday 07 September 2026  10:44:14 +0000 (0:00:00.424)       0:00:45.107 ******
2026-09-07 10:44:14.282374 | instance | ok: [instance]
2026-09-07 10:44:14.282420 | instance |
2026-09-07 10:44:14.282428 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-07 10:44:14.282435 | instance | Monday 07 September 2026  10:44:14 +0000 (0:00:00.136)       0:00:45.244 ******
2026-09-07 10:44:14.532442 | instance | ok: [instance] => (item=instance)
2026-09-07 10:44:14.532556 | instance |
2026-09-07 10:44:14.532571 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-07 10:44:14.532786 | instance | Monday 07 September 2026  10:44:14 +0000 (0:00:00.250)       0:00:45.495 ******
2026-09-07 10:44:14.613667 | instance | ok: [instance]
2026-09-07 10:44:14.614403 | instance |
2026-09-07 10:44:14.614485 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-07 10:44:14.614498 | instance | Monday 07 September 2026  10:44:14 +0000 (0:00:00.080)       0:00:45.575 ******
2026-09-07 10:44:14.703019 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-07 10:44:14.703088 | instance |
2026-09-07 10:44:14.703096 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-07 10:44:14.703103 | instance | Monday 07 September 2026  10:44:14 +0000 (0:00:00.089)       0:00:45.665 ******
2026-09-07 10:44:15.113127 | instance | changed: [instance]
2026-09-07 10:44:15.113203 | instance |
2026-09-07 10:44:15.113216 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-07 10:44:15.113226 | instance | Monday 07 September 2026  10:44:15 +0000 (0:00:00.408)       0:00:46.073 ******
2026-09-07 10:44:15.876460 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-07 10:44:15.876547 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-07 10:44:15.877050 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-07 10:44:15.877112 | instance |
2026-09-07 10:44:15.877120 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-07 10:44:15.877126 | instance | Monday 07 September 2026  10:44:15 +0000 (0:00:00.765)       0:00:46.839 ******
2026-09-07 10:45:47.304269 | instance | ok: [instance]
2026-09-07 10:45:47.304351 | instance |
2026-09-07 10:45:47.304634 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-07 10:45:47.304902 | instance | Monday 07 September 2026  10:45:47 +0000 (0:01:31.426)       0:02:18.266 ******
2026-09-07 10:45:47.556358 | instance | changed: [instance]
2026-09-07 10:45:47.556965 | instance |
2026-09-07 10:45:47.556989 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-07 10:45:47.556997 | instance | Monday 07 September 2026  10:45:47 +0000 (0:00:00.252)       0:02:18.518 ******
2026-09-07 10:45:47.647391 | instance | ok: [instance]
2026-09-07 10:45:47.647522 | instance |
2026-09-07 10:45:47.647719 | instance | TASK [Install Ceph host] *******************************************************
2026-09-07 10:45:47.647880 | instance | Monday 07 September 2026  10:45:47 +0000 (0:00:00.091)       0:02:18.610 ******
2026-09-07 10:45:47.720982 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-07 10:45:47.721051 | instance |
2026-09-07 10:45:47.721261 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-07 10:45:47.721295 | instance | Monday 07 September 2026  10:45:47 +0000 (0:00:00.073)       0:02:18.683 ******
2026-09-07 10:45:49.399164 | instance | ok: [instance]
2026-09-07 10:45:49.399318 | instance |
2026-09-07 10:45:49.399616 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-07 10:45:49.399913 | instance | Monday 07 September 2026  10:45:49 +0000 (0:00:01.677)       0:02:20.361 ******
2026-09-07 10:45:49.474275 | instance | ok: [instance] => (item=instance)
2026-09-07 10:45:49.474481 | instance |
2026-09-07 10:45:49.474739 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-07 10:45:49.474964 | instance | Monday 07 September 2026  10:45:49 +0000 (0:00:00.075)       0:02:20.436 ******
2026-09-07 10:45:49.837503 | instance | ok: [instance]
2026-09-07 10:45:49.837998 | instance |
2026-09-07 10:45:49.838042 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-07 10:45:49.838050 | instance | Monday 07 September 2026  10:45:49 +0000 (0:00:00.363)       0:02:20.800 ******
2026-09-07 10:45:52.193359 | instance | ok: [instance]
2026-09-07 10:45:52.193442 | instance |
2026-09-07 10:45:52.193797 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-07 10:45:52.193845 | instance | Monday 07 September 2026  10:45:52 +0000 (0:00:02.355)       0:02:23.156 ******
2026-09-07 10:46:37.249111 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-07 10:46:37.249531 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n  File \"<stdin>\", line 107, in <module>\n  File \"<stdin>\", line 99, in _ansiballz_main\n  File \"<stdin>\", line 47, in invoke_module\n  File \"/usr/lib/python3.10/runpy.py\", line 224, in run_module\n    return _run_module_code(code, init_globals, run_name, mod_spec)\n  File \"/usr/lib/python3.10/runpy.py\", line 96, in _run_module_code\n    _run_code(code, mod_globals, init_globals,\n  File \"/usr/lib/python3.10/runpy.py\", line 86, in _run_code\n    exec(code, run_globals)\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_18cc8e2c/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in <module>\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_18cc8e2c/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 198, in main\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_18cc8e2c/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 177, in run_module\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_18cc8e2c/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-07T10:46:37.073+0000 7f14e27d9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCulZ5q29/aKCAAsqwqSDKCLQ8uIL7YDiUvlOyODF17CEwai7l+YjdDqck=: Malformed input [buffer:3]\n2026-09-07T10:46:37.073+0000 7f14e27d9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-07T10:46:37.073+0000 7f14e27d9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCulZ5q29/aKCAAsqwqSDKCLQ8uIL7YDiUvlOyODF17CEwai7l+YjdDqck=: Malformed input [buffer:3]\n2026-09-07T10:46:37.073+0000 7f14e27d9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-07T10:46:37.077+0000 7f14e27d9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCulZ5q29/aKCAAsqwqSDKCLQ8uIL7YDiUvlOyODF17CEwai7l+YjdDqck=: Malformed input [buffer:3]\n2026-09-07T10:46:37.077+0000 7f14e27d9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-07T10:46:37.077+0000 7f14e27d9640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-07 10:46:37.252227 | instance |
2026-09-07 10:46:37.252530 | instance | PLAY RECAP *********************************************************************
2026-09-07 10:46:37.254398 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-07 10:46:37.254437 | instance |
2026-09-07 10:46:37.254451 | instance | Monday 07 September 2026  10:46:37 +0000 (0:00:45.059)       0:03:08.215 ******
2026-09-07 10:46:37.254461 | instance | ===============================================================================
2026-09-07 10:46:37.254470 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 91.43s
2026-09-07 10:46:37.258434 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 45.06s
2026-09-07 10:46:37.258469 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.79s
2026-09-07 10:46:37.258480 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 5.52s
2026-09-07 10:46:37.258489 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.51s
2026-09-07 10:46:37.258497 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.26s
2026-09-07 10:46:37.258506 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.82s
2026-09-07 10:46:37.258515 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.36s
2026-09-07 10:46:37.258537 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.69s
2026-09-07 10:46:37.258546 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.68s
2026-09-07 10:46:37.258555 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.44s
2026-09-07 10:46:37.258564 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.31s
2026-09-07 10:46:37.258577 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-09-07 10:46:37.258873 | instance | Gathering Facts --------------------------------------------------------- 0.99s
2026-09-07 10:46:37.259288 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-07 10:46:37.259683 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.96s
2026-09-07 10:46:37.260119 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.95s
2026-09-07 10:46:37.260537 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.92s
2026-09-07 10:46:37.260988 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.88s
2026-09-07 10:46:37.261406 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.77s
2026-09-07 10:46:37.345847 | 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-07 10:46:37.345998 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-07 10:46:37.346094 | 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-07 10:46:37.558327 | instance | ERROR
2026-09-07 10:46:37.558633 | instance | {
2026-09-07 10:46:37.558675 | instance |   "delta": "0:04:02.818400",
2026-09-07 10:46:37.558703 | instance |   "end": "2026-09-07 10:46:37.393579",
2026-09-07 10:46:37.558727 | instance |   "msg": "non-zero return code",
2026-09-07 10:46:37.558751 | instance |   "rc": 2,
2026-09-07 10:46:37.558774 | instance |   "start": "2026-09-07 10:42:34.575179"
2026-09-07 10:46:37.558798 | instance | }
failure
2026-09-07 10:46:37.565785 | 
2026-09-07 10:46:37.565848 | PLAY RECAP
2026-09-07 10:46:37.565915 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:46:37.565937 | 
2026-09-07 10:46:37.811024 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 10:46:37.816975 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-07 10:46:38.441233 | 
2026-09-07 10:46:38.441408 | PLAY [all]
2026-09-07 10:46:38.455575 | 
2026-09-07 10:46:38.455657 | TASK [gather-host-logs : creating directory for system status]
2026-09-07 10:46:38.885279 | instance | changed
2026-09-07 10:46:38.895431 | 
2026-09-07 10:46:38.895626 | TASK [gather-host-logs : Get logs for each host]
2026-09-07 10:46:39.253107 | instance | + systemd-cgls --full --all --no-pager
2026-09-07 10:46:39.266734 | instance | + ip addr
2026-09-07 10:46:39.268314 | instance | + ip route
2026-09-07 10:46:39.270977 | instance | + lsblk
2026-09-07 10:46:39.275788 | instance | + mount
2026-09-07 10:46:39.278659 | instance | + docker images
2026-09-07 10:46:39.294585 | instance | + brctl show
2026-09-07 10:46:39.294900 | instance | /bin/bash: line 8: brctl: command not found
2026-09-07 10:46:39.295055 | instance | + ps aux --sort=-%mem
2026-09-07 10:46:39.307189 | instance | + dpkg -l
2026-09-07 10:46:39.312359 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-07 10:46:39.312648 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-07 10:46:39.328728 | instance | + '[' '!' -z '' ']'
2026-09-07 10:46:39.433400 | instance | ok: Runtime: 0:00:00.079984
2026-09-07 10:46:39.441147 | 
2026-09-07 10:46:39.441269 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-07 10:46:40.178447 | instance | changed:
2026-09-07 10:46:40.178701 | instance | created directory /var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/work/logs/instance
2026-09-07 10:46:40.178758 | instance | cd+++++++++ system/
2026-09-07 10:46:40.178802 | instance | >f+++++++++ system/brctl-show.txt
2026-09-07 10:46:40.178843 | instance | >f+++++++++ system/docker-images.txt
2026-09-07 10:46:40.178904 | instance | >f+++++++++ system/ip-addr.txt
2026-09-07 10:46:40.178950 | instance | >f+++++++++ system/ip-route.txt
2026-09-07 10:46:40.178991 | instance | >f+++++++++ system/lsblk.txt
2026-09-07 10:46:40.179030 | instance | >f+++++++++ system/mount.txt
2026-09-07 10:46:40.179068 | instance | >f+++++++++ system/packages.txt
2026-09-07 10:46:40.179105 | instance | >f+++++++++ system/ps.txt
2026-09-07 10:46:40.179148 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-07 10:46:40.197488 | 
2026-09-07 10:46:40.197627 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-07 10:46:40.407602 | instance | changed: "values"
2026-09-07 10:46:40.578568 | instance | changed: "releases"
2026-09-07 10:46:40.601554 | 
2026-09-07 10:46:40.601804 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-07 10:46:40.854519 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-07 10:46:41.155050 | instance | ok: Runtime: 0:00:00.005670
2026-09-07 10:46:41.166561 | 
2026-09-07 10:46:41.166776 | TASK [helm-release-status : Downloads logs to executor]
2026-09-07 10:46:41.731712 | instance | changed:
2026-09-07 10:46:41.731944 | instance | cd+++++++++ helm/
2026-09-07 10:46:41.731979 | instance | cd+++++++++ helm/releases/
2026-09-07 10:46:41.732004 | instance | cd+++++++++ helm/values/
2026-09-07 10:46:41.743199 | 
2026-09-07 10:46:41.743323 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-07 10:46:41.966378 | instance | changed
2026-09-07 10:46:41.974154 | 
2026-09-07 10:46:41.974344 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-07 10:46:42.301438 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:46:42.302670 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:46:42.307363 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:46:42.308101 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:46:42.308628 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:46:42.309530 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:46:42.311463 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:46:42.312564 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:46:42.312891 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:46:42.314591 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:46:42.316010 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:46:42.317016 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:46:42.511767 | instance | ok: Runtime: 0:00:00.022795
2026-09-07 10:46:42.521394 | 
2026-09-07 10:46:42.521750 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-07 10:46:42.732684 | instance | changed
2026-09-07 10:46:42.745513 | 
2026-09-07 10:46:42.745648 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-07 10:46:43.002946 | instance | environment: line 5: kubectl: command not found
2026-09-07 10:46:43.004141 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:46:43.004409 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:46:43.004768 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:46:43.292550 | instance | ok: Runtime: 0:00:00.007987
2026-09-07 10:46:43.303515 | 
2026-09-07 10:46:43.303675 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-07 10:46:43.844928 | instance | changed:
2026-09-07 10:46:43.845168 | instance | cd+++++++++ objects/
2026-09-07 10:46:43.845224 | instance | cd+++++++++ objects/cluster/
2026-09-07 10:46:43.845270 | instance | cd+++++++++ objects/namespaced/
2026-09-07 10:46:43.862965 | 
2026-09-07 10:46:43.863093 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-07 10:46:44.100432 | instance | changed
2026-09-07 10:46:44.110330 | 
2026-09-07 10:46:44.110455 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-07 10:46:44.342335 | instance | changed
2026-09-07 10:46:44.352580 | 
2026-09-07 10:46:44.352727 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-07 10:46:44.624661 | instance | environment: line 3: kubectl: command not found
2026-09-07 10:46:44.898244 | instance | ok: Runtime: 0:00:00.008304
2026-09-07 10:46:44.909014 | 
2026-09-07 10:46:44.909161 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-07 10:46:45.461757 | instance | changed:
2026-09-07 10:46:45.462036 | instance | cd+++++++++ pod-logs/
2026-09-07 10:46:45.462091 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-07 10:46:45.484028 | 
2026-09-07 10:46:45.484178 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-07 10:46:45.693002 | instance | changed
2026-09-07 10:46:45.704279 | 
2026-09-07 10:46:45.704454 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-07 10:46:45.955628 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-07 10:46:46.255224 | instance | ok: Runtime: 0:00:00.038563
2026-09-07 10:46:46.265559 | 
2026-09-07 10:46:46.265823 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-07 10:46:46.530912 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-07 10:46:46.576836 | instance | ceph-mgr endpoints:
2026-09-07 10:46:46.818911 | instance | ok: Runtime: 0:00:00.051070
2026-09-07 10:46:46.826803 | 
2026-09-07 10:46:46.826955 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-07 10:46:47.083810 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-07 10:46:47.371449 | instance | ok: Runtime: 0:00:00.027488
2026-09-07 10:46:47.382421 | 
2026-09-07 10:46:47.382642 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-07 10:46:47.935155 | instance | changed: cd+++++++++ prometheus/
2026-09-07 10:46:47.952755 | 
2026-09-07 10:46:47.952920 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-07 10:46:48.193511 | instance | changed
2026-09-07 10:46:48.204236 | 
2026-09-07 10:46:48.204372 | TASK [gather-selenium-data : Get selenium data]
2026-09-07 10:46:48.494218 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-07 10:46:48.495273 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-07 10:46:48.752212 | instance | ERROR
2026-09-07 10:46:48.752532 | instance | {
2026-09-07 10:46:48.752599 | instance |   "delta": "0:00:00.006001",
2026-09-07 10:46:48.752643 | instance |   "end": "2026-09-07 10:46:48.495613",
2026-09-07 10:46:48.752686 | instance |   "msg": "non-zero return code",
2026-09-07 10:46:48.752723 | instance |   "rc": 1,
2026-09-07 10:46:48.752759 | instance |   "start": "2026-09-07 10:46:48.489612"
2026-09-07 10:46:48.752795 | instance | }
2026-09-07 10:46:48.752840 | instance | ERROR: Ignoring Errors
2026-09-07 10:46:48.762129 | 
2026-09-07 10:46:48.762273 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-07 10:46:49.335931 | instance | changed: cd+++++++++ selenium/
2026-09-07 10:46:49.348757 | 
2026-09-07 10:46:49.348922 | PLAY RECAP
2026-09-07 10:46:49.348988 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-07 10:46:49.349010 | 
2026-09-07 10:46:49.590662 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-07 10:46:49.768239 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 10:46:50.329956 | 
2026-09-07 10:46:50.330064 | PLAY [all]
2026-09-07 10:46:50.340472 | 
2026-09-07 10:46:50.340561 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 10:46:50.395694 | instance | skipping: Conditional result was False
2026-09-07 10:46:50.402236 | 
2026-09-07 10:46:50.402361 | TASK [fetch-output : Set log path for single node]
2026-09-07 10:46:50.456366 | instance | ok
2026-09-07 10:46:50.465396 | 
2026-09-07 10:46:50.465541 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 10:46:50.915719 | instance -> localhost | ok: "/var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/work/logs"
2026-09-07 10:46:51.167543 | instance -> localhost | changed: "/var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/work/artifacts"
2026-09-07 10:46:51.421668 | instance -> localhost | changed: "/var/lib/zuul/builds/fecb4a075cff488685b4a95552a3816d/work/docs"
2026-09-07 10:46:51.437482 | 
2026-09-07 10:46:51.437625 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 10:46:52.108976 | instance | changed: .d..t...... ./
2026-09-07 10:46:52.109227 | instance | changed: All items complete
2026-09-07 10:46:52.109252 | 
2026-09-07 10:46:52.623050 | instance | changed: .d..t...... ./
2026-09-07 10:46:53.174782 | instance | changed: .d..t...... ./
2026-09-07 10:46:53.199923 | 
2026-09-07 10:46:53.200075 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 10:46:53.664134 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008153
2026-09-07 10:46:53.914261 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006061
2026-09-07 10:46:53.930944 | 
2026-09-07 10:46:53.931082 | PLAY [all]
2026-09-07 10:46:53.939355 | 
2026-09-07 10:46:53.939446 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 10:46:54.370375 | instance | changed
2026-09-07 10:46:54.385136 | 
2026-09-07 10:46:54.385252 | PLAY RECAP
2026-09-07 10:46:54.385324 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 10:46:54.385362 | 
2026-09-07 10:46:54.514309 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 10:46:54.518874 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 10:46:55.076443 | 
2026-09-07 10:46:55.076920 | PLAY [localhost]
2026-09-07 10:46:55.086231 | 
2026-09-07 10:46:55.086357 | TASK [Generate Zuul manifest]
2026-09-07 10:46:55.102550 | localhost | ok
2026-09-07 10:46:55.117998 | 
2026-09-07 10:46:55.118117 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 10:46:55.522970 | localhost | changed
2026-09-07 10:46:55.550971 | 
2026-09-07 10:46:55.551181 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 10:46:55.593357 | localhost | ok
2026-09-07 10:46:55.607361 | 
2026-09-07 10:46:55.607546 | TASK [Upload logs]
2026-09-07 10:46:55.643270 | localhost | ok
2026-09-07 10:46:55.727169 | 
2026-09-07 10:46:55.727367 | TASK [Set zuul-log-path fact]
2026-09-07 10:46:55.747402 | localhost | ok
2026-09-07 10:46:55.773810 | 
2026-09-07 10:46:55.774305 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 10:46:55.807995 | localhost | ok
2026-09-07 10:46:55.825837 | 
2026-09-07 10:46:55.826086 | TASK [upload-logs : Create log directories]
2026-09-07 10:46:56.219074 | localhost | changed
2026-09-07 10:46:56.225382 | 
2026-09-07 10:46:56.225477 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 10:46:56.646879 | localhost -> localhost | ok: Runtime: 0:00:00.004099
2026-09-07 10:46:56.657837 | 
2026-09-07 10:46:56.657977 | TASK [upload-logs : Upload logs to log server]
2026-09-07 10:46:57.119603 | localhost | Output suppressed because no_log was given
2026-09-07 10:46:57.162232 | 
2026-09-07 10:46:57.162372 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 10:46:57.206881 | localhost | skipping: Conditional result was False
2026-09-07 10:46:57.213719 | localhost | skipping: Conditional result was False
2026-09-07 10:46:57.227430 | 
2026-09-07 10:46:57.227643 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 10:46:57.265443 | localhost | skipping: Conditional result was False
2026-09-07 10:46:57.265920 | 
2026-09-07 10:46:57.269137 | localhost | skipping: Conditional result was False
2026-09-07 10:46:57.280786 | 
2026-09-07 10:46:57.280945 | LOOP [upload-logs : Upload console log and json output]