2026-09-02 01:06:17.937702 | Job console starting
2026-09-02 01:06:17.945604 | Updating git repos
2026-09-02 01:06:30.235611 | Cloning repos into workspace
2026-09-02 01:06:47.839523 | Restoring repo states
2026-09-02 01:07:00.247069 | Merging changes
2026-09-02 01:07:02.187135 | Checking out repos
2026-09-02 01:07:05.107893 | Preparing playbooks
2026-09-02 01:07:08.184477 | Running Ansible setup
2026-09-02 01:07:11.693019 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 01:07:12.302533 | 
2026-09-02 01:07:12.302663 | PLAY [localhost]
2026-09-02 01:07:12.309925 | 
2026-09-02 01:07:12.309996 | TASK [Gathering Facts]
2026-09-02 01:07:13.179662 | localhost | ok
2026-09-02 01:07:13.186628 | 
2026-09-02 01:07:13.186716 | TASK [Setup log path fact]
2026-09-02 01:07:13.208816 | localhost | ok
2026-09-02 01:07:13.220140 | 
2026-09-02 01:07:13.220206 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 01:07:13.250520 | localhost | ok
2026-09-02 01:07:13.259071 | 
2026-09-02 01:07:13.259199 | TASK [emit-job-header : Print job information]
2026-09-02 01:07:13.300214 | # Job Information
2026-09-02 01:07:13.300415 | Ansible Version: 2.16.18
2026-09-02 01:07:13.300460 | Job: atmosphere-molecule-csi-rbd
2026-09-02 01:07:13.300491 | Pipeline: check
2026-09-02 01:07:13.300519 | Executor: 2d72f0692154
2026-09-02 01:07:13.300546 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4094
2026-09-02 01:07:13.300578 | Event ID: 751f79d0-a669-11f1-8c6d-a145a6ac9355
2026-09-02 01:07:13.304431 | 
2026-09-02 01:07:13.304517 | LOOP [emit-job-header : Print node information]
2026-09-02 01:07:13.398531 | localhost | ok:
2026-09-02 01:07:13.398764 | localhost | # Node Information
2026-09-02 01:07:13.398793 | localhost | Inventory Hostname: instance
2026-09-02 01:07:13.398814 | localhost | Hostname: np0000191812
2026-09-02 01:07:13.398835 | localhost | Username: zuul
2026-09-02 01:07:13.398859 | localhost | Distro: Ubuntu 22.04
2026-09-02 01:07:13.398880 | localhost | Provider: yul1
2026-09-02 01:07:13.398900 | localhost | Region: ca-ymq-1
2026-09-02 01:07:13.398919 | localhost | Label: ubuntu-jammy
2026-09-02 01:07:13.398937 | localhost | Product Name: OpenStack Nova
2026-09-02 01:07:13.398956 | localhost | Interface IP: 162.253.55.82
2026-09-02 01:07:13.406197 | 
2026-09-02 01:07:13.406268 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-02 01:07:13.836816 | localhost -> localhost | changed
2026-09-02 01:07:13.842261 | 
2026-09-02 01:07:13.842329 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-02 01:07:14.719659 | localhost -> localhost | changed
2026-09-02 01:07:14.728719 | 
2026-09-02 01:07:14.728807 | PLAY [all]
2026-09-02 01:07:14.738460 | 
2026-09-02 01:07:14.738529 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-02 01:07:14.957190 | instance -> localhost | ok
2026-09-02 01:07:14.965387 | 
2026-09-02 01:07:14.965480 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-02 01:07:14.998365 | instance | ok
2026-09-02 01:07:15.011892 | instance | included: /var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-02 01:07:15.017502 | 
2026-09-02 01:07:15.017564 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-02 01:07:16.790215 | instance -> localhost | Generating public/private rsa key pair.
2026-09-02 01:07:16.790412 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/work/a071df1352c142a797debd5b8cf65f30_id_rsa
2026-09-02 01:07:16.790444 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/work/a071df1352c142a797debd5b8cf65f30_id_rsa.pub
2026-09-02 01:07:16.790479 | instance -> localhost | The key fingerprint is:
2026-09-02 01:07:16.790504 | instance -> localhost | SHA256:4mYN0VRhCpI3zUQ04IKrd+dz/hRrG5WL9TK4YygvxMA zuul-build-sshkey
2026-09-02 01:07:16.790541 | instance -> localhost | The key's randomart image is:
2026-09-02 01:07:16.790573 | instance -> localhost | +---[RSA 3072]----+
2026-09-02 01:07:16.790604 | instance -> localhost | |    ..oB*.+.     |
2026-09-02 01:07:16.790627 | instance -> localhost | |   ..oo+o+       |
2026-09-02 01:07:16.790649 | instance -> localhost | |  ....o.o        |
2026-09-02 01:07:16.790670 | instance -> localhost | |   .E. .     .   |
2026-09-02 01:07:16.790692 | instance -> localhost | |  .  oo S . +    |
2026-09-02 01:07:16.790712 | instance -> localhost | | .   .o+   B o   |
2026-09-02 01:07:16.790733 | instance -> localhost | |. . ..= ..B + .  |
2026-09-02 01:07:16.790754 | instance -> localhost | | . . =+ ooo+ o   |
2026-09-02 01:07:16.790777 | instance -> localhost | |      .Boo+.     |
2026-09-02 01:07:16.790798 | instance -> localhost | +----[SHA256]-----+
2026-09-02 01:07:16.790850 | instance -> localhost | ok: Runtime: 0:00:01.339683
2026-09-02 01:07:16.798340 | 
2026-09-02 01:07:16.798425 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-02 01:07:16.831862 | instance | ok
2026-09-02 01:07:16.841267 | instance | included: /var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-02 01:07:16.850831 | 
2026-09-02 01:07:16.850909 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-02 01:07:16.878285 | instance | skipping: Conditional result was False
2026-09-02 01:07:16.890701 | 
2026-09-02 01:07:16.890791 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-02 01:07:17.365256 | instance | changed
2026-09-02 01:07:17.372186 | 
2026-09-02 01:07:17.372259 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-02 01:07:17.569363 | instance | ok
2026-09-02 01:07:17.576471 | 
2026-09-02 01:07:17.576573 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-02 01:07:18.943602 | instance | changed
2026-09-02 01:07:18.949230 | 
2026-09-02 01:07:18.949299 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-02 01:07:19.423962 | instance | changed
2026-09-02 01:07:19.431679 | 
2026-09-02 01:07:19.431749 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-02 01:07:19.459614 | instance | skipping: Conditional result was False
2026-09-02 01:07:19.468929 | 
2026-09-02 01:07:19.469018 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-02 01:07:19.859594 | instance -> localhost | changed
2026-09-02 01:07:19.872111 | 
2026-09-02 01:07:19.872176 | TASK [add-build-sshkey : Add back temp key]
2026-09-02 01:07:20.168580 | instance -> localhost | Identity added: /var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/work/a071df1352c142a797debd5b8cf65f30_id_rsa (zuul-build-sshkey)
2026-09-02 01:07:20.168791 | instance -> localhost | ok: Runtime: 0:00:00.013437
2026-09-02 01:07:20.173725 | 
2026-09-02 01:07:20.173791 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-02 01:07:20.523098 | instance | ok
2026-09-02 01:07:20.528124 | 
2026-09-02 01:07:20.528188 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-02 01:07:20.553907 | instance | skipping: Conditional result was False
2026-09-02 01:07:20.569357 | 
2026-09-02 01:07:20.569422 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-02 01:07:20.927350 | instance | ok
2026-09-02 01:07:20.935615 | 
2026-09-02 01:07:20.935696 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-02 01:07:24.415466 | instance | Output suppressed because no_log was given
2026-09-02 01:07:24.427916 | 
2026-09-02 01:07:24.427985 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-02 01:07:24.635526 | instance | ok: "logs"
2026-09-02 01:07:24.635834 | instance | ok: All items complete
2026-09-02 01:07:24.635864 | 
2026-09-02 01:07:24.805984 | instance | ok: "artifacts"
2026-09-02 01:07:24.997382 | instance | ok: "docs"
2026-09-02 01:07:25.018453 | 
2026-09-02 01:07:25.018627 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-02 01:07:25.231047 | instance | changed: "logs"
2026-09-02 01:07:25.382718 | instance | changed: "artifacts"
2026-09-02 01:07:25.525609 | instance | changed: "docs"
2026-09-02 01:07:25.541239 | 
2026-09-02 01:07:25.541381 | PLAY RECAP
2026-09-02 01:07:25.541436 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-02 01:07:25.541483 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:07:25.541514 | 
2026-09-02 01:07:25.695606 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 01:07:25.698219 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 01:07:26.334009 | 
2026-09-02 01:07:26.334146 | PLAY [all]
2026-09-02 01:07:26.349074 | 
2026-09-02 01:07:26.349261 | TASK [setup-uv : Extract archive]
2026-09-02 01:07:28.710817 | instance | changed
2026-09-02 01:07:28.720783 | 
2026-09-02 01:07:28.720867 | TASK [setup-uv : Print version]
2026-09-02 01:07:29.101528 | instance | uv 0.8.13
2026-09-02 01:07:29.274193 | instance | ok: Runtime: 0:00:00.014128
2026-09-02 01:07:29.284222 | 
2026-09-02 01:07:29.284328 | TASK [Install Ansible collection siblings]
2026-09-02 01:07:38.986869 | instance | changed:
2026-09-02 01:07:39.043646 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-02 01:07:39.043837 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-02 01:07:39.043975 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-02 01:07:39.044108 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-02 01:07:39.044169 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-02 01:07:39.044224 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-02 01:07:39.044272 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-02 01:07:39.044403 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-02 01:07:39.044489 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-02 01:07:39.044574 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-02 01:07:39.044696 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-02 01:07:39.044778 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-02 01:07:39.044927 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-02 01:07:39.044999 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-02 01:07:39.045045 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-02 01:07:39.045136 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-02 01:07:39.045189 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-02 01:07:39.045233 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-02 01:07:39.045276 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-02 01:07:39.045363 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-02 01:07:39.045411 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-02 01:07:39.045454 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-02 01:07:39.045500 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 01:07:39.045593 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-02 01:07:39.045615 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-02 01:07:39.045635 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 01:07:39.045654 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 01:07:39.045674 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 01:07:39.045693 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-02 01:07:39.045713 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-02 01:07:39.045768 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 01:07:39.045794 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 01:07:39.045839 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-02 01:07:39.045865 | 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-02 01:07:39.045893 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.045914 | instance | Process install dependency map
2026-09-02 01:07:39.045934 | instance | Starting collection install process
2026-09-02 01:07:39.045993 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-02 01:07:39.046018 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-02 01:07:39.046038 | instance | ansible.netcommon:* was installed successfully
2026-09-02 01:07:39.046058 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-02 01:07:39.046077 | instance | Creating virtual environment at: .venv
2026-09-02 01:07:39.046097 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 01:07:39.046117 | instance | Downloading netaddr (2.2MiB)
2026-09-02 01:07:39.046136 | instance | Downloading ansible-core (2.1MiB)
2026-09-02 01:07:39.046177 | instance | Downloading rjsonnet (1.2MiB)
2026-09-02 01:07:39.046219 | instance | Downloading cryptography (4.2MiB)
2026-09-02 01:07:39.046241 | instance | Downloading pydantic-core (2.0MiB)
2026-09-02 01:07:39.046287 | instance | Downloading setuptools (1.1MiB)
2026-09-02 01:07:39.046309 | instance | Downloading kubernetes (1.9MiB)
2026-09-02 01:07:39.046328 | instance | Downloading pygments (1.2MiB)
2026-09-02 01:07:39.046347 | instance | Downloading openstacksdk (1.7MiB)
2026-09-02 01:07:39.046367 | instance |  Downloading rjsonnet
2026-09-02 01:07:39.046456 | instance |    Building pyperclip==1.9.0
2026-09-02 01:07:39.046492 | instance |  Downloading pydantic-core
2026-09-02 01:07:39.046514 | instance |  Downloading netaddr
2026-09-02 01:07:39.046546 | instance |  Downloading pygments
2026-09-02 01:07:39.046566 | instance |  Downloading cryptography
2026-09-02 01:07:39.046586 | instance |  Downloading setuptools
2026-09-02 01:07:39.046778 | instance |  Downloading kubernetes
2026-09-02 01:07:39.046804 | instance |  Downloading ansible-core
2026-09-02 01:07:39.046826 | instance |  Downloading openstacksdk
2026-09-02 01:07:39.046847 | instance |       Built pyperclip==1.9.0
2026-09-02 01:07:39.046867 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 01:07:39.046887 | instance | Installed 83 packages in 142ms
2026-09-02 01:07:39.047173 | instance | Checked out ansible.posix to 1.6.0
2026-09-02 01:07:39.047234 | 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-02 01:07:39.047260 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.047300 | instance | Process install dependency map
2026-09-02 01:07:39.047330 | instance | Starting collection install process
2026-09-02 01:07:39.047352 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-02 01:07:39.047393 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-02 01:07:39.047416 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-02 01:07:39.047466 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.047488 | instance | 2.17.12
2026-09-02 01:07:39.047509 | instance | Checked out ansible.utils to v6.1.0
2026-09-02 01:07:39.047529 | 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-02 01:07:39.047549 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.047607 | instance | Process install dependency map
2026-09-02 01:07:39.047636 | instance | Starting collection install process
2026-09-02 01:07:39.047657 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-02 01:07:39.047677 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-02 01:07:39.047697 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-02 01:07:39.047717 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.047736 | instance | 2.17.12
2026-09-02 01:07:39.047756 | instance | Checked out atmosphere.common to v0.7.0
2026-09-02 01:07:39.047775 | 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-02 01:07:39.047820 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.047841 | instance | Process install dependency map
2026-09-02 01:07:39.047889 | instance | Starting collection install process
2026-09-02 01:07:39.047910 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-02 01:07:39.047930 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-02 01:07:39.047949 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-02 01:07:39.047968 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.048023 | instance | 2.17.12
2026-09-02 01:07:39.048050 | instance | Checked out community.crypto to 2.2.3
2026-09-02 01:07:39.048071 | 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-02 01:07:39.048090 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.048109 | instance | Process install dependency map
2026-09-02 01:07:39.048128 | instance | Starting collection install process
2026-09-02 01:07:39.048148 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-02 01:07:39.048167 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-02 01:07:39.048186 | instance | community.crypto:2.2.3 was installed successfully
2026-09-02 01:07:39.048240 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.048265 | instance | 2.17.12
2026-09-02 01:07:39.048308 | instance | Checked out community.general to 7.3.0
2026-09-02 01:07:39.048335 | 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-02 01:07:39.048366 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.048387 | instance | Process install dependency map
2026-09-02 01:07:39.048406 | instance | Starting collection install process
2026-09-02 01:07:39.048461 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-02 01:07:39.048489 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-02 01:07:39.048510 | instance | community.general:7.3.0 was installed successfully
2026-09-02 01:07:39.048530 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.048550 | instance | 2.17.12
2026-09-02 01:07:39.048570 | instance | Checked out community.mysql to 3.6.0
2026-09-02 01:07:39.048592 | 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-02 01:07:39.048612 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.048648 | instance | Process install dependency map
2026-09-02 01:07:39.048694 | instance | Starting collection install process
2026-09-02 01:07:39.048716 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-02 01:07:39.048735 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-02 01:07:39.048754 | instance | community.mysql:3.6.0 was installed successfully
2026-09-02 01:07:39.048773 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.048821 | instance | 2.17.12
2026-09-02 01:07:39.048844 | instance | Checked out kubernetes.core to 2.4.0
2026-09-02 01:07:39.048884 | 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-02 01:07:39.048906 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.048943 | instance | Process install dependency map
2026-09-02 01:07:39.048997 | instance | Starting collection install process
2026-09-02 01:07:39.049019 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-02 01:07:39.049039 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-02 01:07:39.049058 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-02 01:07:39.049078 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.049098 | instance | 2.17.12
2026-09-02 01:07:39.049117 | instance | Checked out openstack.cloud to 2.6.0
2026-09-02 01:07:39.049157 | 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-02 01:07:39.049198 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.049218 | instance | Process install dependency map
2026-09-02 01:07:39.049265 | instance | Starting collection install process
2026-09-02 01:07:39.049286 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-02 01:07:39.049305 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-02 01:07:39.049323 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-02 01:07:39.049357 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.049403 | instance | 2.17.12
2026-09-02 01:07:39.049431 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-02 01:07:39.049451 | 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-02 01:07:39.049470 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.049489 | instance | Process install dependency map
2026-09-02 01:07:39.049508 | instance | Starting collection install process
2026-09-02 01:07:39.049527 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-02 01:07:39.049546 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-02 01:07:39.049565 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-02 01:07:39.049602 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.049647 | instance | 2.17.12
2026-09-02 01:07:39.049670 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-02 01:07:39.049704 | 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-02 01:07:39.049746 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.049772 | instance | Process install dependency map
2026-09-02 01:07:39.049791 | instance | Starting collection install process
2026-09-02 01:07:39.049811 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-02 01:07:39.049830 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-02 01:07:39.049849 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-02 01:07:39.049900 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.049922 | instance | 2.17.12
2026-09-02 01:07:39.049971 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-02 01:07:39.049994 | 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-02 01:07:39.050014 | instance | Starting galaxy collection install process
2026-09-02 01:07:39.050033 | instance | Process install dependency map
2026-09-02 01:07:39.050052 | instance | Starting collection install process
2026-09-02 01:07:39.050103 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-02 01:07:39.050130 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-02 01:07:39.050163 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-02 01:07:39.050203 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 01:07:39.050228 | instance | 2.17.12
2026-09-02 01:07:39.057701 | 
2026-09-02 01:07:39.057749 | PLAY RECAP
2026-09-02 01:07:39.057801 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:07:39.057824 | 
2026-09-02 01:07:39.203097 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 01:07:39.208437 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 01:07:39.783490 | 
2026-09-02 01:07:39.783619 | PLAY [all]
2026-09-02 01:07:39.794869 | 
2026-09-02 01:07:39.794946 | TASK [Install "jq" for log collection]
2026-09-02 01:07:49.614898 | instance | changed
2026-09-02 01:07:49.617392 | 
2026-09-02 01:07:49.617479 | PLAY RECAP
2026-09-02 01:07:49.617551 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:07:49.617630 | 
2026-09-02 01:07:49.744566 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 01:07:49.749629 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 01:07:50.335476 | 
2026-09-02 01:07:50.335615 | PLAY [all]
2026-09-02 01:07:50.346541 | 
2026-09-02 01:07:50.346615 | LOOP [Check inventory directories]
2026-09-02 01:07:50.684896 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-02 01:07:50.853530 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-02 01:07:51.022652 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-02 01:07:51.041044 | 
2026-09-02 01:07:51.041237 | LOOP [Copy inventory file for Zuul]
2026-09-02 01:07:51.885383 | instance | changed:
2026-09-02 01:07:51.885639 | instance | {
2026-09-02 01:07:51.885681 | instance |   "ansible_loop_var": "item",
2026-09-02 01:07:51.885714 | instance |   "changed": false,
2026-09-02 01:07:51.885744 | instance |   "failed": false,
2026-09-02 01:07:51.885777 | instance |   "invocation": {
2026-09-02 01:07:51.885804 | instance |     "module_args": {
2026-09-02 01:07:51.885831 | instance |       "checksum_algorithm": "sha1",
2026-09-02 01:07:51.885858 | instance |       "follow": true,
2026-09-02 01:07:51.885884 | instance |       "get_attributes": true,
2026-09-02 01:07:51.885912 | instance |       "get_checksum": true,
2026-09-02 01:07:51.885939 | instance |       "get_mime": true,
2026-09-02 01:07:51.885964 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-02 01:07:51.885990 | instance |     }
2026-09-02 01:07:51.886017 | instance |   },
2026-09-02 01:07:51.886043 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-02 01:07:51.886069 | instance |   "stat": {
2026-09-02 01:07:51.886096 | instance |     "atime": 1788311251.3894942,
2026-09-02 01:07:51.886122 | instance |     "attr_flags": "e",
2026-09-02 01:07:51.886148 | instance |     "attributes": [
2026-09-02 01:07:51.886175 | instance |       "extents"
2026-09-02 01:07:51.886201 | instance |     ],
2026-09-02 01:07:51.886227 | instance |     "block_size": 4096,
2026-09-02 01:07:51.886253 | instance |     "blocks": 8,
2026-09-02 01:07:51.886279 | instance |     "charset": "unknown",
2026-09-02 01:07:51.886306 | instance |     "ctime": 1788311250.2174613,
2026-09-02 01:07:51.886332 | instance |     "dev": 64513,
2026-09-02 01:07:51.886358 | instance |     "device_type": 0,
2026-09-02 01:07:51.886383 | instance |     "executable": true,
2026-09-02 01:07:51.886408 | instance |     "exists": true,
2026-09-02 01:07:51.886434 | instance |     "gid": 1000,
2026-09-02 01:07:51.886459 | instance |     "gr_name": "zuul",
2026-09-02 01:07:51.886485 | instance |     "inode": 1047955,
2026-09-02 01:07:51.886511 | instance |     "isblk": false,
2026-09-02 01:07:51.886538 | instance |     "ischr": false,
2026-09-02 01:07:51.886563 | instance |     "isdir": true,
2026-09-02 01:07:51.886589 | instance |     "isfifo": false,
2026-09-02 01:07:51.886614 | instance |     "isgid": false,
2026-09-02 01:07:51.886639 | instance |     "islnk": false,
2026-09-02 01:07:51.886663 | instance |     "isreg": false,
2026-09-02 01:07:51.886687 | instance |     "issock": false,
2026-09-02 01:07:51.886712 | instance |     "isuid": false,
2026-09-02 01:07:51.886736 | instance |     "mimetype": "unknown",
2026-09-02 01:07:51.886761 | instance |     "mode": "0755",
2026-09-02 01:07:51.886787 | instance |     "mtime": 1788311250.2174613,
2026-09-02 01:07:51.886812 | instance |     "nlink": 22,
2026-09-02 01:07:51.886837 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-02 01:07:51.886863 | instance |     "pw_name": "zuul",
2026-09-02 01:07:51.886891 | instance |     "readable": true,
2026-09-02 01:07:51.886917 | instance |     "rgrp": true,
2026-09-02 01:07:51.886942 | instance |     "roth": true,
2026-09-02 01:07:51.886981 | instance |     "rusr": true,
2026-09-02 01:07:51.887010 | instance |     "size": 4096,
2026-09-02 01:07:51.887036 | instance |     "uid": 1000,
2026-09-02 01:07:51.887061 | instance |     "version": "304600581",
2026-09-02 01:07:51.887087 | instance |     "wgrp": false,
2026-09-02 01:07:51.887115 | instance |     "woth": false,
2026-09-02 01:07:51.887140 | instance |     "writeable": true,
2026-09-02 01:07:51.887166 | instance |     "wusr": true,
2026-09-02 01:07:51.887192 | instance |     "xgrp": true,
2026-09-02 01:07:51.887216 | instance |     "xoth": true,
2026-09-02 01:07:51.887241 | instance |     "xusr": true
2026-09-02 01:07:51.887266 | instance |   }
2026-09-02 01:07:51.887292 | instance | }
2026-09-02 01:07:51.887339 | instance | ok: All items complete
2026-09-02 01:07:51.887369 | 
2026-09-02 01:07:51.889110 | instance | skipping: Conditional result was False
2026-09-02 01:07:51.889959 | instance | skipping: Conditional result was False
2026-09-02 01:07:51.906799 | 
2026-09-02 01:07:51.906994 | LOOP [Switch "ansible_host" to private IP]
2026-09-02 01:07:52.259994 | instance | changed: 1 replacements made
2026-09-02 01:07:52.264057 | instance | skipping: Conditional result was False
2026-09-02 01:07:52.268256 | instance | skipping: Conditional result was False
2026-09-02 01:07:52.291139 | 
2026-09-02 01:07:52.291294 | TASK [Run Molecule scenario]
2026-09-02 01:07:53.195233 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-02 01:07:53.613285 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-02 01:07:53.613391 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-02 01:08:04.491730 | instance | INFO     [csi > dependency] Executing
2026-09-02 01:08:04.492169 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-02 01:08:04.492553 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-02 01:08:04.492772 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-02 01:08:04.507469 | instance | INFO     [csi > cleanup] Executing
2026-09-02 01:08:04.508138 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 01:08:04.522351 | instance | INFO     [csi > destroy] Executing
2026-09-02 01:08:04.522442 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-02 01:08:04.522539 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-02 01:08:04.532754 | instance | INFO     [csi > syntax] Executing
2026-09-02 01:08:06.148788 | instance |
2026-09-02 01:08:06.149087 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-02 01:08:06.244164 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-02 01:08:06.260783 | instance | INFO     [csi > create] Executing
2026-09-02 01:08:06.264947 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 01:08:06.281106 | instance | INFO     [csi > prepare] Executing
2026-09-02 01:08:07.102785 | instance |
2026-09-02 01:08:07.103054 | instance | PLAY [Prepare] *****************************************************************
2026-09-02 01:08:07.103287 | instance |
2026-09-02 01:08:07.103506 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:07.103744 | instance | Wednesday 02 September 2026  01:08:07 +0000 (0:00:00.024)       0:00:00.024 ***
2026-09-02 01:08:08.383060 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 01:08:08.383330 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 01:08:08.383570 | instance | interpreter could change the meaning of that path. See
2026-09-02 01:08:08.383819 | instance | https://docs.ansible.com/ansible-
2026-09-02 01:08:08.384149 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 01:08:08.394854 | instance | ok: [instance]
2026-09-02 01:08:08.395102 | instance |
2026-09-02 01:08:08.395407 | instance | TASK [Configure short hostname] ************************************************
2026-09-02 01:08:08.395640 | instance | Wednesday 02 September 2026  01:08:08 +0000 (0:00:01.292)       0:00:01.316 ***
2026-09-02 01:08:09.124565 | instance | changed: [instance]
2026-09-02 01:08:09.124766 | instance |
2026-09-02 01:08:09.125010 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-02 01:08:09.125254 | instance | Wednesday 02 September 2026  01:08:09 +0000 (0:00:00.729)       0:00:02.045 ***
2026-09-02 01:08:09.411792 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-02 01:08:09.412002 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-02 01:08:09.412273 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-02 01:08:09.423368 | instance | changed: [instance]
2026-09-02 01:08:09.423719 | instance |
2026-09-02 01:08:09.423969 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-02 01:08:09.424241 | instance | Wednesday 02 September 2026  01:08:09 +0000 (0:00:00.299)       0:00:02.345 ***
2026-09-02 01:08:10.210821 | instance | ok: [instance]
2026-09-02 01:08:10.211021 | instance |
2026-09-02 01:08:10.211384 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-02 01:08:10.211652 | instance |
2026-09-02 01:08:10.211903 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:10.212149 | instance | Wednesday 02 September 2026  01:08:10 +0000 (0:00:00.786)       0:00:03.132 ***
2026-09-02 01:08:11.001419 | instance | ok: [instance]
2026-09-02 01:08:11.001619 | instance |
2026-09-02 01:08:11.001854 | instance | TASK [Install depedencies] *****************************************************
2026-09-02 01:08:11.002119 | instance | Wednesday 02 September 2026  01:08:10 +0000 (0:00:00.790)       0:00:03.922 ***
2026-09-02 01:08:34.643457 | instance | changed: [instance]
2026-09-02 01:08:34.643658 | instance |
2026-09-02 01:08:34.643986 | instance | TASK [Start up service] ********************************************************
2026-09-02 01:08:34.644272 | instance | Wednesday 02 September 2026  01:08:34 +0000 (0:00:23.642)       0:00:27.565 ***
2026-09-02 01:08:35.237569 | instance | ok: [instance]
2026-09-02 01:08:35.237797 | instance |
2026-09-02 01:08:35.238044 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-02 01:08:35.238314 | instance | Wednesday 02 September 2026  01:08:35 +0000 (0:00:00.593)       0:00:28.158 ***
2026-09-02 01:08:35.560122 | instance | ok: [instance]
2026-09-02 01:08:35.560298 | instance |
2026-09-02 01:08:35.560509 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-02 01:08:35.560733 | instance | Wednesday 02 September 2026  01:08:35 +0000 (0:00:00.323)       0:00:28.481 ***
2026-09-02 01:08:36.306134 | instance | changed: [instance]
2026-09-02 01:08:36.306200 | instance |
2026-09-02 01:08:36.306214 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-02 01:08:36.306228 | instance | Wednesday 02 September 2026  01:08:36 +0000 (0:00:00.744)       0:00:29.226 ***
2026-09-02 01:08:36.520938 | instance | ok: [instance]
2026-09-02 01:08:36.521653 | instance |
2026-09-02 01:08:36.521705 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-02 01:08:36.521716 | instance | Wednesday 02 September 2026  01:08:36 +0000 (0:00:00.215)       0:00:29.442 ***
2026-09-02 01:08:36.546776 | instance | skipping: [instance]
2026-09-02 01:08:36.547252 | instance |
2026-09-02 01:08:36.547357 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-02 01:08:36.547377 | instance | Wednesday 02 September 2026  01:08:36 +0000 (0:00:00.026)       0:00:29.468 ***
2026-09-02 01:08:37.152657 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:37.153850 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:37.153903 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:37.153914 | instance |
2026-09-02 01:08:37.153924 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-02 01:08:37.153934 | instance | Wednesday 02 September 2026  01:08:37 +0000 (0:00:00.605)       0:00:30.073 ***
2026-09-02 01:08:37.880787 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:37.882178 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:37.882250 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:37.882266 | instance |
2026-09-02 01:08:37.882303 | instance | TASK [Start loop devices] ******************************************************
2026-09-02 01:08:37.882320 | instance | Wednesday 02 September 2026  01:08:37 +0000 (0:00:00.727)       0:00:30.801 ***
2026-09-02 01:08:38.641265 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:38.641484 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:38.641827 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:38.642109 | instance |
2026-09-02 01:08:38.642430 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-02 01:08:38.642727 | instance | Wednesday 02 September 2026  01:08:38 +0000 (0:00:00.761)       0:00:31.562 ***
2026-09-02 01:08:41.934281 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:41.935185 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:41.935263 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:41.935275 | instance |
2026-09-02 01:08:41.935291 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-02 01:08:41.935610 | instance | Wednesday 02 September 2026  01:08:41 +0000 (0:00:03.292)       0:00:34.855 ***
2026-09-02 01:08:43.923186 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-02 01:08:43.923247 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-02 01:08:43.923273 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-02 01:08:43.923283 | instance |
2026-09-02 01:08:43.923298 | instance | PLAY RECAP *********************************************************************
2026-09-02 01:08:43.923635 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-02 01:08:43.927162 | instance |
2026-09-02 01:08:43.927199 | instance | Wednesday 02 September 2026  01:08:43 +0000 (0:00:01.988)       0:00:36.843 ***
2026-09-02 01:08:43.927210 | instance | ===============================================================================
2026-09-02 01:08:43.927219 | instance | Install depedencies ---------------------------------------------------- 23.64s
2026-09-02 01:08:43.927228 | instance | Create a volume group for each loop device ------------------------------ 3.29s
2026-09-02 01:08:43.927237 | instance | Create a logical volume for each loop device ---------------------------- 1.99s
2026-09-02 01:08:43.927247 | instance | Gathering Facts --------------------------------------------------------- 1.29s
2026-09-02 01:08:43.927256 | instance | Gathering Facts --------------------------------------------------------- 0.79s
2026-09-02 01:08:43.927265 | instance | Purge "snapd" package --------------------------------------------------- 0.79s
2026-09-02 01:08:43.927274 | instance | Start loop devices ------------------------------------------------------ 0.76s
2026-09-02 01:08:43.927282 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.74s
2026-09-02 01:08:43.927291 | instance | Configure short hostname ------------------------------------------------ 0.73s
2026-09-02 01:08:43.927300 | instance | Set permissions on loopback devices ------------------------------------- 0.73s
2026-09-02 01:08:43.927308 | instance | Create devices for Ceph ------------------------------------------------- 0.61s
2026-09-02 01:08:43.927374 | instance | Start up service -------------------------------------------------------- 0.59s
2026-09-02 01:08:43.927388 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s
2026-09-02 01:08:43.927670 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-09-02 01:08:43.928066 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-02 01:08:43.928431 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-02 01:08:44.011584 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-02 01:08:44.019442 | instance | INFO     [csi > converge] Executing
2026-09-02 01:08:45.410797 | instance |
2026-09-02 01:08:45.411242 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-02 01:08:45.411582 | instance |
2026-09-02 01:08:45.411914 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:45.412227 | instance | Wednesday 02 September 2026  01:08:45 +0000 (0:00:00.007)       0:00:00.007 ***
2026-09-02 01:08:46.494127 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 01:08:46.494187 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 01:08:46.494198 | instance | interpreter could change the meaning of that path. See
2026-09-02 01:08:46.494208 | instance | https://docs.ansible.com/ansible-
2026-09-02 01:08:46.494217 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 01:08:46.509572 | instance | ok: [instance]
2026-09-02 01:08:46.509605 | instance |
2026-09-02 01:08:46.509617 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-02 01:08:46.509626 | instance | Wednesday 02 September 2026  01:08:46 +0000 (0:00:01.098)       0:00:01.105 ***
2026-09-02 01:08:46.581000 | instance | ok: [instance] => {
2026-09-02 01:08:46.581043 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-02 01:08:46.581055 | instance | }
2026-09-02 01:08:46.581065 | instance |
2026-09-02 01:08:46.581074 | instance | PLAY [all] *********************************************************************
2026-09-02 01:08:46.581083 | instance |
2026-09-02 01:08:46.581092 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:46.581116 | instance | Wednesday 02 September 2026  01:08:46 +0000 (0:00:00.070)       0:00:01.176 ***
2026-09-02 01:08:47.595175 | instance | ok: [instance]
2026-09-02 01:08:47.595242 | instance |
2026-09-02 01:08:47.595520 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-02 01:08:47.595783 | instance | Wednesday 02 September 2026  01:08:47 +0000 (0:00:01.014)       0:00:02.190 ***
2026-09-02 01:08:47.654454 | instance | skipping: [instance]
2026-09-02 01:08:47.654695 | instance |
2026-09-02 01:08:47.654953 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-02 01:08:47.655282 | instance | Wednesday 02 September 2026  01:08:47 +0000 (0:00:00.059)       0:00:02.250 ***
2026-09-02 01:08:47.864498 | instance | ok: [instance]
2026-09-02 01:08:47.864673 | instance |
2026-09-02 01:08:47.864953 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-02 01:08:47.865180 | instance |
2026-09-02 01:08:47.865401 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:47.865706 | instance | Wednesday 02 September 2026  01:08:47 +0000 (0:00:00.210)       0:00:02.461 ***
2026-09-02 01:08:48.795399 | instance | ok: [instance]
2026-09-02 01:08:48.795665 | instance |
2026-09-02 01:08:48.797356 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 01:08:48.797384 | instance | Wednesday 02 September 2026  01:08:48 +0000 (0:00:00.930)       0:00:03.392 ***
2026-09-02 01:08:49.162109 | instance | ok: [instance]
2026-09-02 01:08:49.162222 | instance |
2026-09-02 01:08:49.162620 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 01:08:49.163002 | instance | Wednesday 02 September 2026  01:08:49 +0000 (0:00:00.366)       0:00:03.758 ***
2026-09-02 01:08:49.235732 | instance | skipping: [instance]
2026-09-02 01:08:49.235825 | instance |
2026-09-02 01:08:49.235921 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-02 01:08:49.236120 | instance | Wednesday 02 September 2026  01:08:49 +0000 (0:00:00.066)       0:00:03.825 ***
2026-09-02 01:08:49.619994 | instance | changed: [instance]
2026-09-02 01:08:49.620471 | instance |
2026-09-02 01:08:49.620865 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 01:08:49.621236 | instance | Wednesday 02 September 2026  01:08:49 +0000 (0:00:00.390)       0:00:04.215 ***
2026-09-02 01:08:49.708104 | instance | ok: [instance] => {
2026-09-02 01:08:49.708607 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-02 01:08:49.709024 | instance | }
2026-09-02 01:08:49.709454 | instance |
2026-09-02 01:08:49.709883 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 01:08:49.710207 | instance | Wednesday 02 September 2026  01:08:49 +0000 (0:00:00.086)       0:00:04.301 ***
2026-09-02 01:08:50.402971 | instance | changed: [instance]
2026-09-02 01:08:50.403454 | instance |
2026-09-02 01:08:50.403784 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 01:08:50.404136 | instance | Wednesday 02 September 2026  01:08:50 +0000 (0:00:00.696)       0:00:04.997 ***
2026-09-02 01:08:50.467506 | instance | skipping: [instance]
2026-09-02 01:08:50.467737 | instance |
2026-09-02 01:08:50.467929 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 01:08:50.468136 | instance | Wednesday 02 September 2026  01:08:50 +0000 (0:00:00.064)       0:00:05.062 ***
2026-09-02 01:08:50.515303 | instance | skipping: [instance]
2026-09-02 01:08:50.515403 | instance |
2026-09-02 01:08:50.515423 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 01:08:50.515619 | instance | Wednesday 02 September 2026  01:08:50 +0000 (0:00:00.049)       0:00:05.112 ***
2026-09-02 01:08:50.766127 | instance | ok: [instance]
2026-09-02 01:08:50.766838 | instance |
2026-09-02 01:08:50.766894 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 01:08:50.766908 | instance | Wednesday 02 September 2026  01:08:50 +0000 (0:00:00.250)       0:00:05.362 ***
2026-09-02 01:08:52.116421 | instance | ok: [instance]
2026-09-02 01:08:52.117017 | instance |
2026-09-02 01:08:52.117149 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 01:08:52.117171 | instance | Wednesday 02 September 2026  01:08:52 +0000 (0:00:01.350)       0:00:06.713 ***
2026-09-02 01:08:52.201479 | instance | ok: [instance] => {
2026-09-02 01:08:52.203024 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-02 01:08:52.203058 | instance | }
2026-09-02 01:08:52.203070 | instance |
2026-09-02 01:08:52.203119 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 01:08:52.203150 | instance | Wednesday 02 September 2026  01:08:52 +0000 (0:00:00.084)       0:00:06.797 ***
2026-09-02 01:08:52.855183 | instance | changed: [instance]
2026-09-02 01:08:52.855243 | instance |
2026-09-02 01:08:52.855451 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 01:08:52.855468 | instance | Wednesday 02 September 2026  01:08:52 +0000 (0:00:00.654)       0:00:07.452 ***
2026-09-02 01:08:55.814976 | instance | changed: [instance]
2026-09-02 01:08:55.815757 | instance |
2026-09-02 01:08:55.815816 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-02 01:08:55.815828 | instance | Wednesday 02 September 2026  01:08:55 +0000 (0:00:02.959)       0:00:10.411 ***
2026-09-02 01:08:55.857464 | instance | skipping: [instance]
2026-09-02 01:08:55.858609 | instance |
2026-09-02 01:08:55.858652 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-02 01:08:55.858663 | instance | Wednesday 02 September 2026  01:08:55 +0000 (0:00:00.042)       0:00:10.453 ***
2026-09-02 01:08:55.895452 | instance | skipping: [instance]
2026-09-02 01:08:55.895612 | instance |
2026-09-02 01:08:55.895966 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-02 01:08:55.896158 | instance | Wednesday 02 September 2026  01:08:55 +0000 (0:00:00.038)       0:00:10.492 ***
2026-09-02 01:08:55.940727 | instance | skipping: [instance]
2026-09-02 01:08:55.940957 | instance |
2026-09-02 01:08:55.941317 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-02 01:08:55.941645 | instance | Wednesday 02 September 2026  01:08:55 +0000 (0:00:00.044)       0:00:10.537 ***
2026-09-02 01:09:02.092047 | instance | changed: [instance]
2026-09-02 01:09:02.092162 | instance |
2026-09-02 01:09:02.092177 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-02 01:09:02.092403 | instance | Wednesday 02 September 2026  01:09:02 +0000 (0:00:06.151)       0:00:16.688 ***
2026-09-02 01:09:02.736138 | instance | changed: [instance]
2026-09-02 01:09:02.736194 | instance |
2026-09-02 01:09:02.736206 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-02 01:09:02.736216 | instance | Wednesday 02 September 2026  01:09:02 +0000 (0:00:00.642)       0:00:17.331 ***
2026-09-02 01:09:03.719720 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-02 01:09:03.719818 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-02 01:09:03.720771 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-02 01:09:03.720849 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-02 01:09:03.720858 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-02 01:09:03.720865 | instance |
2026-09-02 01:09:03.720872 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-02 01:09:03.720878 | instance | Wednesday 02 September 2026  01:09:03 +0000 (0:00:00.984)       0:00:18.316 ***
2026-09-02 01:09:04.314535 | instance | changed: [instance]
2026-09-02 01:09:04.315217 | instance |
2026-09-02 01:09:04.315456 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-02 01:09:04.315469 | instance | Wednesday 02 September 2026  01:09:04 +0000 (0:00:00.569)       0:00:18.885 ***
2026-09-02 01:09:04.315484 | instance |
2026-09-02 01:09:04.315494 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 01:09:04.315700 | instance | Wednesday 02 September 2026  01:09:04 +0000 (0:00:00.025)       0:00:18.911 ***
2026-09-02 01:09:05.374608 | instance | ok: [instance]
2026-09-02 01:09:05.375194 | instance |
2026-09-02 01:09:05.375262 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-02 01:09:05.375436 | instance | Wednesday 02 September 2026  01:09:05 +0000 (0:00:01.059)       0:00:19.971 ***
2026-09-02 01:09:05.860348 | instance | changed: [instance]
2026-09-02 01:09:05.861104 | instance |
2026-09-02 01:09:05.861162 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-02 01:09:05.861174 | instance | Wednesday 02 September 2026  01:09:05 +0000 (0:00:00.485)       0:00:20.456 ***
2026-09-02 01:09:06.530430 | instance | changed: [instance]
2026-09-02 01:09:06.530516 | instance |
2026-09-02 01:09:06.530871 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 01:09:06.530921 | instance | Wednesday 02 September 2026  01:09:06 +0000 (0:00:00.670)       0:00:21.127 ***
2026-09-02 01:09:06.775752 | instance | ok: [instance]
2026-09-02 01:09:06.776518 | instance |
2026-09-02 01:09:06.776577 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 01:09:06.776589 | instance | Wednesday 02 September 2026  01:09:06 +0000 (0:00:00.244)       0:00:21.372 ***
2026-09-02 01:09:06.849942 | instance | ok: [instance] => {
2026-09-02 01:09:06.850634 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-02 01:09:06.850702 | instance | }
2026-09-02 01:09:06.850709 | instance |
2026-09-02 01:09:06.850714 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 01:09:06.850720 | instance | Wednesday 02 September 2026  01:09:06 +0000 (0:00:00.074)       0:00:21.446 ***
2026-09-02 01:09:07.872774 | instance | changed: [instance]
2026-09-02 01:09:07.872867 | instance |
2026-09-02 01:09:07.872929 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 01:09:07.873061 | instance | Wednesday 02 September 2026  01:09:07 +0000 (0:00:01.023)       0:00:22.469 ***
2026-09-02 01:09:12.297840 | instance | changed: [instance]
2026-09-02 01:09:12.297925 | instance |
2026-09-02 01:09:12.298342 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-02 01:09:12.298411 | instance | Wednesday 02 September 2026  01:09:12 +0000 (0:00:04.424)       0:00:26.894 ***
2026-09-02 01:09:13.799606 | instance | ok: [instance]
2026-09-02 01:09:13.800420 | instance |
2026-09-02 01:09:13.800488 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-02 01:09:13.800500 | instance | Wednesday 02 September 2026  01:09:13 +0000 (0:00:01.501)       0:00:28.395 ***
2026-09-02 01:09:14.185975 | instance | changed: [instance]
2026-09-02 01:09:14.186029 | instance |
2026-09-02 01:09:14.186040 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-02 01:09:14.186050 | instance | Wednesday 02 September 2026  01:09:14 +0000 (0:00:00.386)       0:00:28.781 ***
2026-09-02 01:09:14.722356 | instance | changed: [instance]
2026-09-02 01:09:14.722423 | instance |
2026-09-02 01:09:14.722434 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-02 01:09:14.722443 | instance | Wednesday 02 September 2026  01:09:14 +0000 (0:00:00.535)       0:00:29.317 ***
2026-09-02 01:09:15.428577 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-02 01:09:15.430126 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-02 01:09:15.430187 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-02 01:09:15.430198 | instance |
2026-09-02 01:09:15.430208 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-02 01:09:15.430217 | instance | Wednesday 02 September 2026  01:09:15 +0000 (0:00:00.706)       0:00:30.024 ***
2026-09-02 01:09:15.983619 | instance | changed: [instance]
2026-09-02 01:09:15.983676 | instance |
2026-09-02 01:09:15.983681 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-02 01:09:15.983687 | instance | Wednesday 02 September 2026  01:09:15 +0000 (0:00:00.555)       0:00:30.580 ***
2026-09-02 01:09:16.505086 | instance | changed: [instance]
2026-09-02 01:09:16.505169 | instance |
2026-09-02 01:09:16.505246 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-02 01:09:16.505374 | instance | Wednesday 02 September 2026  01:09:16 +0000 (0:00:00.506)       0:00:31.086 ***
2026-09-02 01:09:16.505719 | instance |
2026-09-02 01:09:16.505766 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 01:09:16.505779 | instance | Wednesday 02 September 2026  01:09:16 +0000 (0:00:00.015)       0:00:31.102 ***
2026-09-02 01:09:17.336998 | instance | ok: [instance]
2026-09-02 01:09:17.337781 | instance |
2026-09-02 01:09:17.337859 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-02 01:09:17.337871 | instance | Wednesday 02 September 2026  01:09:17 +0000 (0:00:00.830)       0:00:31.933 ***
2026-09-02 01:09:18.346034 | instance | changed: [instance]
2026-09-02 01:09:18.346149 | instance |
2026-09-02 01:09:18.346493 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-02 01:09:18.346541 | instance | Wednesday 02 September 2026  01:09:18 +0000 (0:00:01.010)       0:00:32.943 ***
2026-09-02 01:09:18.942361 | instance | changed: [instance]
2026-09-02 01:09:18.942416 | instance |
2026-09-02 01:09:18.942729 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-02 01:09:18.942749 | instance | Wednesday 02 September 2026  01:09:18 +0000 (0:00:00.596)       0:00:33.539 ***
2026-09-02 01:09:19.022828 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-02 01:09:19.023406 | instance |
2026-09-02 01:09:19.023522 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-02 01:09:19.023546 | instance | Wednesday 02 September 2026  01:09:19 +0000 (0:00:00.080)       0:00:33.619 ***
2026-09-02 01:09:24.875843 | instance | changed: [instance]
2026-09-02 01:09:24.876182 | instance |
2026-09-02 01:09:24.876277 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-02 01:09:24.876638 | instance | Wednesday 02 September 2026  01:09:24 +0000 (0:00:05.852)       0:00:39.472 ***
2026-09-02 01:09:25.603185 | instance | ok: [instance] => (item=chronyd)
2026-09-02 01:09:25.603253 | instance | ok: [instance] => (item=sshd)
2026-09-02 01:09:25.603260 | instance |
2026-09-02 01:09:25.603272 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-02 01:09:25.603495 | instance | Wednesday 02 September 2026  01:09:25 +0000 (0:00:00.726)       0:00:40.199 ***
2026-09-02 01:09:27.582676 | instance | changed: [instance]
2026-09-02 01:09:27.582736 | instance |
2026-09-02 01:09:27.582981 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-02 01:09:27.583040 | instance | Wednesday 02 September 2026  01:09:27 +0000 (0:00:01.980)       0:00:42.179 ***
2026-09-02 01:09:27.822013 | instance | ok: [instance]
2026-09-02 01:09:27.822685 | instance |
2026-09-02 01:09:27.822730 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-02 01:09:27.822742 | instance | Wednesday 02 September 2026  01:09:27 +0000 (0:00:00.238)       0:00:42.418 ***
2026-09-02 01:09:28.326833 | instance | changed: [instance]
2026-09-02 01:09:28.326901 | instance |
2026-09-02 01:09:28.327183 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-02 01:09:28.327442 | instance | Wednesday 02 September 2026  01:09:28 +0000 (0:00:00.505)       0:00:42.923 ***
2026-09-02 01:09:28.744550 | instance | changed: [instance]
2026-09-02 01:09:28.744648 | instance |
2026-09-02 01:09:28.744920 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-02 01:09:28.744944 | instance | Wednesday 02 September 2026  01:09:28 +0000 (0:00:00.417)       0:00:43.341 ***
2026-09-02 01:09:28.972007 | instance | ok: [instance]
2026-09-02 01:09:28.972091 | instance |
2026-09-02 01:09:28.972166 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-02 01:09:28.972273 | instance | Wednesday 02 September 2026  01:09:28 +0000 (0:00:00.227)       0:00:43.568 ***
2026-09-02 01:09:29.250632 | instance | ok: [instance] => (item=instance)
2026-09-02 01:09:29.251225 | instance |
2026-09-02 01:09:29.251302 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-02 01:09:29.251740 | instance | Wednesday 02 September 2026  01:09:29 +0000 (0:00:00.277)       0:00:43.846 ***
2026-09-02 01:09:29.300850 | instance | ok: [instance]
2026-09-02 01:09:29.300944 | instance |
2026-09-02 01:09:29.300960 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-02 01:09:29.301138 | instance | Wednesday 02 September 2026  01:09:29 +0000 (0:00:00.050)       0:00:43.897 ***
2026-09-02 01:09:29.413416 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-02 01:09:29.413492 | instance |
2026-09-02 01:09:29.413829 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-02 01:09:29.413855 | instance | Wednesday 02 September 2026  01:09:29 +0000 (0:00:00.112)       0:00:44.009 ***
2026-09-02 01:09:29.736776 | instance | changed: [instance]
2026-09-02 01:09:29.738077 | instance |
2026-09-02 01:09:29.738116 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-02 01:09:29.738132 | instance | Wednesday 02 September 2026  01:09:29 +0000 (0:00:00.322)       0:00:44.332 ***
2026-09-02 01:09:30.456939 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-02 01:09:30.458479 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-02 01:09:30.458518 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-02 01:09:30.458532 | instance |
2026-09-02 01:09:30.458545 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-02 01:09:30.458557 | instance | Wednesday 02 September 2026  01:09:30 +0000 (0:00:00.720)       0:00:45.053 ***
2026-09-02 01:11:07.448870 | instance | ok: [instance]
2026-09-02 01:11:07.448933 | instance |
2026-09-02 01:11:07.448945 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-02 01:11:07.448955 | instance | Wednesday 02 September 2026  01:11:07 +0000 (0:01:36.991)       0:02:22.044 ***
2026-09-02 01:11:07.692998 | instance | changed: [instance]
2026-09-02 01:11:07.693204 | instance |
2026-09-02 01:11:07.693488 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-02 01:11:07.693737 | instance | Wednesday 02 September 2026  01:11:07 +0000 (0:00:00.245)       0:02:22.289 ***
2026-09-02 01:11:07.738991 | instance | ok: [instance]
2026-09-02 01:11:07.739219 | instance |
2026-09-02 01:11:07.739485 | instance | TASK [Install Ceph host] *******************************************************
2026-09-02 01:11:07.739741 | instance | Wednesday 02 September 2026  01:11:07 +0000 (0:00:00.045)       0:02:22.335 ***
2026-09-02 01:11:07.818144 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-02 01:11:07.818233 | instance |
2026-09-02 01:11:07.818451 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-02 01:11:07.818467 | instance | Wednesday 02 September 2026  01:11:07 +0000 (0:00:00.079)       0:02:22.415 ***
2026-09-02 01:11:09.547538 | instance | ok: [instance]
2026-09-02 01:11:09.547623 | instance |
2026-09-02 01:11:09.547810 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-02 01:11:09.547945 | instance | Wednesday 02 September 2026  01:11:09 +0000 (0:00:01.729)       0:02:24.144 ***
2026-09-02 01:11:09.626145 | instance | ok: [instance] => (item=instance)
2026-09-02 01:11:09.626356 | instance |
2026-09-02 01:11:09.626601 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-02 01:11:09.626834 | instance | Wednesday 02 September 2026  01:11:09 +0000 (0:00:00.078)       0:02:24.223 ***
2026-09-02 01:11:09.983073 | instance | ok: [instance]
2026-09-02 01:11:09.983156 | instance |
2026-09-02 01:11:09.983167 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-02 01:11:09.983182 | instance | Wednesday 02 September 2026  01:11:09 +0000 (0:00:00.355)       0:02:24.579 ***
2026-09-02 01:11:12.424803 | instance | ok: [instance]
2026-09-02 01:11:12.424847 | instance |
2026-09-02 01:11:12.424856 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-02 01:11:12.424863 | instance | Wednesday 02 September 2026  01:11:12 +0000 (0:00:02.442)       0:02:27.021 ***
2026-09-02 01:12:30.157110 | 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-02 01:12:30.158076 | 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_r819l8nn/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_r819l8nn/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_r819l8nn/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_r819l8nn/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-02T01:12:29.977+0000 7f345289f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCEd5dqQ+EkCCAAapyp4WPHTU2egSU96DoLcEMlt7BLNQv8u2wkxya7S9g=: Malformed input [buffer:3]\n2026-09-02T01:12:29.977+0000 7f345289f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:12:29.981+0000 7f345289f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCEd5dqQ+EkCCAAapyp4WPHTU2egSU96DoLcEMlt7BLNQv8u2wkxya7S9g=: Malformed input [buffer:3]\n2026-09-02T01:12:29.981+0000 7f345289f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:12:29.981+0000 7f345289f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCEd5dqQ+EkCCAAapyp4WPHTU2egSU96DoLcEMlt7BLNQv8u2wkxya7S9g=: Malformed input [buffer:3]\n2026-09-02T01:12:29.981+0000 7f345289f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:12:29.981+0000 7f345289f640 -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-02 01:12:30.163158 | instance |
2026-09-02 01:12:30.163194 | instance | PLAY RECAP *********************************************************************
2026-09-02 01:12:30.163228 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-02 01:12:30.163238 | instance |
2026-09-02 01:12:30.163247 | instance | Wednesday 02 September 2026  01:12:30 +0000 (0:01:17.737)       0:03:44.758 ***
2026-09-02 01:12:30.163256 | instance | ===============================================================================
2026-09-02 01:12:30.163265 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 96.99s
2026-09-02 01:12:30.163273 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 77.74s
2026-09-02 01:12:30.163282 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.15s
2026-09-02 01:12:30.163290 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.85s
2026-09-02 01:12:30.163303 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.42s
2026-09-02 01:12:30.166612 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.96s
2026-09-02 01:12:30.166659 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.44s
2026-09-02 01:12:30.166670 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.98s
2026-09-02 01:12:30.166678 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.89s
2026-09-02 01:12:30.166687 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.73s
2026-09-02 01:12:30.166695 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.50s
2026-09-02 01:12:30.166704 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.35s
2026-09-02 01:12:30.166712 | instance | Gathering Facts --------------------------------------------------------- 1.10s
2026-09-02 01:12:30.166721 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.02s
2026-09-02 01:12:30.166729 | instance | Gathering Facts --------------------------------------------------------- 1.01s
2026-09-02 01:12:30.166737 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.01s
2026-09-02 01:12:30.166746 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.98s
2026-09-02 01:12:30.166754 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-09-02 01:12:30.166762 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.73s
2026-09-02 01:12:30.166771 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.72s
2026-09-02 01:12:30.278028 | 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-02 01:12:30.278093 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-02 01:12:30.278175 | 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-02 01:12:30.701913 | instance | ERROR
2026-09-02 01:12:30.702131 | instance | {
2026-09-02 01:12:30.702160 | instance |   "delta": "0:04:37.693850",
2026-09-02 01:12:30.702181 | instance |   "end": "2026-09-02 01:12:30.330155",
2026-09-02 01:12:30.702241 | instance |   "msg": "non-zero return code",
2026-09-02 01:12:30.702266 | instance |   "rc": 2,
2026-09-02 01:12:30.702286 | instance |   "start": "2026-09-02 01:07:52.636305"
2026-09-02 01:12:30.702306 | instance | }
failure
2026-09-02 01:12:30.707243 | 
2026-09-02 01:12:30.707309 | PLAY RECAP
2026-09-02 01:12:30.707368 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:12:30.707397 | 
2026-09-02 01:12:30.873801 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 01:12:30.876219 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 01:12:31.822558 | 
2026-09-02 01:12:31.822683 | PLAY [all]
2026-09-02 01:12:31.836911 | 
2026-09-02 01:12:31.837032 | TASK [gather-host-logs : creating directory for system status]
2026-09-02 01:12:32.224999 | instance | changed
2026-09-02 01:12:32.231897 | 
2026-09-02 01:12:32.231982 | TASK [gather-host-logs : Get logs for each host]
2026-09-02 01:12:32.604886 | instance | + systemd-cgls --full --all --no-pager
2026-09-02 01:12:32.620626 | instance | + ip addr
2026-09-02 01:12:32.623551 | instance | + ip route
2026-09-02 01:12:32.626347 | instance | + lsblk
2026-09-02 01:12:32.631706 | instance | + mount
2026-09-02 01:12:32.634912 | instance | + docker images
2026-09-02 01:12:32.661453 | instance | + brctl show
2026-09-02 01:12:32.662115 | instance | /bin/bash: line 8: brctl: command not found
2026-09-02 01:12:32.662482 | instance | + ps aux --sort=-%mem
2026-09-02 01:12:32.682644 | instance | + dpkg -l
2026-09-02 01:12:32.694073 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-02 01:12:32.694696 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-02 01:12:32.715556 | instance | + '[' '!' -z '' ']'
2026-09-02 01:12:32.770278 | instance | ok: Runtime: 0:00:00.116834
2026-09-02 01:12:32.778210 | 
2026-09-02 01:12:32.778299 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-02 01:12:33.406031 | instance | changed:
2026-09-02 01:12:33.406245 | instance | created directory /var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/work/logs/instance
2026-09-02 01:12:33.406276 | instance | cd+++++++++ system/
2026-09-02 01:12:33.406299 | instance | >f+++++++++ system/brctl-show.txt
2026-09-02 01:12:33.406320 | instance | >f+++++++++ system/docker-images.txt
2026-09-02 01:12:33.406340 | instance | >f+++++++++ system/ip-addr.txt
2026-09-02 01:12:33.406363 | instance | >f+++++++++ system/ip-route.txt
2026-09-02 01:12:33.406384 | instance | >f+++++++++ system/lsblk.txt
2026-09-02 01:12:33.406404 | instance | >f+++++++++ system/mount.txt
2026-09-02 01:12:33.406424 | instance | >f+++++++++ system/packages.txt
2026-09-02 01:12:33.406444 | instance | >f+++++++++ system/ps.txt
2026-09-02 01:12:33.406466 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-02 01:12:33.415872 | 
2026-09-02 01:12:33.415943 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-02 01:12:33.634009 | instance | changed: "values"
2026-09-02 01:12:33.812278 | instance | changed: "releases"
2026-09-02 01:12:33.824451 | 
2026-09-02 01:12:33.824660 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-02 01:12:34.052773 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-02 01:12:34.365811 | instance | ok: Runtime: 0:00:00.004988
2026-09-02 01:12:34.372795 | 
2026-09-02 01:12:34.372890 | TASK [helm-release-status : Downloads logs to executor]
2026-09-02 01:12:34.883000 | instance | changed:
2026-09-02 01:12:34.883210 | instance | cd+++++++++ helm/
2026-09-02 01:12:34.883237 | instance | cd+++++++++ helm/releases/
2026-09-02 01:12:34.883259 | instance | cd+++++++++ helm/values/
2026-09-02 01:12:34.892475 | 
2026-09-02 01:12:34.892543 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-02 01:12:35.125692 | instance | changed
2026-09-02 01:12:35.133178 | 
2026-09-02 01:12:35.133259 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-02 01:12:35.397900 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:35.397997 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:35.403151 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.403903 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.404485 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.405719 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.407656 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.409145 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.410069 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.410602 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.412454 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.413677 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.670923 | instance | ok: Runtime: 0:00:00.021680
2026-09-02 01:12:35.677267 | 
2026-09-02 01:12:35.677338 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-02 01:12:35.895053 | instance | changed
2026-09-02 01:12:35.901484 | 
2026-09-02 01:12:35.901556 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-02 01:12:36.143106 | instance | environment: line 5: kubectl: command not found
2026-09-02 01:12:36.143716 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:36.143886 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:36.144167 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:36.439506 | instance | ok: Runtime: 0:00:00.008795
2026-09-02 01:12:36.446836 | 
2026-09-02 01:12:36.446908 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-02 01:12:36.925966 | instance | changed:
2026-09-02 01:12:36.926229 | instance | cd+++++++++ objects/
2026-09-02 01:12:36.926262 | instance | cd+++++++++ objects/cluster/
2026-09-02 01:12:36.926286 | instance | cd+++++++++ objects/namespaced/
2026-09-02 01:12:36.936466 | 
2026-09-02 01:12:36.936539 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-02 01:12:37.133559 | instance | changed
2026-09-02 01:12:37.140087 | 
2026-09-02 01:12:37.140159 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-02 01:12:37.356111 | instance | changed
2026-09-02 01:12:37.361024 | 
2026-09-02 01:12:37.363033 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-02 01:12:37.553304 | instance | environment: line 3: kubectl: command not found
2026-09-02 01:12:37.897894 | instance | ok: Runtime: 0:00:00.006105
2026-09-02 01:12:37.904921 | 
2026-09-02 01:12:37.905033 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-02 01:12:38.415421 | instance | changed:
2026-09-02 01:12:38.415604 | instance | cd+++++++++ pod-logs/
2026-09-02 01:12:38.415631 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-02 01:12:38.425579 | 
2026-09-02 01:12:38.425645 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-02 01:12:38.642844 | instance | changed
2026-09-02 01:12:38.649396 | 
2026-09-02 01:12:38.649463 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-02 01:12:38.859589 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 01:12:39.187339 | instance | ok: Runtime: 0:00:00.054739
2026-09-02 01:12:39.192394 | 
2026-09-02 01:12:39.192466 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-02 01:12:39.426669 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 01:12:39.476012 | instance | ceph-mgr endpoints:
2026-09-02 01:12:39.729050 | instance | ok: Runtime: 0:00:00.054526
2026-09-02 01:12:39.735098 | 
2026-09-02 01:12:39.735172 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-02 01:12:39.971935 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-02 01:12:40.268218 | instance | ok: Runtime: 0:00:00.055102
2026-09-02 01:12:40.273480 | 
2026-09-02 01:12:40.273548 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-02 01:12:40.777914 | instance | changed: cd+++++++++ prometheus/
2026-09-02 01:12:40.789614 | 
2026-09-02 01:12:40.789740 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-02 01:12:41.003894 | instance | changed
2026-09-02 01:12:41.008892 | 
2026-09-02 01:12:41.008960 | TASK [gather-selenium-data : Get selenium data]
2026-09-02 01:12:41.281758 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-02 01:12:41.283494 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-02 01:12:41.547586 | instance | ERROR
2026-09-02 01:12:41.548114 | instance | {
2026-09-02 01:12:41.548165 | instance |   "delta": "0:00:00.007818",
2026-09-02 01:12:41.548197 | instance |   "end": "2026-09-02 01:12:41.283913",
2026-09-02 01:12:41.548229 | instance |   "msg": "non-zero return code",
2026-09-02 01:12:41.548258 | instance |   "rc": 1,
2026-09-02 01:12:41.548286 | instance |   "start": "2026-09-02 01:12:41.276095"
2026-09-02 01:12:41.548314 | instance | }
2026-09-02 01:12:41.548350 | instance | ERROR: Ignoring Errors
2026-09-02 01:12:41.554396 | 
2026-09-02 01:12:41.554458 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-02 01:12:42.095770 | instance | changed: cd+++++++++ selenium/
2026-09-02 01:12:42.102548 | 
2026-09-02 01:12:42.102598 | PLAY RECAP
2026-09-02 01:12:42.102643 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-02 01:12:42.102665 | 
2026-09-02 01:12:42.298417 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 01:12:42.308328 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 01:12:43.012931 | 
2026-09-02 01:12:43.013500 | PLAY [all]
2026-09-02 01:12:43.025704 | 
2026-09-02 01:12:43.025779 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-02 01:12:43.071986 | instance | skipping: Conditional result was False
2026-09-02 01:12:43.080960 | 
2026-09-02 01:12:43.081060 | TASK [fetch-output : Set log path for single node]
2026-09-02 01:12:43.124724 | instance | ok
2026-09-02 01:12:43.130791 | 
2026-09-02 01:12:43.130866 | LOOP [fetch-output : Ensure local output dirs]
2026-09-02 01:12:43.538332 | instance -> localhost | ok: "/var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/work/logs"
2026-09-02 01:12:43.809121 | instance -> localhost | changed: "/var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/work/artifacts"
2026-09-02 01:12:44.036485 | instance -> localhost | changed: "/var/lib/zuul/builds/a071df1352c142a797debd5b8cf65f30/work/docs"
2026-09-02 01:12:44.052339 | 
2026-09-02 01:12:44.052515 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-02 01:12:44.714713 | instance | changed: .d..t...... ./
2026-09-02 01:12:44.714905 | instance | changed: All items complete
2026-09-02 01:12:44.714934 | 
2026-09-02 01:12:45.163278 | instance | changed: .d..t...... ./
2026-09-02 01:12:45.628362 | instance | changed: .d..t...... ./
2026-09-02 01:12:45.647615 | 
2026-09-02 01:12:45.647768 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-02 01:12:46.133213 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.027309
2026-09-02 01:12:46.372671 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007761
2026-09-02 01:12:46.392155 | 
2026-09-02 01:12:46.392302 | PLAY [all]
2026-09-02 01:12:46.401499 | 
2026-09-02 01:12:46.401570 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-02 01:12:46.842894 | instance | changed
2026-09-02 01:12:46.849631 | 
2026-09-02 01:12:46.849678 | PLAY RECAP
2026-09-02 01:12:46.849721 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-02 01:12:46.849743 | 
2026-09-02 01:12:46.974228 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 01:12:46.976363 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-02 01:12:47.579837 | 
2026-09-02 01:12:47.579975 | PLAY [localhost]
2026-09-02 01:12:47.589995 | 
2026-09-02 01:12:47.590079 | TASK [Generate Zuul manifest]
2026-09-02 01:12:47.610053 | localhost | ok
2026-09-02 01:12:47.629730 | 
2026-09-02 01:12:47.629815 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-02 01:12:47.988691 | localhost | changed
2026-09-02 01:12:48.000711 | 
2026-09-02 01:12:48.000792 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-02 01:12:48.032905 | localhost | ok
2026-09-02 01:12:48.041400 | 
2026-09-02 01:12:48.041465 | TASK [Upload logs]
2026-09-02 01:12:48.065279 | localhost | ok
2026-09-02 01:12:48.123418 | 
2026-09-02 01:12:48.123518 | TASK [Set zuul-log-path fact]
2026-09-02 01:12:48.145895 | localhost | ok
2026-09-02 01:12:48.158886 | 
2026-09-02 01:12:48.158950 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 01:12:48.191891 | localhost | ok
2026-09-02 01:12:48.199297 | 
2026-09-02 01:12:48.199368 | TASK [upload-logs : Create log directories]
2026-09-02 01:12:48.647539 | localhost | changed
2026-09-02 01:12:48.655650 | 
2026-09-02 01:12:48.659600 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-02 01:12:49.056960 | localhost -> localhost | ok: Runtime: 0:00:00.005567
2026-09-02 01:12:49.063627 | 
2026-09-02 01:12:49.063716 | TASK [upload-logs : Upload logs to log server]
2026-09-02 01:12:50.552136 | localhost | Output suppressed because no_log was given
2026-09-02 01:12:50.595884 | 
2026-09-02 01:12:50.596036 | LOOP [upload-logs : Compress console log and json output]
2026-09-02 01:12:50.642607 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.652061 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.662006 | 
2026-09-02 01:12:50.662176 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-02 01:12:50.709621 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.710044 | 
2026-09-02 01:12:50.714268 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.729446 | 
2026-09-02 01:12:50.729597 | LOOP [upload-logs : Upload console log and json output]