2026-09-15 02:15:03.847846 | Job console starting
2026-09-15 02:15:03.861794 | Updating git repos
2026-09-15 02:15:10.417186 | Cloning repos into workspace
2026-09-15 02:15:27.155228 | Restoring repo states
2026-09-15 02:15:43.669524 | Merging changes
2026-09-15 02:15:44.636536 | Checking out repos
2026-09-15 02:15:46.396622 | Preparing playbooks
2026-09-15 02:15:48.130436 | Running Ansible setup
2026-09-15 02:15:51.789770 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 02:15:52.450917 | 
2026-09-15 02:15:52.451151 | PLAY [localhost]
2026-09-15 02:15:52.463162 | 
2026-09-15 02:15:52.463355 | TASK [Gathering Facts]
2026-09-15 02:15:53.425367 | localhost | ok
2026-09-15 02:15:53.443304 | 
2026-09-15 02:15:53.443480 | TASK [Setup log path fact]
2026-09-15 02:15:53.461431 | localhost | ok
2026-09-15 02:15:53.474516 | 
2026-09-15 02:15:53.474604 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 02:15:53.513941 | localhost | ok
2026-09-15 02:15:53.523988 | 
2026-09-15 02:15:53.524081 | TASK [emit-job-header : Print job information]
2026-09-15 02:15:53.554759 | # Job Information
2026-09-15 02:15:53.555007 | Ansible Version: 2.16.18
2026-09-15 02:15:53.555071 | Job: atmosphere-molecule-csi-rbd
2026-09-15 02:15:53.555114 | Pipeline: check
2026-09-15 02:15:53.555154 | Executor: 2d72f0692154
2026-09-15 02:15:53.555194 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3811
2026-09-15 02:15:53.555239 | Event ID: a2286040-b0aa-11f1-9c78-6bb36c031ad0
2026-09-15 02:15:53.560795 | 
2026-09-15 02:15:53.560925 | LOOP [emit-job-header : Print node information]
2026-09-15 02:15:53.662426 | localhost | ok:
2026-09-15 02:15:53.662635 | localhost | # Node Information
2026-09-15 02:15:53.662661 | localhost | Inventory Hostname: instance
2026-09-15 02:15:53.662680 | localhost | Hostname: np0000192766
2026-09-15 02:15:53.662697 | localhost | Username: zuul
2026-09-15 02:15:53.662716 | localhost | Distro: Ubuntu 22.04
2026-09-15 02:15:53.662733 | localhost | Provider: yul1
2026-09-15 02:15:53.662750 | localhost | Region: ca-ymq-1
2026-09-15 02:15:53.662766 | localhost | Label: ubuntu-jammy
2026-09-15 02:15:53.662782 | localhost | Product Name: OpenStack Nova
2026-09-15 02:15:53.662797 | localhost | Interface IP: 162.253.55.159
2026-09-15 02:15:53.679122 | 
2026-09-15 02:15:53.679321 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 02:15:54.090150 | localhost -> localhost | changed
2026-09-15 02:15:54.099867 | 
2026-09-15 02:15:54.099998 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 02:15:55.006030 | localhost -> localhost | changed
2026-09-15 02:15:55.019009 | 
2026-09-15 02:15:55.019147 | PLAY [all]
2026-09-15 02:15:55.037961 | 
2026-09-15 02:15:55.038121 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 02:15:55.286928 | instance -> localhost | ok
2026-09-15 02:15:55.302389 | 
2026-09-15 02:15:55.302617 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 02:15:55.339254 | instance | ok
2026-09-15 02:15:55.364548 | instance | included: /var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 02:15:55.371783 | 
2026-09-15 02:15:55.371891 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 02:15:56.764394 | instance -> localhost | Generating public/private rsa key pair.
2026-09-15 02:15:56.764600 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/work/7d2cead7e65946b69510b5f2a7369b11_id_rsa
2026-09-15 02:15:56.764628 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/work/7d2cead7e65946b69510b5f2a7369b11_id_rsa.pub
2026-09-15 02:15:56.764648 | instance -> localhost | The key fingerprint is:
2026-09-15 02:15:56.764667 | instance -> localhost | SHA256:b90HFgkZw/OhMkISpLFmQRxoXzJRhKGuvhNcrNU+te4 zuul-build-sshkey
2026-09-15 02:15:56.764694 | instance -> localhost | The key's randomart image is:
2026-09-15 02:15:56.764713 | instance -> localhost | +---[RSA 3072]----+
2026-09-15 02:15:56.764738 | instance -> localhost | |   +BO=.   .+o   |
2026-09-15 02:15:56.764757 | instance -> localhost | |  o.==o .   +o.. |
2026-09-15 02:15:56.764775 | instance -> localhost | | .o.*+ o     +o. |
2026-09-15 02:15:56.764793 | instance -> localhost | | . *.. .. o . .. |
2026-09-15 02:15:56.764810 | instance -> localhost | |. = . . S. o  o  |
2026-09-15 02:15:56.764826 | instance -> localhost | | =   o . . . o . |
2026-09-15 02:15:56.764843 | instance -> localhost | |. .   o   o . . .|
2026-09-15 02:15:56.764860 | instance -> localhost | |..     . .     . |
2026-09-15 02:15:56.764879 | instance -> localhost | | oo   .E         |
2026-09-15 02:15:56.764897 | instance -> localhost | +----[SHA256]-----+
2026-09-15 02:15:56.764943 | instance -> localhost | ok: Runtime: 0:00:00.918628
2026-09-15 02:15:56.779342 | 
2026-09-15 02:15:56.780123 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 02:15:56.811721 | instance | ok
2026-09-15 02:15:56.823573 | instance | included: /var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 02:15:56.831703 | 
2026-09-15 02:15:56.831764 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 02:15:56.855300 | instance | skipping: Conditional result was False
2026-09-15 02:15:56.862481 | 
2026-09-15 02:15:56.862565 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 02:15:57.331551 | instance | changed
2026-09-15 02:15:57.342909 | 
2026-09-15 02:15:57.343030 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 02:15:57.535016 | instance | ok
2026-09-15 02:15:57.539686 | 
2026-09-15 02:15:57.539747 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 02:15:58.078875 | instance | changed
2026-09-15 02:15:58.107867 | 
2026-09-15 02:15:58.108010 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 02:15:58.606831 | instance | changed
2026-09-15 02:15:58.617736 | 
2026-09-15 02:15:58.617864 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 02:15:58.643325 | instance | skipping: Conditional result was False
2026-09-15 02:15:58.669624 | 
2026-09-15 02:15:58.669779 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 02:15:59.058542 | instance -> localhost | changed
2026-09-15 02:15:59.077619 | 
2026-09-15 02:15:59.077759 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 02:15:59.428980 | instance -> localhost | Identity added: /var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/work/7d2cead7e65946b69510b5f2a7369b11_id_rsa (zuul-build-sshkey)
2026-09-15 02:15:59.429209 | instance -> localhost | ok: Runtime: 0:00:00.011983
2026-09-15 02:15:59.435793 | 
2026-09-15 02:15:59.435876 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 02:15:59.764221 | instance | ok
2026-09-15 02:15:59.774597 | 
2026-09-15 02:15:59.774722 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 02:15:59.809912 | instance | skipping: Conditional result was False
2026-09-15 02:15:59.827641 | 
2026-09-15 02:15:59.827771 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 02:16:00.230150 | instance | ok
2026-09-15 02:16:00.240856 | 
2026-09-15 02:16:00.241054 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 02:16:03.783153 | instance | Output suppressed because no_log was given
2026-09-15 02:16:03.798774 | 
2026-09-15 02:16:03.798900 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 02:16:03.997101 | instance | ok: "logs"
2026-09-15 02:16:03.997474 | instance | ok: All items complete
2026-09-15 02:16:03.997529 | 
2026-09-15 02:16:04.150895 | instance | ok: "artifacts"
2026-09-15 02:16:04.301534 | instance | ok: "docs"
2026-09-15 02:16:04.314144 | 
2026-09-15 02:16:04.314388 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 02:16:04.510491 | instance | changed: "logs"
2026-09-15 02:16:04.652112 | instance | changed: "artifacts"
2026-09-15 02:16:04.799324 | instance | changed: "docs"
2026-09-15 02:16:04.814755 | 
2026-09-15 02:16:04.814909 | PLAY RECAP
2026-09-15 02:16:04.814991 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 02:16:04.815045 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 02:16:04.815086 | 
2026-09-15 02:16:04.972414 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 02:16:04.976564 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 02:16:05.549967 | 
2026-09-15 02:16:05.575212 | PLAY [all]
2026-09-15 02:16:05.587168 | 
2026-09-15 02:16:05.587298 | TASK [setup-uv : Extract archive]
2026-09-15 02:16:08.152246 | instance | changed
2026-09-15 02:16:08.159396 | 
2026-09-15 02:16:08.159528 | TASK [setup-uv : Print version]
2026-09-15 02:16:08.530669 | instance | uv 0.8.13
2026-09-15 02:16:08.692248 | instance | ok: Runtime: 0:00:00.011940
2026-09-15 02:16:08.709921 | 
2026-09-15 02:16:08.710091 | TASK [Install Ansible collection siblings]
2026-09-15 02:16:18.239899 | instance | changed:
2026-09-15 02:16:18.240445 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 02:16:18.240563 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 02:16:18.240612 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 02:16:18.240655 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 02:16:18.240697 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 02:16:18.240745 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 02:16:18.240790 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 02:16:18.240835 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 02:16:18.240875 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 02:16:18.240916 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 02:16:18.240956 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 02:16:18.240995 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 02:16:18.241034 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-15 02:16:18.241073 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-15 02:16:18.241113 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-15 02:16:18.241154 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-15 02:16:18.241193 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-15 02:16:18.241232 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-15 02:16:18.241271 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-15 02:16:18.241310 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-15 02:16:18.241349 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-15 02:16:18.241387 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-15 02:16:18.241429 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 02:16:18.241506 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-15 02:16:18.241549 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-15 02:16:18.241589 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 02:16:18.241626 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 02:16:18.241664 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 02:16:18.241702 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-15 02:16:18.241741 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-15 02:16:18.241780 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 02:16:18.241820 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 02:16:18.241859 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-15 02:16:18.241898 | 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 02:16:18.241947 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.241986 | instance | Process install dependency map
2026-09-15 02:16:18.242025 | instance | Starting collection install process
2026-09-15 02:16:18.242064 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-15 02:16:18.242102 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-15 02:16:18.242141 | instance | ansible.netcommon:* was installed successfully
2026-09-15 02:16:18.242178 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-15 02:16:18.242215 | instance | Creating virtual environment at: .venv
2026-09-15 02:16:18.242253 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 02:16:18.242291 | instance | Downloading pydantic-core (2.0MiB)
2026-09-15 02:16:18.242329 | instance | Downloading netaddr (2.2MiB)
2026-09-15 02:16:18.242378 | instance | Downloading setuptools (1.1MiB)
2026-09-15 02:16:18.242418 | instance | Downloading kubernetes (1.9MiB)
2026-09-15 02:16:18.242457 | instance | Downloading pygments (1.2MiB)
2026-09-15 02:16:18.242516 | instance | Downloading ansible-core (2.1MiB)
2026-09-15 02:16:18.242556 | instance | Downloading rjsonnet (1.2MiB)
2026-09-15 02:16:18.242595 | instance | Downloading openstacksdk (1.7MiB)
2026-09-15 02:16:18.242633 | instance | Downloading cryptography (4.2MiB)
2026-09-15 02:16:18.242671 | instance |    Building pyperclip==1.9.0
2026-09-15 02:16:18.242713 | instance |  Downloading rjsonnet
2026-09-15 02:16:18.242753 | instance |  Downloading pygments
2026-09-15 02:16:18.242793 | instance |  Downloading pydantic-core
2026-09-15 02:16:18.242832 | instance |  Downloading netaddr
2026-09-15 02:16:18.242871 | instance |  Downloading setuptools
2026-09-15 02:16:18.242909 | instance |  Downloading cryptography
2026-09-15 02:16:18.242947 | instance |  Downloading kubernetes
2026-09-15 02:16:18.242985 | instance |  Downloading ansible-core
2026-09-15 02:16:18.243023 | instance |  Downloading openstacksdk
2026-09-15 02:16:18.243062 | instance |       Built pyperclip==1.9.0
2026-09-15 02:16:18.243101 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 02:16:18.243140 | instance | Installed 83 packages in 57ms
2026-09-15 02:16:18.243179 | instance | Checked out ansible.posix to 1.6.0
2026-09-15 02:16:18.243217 | 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 02:16:18.243256 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.243295 | instance | Process install dependency map
2026-09-15 02:16:18.243345 | instance | Starting collection install process
2026-09-15 02:16:18.243385 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 02:16:18.243423 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-15 02:16:18.243478 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-15 02:16:18.243520 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.243559 | instance | 2.17.12
2026-09-15 02:16:18.243598 | instance | Checked out ansible.utils to v6.1.0
2026-09-15 02:16:18.243637 | 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 02:16:18.243676 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.243715 | instance | Process install dependency map
2026-09-15 02:16:18.243753 | instance | Starting collection install process
2026-09-15 02:16:18.243791 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-15 02:16:18.243830 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-15 02:16:18.243869 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-15 02:16:18.243908 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.243946 | instance | 2.17.12
2026-09-15 02:16:18.243984 | instance | Checked out atmosphere.common to v0.7.0
2026-09-15 02:16:18.244021 | 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 02:16:18.244059 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.244098 | instance | Process install dependency map
2026-09-15 02:16:18.244135 | instance | Starting collection install process
2026-09-15 02:16:18.244173 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-15 02:16:18.244211 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-15 02:16:18.244248 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-15 02:16:18.244286 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.244324 | instance | 2.17.12
2026-09-15 02:16:18.244361 | instance | Checked out community.crypto to 2.2.3
2026-09-15 02:16:18.244399 | 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 02:16:18.244437 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.244551 | instance | Process install dependency map
2026-09-15 02:16:18.244597 | instance | Starting collection install process
2026-09-15 02:16:18.244636 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 02:16:18.244673 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-15 02:16:18.244711 | instance | community.crypto:2.2.3 was installed successfully
2026-09-15 02:16:18.244748 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.244785 | instance | 2.17.12
2026-09-15 02:16:18.244822 | instance | Checked out community.general to 7.3.0
2026-09-15 02:16:18.244859 | 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 02:16:18.244906 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.244945 | instance | Process install dependency map
2026-09-15 02:16:18.244982 | instance | Starting collection install process
2026-09-15 02:16:18.245019 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 02:16:18.245056 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-15 02:16:18.245094 | instance | community.general:7.3.0 was installed successfully
2026-09-15 02:16:18.245133 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.245172 | instance | 2.17.12
2026-09-15 02:16:18.245210 | instance | Checked out community.mysql to 3.6.0
2026-09-15 02:16:18.245253 | 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 02:16:18.245293 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.245332 | instance | Process install dependency map
2026-09-15 02:16:18.245370 | instance | Starting collection install process
2026-09-15 02:16:18.245409 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-15 02:16:18.245447 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-15 02:16:18.245512 | instance | community.mysql:3.6.0 was installed successfully
2026-09-15 02:16:18.245553 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.245591 | instance | 2.17.12
2026-09-15 02:16:18.245631 | instance | Checked out kubernetes.core to 2.4.0
2026-09-15 02:16:18.245670 | 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 02:16:18.245709 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.245747 | instance | Process install dependency map
2026-09-15 02:16:18.245785 | instance | Starting collection install process
2026-09-15 02:16:18.245825 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 02:16:18.245871 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-15 02:16:18.245925 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-15 02:16:18.245984 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.246048 | instance | 2.17.12
2026-09-15 02:16:18.246104 | instance | Checked out openstack.cloud to 2.6.0
2026-09-15 02:16:18.246152 | 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 02:16:18.246192 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.246230 | instance | Process install dependency map
2026-09-15 02:16:18.246267 | instance | Starting collection install process
2026-09-15 02:16:18.246305 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-15 02:16:18.246342 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-15 02:16:18.246379 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-15 02:16:18.246416 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.246454 | instance | 2.17.12
2026-09-15 02:16:18.246528 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-15 02:16:18.246584 | 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 02:16:18.246642 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.246698 | instance | Process install dependency map
2026-09-15 02:16:18.246744 | instance | Starting collection install process
2026-09-15 02:16:18.246783 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-15 02:16:18.246821 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-15 02:16:18.246858 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-15 02:16:18.246897 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.246934 | instance | 2.17.12
2026-09-15 02:16:18.246972 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-15 02:16:18.247043 | 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 02:16:18.247131 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.247178 | instance | Process install dependency map
2026-09-15 02:16:18.247218 | instance | Starting collection install process
2026-09-15 02:16:18.247268 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-15 02:16:18.247338 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-15 02:16:18.247437 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-15 02:16:18.247514 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.250388 | instance | 2.17.12
2026-09-15 02:16:18.250516 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-15 02:16:18.250571 | 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 02:16:18.250616 | instance | Starting galaxy collection install process
2026-09-15 02:16:18.250657 | instance | Process install dependency map
2026-09-15 02:16:18.250698 | instance | Starting collection install process
2026-09-15 02:16:18.250738 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-15 02:16:18.250780 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-15 02:16:18.250819 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-15 02:16:18.250859 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 02:16:18.250898 | instance | 2.17.12
2026-09-15 02:16:18.264040 | 
2026-09-15 02:16:18.264367 | PLAY RECAP
2026-09-15 02:16:18.264631 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 02:16:18.264693 | 
2026-09-15 02:16:18.433865 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 02:16:18.438023 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 02:16:18.978315 | 
2026-09-15 02:16:18.978476 | PLAY [all]
2026-09-15 02:16:18.988759 | 
2026-09-15 02:16:18.988832 | TASK [Install "jq" for log collection]
2026-09-15 02:16:28.954634 | instance | changed
2026-09-15 02:16:28.956869 | 
2026-09-15 02:16:28.956943 | PLAY RECAP
2026-09-15 02:16:28.957005 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 02:16:28.957086 | 
2026-09-15 02:16:29.074733 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 02:16:29.076550 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 02:16:29.668753 | 
2026-09-15 02:16:29.668942 | PLAY [all]
2026-09-15 02:16:29.679289 | 
2026-09-15 02:16:29.679359 | LOOP [Check inventory directories]
2026-09-15 02:16:30.034083 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-15 02:16:30.209424 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-15 02:16:30.382049 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-15 02:16:30.399091 | 
2026-09-15 02:16:30.399285 | LOOP [Copy inventory file for Zuul]
2026-09-15 02:16:31.305300 | instance | changed:
2026-09-15 02:16:31.305560 | instance | {
2026-09-15 02:16:31.305598 | instance |   "ansible_loop_var": "item",
2026-09-15 02:16:31.305628 | instance |   "changed": false,
2026-09-15 02:16:31.305655 | instance |   "failed": false,
2026-09-15 02:16:31.305685 | instance |   "invocation": {
2026-09-15 02:16:31.305709 | instance |     "module_args": {
2026-09-15 02:16:31.305733 | instance |       "checksum_algorithm": "sha1",
2026-09-15 02:16:31.305755 | instance |       "follow": true,
2026-09-15 02:16:31.305778 | instance |       "get_attributes": true,
2026-09-15 02:16:31.305802 | instance |       "get_checksum": true,
2026-09-15 02:16:31.305826 | instance |       "get_mime": true,
2026-09-15 02:16:31.305849 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-15 02:16:31.305872 | instance |     }
2026-09-15 02:16:31.305893 | instance |   },
2026-09-15 02:16:31.305915 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-15 02:16:31.305938 | instance |   "stat": {
2026-09-15 02:16:31.305961 | instance |     "atime": 1789438571.1478179,
2026-09-15 02:16:31.305983 | instance |     "attr_flags": "e",
2026-09-15 02:16:31.306005 | instance |     "attributes": [
2026-09-15 02:16:31.306025 | instance |       "extents"
2026-09-15 02:16:31.306047 | instance |     ],
2026-09-15 02:16:31.306068 | instance |     "block_size": 4096,
2026-09-15 02:16:31.306088 | instance |     "blocks": 8,
2026-09-15 02:16:31.306111 | instance |     "charset": "unknown",
2026-09-15 02:16:31.306135 | instance |     "ctime": 1789438569.623709,
2026-09-15 02:16:31.306156 | instance |     "dev": 64513,
2026-09-15 02:16:31.306178 | instance |     "device_type": 0,
2026-09-15 02:16:31.306200 | instance |     "executable": true,
2026-09-15 02:16:31.306220 | instance |     "exists": true,
2026-09-15 02:16:31.306241 | instance |     "gid": 1000,
2026-09-15 02:16:31.306262 | instance |     "gr_name": "zuul",
2026-09-15 02:16:31.306284 | instance |     "inode": 1049268,
2026-09-15 02:16:31.306307 | instance |     "isblk": false,
2026-09-15 02:16:31.306329 | instance |     "ischr": false,
2026-09-15 02:16:31.306351 | instance |     "isdir": true,
2026-09-15 02:16:31.306373 | instance |     "isfifo": false,
2026-09-15 02:16:31.306395 | instance |     "isgid": false,
2026-09-15 02:16:31.306416 | instance |     "islnk": false,
2026-09-15 02:16:31.306438 | instance |     "isreg": false,
2026-09-15 02:16:31.306460 | instance |     "issock": false,
2026-09-15 02:16:31.306483 | instance |     "isuid": false,
2026-09-15 02:16:31.306518 | instance |     "mimetype": "unknown",
2026-09-15 02:16:31.306542 | instance |     "mode": "0755",
2026-09-15 02:16:31.306563 | instance |     "mtime": 1789438569.623709,
2026-09-15 02:16:31.306583 | instance |     "nlink": 23,
2026-09-15 02:16:31.306605 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-15 02:16:31.306625 | instance |     "pw_name": "zuul",
2026-09-15 02:16:31.306650 | instance |     "readable": true,
2026-09-15 02:16:31.306671 | instance |     "rgrp": true,
2026-09-15 02:16:31.306691 | instance |     "roth": true,
2026-09-15 02:16:31.306725 | instance |     "rusr": true,
2026-09-15 02:16:31.306747 | instance |     "size": 4096,
2026-09-15 02:16:31.306767 | instance |     "uid": 1000,
2026-09-15 02:16:31.306787 | instance |     "version": "3540634876",
2026-09-15 02:16:31.306807 | instance |     "wgrp": false,
2026-09-15 02:16:31.306830 | instance |     "woth": false,
2026-09-15 02:16:31.306851 | instance |     "writeable": true,
2026-09-15 02:16:31.306871 | instance |     "wusr": true,
2026-09-15 02:16:31.306892 | instance |     "xgrp": true,
2026-09-15 02:16:31.306912 | instance |     "xoth": true,
2026-09-15 02:16:31.306932 | instance |     "xusr": true
2026-09-15 02:16:31.306952 | instance |   }
2026-09-15 02:16:31.306973 | instance | }
2026-09-15 02:16:31.307016 | instance | ok: All items complete
2026-09-15 02:16:31.307042 | 
2026-09-15 02:16:31.308681 | instance | skipping: Conditional result was False
2026-09-15 02:16:31.313340 | instance | skipping: Conditional result was False
2026-09-15 02:16:31.336079 | 
2026-09-15 02:16:31.336323 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 02:16:31.716369 | instance | changed: 1 replacements made
2026-09-15 02:16:31.718974 | instance | skipping: Conditional result was False
2026-09-15 02:16:31.724663 | instance | skipping: Conditional result was False
2026-09-15 02:16:31.745997 | 
2026-09-15 02:16:31.746197 | TASK [Run Molecule scenario]
2026-09-15 02:16:32.727163 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-15 02:16:33.131386 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 02:16:33.131466 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-15 02:16:43.538779 | instance | INFO     [csi > dependency] Executing
2026-09-15 02:16:43.539174 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-15 02:16:43.539563 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-15 02:16:43.539787 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-15 02:16:43.554487 | instance | INFO     [csi > cleanup] Executing
2026-09-15 02:16:43.555127 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 02:16:43.569032 | instance | INFO     [csi > destroy] Executing
2026-09-15 02:16:43.569121 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-15 02:16:43.569212 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-15 02:16:43.578406 | instance | INFO     [csi > syntax] Executing
2026-09-15 02:16:45.107367 | instance |
2026-09-15 02:16:45.107548 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 02:16:45.180194 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-15 02:16:45.196449 | instance | INFO     [csi > create] Executing
2026-09-15 02:16:45.200123 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 02:16:45.215938 | instance | INFO     [csi > prepare] Executing
2026-09-15 02:16:45.990778 | instance |
2026-09-15 02:16:45.991039 | instance | PLAY [Prepare] *****************************************************************
2026-09-15 02:16:45.991279 | instance |
2026-09-15 02:16:45.991522 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 02:16:45.991758 | instance | Tuesday 15 September 2026  02:16:45 +0000 (0:00:00.026)       0:00:00.026 *****
2026-09-15 02:16:47.257435 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 02:16:47.257532 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 02:16:47.257667 | instance | interpreter could change the meaning of that path. See
2026-09-15 02:16:47.257810 | instance | https://docs.ansible.com/ansible-
2026-09-15 02:16:47.257941 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 02:16:47.268286 | instance | ok: [instance]
2026-09-15 02:16:47.268418 | instance |
2026-09-15 02:16:47.268581 | instance | TASK [Configure short hostname] ************************************************
2026-09-15 02:16:47.268710 | instance | Tuesday 15 September 2026  02:16:47 +0000 (0:00:01.281)       0:00:01.307 *****
2026-09-15 02:16:47.940611 | instance | changed: [instance]
2026-09-15 02:16:47.940836 | instance |
2026-09-15 02:16:47.941135 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-15 02:16:47.941437 | instance | Tuesday 15 September 2026  02:16:47 +0000 (0:00:00.672)       0:00:01.979 *****
2026-09-15 02:16:48.222693 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-15 02:16:48.222826 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-15 02:16:48.222997 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-15 02:16:48.232310 | instance | changed: [instance]
2026-09-15 02:16:48.232436 | instance |
2026-09-15 02:16:48.232774 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-15 02:16:48.233285 | instance | Tuesday 15 September 2026  02:16:48 +0000 (0:00:00.291)       0:00:02.271 *****
2026-09-15 02:16:49.128956 | instance | ok: [instance]
2026-09-15 02:16:49.129098 | instance |
2026-09-15 02:16:49.129266 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-15 02:16:49.129416 | instance |
2026-09-15 02:16:49.129577 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 02:16:49.129736 | instance | Tuesday 15 September 2026  02:16:49 +0000 (0:00:00.896)       0:00:03.168 *****
2026-09-15 02:16:49.902072 | instance | ok: [instance]
2026-09-15 02:16:49.902270 | instance |
2026-09-15 02:16:49.902524 | instance | TASK [Install depedencies] *****************************************************
2026-09-15 02:16:49.902824 | instance | Tuesday 15 September 2026  02:16:49 +0000 (0:00:00.772)       0:00:03.940 *****
2026-09-15 02:17:13.601749 | instance | changed: [instance]
2026-09-15 02:17:13.601966 | instance |
2026-09-15 02:17:13.602187 | instance | TASK [Start up service] ********************************************************
2026-09-15 02:17:13.602420 | instance | Tuesday 15 September 2026  02:17:13 +0000 (0:00:23.699)       0:00:27.640 *****
2026-09-15 02:17:14.144639 | instance | ok: [instance]
2026-09-15 02:17:14.144967 | instance |
2026-09-15 02:17:14.145181 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-15 02:17:14.145412 | instance | Tuesday 15 September 2026  02:17:14 +0000 (0:00:00.543)       0:00:28.183 *****
2026-09-15 02:17:14.447579 | instance | ok: [instance]
2026-09-15 02:17:14.447778 | instance |
2026-09-15 02:17:14.448073 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-15 02:17:14.448309 | instance | Tuesday 15 September 2026  02:17:14 +0000 (0:00:00.302)       0:00:28.486 *****
2026-09-15 02:17:15.151521 | instance | changed: [instance]
2026-09-15 02:17:15.151665 | instance |
2026-09-15 02:17:15.151935 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-15 02:17:15.152175 | instance | Tuesday 15 September 2026  02:17:15 +0000 (0:00:00.703)       0:00:29.190 *****
2026-09-15 02:17:15.370407 | instance | ok: [instance]
2026-09-15 02:17:15.370620 | instance |
2026-09-15 02:17:15.370904 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-15 02:17:15.371144 | instance | Tuesday 15 September 2026  02:17:15 +0000 (0:00:00.218)       0:00:29.408 *****
2026-09-15 02:17:15.392082 | instance | skipping: [instance]
2026-09-15 02:17:15.392299 | instance |
2026-09-15 02:17:15.392534 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-15 02:17:15.392911 | instance | Tuesday 15 September 2026  02:17:15 +0000 (0:00:00.022)       0:00:29.431 *****
2026-09-15 02:17:15.930094 | instance | changed: [instance] => (item=osd0)
2026-09-15 02:17:15.930307 | instance | changed: [instance] => (item=osd1)
2026-09-15 02:17:15.930849 | instance | changed: [instance] => (item=osd2)
2026-09-15 02:17:15.931190 | instance |
2026-09-15 02:17:15.931738 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-15 02:17:15.932121 | instance | Tuesday 15 September 2026  02:17:15 +0000 (0:00:00.537)       0:00:29.969 *****
2026-09-15 02:17:16.581763 | instance | changed: [instance] => (item=osd0)
2026-09-15 02:17:16.582039 | instance | changed: [instance] => (item=osd1)
2026-09-15 02:17:16.582244 | instance | changed: [instance] => (item=osd2)
2026-09-15 02:17:16.582435 | instance |
2026-09-15 02:17:16.582632 | instance | TASK [Start loop devices] ******************************************************
2026-09-15 02:17:16.582850 | instance | Tuesday 15 September 2026  02:17:16 +0000 (0:00:00.651)       0:00:30.620 *****
2026-09-15 02:17:17.316065 | instance | changed: [instance] => (item=osd0)
2026-09-15 02:17:17.316287 | instance | changed: [instance] => (item=osd1)
2026-09-15 02:17:17.316711 | instance | changed: [instance] => (item=osd2)
2026-09-15 02:17:17.317020 | instance |
2026-09-15 02:17:17.317318 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-15 02:17:17.317610 | instance | Tuesday 15 September 2026  02:17:17 +0000 (0:00:00.734)       0:00:31.355 *****
2026-09-15 02:17:20.518151 | instance | changed: [instance] => (item=osd0)
2026-09-15 02:17:20.518389 | instance | changed: [instance] => (item=osd1)
2026-09-15 02:17:20.519309 | instance | changed: [instance] => (item=osd2)
2026-09-15 02:17:20.519360 | instance |
2026-09-15 02:17:20.519372 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-15 02:17:20.519382 | instance | Tuesday 15 September 2026  02:17:20 +0000 (0:00:03.202)       0:00:34.557 *****
2026-09-15 02:17:22.504681 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-15 02:17:22.504754 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-15 02:17:22.504814 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-15 02:17:22.504827 | instance |
2026-09-15 02:17:22.504836 | instance | PLAY RECAP *********************************************************************
2026-09-15 02:17:22.504846 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-15 02:17:22.504855 | instance |
2026-09-15 02:17:22.504864 | instance | Tuesday 15 September 2026  02:17:22 +0000 (0:00:01.983)       0:00:36.540 *****
2026-09-15 02:17:22.504873 | instance | ===============================================================================
2026-09-15 02:17:22.504881 | instance | Install depedencies ---------------------------------------------------- 23.70s
2026-09-15 02:17:22.504889 | instance | Create a volume group for each loop device ------------------------------ 3.20s
2026-09-15 02:17:22.504898 | instance | Create a logical volume for each loop device ---------------------------- 1.98s
2026-09-15 02:17:22.504907 | instance | Gathering Facts --------------------------------------------------------- 1.28s
2026-09-15 02:17:22.504915 | instance | Purge "snapd" package --------------------------------------------------- 0.90s
2026-09-15 02:17:22.504923 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-15 02:17:22.504932 | instance | Start loop devices ------------------------------------------------------ 0.73s
2026-09-15 02:17:22.504947 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.70s
2026-09-15 02:17:22.505082 | instance | Configure short hostname ------------------------------------------------ 0.67s
2026-09-15 02:17:22.505340 | instance | Set permissions on loopback devices ------------------------------------- 0.65s
2026-09-15 02:17:22.505577 | instance | Start up service -------------------------------------------------------- 0.54s
2026-09-15 02:17:22.505822 | instance | Create devices for Ceph ------------------------------------------------- 0.54s
2026-09-15 02:17:22.506051 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-09-15 02:17:22.506288 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-15 02:17:22.506535 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-15 02:17:22.506829 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-15 02:17:22.570132 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-15 02:17:22.584379 | instance | INFO     [csi > converge] Executing
2026-09-15 02:17:24.127991 | instance |
2026-09-15 02:17:24.128105 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-15 02:17:24.128317 | instance |
2026-09-15 02:17:24.128552 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 02:17:24.128843 | instance | Tuesday 15 September 2026  02:17:24 +0000 (0:00:00.007)       0:00:00.007 *****
2026-09-15 02:17:25.149361 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 02:17:25.149543 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 02:17:25.149789 | instance | interpreter could change the meaning of that path. See
2026-09-15 02:17:25.150026 | instance | https://docs.ansible.com/ansible-
2026-09-15 02:17:25.150262 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 02:17:25.166139 | instance | ok: [instance]
2026-09-15 02:17:25.166349 | instance |
2026-09-15 02:17:25.166588 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-15 02:17:25.166843 | instance | Tuesday 15 September 2026  02:17:25 +0000 (0:00:01.037)       0:00:01.045 *****
2026-09-15 02:17:25.238221 | instance | ok: [instance] => {
2026-09-15 02:17:25.238428 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-15 02:17:25.238649 | instance | }
2026-09-15 02:17:25.238898 | instance |
2026-09-15 02:17:25.239133 | instance | PLAY [all] *********************************************************************
2026-09-15 02:17:25.239354 | instance |
2026-09-15 02:17:25.239583 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 02:17:25.239831 | instance | Tuesday 15 September 2026  02:17:25 +0000 (0:00:00.071)       0:00:01.117 *****
2026-09-15 02:17:26.190142 | instance | ok: [instance]
2026-09-15 02:17:26.190478 | instance |
2026-09-15 02:17:26.190811 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-15 02:17:26.191108 | instance | Tuesday 15 September 2026  02:17:26 +0000 (0:00:00.951)       0:00:02.069 *****
2026-09-15 02:17:26.240928 | instance | skipping: [instance]
2026-09-15 02:17:26.241271 | instance |
2026-09-15 02:17:26.241584 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-15 02:17:26.241908 | instance | Tuesday 15 September 2026  02:17:26 +0000 (0:00:00.050)       0:00:02.119 *****
2026-09-15 02:17:26.465141 | instance | ok: [instance]
2026-09-15 02:17:26.465316 | instance |
2026-09-15 02:17:26.465460 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-15 02:17:26.465589 | instance |
2026-09-15 02:17:26.465724 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 02:17:26.465884 | instance | Tuesday 15 September 2026  02:17:26 +0000 (0:00:00.223)       0:00:02.343 *****
2026-09-15 02:17:27.435912 | instance | ok: [instance]
2026-09-15 02:17:27.436257 | instance |
2026-09-15 02:17:27.436653 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 02:17:27.437005 | instance | Tuesday 15 September 2026  02:17:27 +0000 (0:00:00.970)       0:00:03.313 *****
2026-09-15 02:17:27.740909 | instance | ok: [instance]
2026-09-15 02:17:27.741254 | instance |
2026-09-15 02:17:27.741589 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 02:17:27.741926 | instance | Tuesday 15 September 2026  02:17:27 +0000 (0:00:00.306)       0:00:03.620 *****
2026-09-15 02:17:27.791025 | instance | skipping: [instance]
2026-09-15 02:17:27.791474 | instance |
2026-09-15 02:17:27.791959 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-15 02:17:27.792421 | instance | Tuesday 15 September 2026  02:17:27 +0000 (0:00:00.050)       0:00:03.670 *****
2026-09-15 02:17:28.131961 | instance | changed: [instance]
2026-09-15 02:17:28.132318 | instance |
2026-09-15 02:17:28.132716 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 02:17:28.133068 | instance | Tuesday 15 September 2026  02:17:28 +0000 (0:00:00.341)       0:00:04.011 *****
2026-09-15 02:17:28.219276 | instance | ok: [instance] => {
2026-09-15 02:17:28.219661 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-15 02:17:28.219997 | instance | }
2026-09-15 02:17:28.220293 | instance |
2026-09-15 02:17:28.220645 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 02:17:28.220994 | instance | Tuesday 15 September 2026  02:17:28 +0000 (0:00:00.087)       0:00:04.098 *****
2026-09-15 02:17:28.803171 | instance | changed: [instance]
2026-09-15 02:17:28.803215 | instance |
2026-09-15 02:17:28.803228 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 02:17:28.803239 | instance | Tuesday 15 September 2026  02:17:28 +0000 (0:00:00.583)       0:00:04.681 *****
2026-09-15 02:17:28.864699 | instance | skipping: [instance]
2026-09-15 02:17:28.864820 | instance |
2026-09-15 02:17:28.864835 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 02:17:28.865010 | instance | Tuesday 15 September 2026  02:17:28 +0000 (0:00:00.062)       0:00:04.744 *****
2026-09-15 02:17:28.925583 | instance | skipping: [instance]
2026-09-15 02:17:28.925829 | instance |
2026-09-15 02:17:28.926092 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 02:17:28.926314 | instance | Tuesday 15 September 2026  02:17:28 +0000 (0:00:00.060)       0:00:04.805 *****
2026-09-15 02:17:29.140521 | instance | ok: [instance]
2026-09-15 02:17:29.140861 | instance |
2026-09-15 02:17:29.141214 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 02:17:29.141521 | instance | Tuesday 15 September 2026  02:17:29 +0000 (0:00:00.214)       0:00:05.019 *****
2026-09-15 02:17:30.241336 | instance | ok: [instance]
2026-09-15 02:17:30.241701 | instance |
2026-09-15 02:17:30.242033 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 02:17:30.242364 | instance | Tuesday 15 September 2026  02:17:30 +0000 (0:00:01.100)       0:00:06.120 *****
2026-09-15 02:17:30.305417 | instance | ok: [instance] => {
2026-09-15 02:17:30.305591 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-15 02:17:30.305855 | instance | }
2026-09-15 02:17:30.306033 | instance |
2026-09-15 02:17:30.306222 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 02:17:30.306411 | instance | Tuesday 15 September 2026  02:17:30 +0000 (0:00:00.064)       0:00:06.184 *****
2026-09-15 02:17:30.933094 | instance | changed: [instance]
2026-09-15 02:17:30.933147 | instance |
2026-09-15 02:17:30.933158 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 02:17:30.933168 | instance | Tuesday 15 September 2026  02:17:30 +0000 (0:00:00.626)       0:00:06.811 *****
2026-09-15 02:17:33.840127 | instance | changed: [instance]
2026-09-15 02:17:33.840229 | instance |
2026-09-15 02:17:33.840239 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-15 02:17:33.840392 | instance | Tuesday 15 September 2026  02:17:33 +0000 (0:00:02.908)       0:00:09.720 *****
2026-09-15 02:17:33.879076 | instance | skipping: [instance]
2026-09-15 02:17:33.879811 | instance |
2026-09-15 02:17:33.879840 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-15 02:17:33.879848 | instance | Tuesday 15 September 2026  02:17:33 +0000 (0:00:00.038)       0:00:09.758 *****
2026-09-15 02:17:33.925087 | instance | skipping: [instance]
2026-09-15 02:17:33.925615 | instance |
2026-09-15 02:17:33.925862 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-15 02:17:33.925872 | instance | Tuesday 15 September 2026  02:17:33 +0000 (0:00:00.044)       0:00:09.803 *****
2026-09-15 02:17:33.963292 | instance | skipping: [instance]
2026-09-15 02:17:33.963467 | instance |
2026-09-15 02:17:33.963987 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-15 02:17:33.964024 | instance | Tuesday 15 September 2026  02:17:33 +0000 (0:00:00.038)       0:00:09.842 *****
2026-09-15 02:17:40.543312 | instance | changed: [instance]
2026-09-15 02:17:40.544014 | instance |
2026-09-15 02:17:40.544060 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-15 02:17:40.544072 | instance | Tuesday 15 September 2026  02:17:40 +0000 (0:00:06.579)       0:00:16.422 *****
2026-09-15 02:17:41.189892 | instance | changed: [instance]
2026-09-15 02:17:41.190636 | instance |
2026-09-15 02:17:41.190687 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-15 02:17:41.190700 | instance | Tuesday 15 September 2026  02:17:41 +0000 (0:00:00.646)       0:00:17.069 *****
2026-09-15 02:17:42.138625 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-15 02:17:42.140303 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-15 02:17:42.140360 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-15 02:17:42.140387 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-15 02:17:42.140403 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-15 02:17:42.140416 | instance |
2026-09-15 02:17:42.140430 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-15 02:17:42.140442 | instance | Tuesday 15 September 2026  02:17:42 +0000 (0:00:00.948)       0:00:18.017 *****
2026-09-15 02:17:42.783556 | instance | changed: [instance]
2026-09-15 02:17:42.783642 | instance |
2026-09-15 02:17:42.784539 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-15 02:17:42.784603 | instance | Tuesday 15 September 2026  02:17:42 +0000 (0:00:00.615)       0:00:18.633 *****
2026-09-15 02:17:42.784611 | instance |
2026-09-15 02:17:42.784617 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 02:17:42.784623 | instance | Tuesday 15 September 2026  02:17:42 +0000 (0:00:00.030)       0:00:18.663 *****
2026-09-15 02:17:43.781977 | instance | ok: [instance]
2026-09-15 02:17:43.782784 | instance |
2026-09-15 02:17:43.782836 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-15 02:17:43.782848 | instance | Tuesday 15 September 2026  02:17:43 +0000 (0:00:00.997)       0:00:19.661 *****
2026-09-15 02:17:44.246971 | instance | changed: [instance]
2026-09-15 02:17:44.247777 | instance |
2026-09-15 02:17:44.247831 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-15 02:17:44.247844 | instance | Tuesday 15 September 2026  02:17:44 +0000 (0:00:00.465)       0:00:20.126 *****
2026-09-15 02:17:44.789889 | instance | changed: [instance]
2026-09-15 02:17:44.789937 | instance |
2026-09-15 02:17:44.789948 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 02:17:44.789958 | instance | Tuesday 15 September 2026  02:17:44 +0000 (0:00:00.542)       0:00:20.668 *****
2026-09-15 02:17:45.012476 | instance | ok: [instance]
2026-09-15 02:17:45.012528 | instance |
2026-09-15 02:17:45.012540 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 02:17:45.012550 | instance | Tuesday 15 September 2026  02:17:45 +0000 (0:00:00.222)       0:00:20.891 *****
2026-09-15 02:17:45.083999 | instance | ok: [instance] => {
2026-09-15 02:17:45.084592 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-15 02:17:45.084666 | instance | }
2026-09-15 02:17:45.084672 | instance |
2026-09-15 02:17:45.084677 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 02:17:45.084684 | instance | Tuesday 15 September 2026  02:17:45 +0000 (0:00:00.072)       0:00:20.963 *****
2026-09-15 02:17:46.923734 | instance | changed: [instance]
2026-09-15 02:17:46.924485 | instance |
2026-09-15 02:17:46.924543 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 02:17:46.924578 | instance | Tuesday 15 September 2026  02:17:46 +0000 (0:00:01.839)       0:00:22.802 *****
2026-09-15 02:17:51.300026 | instance | changed: [instance]
2026-09-15 02:17:51.300132 | instance |
2026-09-15 02:17:51.300144 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-15 02:17:51.300276 | instance | Tuesday 15 September 2026  02:17:51 +0000 (0:00:04.377)       0:00:27.180 *****
2026-09-15 02:17:52.315989 | instance | ok: [instance]
2026-09-15 02:17:52.316648 | instance |
2026-09-15 02:17:52.316825 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-15 02:17:52.316840 | instance | Tuesday 15 September 2026  02:17:52 +0000 (0:00:01.015)       0:00:28.195 *****
2026-09-15 02:17:52.872286 | instance | changed: [instance]
2026-09-15 02:17:52.872673 | instance |
2026-09-15 02:17:52.873125 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-15 02:17:52.873179 | instance | Tuesday 15 September 2026  02:17:52 +0000 (0:00:00.556)       0:00:28.751 *****
2026-09-15 02:17:53.364111 | instance | changed: [instance]
2026-09-15 02:17:53.364233 | instance |
2026-09-15 02:17:53.364606 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-15 02:17:53.364664 | instance | Tuesday 15 September 2026  02:17:53 +0000 (0:00:00.492)       0:00:29.243 *****
2026-09-15 02:17:54.008966 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-15 02:17:54.009022 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-15 02:17:54.009034 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-15 02:17:54.009043 | instance |
2026-09-15 02:17:54.009052 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-15 02:17:54.009062 | instance | Tuesday 15 September 2026  02:17:54 +0000 (0:00:00.642)       0:00:29.886 *****
2026-09-15 02:17:54.519161 | instance | changed: [instance]
2026-09-15 02:17:54.519966 | instance |
2026-09-15 02:17:54.520030 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-15 02:17:54.520042 | instance | Tuesday 15 September 2026  02:17:54 +0000 (0:00:00.511)       0:00:30.398 *****
2026-09-15 02:17:55.056987 | instance | changed: [instance]
2026-09-15 02:17:55.057100 | instance |
2026-09-15 02:17:55.057875 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-15 02:17:55.057925 | instance | Tuesday 15 September 2026  02:17:55 +0000 (0:00:00.517)       0:00:30.916 *****
2026-09-15 02:17:55.057933 | instance |
2026-09-15 02:17:55.057939 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 02:17:55.057950 | instance | Tuesday 15 September 2026  02:17:55 +0000 (0:00:00.020)       0:00:30.936 *****
2026-09-15 02:17:55.823596 | instance | ok: [instance]
2026-09-15 02:17:55.824360 | instance |
2026-09-15 02:17:55.824410 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-15 02:17:55.824421 | instance | Tuesday 15 September 2026  02:17:55 +0000 (0:00:00.766)       0:00:31.703 *****
2026-09-15 02:17:57.834768 | instance | changed: [instance]
2026-09-15 02:17:57.834864 | instance |
2026-09-15 02:17:57.835250 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-15 02:17:57.835320 | instance | Tuesday 15 September 2026  02:17:57 +0000 (0:00:02.011)       0:00:33.714 *****
2026-09-15 02:17:58.440598 | instance | changed: [instance]
2026-09-15 02:17:58.441092 | instance |
2026-09-15 02:17:58.441125 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-15 02:17:58.441133 | instance | Tuesday 15 September 2026  02:17:58 +0000 (0:00:00.606)       0:00:34.320 *****
2026-09-15 02:17:58.510961 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-15 02:17:58.511437 | instance |
2026-09-15 02:17:58.511482 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-15 02:17:58.511490 | instance | Tuesday 15 September 2026  02:17:58 +0000 (0:00:00.070)       0:00:34.390 *****
2026-09-15 02:18:03.503809 | instance | changed: [instance]
2026-09-15 02:18:03.503862 | instance |
2026-09-15 02:18:03.503872 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-15 02:18:03.503880 | instance | Tuesday 15 September 2026  02:18:03 +0000 (0:00:04.992)       0:00:39.382 *****
2026-09-15 02:18:04.204694 | instance | ok: [instance] => (item=chronyd)
2026-09-15 02:18:04.204757 | instance | ok: [instance] => (item=sshd)
2026-09-15 02:18:04.204765 | instance |
2026-09-15 02:18:04.204770 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-15 02:18:04.204776 | instance | Tuesday 15 September 2026  02:18:04 +0000 (0:00:00.700)       0:00:40.083 *****
2026-09-15 02:18:15.156534 | instance | changed: [instance]
2026-09-15 02:18:15.156652 | instance |
2026-09-15 02:18:15.156663 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-15 02:18:15.156674 | instance | Tuesday 15 September 2026  02:18:15 +0000 (0:00:10.951)       0:00:51.034 *****
2026-09-15 02:18:15.397678 | instance | ok: [instance]
2026-09-15 02:18:15.397758 | instance |
2026-09-15 02:18:15.397770 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-15 02:18:15.397780 | instance | Tuesday 15 September 2026  02:18:15 +0000 (0:00:00.240)       0:00:51.275 *****
2026-09-15 02:18:16.025048 | instance | changed: [instance]
2026-09-15 02:18:16.025191 | instance |
2026-09-15 02:18:16.025943 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-15 02:18:16.026006 | instance | Tuesday 15 September 2026  02:18:16 +0000 (0:00:00.628)       0:00:51.904 *****
2026-09-15 02:18:16.408990 | instance | changed: [instance]
2026-09-15 02:18:16.409046 | instance |
2026-09-15 02:18:16.409409 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-15 02:18:16.409461 | instance | Tuesday 15 September 2026  02:18:16 +0000 (0:00:00.384)       0:00:52.288 *****
2026-09-15 02:18:16.518915 | instance | ok: [instance]
2026-09-15 02:18:16.519404 | instance |
2026-09-15 02:18:16.519451 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-15 02:18:16.519460 | instance | Tuesday 15 September 2026  02:18:16 +0000 (0:00:00.110)       0:00:52.398 *****
2026-09-15 02:18:16.766719 | instance | ok: [instance] => (item=instance)
2026-09-15 02:18:16.767421 | instance |
2026-09-15 02:18:16.767557 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-15 02:18:16.767569 | instance | Tuesday 15 September 2026  02:18:16 +0000 (0:00:00.247)       0:00:52.646 *****
2026-09-15 02:18:16.801603 | instance | ok: [instance]
2026-09-15 02:18:16.801689 | instance |
2026-09-15 02:18:16.801793 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-15 02:18:16.801894 | instance | Tuesday 15 September 2026  02:18:16 +0000 (0:00:00.035)       0:00:52.681 *****
2026-09-15 02:18:16.867350 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-15 02:18:16.867405 | instance |
2026-09-15 02:18:16.867535 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-15 02:18:16.867630 | instance | Tuesday 15 September 2026  02:18:16 +0000 (0:00:00.065)       0:00:52.747 *****
2026-09-15 02:18:17.177798 | instance | changed: [instance]
2026-09-15 02:18:17.177855 | instance |
2026-09-15 02:18:17.178113 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-15 02:18:17.178155 | instance | Tuesday 15 September 2026  02:18:17 +0000 (0:00:00.309)       0:00:53.057 *****
2026-09-15 02:18:17.892683 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-15 02:18:17.892753 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-15 02:18:17.893166 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-15 02:18:17.893202 | instance |
2026-09-15 02:18:17.893207 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-15 02:18:17.893212 | instance | Tuesday 15 September 2026  02:18:17 +0000 (0:00:00.714)       0:00:53.772 *****
2026-09-15 02:19:55.201123 | instance | ok: [instance]
2026-09-15 02:19:55.201231 | instance |
2026-09-15 02:19:55.201340 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-15 02:19:55.201545 | instance | Tuesday 15 September 2026  02:19:55 +0000 (0:01:37.307)       0:02:31.079 *****
2026-09-15 02:19:55.467544 | instance | changed: [instance]
2026-09-15 02:19:55.467644 | instance |
2026-09-15 02:19:55.467657 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-15 02:19:55.467668 | instance | Tuesday 15 September 2026  02:19:55 +0000 (0:00:00.265)       0:02:31.345 *****
2026-09-15 02:19:55.522778 | instance | ok: [instance]
2026-09-15 02:19:55.522855 | instance |
2026-09-15 02:19:55.522867 | instance | TASK [Install Ceph host] *******************************************************
2026-09-15 02:19:55.522971 | instance | Tuesday 15 September 2026  02:19:55 +0000 (0:00:00.054)       0:02:31.399 *****
2026-09-15 02:19:55.664681 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-15 02:19:55.664749 | instance |
2026-09-15 02:19:55.664946 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-15 02:19:55.665098 | instance | Tuesday 15 September 2026  02:19:55 +0000 (0:00:00.144)       0:02:31.544 *****
2026-09-15 02:19:57.303542 | instance | ok: [instance]
2026-09-15 02:19:57.303605 | instance |
2026-09-15 02:19:57.303624 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-15 02:19:57.303656 | instance | Tuesday 15 September 2026  02:19:57 +0000 (0:00:01.638)       0:02:33.182 *****
2026-09-15 02:19:57.386318 | instance | ok: [instance] => (item=instance)
2026-09-15 02:19:57.386503 | instance |
2026-09-15 02:19:57.386761 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-15 02:19:57.386992 | instance | Tuesday 15 September 2026  02:19:57 +0000 (0:00:00.082)       0:02:33.265 *****
2026-09-15 02:19:57.764970 | instance | ok: [instance]
2026-09-15 02:19:57.765240 | instance |
2026-09-15 02:19:57.765563 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-15 02:19:57.765909 | instance | Tuesday 15 September 2026  02:19:57 +0000 (0:00:00.379)       0:02:33.644 *****
2026-09-15 02:20:00.338100 | instance | ok: [instance]
2026-09-15 02:20:00.338168 | instance |
2026-09-15 02:20:00.338180 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-15 02:20:00.338190 | instance | Tuesday 15 September 2026  02:20:00 +0000 (0:00:02.572)       0:02:36.216 *****
2026-09-15 02:20:57.414733 | 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-15 02:20:57.415112 | 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__rwlkny6/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__rwlkny6/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__rwlkny6/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__rwlkny6/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-15T02:20:57.218+0000 7f0572f3b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAgq6hqrJWlJSAA7HifloFd1AWJ3nH4Aa0TUPJWIeyEkEvkN4EEciezGKE=: Malformed input [buffer:3]\n2026-09-15T02:20:57.218+0000 7f0572f3b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T02:20:57.218+0000 7f0572f3b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAgq6hqrJWlJSAA7HifloFd1AWJ3nH4Aa0TUPJWIeyEkEvkN4EEciezGKE=: Malformed input [buffer:3]\n2026-09-15T02:20:57.218+0000 7f0572f3b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T02:20:57.218+0000 7f0572f3b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAgq6hqrJWlJSAA7HifloFd1AWJ3nH4Aa0TUPJWIeyEkEvkN4EEciezGKE=: Malformed input [buffer:3]\n2026-09-15T02:20:57.218+0000 7f0572f3b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T02:20:57.218+0000 7f0572f3b640 -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-15 02:20:57.417108 | instance |
2026-09-15 02:20:57.417218 | instance | PLAY RECAP *********************************************************************
2026-09-15 02:20:57.417337 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-15 02:20:57.417432 | instance |
2026-09-15 02:20:57.419630 | instance | Tuesday 15 September 2026  02:20:57 +0000 (0:00:57.081)       0:03:33.297 *****
2026-09-15 02:20:57.419678 | instance | ===============================================================================
2026-09-15 02:20:57.419684 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 97.31s
2026-09-15 02:20:57.419688 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 57.08s
2026-09-15 02:20:57.419693 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 10.95s
2026-09-15 02:20:57.419698 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.58s
2026-09-15 02:20:57.419702 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.99s
2026-09-15 02:20:57.419706 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.38s
2026-09-15 02:20:57.419724 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.91s
2026-09-15 02:20:57.419728 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.57s
2026-09-15 02:20:57.419732 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 2.01s
2026-09-15 02:20:57.419735 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.84s
2026-09-15 02:20:57.419740 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.76s
2026-09-15 02:20:57.419744 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.64s
2026-09-15 02:20:57.419748 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.10s
2026-09-15 02:20:57.419752 | instance | Gathering Facts --------------------------------------------------------- 1.04s
2026-09-15 02:20:57.419756 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.02s
2026-09-15 02:20:57.419761 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-15 02:20:57.419765 | instance | Gathering Facts --------------------------------------------------------- 0.95s
2026-09-15 02:20:57.419769 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.95s
2026-09-15 02:20:57.419773 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.71s
2026-09-15 02:20:57.419776 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.70s
2026-09-15 02:20:57.524661 | 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 02:20:57.524730 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-15 02:20:57.524742 | 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 02:20:57.651243 | instance | ERROR
2026-09-15 02:20:57.651534 | instance | {
2026-09-15 02:20:57.651595 | instance |   "delta": "0:04:25.466790",
2026-09-15 02:20:57.651636 | instance |   "end": "2026-09-15 02:20:57.593915",
2026-09-15 02:20:57.651671 | instance |   "msg": "non-zero return code",
2026-09-15 02:20:57.651706 | instance |   "rc": 2,
2026-09-15 02:20:57.651740 | instance |   "start": "2026-09-15 02:16:32.127125"
2026-09-15 02:20:57.651775 | instance | }
failure
2026-09-15 02:20:57.660721 | 
2026-09-15 02:20:57.660822 | PLAY RECAP
2026-09-15 02:20:57.660904 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 02:20:57.660946 | 
2026-09-15 02:20:57.808192 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 02:20:57.812074 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 02:20:58.410636 | 
2026-09-15 02:20:58.410763 | PLAY [all]
2026-09-15 02:20:58.423650 | 
2026-09-15 02:20:58.423762 | TASK [gather-host-logs : creating directory for system status]
2026-09-15 02:20:58.812238 | instance | changed
2026-09-15 02:20:58.823243 | 
2026-09-15 02:20:58.823403 | TASK [gather-host-logs : Get logs for each host]
2026-09-15 02:20:59.216672 | instance | + systemd-cgls --full --all --no-pager
2026-09-15 02:20:59.228174 | instance | + ip addr
2026-09-15 02:20:59.230307 | instance | + ip route
2026-09-15 02:20:59.233910 | instance | + lsblk
2026-09-15 02:20:59.237951 | instance | + mount
2026-09-15 02:20:59.239955 | instance | + docker images
2026-09-15 02:20:59.265486 | instance | + brctl show
2026-09-15 02:20:59.266037 | instance | /bin/bash: line 8: brctl: command not found
2026-09-15 02:20:59.266260 | instance | + ip neigh
2026-09-15 02:20:59.268300 | instance | + bridge fdb show
2026-09-15 02:20:59.271980 | instance | + ps aux --sort=-%mem
2026-09-15 02:20:59.294045 | instance | + dpkg -l
2026-09-15 02:20:59.307004 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-15 02:20:59.307389 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-15 02:20:59.325315 | instance | + '[' '!' -z '' ']'
2026-09-15 02:20:59.377684 | instance | ok: Runtime: 0:00:00.114936
2026-09-15 02:20:59.389184 | 
2026-09-15 02:20:59.389328 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-15 02:21:00.093810 | instance | changed:
2026-09-15 02:21:00.094107 | instance | created directory /var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/work/logs/instance
2026-09-15 02:21:00.094165 | instance | cd+++++++++ system/
2026-09-15 02:21:00.094209 | instance | >f+++++++++ system/brctl-show.txt
2026-09-15 02:21:00.094249 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-15 02:21:00.094287 | instance | >f+++++++++ system/docker-images.txt
2026-09-15 02:21:00.094330 | instance | >f+++++++++ system/ip-addr.txt
2026-09-15 02:21:00.094369 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-15 02:21:00.094408 | instance | >f+++++++++ system/ip-route.txt
2026-09-15 02:21:00.094451 | instance | >f+++++++++ system/lsblk.txt
2026-09-15 02:21:00.094489 | instance | >f+++++++++ system/mount.txt
2026-09-15 02:21:00.094526 | instance | >f+++++++++ system/packages.txt
2026-09-15 02:21:00.094562 | instance | >f+++++++++ system/ps.txt
2026-09-15 02:21:00.094598 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-15 02:21:00.113235 | 
2026-09-15 02:21:00.113398 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 02:21:00.323697 | instance | changed: "values"
2026-09-15 02:21:00.490779 | instance | changed: "releases"
2026-09-15 02:21:00.506736 | 
2026-09-15 02:21:00.507002 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 02:21:00.750716 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-15 02:21:01.059171 | instance | ok: Runtime: 0:00:00.005327
2026-09-15 02:21:01.070101 | 
2026-09-15 02:21:01.070393 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 02:21:01.624415 | instance | changed:
2026-09-15 02:21:01.624721 | instance | cd+++++++++ helm/
2026-09-15 02:21:01.624781 | instance | cd+++++++++ helm/releases/
2026-09-15 02:21:01.624826 | instance | cd+++++++++ helm/values/
2026-09-15 02:21:01.641890 | 
2026-09-15 02:21:01.642045 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 02:21:01.870188 | instance | changed
2026-09-15 02:21:01.882259 | 
2026-09-15 02:21:01.882433 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 02:21:02.185788 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 02:21:02.185966 | instance |
2026-09-15 02:21:02.193362 | instance | environment: line 1: kubectl: command not found
2026-09-15 02:21:02.194943 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 02:21:02.195091 | instance | environment: line 1: kubectl: command not found
2026-09-15 02:21:02.196358 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 02:21:02.198740 | instance | environment: line 1: kubectl: command not found
2026-09-15 02:21:02.199785 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 02:21:02.202010 | instance | environment: line 1: kubectl: command not found
2026-09-15 02:21:02.203440 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 02:21:02.204310 | instance | environment: line 1: kubectl: command not found
2026-09-15 02:21:02.205797 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 02:21:02.431765 | instance | ok: Runtime: 0:00:00.028369
2026-09-15 02:21:02.445388 | 
2026-09-15 02:21:02.445616 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 02:21:02.639527 | instance | changed
2026-09-15 02:21:02.645555 | 
2026-09-15 02:21:02.645647 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 02:21:02.888510 | instance | environment: line 5: kubectl: command not found
2026-09-15 02:21:02.889541 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 02:21:02.889970 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 02:21:02.890544 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 02:21:03.189570 | instance | ok: Runtime: 0:00:00.010452
2026-09-15 02:21:03.201062 | 
2026-09-15 02:21:03.201205 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 02:21:03.782288 | instance | changed:
2026-09-15 02:21:03.782593 | instance | cd+++++++++ objects/
2026-09-15 02:21:03.782649 | instance | cd+++++++++ objects/cluster/
2026-09-15 02:21:03.782695 | instance | cd+++++++++ objects/namespaced/
2026-09-15 02:21:03.802444 | 
2026-09-15 02:21:03.802652 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 02:21:04.024261 | instance | changed
2026-09-15 02:21:04.035712 | 
2026-09-15 02:21:04.035853 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 02:21:04.267926 | instance | changed
2026-09-15 02:21:04.277346 | 
2026-09-15 02:21:04.277491 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 02:21:04.533493 | instance | environment: line 3: kubectl: command not found
2026-09-15 02:21:04.823152 | instance | ok: Runtime: 0:00:00.006618
2026-09-15 02:21:04.831190 | 
2026-09-15 02:21:04.831320 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 02:21:05.373263 | instance | changed:
2026-09-15 02:21:05.373525 | instance | cd+++++++++ pod-logs/
2026-09-15 02:21:05.373579 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-15 02:21:05.398804 | 
2026-09-15 02:21:05.398987 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-15 02:21:05.626854 | instance | changed
2026-09-15 02:21:05.637366 | 
2026-09-15 02:21:05.637530 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-15 02:21:05.893605 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 02:21:06.185614 | instance | ok: Runtime: 0:00:00.051225
2026-09-15 02:21:06.194750 | 
2026-09-15 02:21:06.194887 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-15 02:21:06.445201 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 02:21:06.477474 | instance | ceph-mgr endpoints:
2026-09-15 02:21:06.739611 | instance | ok: Runtime: 0:00:00.037856
2026-09-15 02:21:06.746422 | 
2026-09-15 02:21:06.746594 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-15 02:21:06.982304 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-15 02:21:07.287458 | instance | ok: Runtime: 0:00:00.028411
2026-09-15 02:21:07.298669 | 
2026-09-15 02:21:07.298816 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-15 02:21:07.911524 | instance | changed: cd+++++++++ prometheus/
2026-09-15 02:21:07.923476 | 
2026-09-15 02:21:07.923564 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-15 02:21:08.152788 | instance | changed
2026-09-15 02:21:08.162896 | 
2026-09-15 02:21:08.163077 | TASK [gather-selenium-data : Get selenium data]
2026-09-15 02:21:08.489533 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-15 02:21:08.490929 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-15 02:21:08.712251 | instance | ERROR
2026-09-15 02:21:08.712598 | instance | {
2026-09-15 02:21:08.712666 | instance |   "delta": "0:00:00.005046",
2026-09-15 02:21:08.712707 | instance |   "end": "2026-09-15 02:21:08.491220",
2026-09-15 02:21:08.712744 | instance |   "msg": "non-zero return code",
2026-09-15 02:21:08.712780 | instance |   "rc": 1,
2026-09-15 02:21:08.712814 | instance |   "start": "2026-09-15 02:21:08.486174"
2026-09-15 02:21:08.712847 | instance | }
2026-09-15 02:21:08.712892 | instance | ERROR: Ignoring Errors
2026-09-15 02:21:08.722614 | 
2026-09-15 02:21:08.722800 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-15 02:21:09.287306 | instance | changed: cd+++++++++ selenium/
2026-09-15 02:21:09.298685 | 
2026-09-15 02:21:09.298805 | PLAY RECAP
2026-09-15 02:21:09.298895 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-15 02:21:09.298937 | 
2026-09-15 02:21:09.447629 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 02:21:09.449748 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 02:21:10.047624 | 
2026-09-15 02:21:10.047766 | PLAY [all]
2026-09-15 02:21:10.058838 | 
2026-09-15 02:21:10.058920 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 02:21:10.113116 | instance | skipping: Conditional result was False
2026-09-15 02:21:10.117823 | 
2026-09-15 02:21:10.117892 | TASK [fetch-output : Set log path for single node]
2026-09-15 02:21:10.147276 | instance | ok
2026-09-15 02:21:10.152737 | 
2026-09-15 02:21:10.152820 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 02:21:10.533061 | instance -> localhost | ok: "/var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/work/logs"
2026-09-15 02:21:10.802390 | instance -> localhost | changed: "/var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/work/artifacts"
2026-09-15 02:21:11.026857 | instance -> localhost | changed: "/var/lib/zuul/builds/7d2cead7e65946b69510b5f2a7369b11/work/docs"
2026-09-15 02:21:11.042775 | 
2026-09-15 02:21:11.042908 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 02:21:11.789689 | instance | changed: .d..t...... ./
2026-09-15 02:21:11.790008 | instance | changed: All items complete
2026-09-15 02:21:11.790063 | 
2026-09-15 02:21:12.289743 | instance | changed: .d..t...... ./
2026-09-15 02:21:12.791560 | instance | changed: .d..t...... ./
2026-09-15 02:21:12.823457 | 
2026-09-15 02:21:12.823654 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 02:21:13.268481 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006242
2026-09-15 02:21:13.521885 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008632
2026-09-15 02:21:13.547358 | 
2026-09-15 02:21:13.547473 | PLAY [all]
2026-09-15 02:21:13.562478 | 
2026-09-15 02:21:13.562611 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 02:21:14.022360 | instance | changed
2026-09-15 02:21:14.034835 | 
2026-09-15 02:21:14.035184 | PLAY RECAP
2026-09-15 02:21:14.035278 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 02:21:14.035322 | 
2026-09-15 02:21:14.237513 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 02:21:14.242353 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 02:21:14.897034 | 
2026-09-15 02:21:14.897194 | PLAY [localhost]
2026-09-15 02:21:14.907004 | 
2026-09-15 02:21:14.907087 | TASK [Generate Zuul manifest]
2026-09-15 02:21:14.924298 | localhost | ok
2026-09-15 02:21:14.941003 | 
2026-09-15 02:21:14.941098 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 02:21:15.317571 | localhost | changed
2026-09-15 02:21:15.338520 | 
2026-09-15 02:21:15.338721 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 02:21:15.368652 | localhost | ok
2026-09-15 02:21:15.379444 | 
2026-09-15 02:21:15.379543 | TASK [Upload logs]
2026-09-15 02:21:15.398509 | localhost | ok
2026-09-15 02:21:15.502449 | 
2026-09-15 02:21:15.502586 | TASK [Set zuul-log-path fact]
2026-09-15 02:21:15.519816 | localhost | ok
2026-09-15 02:21:15.530785 | 
2026-09-15 02:21:15.530879 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 02:21:15.559049 | localhost | ok
2026-09-15 02:21:15.568142 | 
2026-09-15 02:21:15.568231 | TASK [upload-logs : Create log directories]
2026-09-15 02:21:16.031284 | localhost | changed
2026-09-15 02:21:16.037373 | 
2026-09-15 02:21:16.037464 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 02:21:16.501213 | localhost -> localhost | ok: Runtime: 0:00:00.005818
2026-09-15 02:21:16.507409 | 
2026-09-15 02:21:16.507492 | TASK [upload-logs : Upload logs to log server]
2026-09-15 02:21:17.077818 | localhost | Output suppressed because no_log was given
2026-09-15 02:21:17.131711 | 
2026-09-15 02:21:17.131878 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 02:21:17.181435 | localhost | skipping: Conditional result was False
2026-09-15 02:21:17.189302 | localhost | skipping: Conditional result was False
2026-09-15 02:21:17.207221 | 
2026-09-15 02:21:17.207447 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 02:21:17.274773 | localhost | skipping: Conditional result was False
2026-09-15 02:21:17.275113 | 
2026-09-15 02:21:17.287343 | localhost | skipping: Conditional result was False
2026-09-15 02:21:17.302448 | 
2026-09-15 02:21:17.302659 | LOOP [upload-logs : Upload console log and json output]