2026-09-14 19:36:27.552959 | Job console starting
2026-09-14 19:36:27.565600 | Updating git repos
2026-09-14 19:36:31.466919 | Cloning repos into workspace
2026-09-14 19:36:48.069193 | Restoring repo states
2026-09-14 19:37:00.963901 | Merging changes
2026-09-14 19:37:02.379882 | Checking out repos
2026-09-14 19:37:04.501919 | Preparing playbooks
2026-09-14 19:37:07.767496 | Running Ansible setup
2026-09-14 19:37:11.117755 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-14 19:37:11.714050 | 
2026-09-14 19:37:11.714617 | PLAY [localhost]
2026-09-14 19:37:11.721675 | 
2026-09-14 19:37:11.721740 | TASK [Gathering Facts]
2026-09-14 19:37:12.601589 | localhost | ok
2026-09-14 19:37:12.614359 | 
2026-09-14 19:37:12.614515 | TASK [Setup log path fact]
2026-09-14 19:37:12.636457 | localhost | ok
2026-09-14 19:37:12.649535 | 
2026-09-14 19:37:12.649649 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-14 19:37:12.680788 | localhost | ok
2026-09-14 19:37:12.691651 | 
2026-09-14 19:37:12.691789 | TASK [emit-job-header : Print job information]
2026-09-14 19:37:12.735073 | # Job Information
2026-09-14 19:37:12.735289 | Ansible Version: 2.16.18
2026-09-14 19:37:12.735351 | Job: atmosphere-molecule-csi-rbd
2026-09-14 19:37:12.735393 | Pipeline: check
2026-09-14 19:37:12.735430 | Executor: 2d72f0692154
2026-09-14 19:37:12.735466 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-09-14 19:37:12.735509 | Event ID: 08534ac0-b073-11f1-84f7-f3287fe90001
2026-09-14 19:37:12.741714 | 
2026-09-14 19:37:12.741866 | LOOP [emit-job-header : Print node information]
2026-09-14 19:37:12.852258 | localhost | ok:
2026-09-14 19:37:12.852603 | localhost | # Node Information
2026-09-14 19:37:12.852662 | localhost | Inventory Hostname: instance
2026-09-14 19:37:12.852703 | localhost | Hostname: np0000192749
2026-09-14 19:37:12.852740 | localhost | Username: zuul
2026-09-14 19:37:12.852785 | localhost | Distro: Ubuntu 22.04
2026-09-14 19:37:12.852843 | localhost | Provider: yul1
2026-09-14 19:37:12.852880 | localhost | Region: ca-ymq-1
2026-09-14 19:37:12.852915 | localhost | Label: ubuntu-jammy
2026-09-14 19:37:12.852950 | localhost | Product Name: OpenStack Nova
2026-09-14 19:37:12.852984 | localhost | Interface IP: 162.253.55.102
2026-09-14 19:37:12.870375 | 
2026-09-14 19:37:12.870572 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-14 19:37:13.271898 | localhost -> localhost | changed
2026-09-14 19:37:13.281887 | 
2026-09-14 19:37:13.282029 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-14 19:37:14.133215 | localhost -> localhost | changed
2026-09-14 19:37:14.138579 | 
2026-09-14 19:37:14.138626 | PLAY [all]
2026-09-14 19:37:14.146489 | 
2026-09-14 19:37:14.146550 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-14 19:37:14.401422 | instance -> localhost | ok
2026-09-14 19:37:14.408454 | 
2026-09-14 19:37:14.408557 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-14 19:37:14.436711 | instance | ok
2026-09-14 19:37:14.453163 | instance | included: /var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-14 19:37:14.459967 | 
2026-09-14 19:37:14.460045 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-14 19:37:16.587222 | instance -> localhost | Generating public/private rsa key pair.
2026-09-14 19:37:16.587396 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/work/6e67ea8ea5da4b4689791a06819dd8ad_id_rsa
2026-09-14 19:37:16.587422 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/work/6e67ea8ea5da4b4689791a06819dd8ad_id_rsa.pub
2026-09-14 19:37:16.587442 | instance -> localhost | The key fingerprint is:
2026-09-14 19:37:16.587461 | instance -> localhost | SHA256:PzuC6T/HwP/VxFIVO6HYpub830lU+CMKizVKt1B1EOY zuul-build-sshkey
2026-09-14 19:37:16.587490 | instance -> localhost | The key's randomart image is:
2026-09-14 19:37:16.587509 | instance -> localhost | +---[RSA 3072]----+
2026-09-14 19:37:16.587530 | instance -> localhost | |           =o. oo|
2026-09-14 19:37:16.587549 | instance -> localhost | |          + + ..+|
2026-09-14 19:37:16.587566 | instance -> localhost | |         . E +.oo|
2026-09-14 19:37:16.587583 | instance -> localhost | |        .   o  +o|
2026-09-14 19:37:16.587600 | instance -> localhost | |       +S= o ..o+|
2026-09-14 19:37:16.587617 | instance -> localhost | |      . O.O . o+.|
2026-09-14 19:37:16.587633 | instance -> localhost | |       = *o+  ...|
2026-09-14 19:37:16.587650 | instance -> localhost | |      o o =o... o|
2026-09-14 19:37:16.587670 | instance -> localhost | |     ....+.o...o.|
2026-09-14 19:37:16.587687 | instance -> localhost | +----[SHA256]-----+
2026-09-14 19:37:16.587731 | instance -> localhost | ok: Runtime: 0:00:01.685588
2026-09-14 19:37:16.593225 | 
2026-09-14 19:37:16.593281 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-14 19:37:16.621864 | instance | ok
2026-09-14 19:37:16.631951 | instance | included: /var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-14 19:37:16.640316 | 
2026-09-14 19:37:16.640369 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-14 19:37:16.663502 | instance | skipping: Conditional result was False
2026-09-14 19:37:16.669446 | 
2026-09-14 19:37:16.669525 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-14 19:37:17.146138 | instance | changed
2026-09-14 19:37:17.156752 | 
2026-09-14 19:37:17.156898 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-14 19:37:17.345577 | instance | ok
2026-09-14 19:37:17.356247 | 
2026-09-14 19:37:17.356373 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-14 19:37:17.845094 | instance | changed
2026-09-14 19:37:17.854188 | 
2026-09-14 19:37:17.854312 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-14 19:37:18.315505 | instance | changed
2026-09-14 19:37:18.326616 | 
2026-09-14 19:37:18.326739 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-14 19:37:18.350857 | instance | skipping: Conditional result was False
2026-09-14 19:37:18.361231 | 
2026-09-14 19:37:18.361357 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-14 19:37:18.700451 | instance -> localhost | changed
2026-09-14 19:37:18.719959 | 
2026-09-14 19:37:18.720091 | TASK [add-build-sshkey : Add back temp key]
2026-09-14 19:37:19.013345 | instance -> localhost | Identity added: /var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/work/6e67ea8ea5da4b4689791a06819dd8ad_id_rsa (zuul-build-sshkey)
2026-09-14 19:37:19.013612 | instance -> localhost | ok: Runtime: 0:00:00.014868
2026-09-14 19:37:19.019410 | 
2026-09-14 19:37:19.019489 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-14 19:37:19.337155 | instance | ok
2026-09-14 19:37:19.345602 | 
2026-09-14 19:37:19.345733 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-14 19:37:19.372263 | instance | skipping: Conditional result was False
2026-09-14 19:37:19.389651 | 
2026-09-14 19:37:19.389773 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-14 19:37:19.737607 | instance | ok
2026-09-14 19:37:19.748150 | 
2026-09-14 19:37:19.748280 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-14 19:37:23.139249 | instance | Output suppressed because no_log was given
2026-09-14 19:37:23.155254 | 
2026-09-14 19:37:23.155376 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-14 19:37:23.373723 | instance | ok: "logs"
2026-09-14 19:37:23.374358 | instance | ok: All items complete
2026-09-14 19:37:23.374426 | 
2026-09-14 19:37:23.507360 | instance | ok: "artifacts"
2026-09-14 19:37:23.646884 | instance | ok: "docs"
2026-09-14 19:37:23.665923 | 
2026-09-14 19:37:23.666135 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-14 19:37:23.857063 | instance | changed: "logs"
2026-09-14 19:37:24.001723 | instance | changed: "artifacts"
2026-09-14 19:37:24.139334 | instance | changed: "docs"
2026-09-14 19:37:24.160131 | 
2026-09-14 19:37:24.160274 | PLAY RECAP
2026-09-14 19:37:24.160358 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-14 19:37:24.160528 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-14 19:37:24.160576 | 
2026-09-14 19:37:24.307184 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-14 19:37:24.348008 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-14 19:37:24.904910 | 
2026-09-14 19:37:24.905150 | PLAY [all]
2026-09-14 19:37:24.915100 | 
2026-09-14 19:37:24.915171 | TASK [setup-uv : Extract archive]
2026-09-14 19:37:27.285355 | instance | changed
2026-09-14 19:37:27.295550 | 
2026-09-14 19:37:27.295698 | TASK [setup-uv : Print version]
2026-09-14 19:37:27.698952 | instance | uv 0.8.13
2026-09-14 19:37:27.842784 | instance | ok: Runtime: 0:00:00.011840
2026-09-14 19:37:27.859077 | 
2026-09-14 19:37:27.859204 | TASK [Install Ansible collection siblings]
2026-09-14 19:38:10.018537 | instance | changed:
2026-09-14 19:38:10.018817 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-14 19:38:10.018876 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-14 19:38:10.018919 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-14 19:38:10.018958 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-14 19:38:10.018996 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-14 19:38:10.019041 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-14 19:38:10.019081 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-14 19:38:10.019122 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-14 19:38:10.019160 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-14 19:38:10.019197 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-14 19:38:10.019234 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-14 19:38:10.019297 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-14 19:38:10.019335 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-14 19:38:10.019388 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-14 19:38:10.019426 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-14 19:38:10.019463 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-14 19:38:10.019499 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-14 19:38:10.019534 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-14 19:38:10.019570 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-14 19:38:10.019604 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-14 19:38:10.019639 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-14 19:38:10.019672 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-14 19:38:10.019710 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-14 19:38:10.019762 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-14 19:38:10.019821 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-14 19:38:10.019858 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-14 19:38:10.019893 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-14 19:38:10.019927 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-14 19:38:10.019962 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-14 19:38:10.019995 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-14 19:38:10.020029 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-14 19:38:10.020064 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-14 19:38:10.020100 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-14 19:38:10.020135 | 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-14 19:38:10.020181 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.020217 | instance | Process install dependency map
2026-09-14 19:38:10.020253 | instance | Starting collection install process
2026-09-14 19:38:10.020288 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-14 19:38:10.020322 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-14 19:38:10.020358 | instance | ansible.netcommon:* was installed successfully
2026-09-14 19:38:10.020392 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-14 19:38:10.020427 | instance | Creating virtual environment at: .venv
2026-09-14 19:38:10.020461 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-14 19:38:10.020520 | instance | Downloading setuptools (1.1MiB)
2026-09-14 19:38:10.020560 | instance | Downloading ansible-core (2.1MiB)
2026-09-14 19:38:10.020600 | instance | Downloading cryptography (4.2MiB)
2026-09-14 19:38:10.020636 | instance | Downloading kubernetes (1.9MiB)
2026-09-14 19:38:10.020673 | instance | Downloading openstacksdk (1.7MiB)
2026-09-14 19:38:10.020709 | instance | Downloading pygments (1.2MiB)
2026-09-14 19:38:10.020745 | instance | Downloading rjsonnet (1.2MiB)
2026-09-14 19:38:10.020780 | instance | Downloading netaddr (2.2MiB)
2026-09-14 19:38:10.020840 | instance | Downloading pydantic-core (2.0MiB)
2026-09-14 19:38:10.020876 | instance |    Building pyperclip==1.9.0
2026-09-14 19:38:10.020914 | instance |  Downloading rjsonnet
2026-09-14 19:38:10.020950 | instance |  Downloading pydantic-core
2026-09-14 19:38:10.020985 | instance |  Downloading netaddr
2026-09-14 19:38:10.021020 | instance |  Downloading pygments
2026-09-14 19:38:10.021055 | instance |  Downloading cryptography
2026-09-14 19:38:10.021089 | instance |  Downloading setuptools
2026-09-14 19:38:10.021122 | instance |  Downloading kubernetes
2026-09-14 19:38:10.021155 | instance |  Downloading ansible-core
2026-09-14 19:38:10.021190 | instance |  Downloading openstacksdk
2026-09-14 19:38:10.021224 | instance |       Built pyperclip==1.9.0
2026-09-14 19:38:10.021260 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-14 19:38:10.021295 | instance | Installed 83 packages in 64ms
2026-09-14 19:38:10.021330 | instance | Checked out ansible.posix to 1.6.0
2026-09-14 19:38:10.021364 | 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-14 19:38:10.021399 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.021434 | instance | Process install dependency map
2026-09-14 19:38:10.021477 | instance | Starting collection install process
2026-09-14 19:38:10.021513 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-14 19:38:10.021548 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-14 19:38:10.021582 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-14 19:38:10.021617 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.021673 | instance | 2.17.12
2026-09-14 19:38:10.021711 | instance | Checked out ansible.utils to v6.1.0
2026-09-14 19:38:10.021746 | 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-14 19:38:10.021814 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.021851 | instance | Process install dependency map
2026-09-14 19:38:10.021902 | instance | Starting collection install process
2026-09-14 19:38:10.021939 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-14 19:38:10.021990 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-14 19:38:10.022027 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-14 19:38:10.022063 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.022114 | instance | 2.17.12
2026-09-14 19:38:10.022151 | instance | Checked out atmosphere.common to v0.7.0
2026-09-14 19:38:10.022202 | 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-14 19:38:10.022239 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.022310 | instance | Process install dependency map
2026-09-14 19:38:10.022347 | instance | Starting collection install process
2026-09-14 19:38:10.022383 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-14 19:38:10.022417 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-14 19:38:10.022451 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-14 19:38:10.022485 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.022519 | instance | 2.17.12
2026-09-14 19:38:10.022553 | instance | Checked out community.crypto to 2.2.3
2026-09-14 19:38:10.022587 | 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-14 19:38:10.022621 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.022656 | instance | Process install dependency map
2026-09-14 19:38:10.022690 | instance | Starting collection install process
2026-09-14 19:38:10.022724 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-14 19:38:10.022759 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-14 19:38:10.022815 | instance | community.crypto:2.2.3 was installed successfully
2026-09-14 19:38:10.022851 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.022886 | instance | 2.17.12
2026-09-14 19:38:10.022920 | instance | Checked out community.general to 7.3.0
2026-09-14 19:38:10.022955 | 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-14 19:38:10.022998 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.023033 | instance | Process install dependency map
2026-09-14 19:38:10.023067 | instance | Starting collection install process
2026-09-14 19:38:10.023101 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-14 19:38:10.023134 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-14 19:38:10.023168 | instance | community.general:7.3.0 was installed successfully
2026-09-14 19:38:10.023202 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.023236 | instance | 2.17.12
2026-09-14 19:38:10.023270 | instance | Checked out community.mysql to 3.6.0
2026-09-14 19:38:10.023308 | 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-14 19:38:10.023344 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.023378 | instance | Process install dependency map
2026-09-14 19:38:10.023413 | instance | Starting collection install process
2026-09-14 19:38:10.023447 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-14 19:38:10.023481 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-14 19:38:10.023515 | instance | community.mysql:3.6.0 was installed successfully
2026-09-14 19:38:10.023549 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.023583 | instance | 2.17.12
2026-09-14 19:38:10.023617 | instance | Checked out kubernetes.core to 2.4.0
2026-09-14 19:38:10.023652 | 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-14 19:38:10.023688 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.023722 | instance | Process install dependency map
2026-09-14 19:38:10.023756 | instance | Starting collection install process
2026-09-14 19:38:10.023806 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-14 19:38:10.023843 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-14 19:38:10.023877 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-14 19:38:10.023912 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.023946 | instance | 2.17.12
2026-09-14 19:38:10.023981 | instance | Checked out openstack.cloud to 2.6.0
2026-09-14 19:38:10.024015 | 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-14 19:38:10.024050 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.024084 | instance | Process install dependency map
2026-09-14 19:38:10.024117 | instance | Starting collection install process
2026-09-14 19:38:10.024151 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-14 19:38:10.024185 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-14 19:38:10.024220 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-14 19:38:10.024254 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.024289 | instance | 2.17.12
2026-09-14 19:38:10.024332 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-14 19:38:10.024367 | 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-14 19:38:10.024402 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.024436 | instance | Process install dependency map
2026-09-14 19:38:10.024470 | instance | Starting collection install process
2026-09-14 19:38:10.024529 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-14 19:38:10.024567 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-14 19:38:10.024602 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-14 19:38:10.024636 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.024670 | instance | 2.17.12
2026-09-14 19:38:10.024704 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-14 19:38:10.024738 | 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-14 19:38:10.024772 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.024827 | instance | Process install dependency map
2026-09-14 19:38:10.024863 | instance | Starting collection install process
2026-09-14 19:38:10.024896 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-14 19:38:10.024931 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-14 19:38:10.024965 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-14 19:38:10.024999 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.025034 | instance | 2.17.12
2026-09-14 19:38:10.025067 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-14 19:38:10.025218 | 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-14 19:38:10.025252 | instance | Starting galaxy collection install process
2026-09-14 19:38:10.025286 | instance | Process install dependency map
2026-09-14 19:38:10.025320 | instance | Starting collection install process
2026-09-14 19:38:10.025354 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-14 19:38:10.025387 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-14 19:38:10.025420 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-14 19:38:10.025453 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-14 19:38:10.025486 | instance | 2.17.12
2026-09-14 19:38:10.035463 | 
2026-09-14 19:38:10.035556 | PLAY RECAP
2026-09-14 19:38:10.035635 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-14 19:38:10.035675 | 
2026-09-14 19:38:10.163973 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-14 19:38:10.167490 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-14 19:38:10.707863 | 
2026-09-14 19:38:10.707989 | PLAY [all]
2026-09-14 19:38:10.717907 | 
2026-09-14 19:38:10.717973 | TASK [Install "jq" for log collection]
2026-09-14 19:38:19.518817 | instance | changed
2026-09-14 19:38:19.522571 | 
2026-09-14 19:38:19.522694 | PLAY RECAP
2026-09-14 19:38:19.522825 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-14 19:38:19.522949 | 
2026-09-14 19:38:19.647993 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-14 19:38:19.651762 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-14 19:38:20.194096 | 
2026-09-14 19:38:20.194215 | PLAY [all]
2026-09-14 19:38:20.204014 | 
2026-09-14 19:38:20.204082 | LOOP [Check inventory directories]
2026-09-14 19:38:20.550181 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-14 19:38:20.712430 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-14 19:38:20.884537 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-14 19:38:20.897266 | 
2026-09-14 19:38:20.897527 | LOOP [Copy inventory file for Zuul]
2026-09-14 19:38:21.695424 | instance | changed:
2026-09-14 19:38:21.695725 | instance | {
2026-09-14 19:38:21.695802 | instance |   "ansible_loop_var": "item",
2026-09-14 19:38:21.695858 | instance |   "changed": false,
2026-09-14 19:38:21.695905 | instance |   "failed": false,
2026-09-14 19:38:21.695956 | instance |   "invocation": {
2026-09-14 19:38:21.696000 | instance |     "module_args": {
2026-09-14 19:38:21.696044 | instance |       "checksum_algorithm": "sha1",
2026-09-14 19:38:21.696085 | instance |       "follow": true,
2026-09-14 19:38:21.696126 | instance |       "get_attributes": true,
2026-09-14 19:38:21.696171 | instance |       "get_checksum": true,
2026-09-14 19:38:21.696212 | instance |       "get_mime": true,
2026-09-14 19:38:21.696253 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-14 19:38:21.696295 | instance |     }
2026-09-14 19:38:21.696336 | instance |   },
2026-09-14 19:38:21.696378 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-14 19:38:21.696417 | instance |   "stat": {
2026-09-14 19:38:21.696456 | instance |     "atime": 1789414683.2910395,
2026-09-14 19:38:21.696531 | instance |     "attr_flags": "e",
2026-09-14 19:38:21.696574 | instance |     "attributes": [
2026-09-14 19:38:21.696615 | instance |       "extents"
2026-09-14 19:38:21.696655 | instance |     ],
2026-09-14 19:38:21.696694 | instance |     "block_size": 4096,
2026-09-14 19:38:21.696733 | instance |     "blocks": 8,
2026-09-14 19:38:21.696774 | instance |     "charset": "unknown",
2026-09-14 19:38:21.696836 | instance |     "ctime": 1789414648.6739695,
2026-09-14 19:38:21.696878 | instance |     "dev": 64513,
2026-09-14 19:38:21.696920 | instance |     "device_type": 0,
2026-09-14 19:38:21.696960 | instance |     "executable": true,
2026-09-14 19:38:21.696999 | instance |     "exists": true,
2026-09-14 19:38:21.697039 | instance |     "gid": 1000,
2026-09-14 19:38:21.697079 | instance |     "gr_name": "zuul",
2026-09-14 19:38:21.697118 | instance |     "inode": 1049098,
2026-09-14 19:38:21.697157 | instance |     "isblk": false,
2026-09-14 19:38:21.697196 | instance |     "ischr": false,
2026-09-14 19:38:21.697235 | instance |     "isdir": true,
2026-09-14 19:38:21.697274 | instance |     "isfifo": false,
2026-09-14 19:38:21.697313 | instance |     "isgid": false,
2026-09-14 19:38:21.697352 | instance |     "islnk": false,
2026-09-14 19:38:21.697391 | instance |     "isreg": false,
2026-09-14 19:38:21.697433 | instance |     "issock": false,
2026-09-14 19:38:21.697471 | instance |     "isuid": false,
2026-09-14 19:38:21.697510 | instance |     "mimetype": "unknown",
2026-09-14 19:38:21.697548 | instance |     "mode": "0755",
2026-09-14 19:38:21.697586 | instance |     "mtime": 1789414648.6739695,
2026-09-14 19:38:21.697625 | instance |     "nlink": 23,
2026-09-14 19:38:21.697663 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-14 19:38:21.697701 | instance |     "pw_name": "zuul",
2026-09-14 19:38:21.697742 | instance |     "readable": true,
2026-09-14 19:38:21.697796 | instance |     "rgrp": true,
2026-09-14 19:38:21.697839 | instance |     "roth": true,
2026-09-14 19:38:21.697985 | instance |     "rusr": true,
2026-09-14 19:38:21.698036 | instance |     "size": 4096,
2026-09-14 19:38:21.698071 | instance |     "uid": 1000,
2026-09-14 19:38:21.698105 | instance |     "version": "3480557933",
2026-09-14 19:38:21.698138 | instance |     "wgrp": false,
2026-09-14 19:38:21.698175 | instance |     "woth": false,
2026-09-14 19:38:21.698209 | instance |     "writeable": true,
2026-09-14 19:38:21.698242 | instance |     "wusr": true,
2026-09-14 19:38:21.698274 | instance |     "xgrp": true,
2026-09-14 19:38:21.698306 | instance |     "xoth": true,
2026-09-14 19:38:21.698338 | instance |     "xusr": true
2026-09-14 19:38:21.698370 | instance |   }
2026-09-14 19:38:21.698402 | instance | }
2026-09-14 19:38:21.698469 | instance | ok: All items complete
2026-09-14 19:38:21.698509 | 
2026-09-14 19:38:21.701228 | instance | skipping: Conditional result was False
2026-09-14 19:38:21.702666 | instance | skipping: Conditional result was False
2026-09-14 19:38:21.721633 | 
2026-09-14 19:38:21.721838 | LOOP [Switch "ansible_host" to private IP]
2026-09-14 19:38:22.095641 | instance | changed: 1 replacements made
2026-09-14 19:38:22.097938 | instance | skipping: Conditional result was False
2026-09-14 19:38:22.104904 | instance | skipping: Conditional result was False
2026-09-14 19:38:22.126111 | 
2026-09-14 19:38:22.126281 | TASK [Run Molecule scenario]
2026-09-14 19:38:23.030901 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-14 19:38:23.432522 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-14 19:38:23.432585 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-14 19:38:33.787178 | instance | INFO     [csi > dependency] Executing
2026-09-14 19:38:33.787558 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-14 19:38:33.787987 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-14 19:38:33.788200 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-14 19:38:33.803400 | instance | INFO     [csi > cleanup] Executing
2026-09-14 19:38:33.804116 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-14 19:38:33.818653 | instance | INFO     [csi > destroy] Executing
2026-09-14 19:38:33.818727 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-14 19:38:33.818857 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-14 19:38:33.828186 | instance | INFO     [csi > syntax] Executing
2026-09-14 19:38:35.287698 | instance |
2026-09-14 19:38:35.287813 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-14 19:38:35.347308 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-14 19:38:35.364631 | instance | INFO     [csi > create] Executing
2026-09-14 19:38:35.368686 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-14 19:38:35.384492 | instance | INFO     [csi > prepare] Executing
2026-09-14 19:38:36.083391 | instance |
2026-09-14 19:38:36.083652 | instance | PLAY [Prepare] *****************************************************************
2026-09-14 19:38:36.083872 | instance |
2026-09-14 19:38:36.084114 | instance | TASK [Gathering Facts] *********************************************************
2026-09-14 19:38:36.084342 | instance | Monday 14 September 2026  19:38:36 +0000 (0:00:00.023)       0:00:00.023 ******
2026-09-14 19:38:37.304640 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-14 19:38:37.304890 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-14 19:38:37.305086 | instance | interpreter could change the meaning of that path. See
2026-09-14 19:38:37.305347 | instance | https://docs.ansible.com/ansible-
2026-09-14 19:38:37.305664 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-14 19:38:37.315035 | instance | ok: [instance]
2026-09-14 19:38:37.315239 | instance |
2026-09-14 19:38:37.315469 | instance | TASK [Configure short hostname] ************************************************
2026-09-14 19:38:37.315732 | instance | Monday 14 September 2026  19:38:37 +0000 (0:00:01.232)       0:00:01.255 ******
2026-09-14 19:38:38.044571 | instance | changed: [instance]
2026-09-14 19:38:38.044776 | instance |
2026-09-14 19:38:38.045029 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-14 19:38:38.045267 | instance | Monday 14 September 2026  19:38:38 +0000 (0:00:00.728)       0:00:01.984 ******
2026-09-14 19:38:38.322354 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-14 19:38:38.322417 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-14 19:38:38.322428 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-14 19:38:38.327656 | instance | changed: [instance]
2026-09-14 19:38:38.327688 | instance |
2026-09-14 19:38:38.327700 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-14 19:38:38.327710 | instance | Monday 14 September 2026  19:38:38 +0000 (0:00:00.283)       0:00:02.268 ******
2026-09-14 19:38:39.097474 | instance | ok: [instance]
2026-09-14 19:38:39.097661 | instance |
2026-09-14 19:38:39.097809 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-14 19:38:39.097943 | instance |
2026-09-14 19:38:39.098086 | instance | TASK [Gathering Facts] *********************************************************
2026-09-14 19:38:39.098241 | instance | Monday 14 September 2026  19:38:39 +0000 (0:00:00.770)       0:00:03.038 ******
2026-09-14 19:38:39.856149 | instance | ok: [instance]
2026-09-14 19:38:39.856489 | instance |
2026-09-14 19:38:39.856815 | instance | TASK [Install depedencies] *****************************************************
2026-09-14 19:38:39.857227 | instance | Monday 14 September 2026  19:38:39 +0000 (0:00:00.757)       0:00:03.796 ******
2026-09-14 19:39:01.856694 | instance | changed: [instance]
2026-09-14 19:39:01.856757 | instance |
2026-09-14 19:39:01.856771 | instance | TASK [Start up service] ********************************************************
2026-09-14 19:39:01.856782 | instance | Monday 14 September 2026  19:39:01 +0000 (0:00:21.999)       0:00:25.796 ******
2026-09-14 19:39:02.397278 | instance | ok: [instance]
2026-09-14 19:39:02.397324 | instance |
2026-09-14 19:39:02.397335 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-14 19:39:02.397345 | instance | Monday 14 September 2026  19:39:02 +0000 (0:00:00.540)       0:00:26.336 ******
2026-09-14 19:39:02.722879 | instance | ok: [instance]
2026-09-14 19:39:02.722942 | instance |
2026-09-14 19:39:02.722956 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-14 19:39:02.722973 | instance | Monday 14 September 2026  19:39:02 +0000 (0:00:00.325)       0:00:26.662 ******
2026-09-14 19:39:03.390934 | instance | changed: [instance]
2026-09-14 19:39:03.391000 | instance |
2026-09-14 19:39:03.391538 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-14 19:39:03.391597 | instance | Monday 14 September 2026  19:39:03 +0000 (0:00:00.668)       0:00:27.331 ******
2026-09-14 19:39:03.598613 | instance | ok: [instance]
2026-09-14 19:39:03.598903 | instance |
2026-09-14 19:39:03.599381 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-14 19:39:03.599430 | instance | Monday 14 September 2026  19:39:03 +0000 (0:00:00.207)       0:00:27.538 ******
2026-09-14 19:39:03.624400 | instance | skipping: [instance]
2026-09-14 19:39:03.625146 | instance |
2026-09-14 19:39:03.625179 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-14 19:39:03.625190 | instance | Monday 14 September 2026  19:39:03 +0000 (0:00:00.025)       0:00:27.564 ******
2026-09-14 19:39:04.197396 | instance | changed: [instance] => (item=osd0)
2026-09-14 19:39:04.197449 | instance | changed: [instance] => (item=osd1)
2026-09-14 19:39:04.197460 | instance | changed: [instance] => (item=osd2)
2026-09-14 19:39:04.197470 | instance |
2026-09-14 19:39:04.197479 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-14 19:39:04.197489 | instance | Monday 14 September 2026  19:39:04 +0000 (0:00:00.571)       0:00:28.136 ******
2026-09-14 19:39:04.844113 | instance | changed: [instance] => (item=osd0)
2026-09-14 19:39:04.845324 | instance | changed: [instance] => (item=osd1)
2026-09-14 19:39:04.845377 | instance | changed: [instance] => (item=osd2)
2026-09-14 19:39:04.845389 | instance |
2026-09-14 19:39:04.845399 | instance | TASK [Start loop devices] ******************************************************
2026-09-14 19:39:04.845409 | instance | Monday 14 September 2026  19:39:04 +0000 (0:00:00.647)       0:00:28.784 ******
2026-09-14 19:39:05.563757 | instance | changed: [instance] => (item=osd0)
2026-09-14 19:39:05.564502 | instance | changed: [instance] => (item=osd1)
2026-09-14 19:39:05.564547 | instance | changed: [instance] => (item=osd2)
2026-09-14 19:39:05.564555 | instance |
2026-09-14 19:39:05.564561 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-14 19:39:05.564567 | instance | Monday 14 September 2026  19:39:05 +0000 (0:00:00.720)       0:00:29.504 ******
2026-09-14 19:39:08.874324 | instance | changed: [instance] => (item=osd0)
2026-09-14 19:39:08.874843 | instance | changed: [instance] => (item=osd1)
2026-09-14 19:39:08.874877 | instance | changed: [instance] => (item=osd2)
2026-09-14 19:39:08.875524 | instance |
2026-09-14 19:39:08.875585 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-14 19:39:08.875597 | instance | Monday 14 September 2026  19:39:08 +0000 (0:00:03.309)       0:00:32.814 ******
2026-09-14 19:39:10.762903 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-14 19:39:10.762956 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-14 19:39:10.762985 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-14 19:39:10.762996 | instance |
2026-09-14 19:39:10.763006 | instance | PLAY RECAP *********************************************************************
2026-09-14 19:39:10.763016 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-14 19:39:10.763026 | instance |
2026-09-14 19:39:10.763035 | instance | Monday 14 September 2026  19:39:10 +0000 (0:00:01.887)       0:00:34.701 ******
2026-09-14 19:39:10.763045 | instance | ===============================================================================
2026-09-14 19:39:10.763060 | instance | Install depedencies ---------------------------------------------------- 22.00s
2026-09-14 19:39:10.766364 | instance | Create a volume group for each loop device ------------------------------ 3.31s
2026-09-14 19:39:10.766413 | instance | Create a logical volume for each loop device ---------------------------- 1.89s
2026-09-14 19:39:10.766426 | instance | Gathering Facts --------------------------------------------------------- 1.23s
2026-09-14 19:39:10.766435 | instance | Purge "snapd" package --------------------------------------------------- 0.77s
2026-09-14 19:39:10.766444 | instance | Gathering Facts --------------------------------------------------------- 0.76s
2026-09-14 19:39:10.766453 | instance | Configure short hostname ------------------------------------------------ 0.73s
2026-09-14 19:39:10.766462 | instance | Start loop devices ------------------------------------------------------ 0.72s
2026-09-14 19:39:10.766471 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.67s
2026-09-14 19:39:10.766479 | instance | Set permissions on loopback devices ------------------------------------- 0.65s
2026-09-14 19:39:10.766488 | instance | Create devices for Ceph ------------------------------------------------- 0.57s
2026-09-14 19:39:10.766496 | instance | Start up service -------------------------------------------------------- 0.54s
2026-09-14 19:39:10.766505 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s
2026-09-14 19:39:10.766514 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-09-14 19:39:10.766523 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-14 19:39:10.766531 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-14 19:39:10.828877 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-14 19:39:10.844182 | instance | INFO     [csi > converge] Executing
2026-09-14 19:39:12.134629 | instance |
2026-09-14 19:39:12.135064 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-14 19:39:12.135404 | instance |
2026-09-14 19:39:12.135711 | instance | TASK [Gathering Facts] *********************************************************
2026-09-14 19:39:12.136036 | instance | Monday 14 September 2026  19:39:12 +0000 (0:00:00.007)       0:00:00.007 ******
2026-09-14 19:39:13.142895 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-14 19:39:13.142957 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-14 19:39:13.142969 | instance | interpreter could change the meaning of that path. See
2026-09-14 19:39:13.143038 | instance | https://docs.ansible.com/ansible-
2026-09-14 19:39:13.143327 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-14 19:39:13.155010 | instance | ok: [instance]
2026-09-14 19:39:13.155248 | instance |
2026-09-14 19:39:13.155470 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-14 19:39:13.155708 | instance | Monday 14 September 2026  19:39:13 +0000 (0:00:01.020)       0:00:01.027 ******
2026-09-14 19:39:13.212358 | instance | ok: [instance] => {
2026-09-14 19:39:13.212562 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-14 19:39:13.212793 | instance | }
2026-09-14 19:39:13.212994 | instance |
2026-09-14 19:39:13.213250 | instance | PLAY [all] *********************************************************************
2026-09-14 19:39:13.213454 | instance |
2026-09-14 19:39:13.213681 | instance | TASK [Gathering Facts] *********************************************************
2026-09-14 19:39:13.213913 | instance | Monday 14 September 2026  19:39:13 +0000 (0:00:00.057)       0:00:01.084 ******
2026-09-14 19:39:14.152179 | instance | ok: [instance]
2026-09-14 19:39:14.152528 | instance |
2026-09-14 19:39:14.152833 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-14 19:39:14.153152 | instance | Monday 14 September 2026  19:39:14 +0000 (0:00:00.939)       0:00:02.024 ******
2026-09-14 19:39:14.203241 | instance | skipping: [instance]
2026-09-14 19:39:14.203681 | instance |
2026-09-14 19:39:14.204102 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-14 19:39:14.204516 | instance | Monday 14 September 2026  19:39:14 +0000 (0:00:00.051)       0:00:02.075 ******
2026-09-14 19:39:14.411697 | instance | ok: [instance]
2026-09-14 19:39:14.412051 | instance |
2026-09-14 19:39:14.412383 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-14 19:39:14.412661 | instance |
2026-09-14 19:39:14.412954 | instance | TASK [Gathering Facts] *********************************************************
2026-09-14 19:39:14.413298 | instance | Monday 14 September 2026  19:39:14 +0000 (0:00:00.207)       0:00:02.283 ******
2026-09-14 19:39:15.363275 | instance | ok: [instance]
2026-09-14 19:39:15.363454 | instance |
2026-09-14 19:39:15.363614 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-14 19:39:15.363758 | instance | Monday 14 September 2026  19:39:15 +0000 (0:00:00.950)       0:00:03.234 ******
2026-09-14 19:39:15.675575 | instance | ok: [instance]
2026-09-14 19:39:15.675925 | instance |
2026-09-14 19:39:15.676271 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-14 19:39:15.676573 | instance | Monday 14 September 2026  19:39:15 +0000 (0:00:00.310)       0:00:03.545 ******
2026-09-14 19:39:15.723452 | instance | skipping: [instance]
2026-09-14 19:39:15.723600 | instance |
2026-09-14 19:39:15.723834 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-14 19:39:15.724021 | instance | Monday 14 September 2026  19:39:15 +0000 (0:00:00.050)       0:00:03.595 ******
2026-09-14 19:39:16.082568 | instance | changed: [instance]
2026-09-14 19:39:16.082624 | instance |
2026-09-14 19:39:16.082635 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-14 19:39:16.082645 | instance | Monday 14 September 2026  19:39:16 +0000 (0:00:00.358)       0:00:03.954 ******
2026-09-14 19:39:16.174767 | instance | ok: [instance] => {
2026-09-14 19:39:16.174838 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-14 19:39:16.174850 | instance | }
2026-09-14 19:39:16.174859 | instance |
2026-09-14 19:39:16.174868 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-14 19:39:16.174877 | instance | Monday 14 September 2026  19:39:16 +0000 (0:00:00.092)       0:00:04.046 ******
2026-09-14 19:39:16.880106 | instance | changed: [instance]
2026-09-14 19:39:16.880812 | instance |
2026-09-14 19:39:16.880859 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-14 19:39:16.880871 | instance | Monday 14 September 2026  19:39:16 +0000 (0:00:00.706)       0:00:04.752 ******
2026-09-14 19:39:16.945108 | instance | skipping: [instance]
2026-09-14 19:39:16.945170 | instance |
2026-09-14 19:39:16.945501 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-14 19:39:16.945540 | instance | Monday 14 September 2026  19:39:16 +0000 (0:00:00.065)       0:00:04.817 ******
2026-09-14 19:39:17.008472 | instance | skipping: [instance]
2026-09-14 19:39:17.008923 | instance |
2026-09-14 19:39:17.008973 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-14 19:39:17.008983 | instance | Monday 14 September 2026  19:39:17 +0000 (0:00:00.063)       0:00:04.881 ******
2026-09-14 19:39:17.259806 | instance | ok: [instance]
2026-09-14 19:39:17.260139 | instance |
2026-09-14 19:39:17.260544 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-14 19:39:17.260857 | instance | Monday 14 September 2026  19:39:17 +0000 (0:00:00.250)       0:00:05.131 ******
2026-09-14 19:39:18.477644 | instance | ok: [instance]
2026-09-14 19:39:18.477775 | instance |
2026-09-14 19:39:18.478055 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-14 19:39:18.478446 | instance | Monday 14 September 2026  19:39:18 +0000 (0:00:01.218)       0:00:06.349 ******
2026-09-14 19:39:18.571647 | instance | ok: [instance] => {
2026-09-14 19:39:18.572090 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-14 19:39:18.572396 | instance | }
2026-09-14 19:39:18.572677 | instance |
2026-09-14 19:39:18.573007 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-14 19:39:18.573338 | instance | Monday 14 September 2026  19:39:18 +0000 (0:00:00.093)       0:00:06.443 ******
2026-09-14 19:39:19.327596 | instance | changed: [instance]
2026-09-14 19:39:19.327940 | instance |
2026-09-14 19:39:19.328293 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-14 19:39:19.328626 | instance | Monday 14 September 2026  19:39:19 +0000 (0:00:00.753)       0:00:07.196 ******
2026-09-14 19:39:22.227398 | instance | changed: [instance]
2026-09-14 19:39:22.227462 | instance |
2026-09-14 19:39:22.227585 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-14 19:39:22.227780 | instance | Monday 14 September 2026  19:39:22 +0000 (0:00:02.903)       0:00:10.100 ******
2026-09-14 19:39:22.265642 | instance | skipping: [instance]
2026-09-14 19:39:22.265790 | instance |
2026-09-14 19:39:22.266460 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-14 19:39:22.266660 | instance | Monday 14 September 2026  19:39:22 +0000 (0:00:00.037)       0:00:10.137 ******
2026-09-14 19:39:22.311789 | instance | skipping: [instance]
2026-09-14 19:39:22.312049 | instance |
2026-09-14 19:39:22.312416 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-14 19:39:22.312707 | instance | Monday 14 September 2026  19:39:22 +0000 (0:00:00.043)       0:00:10.181 ******
2026-09-14 19:39:22.359554 | instance | skipping: [instance]
2026-09-14 19:39:22.359804 | instance |
2026-09-14 19:39:22.359999 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-14 19:39:22.360207 | instance | Monday 14 September 2026  19:39:22 +0000 (0:00:00.045)       0:00:10.227 ******
2026-09-14 19:39:28.127287 | instance | changed: [instance]
2026-09-14 19:39:28.127420 | instance |
2026-09-14 19:39:28.127996 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-14 19:39:28.128047 | instance | Monday 14 September 2026  19:39:28 +0000 (0:00:05.771)       0:00:15.999 ******
2026-09-14 19:39:28.772803 | instance | changed: [instance]
2026-09-14 19:39:28.773542 | instance |
2026-09-14 19:39:28.773741 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-14 19:39:28.773754 | instance | Monday 14 September 2026  19:39:28 +0000 (0:00:00.645)       0:00:16.645 ******
2026-09-14 19:39:29.766108 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-14 19:39:29.766847 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-14 19:39:29.766874 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-14 19:39:29.766890 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-14 19:39:29.766898 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-14 19:39:29.766910 | instance |
2026-09-14 19:39:29.767286 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-14 19:39:29.767339 | instance | Monday 14 September 2026  19:39:29 +0000 (0:00:00.993)       0:00:17.638 ******
2026-09-14 19:39:30.400976 | instance | changed: [instance]
2026-09-14 19:39:30.401042 | instance |
2026-09-14 19:39:30.401779 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-14 19:39:30.401799 | instance | Monday 14 September 2026  19:39:30 +0000 (0:00:00.609)       0:00:18.248 ******
2026-09-14 19:39:30.401806 | instance |
2026-09-14 19:39:30.401812 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-14 19:39:30.401818 | instance | Monday 14 September 2026  19:39:30 +0000 (0:00:00.025)       0:00:18.273 ******
2026-09-14 19:39:31.414744 | instance | ok: [instance]
2026-09-14 19:39:31.414847 | instance |
2026-09-14 19:39:31.415086 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-14 19:39:31.415141 | instance | Monday 14 September 2026  19:39:31 +0000 (0:00:01.013)       0:00:19.287 ******
2026-09-14 19:39:31.924312 | instance | changed: [instance]
2026-09-14 19:39:31.925018 | instance |
2026-09-14 19:39:31.925097 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-14 19:39:31.925109 | instance | Monday 14 September 2026  19:39:31 +0000 (0:00:00.508)       0:00:19.796 ******
2026-09-14 19:39:32.544784 | instance | changed: [instance]
2026-09-14 19:39:32.545684 | instance |
2026-09-14 19:39:32.545718 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-14 19:39:32.545731 | instance | Monday 14 September 2026  19:39:32 +0000 (0:00:00.620)       0:00:20.416 ******
2026-09-14 19:39:32.792989 | instance | ok: [instance]
2026-09-14 19:39:32.793684 | instance |
2026-09-14 19:39:32.793713 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-14 19:39:32.793725 | instance | Monday 14 September 2026  19:39:32 +0000 (0:00:00.248)       0:00:20.665 ******
2026-09-14 19:39:32.870574 | instance | ok: [instance] => {
2026-09-14 19:39:32.870889 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-14 19:39:32.871942 | instance | }
2026-09-14 19:39:32.871981 | instance |
2026-09-14 19:39:32.871993 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-14 19:39:32.872005 | instance | Monday 14 September 2026  19:39:32 +0000 (0:00:00.077)       0:00:20.742 ******
2026-09-14 19:39:33.763099 | instance | changed: [instance]
2026-09-14 19:39:33.763173 | instance |
2026-09-14 19:39:33.763383 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-14 19:39:33.763398 | instance | Monday 14 September 2026  19:39:33 +0000 (0:00:00.893)       0:00:21.636 ******
2026-09-14 19:39:38.012598 | instance | changed: [instance]
2026-09-14 19:39:38.013330 | instance |
2026-09-14 19:39:38.013403 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-14 19:39:38.013416 | instance | Monday 14 September 2026  19:39:38 +0000 (0:00:04.248)       0:00:25.884 ******
2026-09-14 19:39:39.110329 | instance | ok: [instance]
2026-09-14 19:39:39.110902 | instance |
2026-09-14 19:39:39.110987 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-14 19:39:39.111006 | instance | Monday 14 September 2026  19:39:39 +0000 (0:00:01.097)       0:00:26.981 ******
2026-09-14 19:39:39.508728 | instance | changed: [instance]
2026-09-14 19:39:39.508808 | instance |
2026-09-14 19:39:39.508975 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-14 19:39:39.509137 | instance | Monday 14 September 2026  19:39:39 +0000 (0:00:00.398)       0:00:27.380 ******
2026-09-14 19:39:40.031311 | instance | changed: [instance]
2026-09-14 19:39:40.031518 | instance |
2026-09-14 19:39:40.031771 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-14 19:39:40.032015 | instance | Monday 14 September 2026  19:39:40 +0000 (0:00:00.522)       0:00:27.903 ******
2026-09-14 19:39:40.651275 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-14 19:39:40.651418 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-14 19:39:40.651570 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-14 19:39:40.651708 | instance |
2026-09-14 19:39:40.651859 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-14 19:39:40.652006 | instance | Monday 14 September 2026  19:39:40 +0000 (0:00:00.620)       0:00:28.523 ******
2026-09-14 19:39:41.147864 | instance | changed: [instance]
2026-09-14 19:39:41.148050 | instance |
2026-09-14 19:39:41.148338 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-14 19:39:41.148573 | instance | Monday 14 September 2026  19:39:41 +0000 (0:00:00.496)       0:00:29.020 ******
2026-09-14 19:39:41.636196 | instance | changed: [instance]
2026-09-14 19:39:41.636398 | instance |
2026-09-14 19:39:41.636640 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-14 19:39:41.636879 | instance | Monday 14 September 2026  19:39:41 +0000 (0:00:00.461)       0:00:29.481 ******
2026-09-14 19:39:41.637103 | instance |
2026-09-14 19:39:41.637341 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-14 19:39:41.637579 | instance | Monday 14 September 2026  19:39:41 +0000 (0:00:00.027)       0:00:29.508 ******
2026-09-14 19:39:42.350236 | instance | ok: [instance]
2026-09-14 19:39:42.350282 | instance |
2026-09-14 19:39:42.350290 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-14 19:39:42.350297 | instance | Monday 14 September 2026  19:39:42 +0000 (0:00:00.714)       0:00:30.222 ******
2026-09-14 19:39:43.321004 | instance | changed: [instance]
2026-09-14 19:39:43.321741 | instance |
2026-09-14 19:39:43.322036 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-14 19:39:43.322050 | instance | Monday 14 September 2026  19:39:43 +0000 (0:00:00.970)       0:00:31.193 ******
2026-09-14 19:39:43.993250 | instance | changed: [instance]
2026-09-14 19:39:43.993334 | instance |
2026-09-14 19:39:43.993662 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-14 19:39:43.993701 | instance | Monday 14 September 2026  19:39:43 +0000 (0:00:00.672)       0:00:31.865 ******
2026-09-14 19:39:44.062700 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-14 19:39:44.062918 | instance |
2026-09-14 19:39:44.063005 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-14 19:39:44.063199 | instance | Monday 14 September 2026  19:39:44 +0000 (0:00:00.069)       0:00:31.935 ******
2026-09-14 19:39:50.009204 | instance | changed: [instance]
2026-09-14 19:39:50.009298 | instance |
2026-09-14 19:39:50.009661 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-14 19:39:50.009717 | instance | Monday 14 September 2026  19:39:50 +0000 (0:00:05.946)       0:00:37.881 ******
2026-09-14 19:39:50.688023 | instance | ok: [instance] => (item=chronyd)
2026-09-14 19:39:50.688980 | instance | ok: [instance] => (item=sshd)
2026-09-14 19:39:50.689209 | instance |
2026-09-14 19:39:50.689222 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-14 19:39:50.689232 | instance | Monday 14 September 2026  19:39:50 +0000 (0:00:00.678)       0:00:38.560 ******
2026-09-14 19:40:11.785736 | instance | changed: [instance]
2026-09-14 19:40:11.786444 | instance |
2026-09-14 19:40:11.786501 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-14 19:40:11.786513 | instance | Monday 14 September 2026  19:40:11 +0000 (0:00:21.097)       0:00:59.658 ******
2026-09-14 19:40:12.011588 | instance | ok: [instance]
2026-09-14 19:40:12.012383 | instance |
2026-09-14 19:40:12.012446 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-14 19:40:12.012458 | instance | Monday 14 September 2026  19:40:12 +0000 (0:00:00.225)       0:00:59.883 ******
2026-09-14 19:40:12.476073 | instance | changed: [instance]
2026-09-14 19:40:12.476824 | instance |
2026-09-14 19:40:12.476874 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-14 19:40:12.476886 | instance | Monday 14 September 2026  19:40:12 +0000 (0:00:00.464)       0:01:00.348 ******
2026-09-14 19:40:12.838566 | instance | changed: [instance]
2026-09-14 19:40:12.838886 | instance |
2026-09-14 19:40:12.839141 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-14 19:40:12.839155 | instance | Monday 14 September 2026  19:40:12 +0000 (0:00:00.362)       0:01:00.710 ******
2026-09-14 19:40:12.972328 | instance | ok: [instance]
2026-09-14 19:40:12.973164 | instance |
2026-09-14 19:40:12.973223 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-14 19:40:12.973235 | instance | Monday 14 September 2026  19:40:12 +0000 (0:00:00.133)       0:01:00.844 ******
2026-09-14 19:40:13.230497 | instance | ok: [instance] => (item=instance)
2026-09-14 19:40:13.231013 | instance |
2026-09-14 19:40:13.231084 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-14 19:40:13.231254 | instance | Monday 14 September 2026  19:40:13 +0000 (0:00:00.258)       0:01:01.102 ******
2026-09-14 19:40:13.291888 | instance | ok: [instance]
2026-09-14 19:40:13.291987 | instance |
2026-09-14 19:40:13.292306 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-14 19:40:13.292358 | instance | Monday 14 September 2026  19:40:13 +0000 (0:00:00.061)       0:01:01.164 ******
2026-09-14 19:40:13.385359 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-14 19:40:13.385780 | instance |
2026-09-14 19:40:13.385817 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-14 19:40:13.385825 | instance | Monday 14 September 2026  19:40:13 +0000 (0:00:00.093)       0:01:01.258 ******
2026-09-14 19:40:13.720960 | instance | changed: [instance]
2026-09-14 19:40:13.721831 | instance |
2026-09-14 19:40:13.721875 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-14 19:40:13.721889 | instance | Monday 14 September 2026  19:40:13 +0000 (0:00:00.335)       0:01:01.593 ******
2026-09-14 19:40:14.471997 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-14 19:40:14.473259 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-14 19:40:14.473321 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-14 19:40:14.473334 | instance |
2026-09-14 19:40:14.473345 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-14 19:40:14.473356 | instance | Monday 14 September 2026  19:40:14 +0000 (0:00:00.750)       0:01:02.344 ******
2026-09-14 19:41:48.845424 | instance | ok: [instance]
2026-09-14 19:41:48.845477 | instance |
2026-09-14 19:41:48.845489 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-14 19:41:48.845498 | instance | Monday 14 September 2026  19:41:48 +0000 (0:01:34.372)       0:02:36.716 ******
2026-09-14 19:41:49.096888 | instance | changed: [instance]
2026-09-14 19:41:49.096937 | instance |
2026-09-14 19:41:49.096949 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-14 19:41:49.096959 | instance | Monday 14 September 2026  19:41:49 +0000 (0:00:00.251)       0:02:36.968 ******
2026-09-14 19:41:49.142861 | instance | ok: [instance]
2026-09-14 19:41:49.142891 | instance |
2026-09-14 19:41:49.142898 | instance | TASK [Install Ceph host] *******************************************************
2026-09-14 19:41:49.142904 | instance | Monday 14 September 2026  19:41:49 +0000 (0:00:00.046)       0:02:37.014 ******
2026-09-14 19:41:49.248297 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-14 19:41:49.248344 | instance |
2026-09-14 19:41:49.248350 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-14 19:41:49.248354 | instance | Monday 14 September 2026  19:41:49 +0000 (0:00:00.106)       0:02:37.121 ******
2026-09-14 19:41:50.881160 | instance | ok: [instance]
2026-09-14 19:41:50.881377 | instance |
2026-09-14 19:41:50.881638 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-14 19:41:50.881852 | instance | Monday 14 September 2026  19:41:50 +0000 (0:00:01.632)       0:02:38.753 ******
2026-09-14 19:41:50.959506 | instance | ok: [instance] => (item=instance)
2026-09-14 19:41:50.959725 | instance |
2026-09-14 19:41:50.959963 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-14 19:41:50.960230 | instance | Monday 14 September 2026  19:41:50 +0000 (0:00:00.077)       0:02:38.831 ******
2026-09-14 19:41:51.298121 | instance | ok: [instance]
2026-09-14 19:41:51.298340 | instance |
2026-09-14 19:41:51.298597 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-14 19:41:51.300844 | instance | Monday 14 September 2026  19:41:51 +0000 (0:00:00.338)       0:02:39.170 ******
2026-09-14 19:41:53.648722 | instance | ok: [instance]
2026-09-14 19:41:53.648934 | instance |
2026-09-14 19:41:53.649156 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-14 19:41:53.649405 | instance | Monday 14 September 2026  19:41:53 +0000 (0:00:02.350)       0:02:41.520 ******
2026-09-14 19:43:10.494208 | 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-14 19:43:10.494887 | 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_48v5t7pa/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_48v5t7pa/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_48v5t7pa/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_48v5t7pa/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-14T19:43:10.308+0000 7fd42cb14640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDSTahqTdUrOyAANeqVaYn3Pu+vt+YXl/FHINXGsfyxzr1MzJlehkn9L8U=: Malformed input [buffer:3]\n2026-09-14T19:43:10.308+0000 7fd42cb14640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-14T19:43:10.308+0000 7fd42cb14640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDSTahqTdUrOyAANeqVaYn3Pu+vt+YXl/FHINXGsfyxzr1MzJlehkn9L8U=: Malformed input [buffer:3]\n2026-09-14T19:43:10.308+0000 7fd42cb14640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-14T19:43:10.308+0000 7fd42cb14640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDSTahqTdUrOyAANeqVaYn3Pu+vt+YXl/FHINXGsfyxzr1MzJlehkn9L8U=: Malformed input [buffer:3]\n2026-09-14T19:43:10.308+0000 7fd42cb14640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-14T19:43:10.308+0000 7fd42cb14640 -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-14 19:43:10.496850 | instance |
2026-09-14 19:43:10.497090 | instance | PLAY RECAP *********************************************************************
2026-09-14 19:43:10.497361 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-14 19:43:10.497608 | instance |
2026-09-14 19:43:10.497896 | instance | Monday 14 September 2026  19:43:10 +0000 (0:01:16.849)       0:03:58.370 ******
2026-09-14 19:43:10.498138 | instance | ===============================================================================
2026-09-14 19:43:10.498396 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 94.37s
2026-09-14 19:43:10.498664 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 76.85s
2026-09-14 19:43:10.498975 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 21.10s
2026-09-14 19:43:10.499228 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.95s
2026-09-14 19:43:10.499458 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.77s
2026-09-14 19:43:10.499734 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.25s
2026-09-14 19:43:10.500000 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.90s
2026-09-14 19:43:10.500235 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.35s
2026-09-14 19:43:10.500463 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.73s
2026-09-14 19:43:10.500720 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.63s
2026-09-14 19:43:10.500980 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.22s
2026-09-14 19:43:10.501216 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.10s
2026-09-14 19:43:10.501448 | instance | Gathering Facts --------------------------------------------------------- 1.02s
2026-09-14 19:43:10.501705 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.99s
2026-09-14 19:43:10.501960 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.97s
2026-09-14 19:43:10.502211 | instance | Gathering Facts --------------------------------------------------------- 0.95s
2026-09-14 19:43:10.502455 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-09-14 19:43:10.502714 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.89s
2026-09-14 19:43:10.503016 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.75s
2026-09-14 19:43:10.503251 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.75s
2026-09-14 19:43:10.594376 | 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-14 19:43:10.594510 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-14 19:43:10.594656 | 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-14 19:43:11.045862 | instance | ERROR
2026-09-14 19:43:11.046736 | instance | {
2026-09-14 19:43:11.046812 | instance |   "delta": "0:04:48.155101",
2026-09-14 19:43:11.046854 | instance |   "end": "2026-09-14 19:43:10.659661",
2026-09-14 19:43:11.046891 | instance |   "msg": "non-zero return code",
2026-09-14 19:43:11.046924 | instance |   "rc": 2,
2026-09-14 19:43:11.046965 | instance |   "start": "2026-09-14 19:38:22.504560"
2026-09-14 19:43:11.047019 | instance | }
failure
2026-09-14 19:43:11.056393 | 
2026-09-14 19:43:11.056553 | PLAY RECAP
2026-09-14 19:43:11.056664 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-14 19:43:11.056762 | 
2026-09-14 19:43:11.312325 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-14 19:43:11.316227 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-14 19:43:11.966610 | 
2026-09-14 19:43:11.966756 | PLAY [all]
2026-09-14 19:43:11.979364 | 
2026-09-14 19:43:11.979435 | TASK [gather-host-logs : creating directory for system status]
2026-09-14 19:43:12.358392 | instance | changed
2026-09-14 19:43:12.368579 | 
2026-09-14 19:43:12.368781 | TASK [gather-host-logs : Get logs for each host]
2026-09-14 19:43:12.795548 | instance | + systemd-cgls --full --all --no-pager
2026-09-14 19:43:12.810654 | instance | + ip addr
2026-09-14 19:43:12.813174 | instance | + ip route
2026-09-14 19:43:12.815508 | instance | + lsblk
2026-09-14 19:43:12.820232 | instance | + mount
2026-09-14 19:43:12.823076 | instance | + docker images
2026-09-14 19:43:12.848162 | instance | + brctl show
2026-09-14 19:43:12.848723 | instance | /bin/bash: line 8: brctl: command not found
2026-09-14 19:43:12.848981 | instance | + ip neigh
2026-09-14 19:43:12.851437 | instance | + bridge fdb show
2026-09-14 19:43:12.855554 | instance | + ps aux --sort=-%mem
2026-09-14 19:43:12.874478 | instance | + dpkg -l
2026-09-14 19:43:12.885105 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-14 19:43:12.885619 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-14 19:43:12.905305 | instance | + '[' '!' -z '' ']'
2026-09-14 19:43:12.959074 | instance | ok: Runtime: 0:00:00.114439
2026-09-14 19:43:12.971190 | 
2026-09-14 19:43:12.971331 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-14 19:43:13.685096 | instance | changed:
2026-09-14 19:43:13.685371 | instance | created directory /var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/work/logs/instance
2026-09-14 19:43:13.685429 | instance | cd+++++++++ system/
2026-09-14 19:43:13.685475 | instance | >f+++++++++ system/brctl-show.txt
2026-09-14 19:43:13.685518 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-14 19:43:13.685555 | instance | >f+++++++++ system/docker-images.txt
2026-09-14 19:43:13.685597 | instance | >f+++++++++ system/ip-addr.txt
2026-09-14 19:43:13.685637 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-14 19:43:13.685674 | instance | >f+++++++++ system/ip-route.txt
2026-09-14 19:43:13.685737 | instance | >f+++++++++ system/lsblk.txt
2026-09-14 19:43:13.685776 | instance | >f+++++++++ system/mount.txt
2026-09-14 19:43:13.685813 | instance | >f+++++++++ system/packages.txt
2026-09-14 19:43:13.685848 | instance | >f+++++++++ system/ps.txt
2026-09-14 19:43:13.685883 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-14 19:43:13.703104 | 
2026-09-14 19:43:13.703240 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-14 19:43:13.913449 | instance | changed: "values"
2026-09-14 19:43:14.076302 | instance | changed: "releases"
2026-09-14 19:43:14.095052 | 
2026-09-14 19:43:14.095239 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-14 19:43:14.353256 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-14 19:43:14.646261 | instance | ok: Runtime: 0:00:00.005372
2026-09-14 19:43:14.655373 | 
2026-09-14 19:43:14.655500 | TASK [helm-release-status : Downloads logs to executor]
2026-09-14 19:43:15.195229 | instance | changed:
2026-09-14 19:43:15.195471 | instance | cd+++++++++ helm/
2026-09-14 19:43:15.195523 | instance | cd+++++++++ helm/releases/
2026-09-14 19:43:15.195571 | instance | cd+++++++++ helm/values/
2026-09-14 19:43:15.212965 | 
2026-09-14 19:43:15.213089 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-14 19:43:15.428444 | instance | changed
2026-09-14 19:43:15.438993 | 
2026-09-14 19:43:15.439129 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-14 19:43:15.738702 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-14 19:43:15.739446 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-14 19:43:15.744354 | instance | environment: line 1: kubectl: command not found
2026-09-14 19:43:15.745733 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-14 19:43:15.746456 | instance | environment: line 1: kubectl: command not found
2026-09-14 19:43:15.747058 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-14 19:43:15.748815 | instance | environment: line 1: kubectl: command not found
2026-09-14 19:43:15.750287 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-14 19:43:15.750734 | instance | environment: line 1: kubectl: command not found
2026-09-14 19:43:15.752032 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-14 19:43:15.753630 | instance | environment: line 1: kubectl: command not found
2026-09-14 19:43:15.754878 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-14 19:43:15.986233 | instance | ok: Runtime: 0:00:00.023343
2026-09-14 19:43:15.994953 | 
2026-09-14 19:43:15.995073 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-14 19:43:16.209727 | instance | changed
2026-09-14 19:43:16.218532 | 
2026-09-14 19:43:16.218663 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-14 19:43:16.465092 | instance | environment: line 5: kubectl: command not found
2026-09-14 19:43:16.465974 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-14 19:43:16.466126 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-14 19:43:16.466145 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-14 19:43:16.762963 | instance | ok: Runtime: 0:00:00.008250
2026-09-14 19:43:16.772739 | 
2026-09-14 19:43:16.772871 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-14 19:43:17.293190 | instance | changed:
2026-09-14 19:43:17.293444 | instance | cd+++++++++ objects/
2026-09-14 19:43:17.293498 | instance | cd+++++++++ objects/cluster/
2026-09-14 19:43:17.293539 | instance | cd+++++++++ objects/namespaced/
2026-09-14 19:43:17.309918 | 
2026-09-14 19:43:17.310039 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-14 19:43:17.526957 | instance | changed
2026-09-14 19:43:17.535808 | 
2026-09-14 19:43:17.535925 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-14 19:43:17.762066 | instance | changed
2026-09-14 19:43:17.771246 | 
2026-09-14 19:43:17.771375 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-14 19:43:18.019278 | instance | environment: line 3: kubectl: command not found
2026-09-14 19:43:18.316407 | instance | ok: Runtime: 0:00:00.007884
2026-09-14 19:43:18.322756 | 
2026-09-14 19:43:18.322831 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-14 19:43:18.828881 | instance | changed:
2026-09-14 19:43:18.829081 | instance | cd+++++++++ pod-logs/
2026-09-14 19:43:18.829114 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-14 19:43:18.841870 | 
2026-09-14 19:43:18.841941 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-14 19:43:19.056896 | instance | changed
2026-09-14 19:43:19.065662 | 
2026-09-14 19:43:19.065800 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-14 19:43:19.301042 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-14 19:43:19.616212 | instance | ok: Runtime: 0:00:00.053996
2026-09-14 19:43:19.624856 | 
2026-09-14 19:43:19.624980 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-14 19:43:19.869069 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-14 19:43:19.917032 | instance | ceph-mgr endpoints:
2026-09-14 19:43:20.168424 | instance | ok: Runtime: 0:00:00.053361
2026-09-14 19:43:20.174671 | 
2026-09-14 19:43:20.174771 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-14 19:43:20.393748 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-14 19:43:20.708649 | instance | ok: Runtime: 0:00:00.051035
2026-09-14 19:43:20.712965 | 
2026-09-14 19:43:20.713024 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-14 19:43:21.229801 | instance | changed: cd+++++++++ prometheus/
2026-09-14 19:43:21.241881 | 
2026-09-14 19:43:21.241961 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-14 19:43:21.445578 | instance | changed
2026-09-14 19:43:21.463365 | 
2026-09-14 19:43:21.463512 | TASK [gather-selenium-data : Get selenium data]
2026-09-14 19:43:21.727013 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-14 19:43:21.728417 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-14 19:43:21.993780 | instance | ERROR
2026-09-14 19:43:21.994063 | instance | {
2026-09-14 19:43:21.994118 | instance |   "delta": "0:00:00.005607",
2026-09-14 19:43:21.994158 | instance |   "end": "2026-09-14 19:43:21.728757",
2026-09-14 19:43:21.994194 | instance |   "msg": "non-zero return code",
2026-09-14 19:43:21.994229 | instance |   "rc": 1,
2026-09-14 19:43:21.994263 | instance |   "start": "2026-09-14 19:43:21.723150"
2026-09-14 19:43:21.994297 | instance | }
2026-09-14 19:43:21.994341 | instance | ERROR: Ignoring Errors
2026-09-14 19:43:22.003208 | 
2026-09-14 19:43:22.003326 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-14 19:43:22.542755 | instance | changed: cd+++++++++ selenium/
2026-09-14 19:43:22.554070 | 
2026-09-14 19:43:22.554161 | PLAY RECAP
2026-09-14 19:43:22.554243 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-14 19:43:22.554285 | 
2026-09-14 19:43:22.679137 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-14 19:43:22.681056 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-14 19:43:23.437573 | 
2026-09-14 19:43:23.437848 | PLAY [all]
2026-09-14 19:43:23.448468 | 
2026-09-14 19:43:23.448561 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-14 19:43:23.502660 | instance | skipping: Conditional result was False
2026-09-14 19:43:23.508657 | 
2026-09-14 19:43:23.508782 | TASK [fetch-output : Set log path for single node]
2026-09-14 19:43:23.551366 | instance | ok
2026-09-14 19:43:23.559825 | 
2026-09-14 19:43:23.559955 | LOOP [fetch-output : Ensure local output dirs]
2026-09-14 19:43:23.966328 | instance -> localhost | ok: "/var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/work/logs"
2026-09-14 19:43:24.204460 | instance -> localhost | changed: "/var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/work/artifacts"
2026-09-14 19:43:24.426687 | instance -> localhost | changed: "/var/lib/zuul/builds/6e67ea8ea5da4b4689791a06819dd8ad/work/docs"
2026-09-14 19:43:24.443953 | 
2026-09-14 19:43:24.444085 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-14 19:43:25.125222 | instance | changed: .d..t...... ./
2026-09-14 19:43:25.125518 | instance | changed: All items complete
2026-09-14 19:43:25.125572 | 
2026-09-14 19:43:25.599020 | instance | changed: .d..t...... ./
2026-09-14 19:43:26.103736 | instance | changed: .d..t...... ./
2026-09-14 19:43:26.130373 | 
2026-09-14 19:43:26.130511 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-14 19:43:26.552860 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009779
2026-09-14 19:43:26.777316 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006810
2026-09-14 19:43:26.790563 | 
2026-09-14 19:43:26.790670 | PLAY [all]
2026-09-14 19:43:26.801278 | 
2026-09-14 19:43:26.801424 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-14 19:43:27.237620 | instance | changed
2026-09-14 19:43:27.244528 | 
2026-09-14 19:43:27.244590 | PLAY RECAP
2026-09-14 19:43:27.244648 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-14 19:43:27.244678 | 
2026-09-14 19:43:27.370984 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-14 19:43:27.372880 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-14 19:43:27.952929 | 
2026-09-14 19:43:27.953060 | PLAY [localhost]
2026-09-14 19:43:27.961923 | 
2026-09-14 19:43:27.961993 | TASK [Generate Zuul manifest]
2026-09-14 19:43:27.978445 | localhost | ok
2026-09-14 19:43:27.993449 | 
2026-09-14 19:43:27.993525 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-14 19:43:28.375777 | localhost | changed
2026-09-14 19:43:28.433228 | 
2026-09-14 19:43:28.433381 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-14 19:43:28.475223 | localhost | ok
2026-09-14 19:43:28.487888 | 
2026-09-14 19:43:28.488019 | TASK [Upload logs]
2026-09-14 19:43:28.510351 | localhost | ok
2026-09-14 19:43:28.583247 | 
2026-09-14 19:43:28.583354 | TASK [Set zuul-log-path fact]
2026-09-14 19:43:28.599941 | localhost | ok
2026-09-14 19:43:28.609733 | 
2026-09-14 19:43:28.609809 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-14 19:43:28.638167 | localhost | ok
2026-09-14 19:43:28.646233 | 
2026-09-14 19:43:28.646318 | TASK [upload-logs : Create log directories]
2026-09-14 19:43:29.061212 | localhost | changed
2026-09-14 19:43:29.069904 | 
2026-09-14 19:43:29.070344 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-14 19:43:29.482628 | localhost -> localhost | ok: Runtime: 0:00:00.004471
2026-09-14 19:43:29.487116 | 
2026-09-14 19:43:29.487176 | TASK [upload-logs : Upload logs to log server]
2026-09-14 19:43:29.984347 | localhost | Output suppressed because no_log was given
2026-09-14 19:43:30.030359 | 
2026-09-14 19:43:30.030512 | LOOP [upload-logs : Compress console log and json output]
2026-09-14 19:43:30.094985 | localhost | skipping: Conditional result was False
2026-09-14 19:43:30.104157 | localhost | skipping: Conditional result was False
2026-09-14 19:43:30.114400 | 
2026-09-14 19:43:30.114538 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-14 19:43:30.159450 | localhost | skipping: Conditional result was False
2026-09-14 19:43:30.159824 | 
2026-09-14 19:43:30.163528 | localhost | skipping: Conditional result was False
2026-09-14 19:43:30.178522 | 
2026-09-14 19:43:30.178657 | LOOP [upload-logs : Upload console log and json output]