2026-09-11 05:15:37.449008 | Job console starting
2026-09-11 05:15:37.459317 | Updating git repos
2026-09-11 05:15:40.072839 | Cloning repos into workspace
2026-09-11 05:15:55.620737 | Restoring repo states
2026-09-11 05:16:10.075928 | Merging changes
2026-09-11 05:16:11.160034 | Checking out repos
2026-09-11 05:16:12.566820 | Preparing playbooks
2026-09-11 05:16:14.226979 | Running Ansible setup
2026-09-11 05:16:17.952069 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-11 05:16:18.512777 | 
2026-09-11 05:16:18.513020 | PLAY [localhost]
2026-09-11 05:16:18.520216 | 
2026-09-11 05:16:18.520305 | TASK [Gathering Facts]
2026-09-11 05:16:19.358284 | localhost | ok
2026-09-11 05:16:19.372096 | 
2026-09-11 05:16:19.372330 | TASK [Setup log path fact]
2026-09-11 05:16:19.391119 | localhost | ok
2026-09-11 05:16:19.406813 | 
2026-09-11 05:16:19.407018 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-11 05:16:19.434307 | localhost | ok
2026-09-11 05:16:19.440476 | 
2026-09-11 05:16:19.440582 | TASK [emit-job-header : Print job information]
2026-09-11 05:16:19.477680 | # Job Information
2026-09-11 05:16:19.477848 | Ansible Version: 2.16.18
2026-09-11 05:16:19.477891 | Job: atmosphere-molecule-csi-rbd
2026-09-11 05:16:19.477921 | Pipeline: check
2026-09-11 05:16:19.477948 | Executor: 2d72f0692154
2026-09-11 05:16:19.477974 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-09-11 05:16:19.478006 | Event ID: 5190de90-ad9f-11f1-828d-d676869aa97e
2026-09-11 05:16:19.481709 | 
2026-09-11 05:16:19.481802 | LOOP [emit-job-header : Print node information]
2026-09-11 05:16:19.579130 | localhost | ok:
2026-09-11 05:16:19.579379 | localhost | # Node Information
2026-09-11 05:16:19.579474 | localhost | Inventory Hostname: instance
2026-09-11 05:16:19.579530 | localhost | Hostname: np0000192577
2026-09-11 05:16:19.579576 | localhost | Username: zuul
2026-09-11 05:16:19.579631 | localhost | Distro: Ubuntu 22.04
2026-09-11 05:16:19.579698 | localhost | Provider: yul1
2026-09-11 05:16:19.579859 | localhost | Region: ca-ymq-1
2026-09-11 05:16:19.579913 | localhost | Label: ubuntu-jammy
2026-09-11 05:16:19.579950 | localhost | Product Name: OpenStack Nova
2026-09-11 05:16:19.579989 | localhost | Interface IP: 162.253.55.140
2026-09-11 05:16:19.601337 | 
2026-09-11 05:16:19.601545 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-11 05:16:19.964726 | localhost -> localhost | changed
2026-09-11 05:16:19.974389 | 
2026-09-11 05:16:19.974557 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-11 05:16:21.078671 | localhost -> localhost | changed
2026-09-11 05:16:21.086221 | 
2026-09-11 05:16:21.086294 | PLAY [all]
2026-09-11 05:16:21.096114 | 
2026-09-11 05:16:21.096200 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-11 05:16:21.305722 | instance -> localhost | ok
2026-09-11 05:16:21.318066 | 
2026-09-11 05:16:21.318209 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-11 05:16:21.353385 | instance | ok
2026-09-11 05:16:21.379573 | instance | included: /var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-11 05:16:21.386823 | 
2026-09-11 05:16:21.386923 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-11 05:16:22.550066 | instance -> localhost | Generating public/private rsa key pair.
2026-09-11 05:16:22.550316 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/work/12167a748f354b23b1cfc4be4f19e6bd_id_rsa
2026-09-11 05:16:22.550377 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/work/12167a748f354b23b1cfc4be4f19e6bd_id_rsa.pub
2026-09-11 05:16:22.550451 | instance -> localhost | The key fingerprint is:
2026-09-11 05:16:22.550498 | instance -> localhost | SHA256:OVuhT1smf8pWhd0JSxSYQHmbFzV3HCnzwfFRou5te/k zuul-build-sshkey
2026-09-11 05:16:22.550559 | instance -> localhost | The key's randomart image is:
2026-09-11 05:16:22.550601 | instance -> localhost | +---[RSA 3072]----+
2026-09-11 05:16:22.550652 | instance -> localhost | |        .oo +o=BO|
2026-09-11 05:16:22.550694 | instance -> localhost | |         . + B =B|
2026-09-11 05:16:22.550735 | instance -> localhost | |          o = Bo=|
2026-09-11 05:16:22.550774 | instance -> localhost | |         o = o.o+|
2026-09-11 05:16:22.550813 | instance -> localhost | |        S + =  . |
2026-09-11 05:16:22.550852 | instance -> localhost | |         * B ..  |
2026-09-11 05:16:22.550890 | instance -> localhost | |        . o o.+ .|
2026-09-11 05:16:22.550955 | instance -> localhost | |           ..+ o.|
2026-09-11 05:16:22.551060 | instance -> localhost | |           .o ..E|
2026-09-11 05:16:22.551125 | instance -> localhost | +----[SHA256]-----+
2026-09-11 05:16:22.551246 | instance -> localhost | ok: Runtime: 0:00:00.766327
2026-09-11 05:16:22.562801 | 
2026-09-11 05:16:22.562920 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-11 05:16:22.595894 | instance | ok
2026-09-11 05:16:22.611894 | instance | included: /var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-11 05:16:22.624225 | 
2026-09-11 05:16:22.624305 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-11 05:16:22.647695 | instance | skipping: Conditional result was False
2026-09-11 05:16:22.657523 | 
2026-09-11 05:16:22.657658 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-11 05:16:23.115081 | instance | changed
2026-09-11 05:16:23.126277 | 
2026-09-11 05:16:23.127090 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-11 05:16:23.309699 | instance | ok
2026-09-11 05:16:23.315701 | 
2026-09-11 05:16:23.315899 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-11 05:16:23.806443 | instance | changed
2026-09-11 05:16:23.814979 | 
2026-09-11 05:16:23.815142 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-11 05:16:24.338330 | instance | changed
2026-09-11 05:16:24.354610 | 
2026-09-11 05:16:24.354783 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-11 05:16:24.379971 | instance | skipping: Conditional result was False
2026-09-11 05:16:24.391827 | 
2026-09-11 05:16:24.391983 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-11 05:16:24.794979 | instance -> localhost | changed
2026-09-11 05:16:24.809302 | 
2026-09-11 05:16:24.809477 | TASK [add-build-sshkey : Add back temp key]
2026-09-11 05:16:25.122878 | instance -> localhost | Identity added: /var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/work/12167a748f354b23b1cfc4be4f19e6bd_id_rsa (zuul-build-sshkey)
2026-09-11 05:16:25.123097 | instance -> localhost | ok: Runtime: 0:00:00.012789
2026-09-11 05:16:25.128595 | 
2026-09-11 05:16:25.128653 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-11 05:16:25.480030 | instance | ok
2026-09-11 05:16:25.490033 | 
2026-09-11 05:16:25.490181 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-11 05:16:25.524773 | instance | skipping: Conditional result was False
2026-09-11 05:16:25.545042 | 
2026-09-11 05:16:25.545245 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-11 05:16:25.902631 | instance | ok
2026-09-11 05:16:25.909541 | 
2026-09-11 05:16:25.909646 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-11 05:16:29.316073 | instance | Output suppressed because no_log was given
2026-09-11 05:16:29.327628 | 
2026-09-11 05:16:29.327814 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-11 05:16:29.532960 | instance | ok: "logs"
2026-09-11 05:16:29.533230 | instance | ok: All items complete
2026-09-11 05:16:29.533275 | 
2026-09-11 05:16:29.696272 | instance | ok: "artifacts"
2026-09-11 05:16:29.842595 | instance | ok: "docs"
2026-09-11 05:16:29.856809 | 
2026-09-11 05:16:29.857003 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-11 05:16:30.055041 | instance | changed: "logs"
2026-09-11 05:16:30.217080 | instance | changed: "artifacts"
2026-09-11 05:16:30.364802 | instance | changed: "docs"
2026-09-11 05:16:30.384357 | 
2026-09-11 05:16:30.384603 | PLAY RECAP
2026-09-11 05:16:30.384702 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-11 05:16:30.384764 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-11 05:16:30.384811 | 
2026-09-11 05:16:30.524824 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-11 05:16:30.527642 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-11 05:16:31.093850 | 
2026-09-11 05:16:31.093963 | PLAY [all]
2026-09-11 05:16:31.103660 | 
2026-09-11 05:16:31.103734 | TASK [setup-uv : Extract archive]
2026-09-11 05:16:33.393705 | instance | changed
2026-09-11 05:16:33.404948 | 
2026-09-11 05:16:33.405128 | TASK [setup-uv : Print version]
2026-09-11 05:16:33.808945 | instance | uv 0.8.13
2026-09-11 05:16:33.952194 | instance | ok: Runtime: 0:00:00.012988
2026-09-11 05:16:33.969301 | 
2026-09-11 05:16:33.969461 | TASK [Install Ansible collection siblings]
2026-09-11 05:16:42.675151 | instance | changed:
2026-09-11 05:16:42.675396 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-11 05:16:42.675496 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-11 05:16:42.675561 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-11 05:16:42.675612 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-11 05:16:42.675652 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-11 05:16:42.675694 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-11 05:16:42.675733 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-11 05:16:42.675774 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-11 05:16:42.675817 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-11 05:16:42.675869 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-11 05:16:42.675922 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-11 05:16:42.675960 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-11 05:16:42.675996 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-11 05:16:42.676031 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-11 05:16:42.676067 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-11 05:16:42.676103 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-11 05:16:42.676140 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-11 05:16:42.676175 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-11 05:16:42.676210 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-11 05:16:42.676245 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-11 05:16:42.676279 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-11 05:16:42.676314 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-11 05:16:42.676352 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-11 05:16:42.676405 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-11 05:16:42.676468 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-11 05:16:42.676535 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-11 05:16:42.676591 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-11 05:16:42.676648 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-11 05:16:42.676689 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-11 05:16:42.676727 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-11 05:16:42.676765 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-11 05:16:42.676804 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-11 05:16:42.676843 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-11 05:16:42.676881 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-11 05:16:42.676920 | 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-11 05:16:42.676970 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.677011 | instance | Process install dependency map
2026-09-11 05:16:42.677048 | instance | Starting collection install process
2026-09-11 05:16:42.677085 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-11 05:16:42.677121 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-11 05:16:42.677158 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-11 05:16:42.677195 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-11 05:16:42.677233 | instance | Creating virtual environment at: .venv
2026-09-11 05:16:42.677271 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-11 05:16:42.677308 | instance | Downloading cryptography (4.2MiB)
2026-09-11 05:16:42.677350 | instance | Downloading kubernetes (1.9MiB)
2026-09-11 05:16:42.677388 | instance | Downloading openstacksdk (1.7MiB)
2026-09-11 05:16:42.677424 | instance | Downloading rjsonnet (1.2MiB)
2026-09-11 05:16:42.677495 | instance | Downloading ansible-core (2.1MiB)
2026-09-11 05:16:42.677535 | instance | Downloading netaddr (2.2MiB)
2026-09-11 05:16:42.677573 | instance | Downloading setuptools (1.1MiB)
2026-09-11 05:16:42.677617 | instance | Downloading pygments (1.2MiB)
2026-09-11 05:16:42.677672 | instance |    Building pyperclip==1.9.0
2026-09-11 05:16:42.677718 | instance |  Downloading rjsonnet
2026-09-11 05:16:42.677755 | instance |  Downloading netaddr
2026-09-11 05:16:42.677793 | instance |  Downloading pygments
2026-09-11 05:16:42.677832 | instance |  Downloading cryptography
2026-09-11 05:16:42.677869 | instance |  Downloading setuptools
2026-09-11 05:16:42.677906 | instance |  Downloading kubernetes
2026-09-11 05:16:42.677943 | instance |  Downloading ansible-core
2026-09-11 05:16:42.677980 | instance |  Downloading openstacksdk
2026-09-11 05:16:42.678016 | instance |       Built pyperclip==1.9.0
2026-09-11 05:16:42.678056 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-11 05:16:42.678094 | instance | Installed 79 packages in 45ms
2026-09-11 05:16:42.678132 | instance | Checked out ansible.posix to 1.6.0
2026-09-11 05:16:42.678169 | 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-11 05:16:42.678207 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.678245 | instance | Process install dependency map
2026-09-11 05:16:42.678292 | instance | Starting collection install process
2026-09-11 05:16:42.678331 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-11 05:16:42.678368 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-11 05:16:42.678405 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-11 05:16:42.678466 | instance | Checked out ansible.utils to v6.1.0
2026-09-11 05:16:42.678508 | 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-11 05:16:42.678546 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.678583 | instance | Process install dependency map
2026-09-11 05:16:42.678620 | instance | Starting collection install process
2026-09-11 05:16:42.678669 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-11 05:16:42.678723 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-11 05:16:42.678763 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-11 05:16:42.678801 | instance | Checked out atmosphere.common to v0.6.0
2026-09-11 05:16:42.678839 | 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-11 05:16:42.678877 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.678914 | instance | Process install dependency map
2026-09-11 05:16:42.678952 | instance | Starting collection install process
2026-09-11 05:16:42.678989 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-11 05:16:42.679027 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-11 05:16:42.679063 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-11 05:16:42.679099 | instance | Checked out community.crypto to 2.2.3
2026-09-11 05:16:42.679135 | 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-11 05:16:42.679172 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.679208 | instance | Process install dependency map
2026-09-11 05:16:42.679243 | instance | Starting collection install process
2026-09-11 05:16:42.679279 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-11 05:16:42.679315 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-11 05:16:42.679351 | instance | community.crypto:2.2.3 was installed successfully
2026-09-11 05:16:42.679386 | instance | Checked out community.general to 7.3.0
2026-09-11 05:16:42.679422 | 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-11 05:16:42.679481 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.679520 | instance | Process install dependency map
2026-09-11 05:16:42.679555 | instance | Starting collection install process
2026-09-11 05:16:42.679589 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-11 05:16:42.679627 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-11 05:16:42.679661 | instance | community.general:7.3.0 was installed successfully
2026-09-11 05:16:42.679724 | instance | Checked out community.mysql to 3.6.0
2026-09-11 05:16:42.679775 | 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-11 05:16:42.679817 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.679854 | instance | Process install dependency map
2026-09-11 05:16:42.679890 | instance | Starting collection install process
2026-09-11 05:16:42.679926 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-11 05:16:42.679962 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-11 05:16:42.679998 | instance | community.mysql:3.6.0 was installed successfully
2026-09-11 05:16:42.680035 | instance | Checked out kubernetes.core to 2.4.0
2026-09-11 05:16:42.680071 | 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-11 05:16:42.680108 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.680147 | instance | Process install dependency map
2026-09-11 05:16:42.680187 | instance | Starting collection install process
2026-09-11 05:16:42.680225 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-11 05:16:42.680262 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-11 05:16:42.680297 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-11 05:16:42.680334 | instance | Checked out openstack.cloud to 1.7.0
2026-09-11 05:16:42.680369 | 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-11 05:16:42.680407 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.680474 | instance | Process install dependency map
2026-09-11 05:16:42.680542 | instance | Starting collection install process
2026-09-11 05:16:42.680581 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-11 05:16:42.680621 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-11 05:16:42.680658 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-11 05:16:42.680696 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-11 05:16:42.680740 | 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-11 05:16:42.680795 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.680836 | instance | Process install dependency map
2026-09-11 05:16:42.680874 | instance | Starting collection install process
2026-09-11 05:16:42.680910 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-11 05:16:42.680948 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-11 05:16:42.680985 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-11 05:16:42.681021 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-11 05:16:42.681058 | 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-11 05:16:42.681095 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.681145 | instance | Process install dependency map
2026-09-11 05:16:42.681183 | instance | Starting collection install process
2026-09-11 05:16:42.681220 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-11 05:16:42.681257 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-11 05:16:42.681293 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-11 05:16:42.681330 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-11 05:16:42.681367 | 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-11 05:16:42.681403 | instance | Starting galaxy collection install process
2026-09-11 05:16:42.681463 | instance | Process install dependency map
2026-09-11 05:16:42.681505 | instance | Starting collection install process
2026-09-11 05:16:42.681541 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-11 05:16:42.681576 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-11 05:16:42.681612 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-11 05:16:42.691761 | 
2026-09-11 05:16:42.691856 | PLAY RECAP
2026-09-11 05:16:42.691936 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-11 05:16:42.691982 | 
2026-09-11 05:16:42.805475 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-11 05:16:42.807121 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-11 05:16:43.334849 | 
2026-09-11 05:16:43.334954 | PLAY [all]
2026-09-11 05:16:43.344628 | 
2026-09-11 05:16:43.344697 | TASK [Install "jq" for log collection]
2026-09-11 05:16:51.406540 | instance | changed
2026-09-11 05:16:51.408069 | 
2026-09-11 05:16:51.408120 | PLAY RECAP
2026-09-11 05:16:51.408163 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-11 05:16:51.408208 | 
2026-09-11 05:16:51.537848 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-11 05:16:51.541665 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-11 05:16:52.154627 | 
2026-09-11 05:16:52.154745 | PLAY [all]
2026-09-11 05:16:52.164962 | 
2026-09-11 05:16:52.165041 | LOOP [Check inventory directories]
2026-09-11 05:16:52.507086 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-11 05:16:52.678121 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-11 05:16:52.839065 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-11 05:16:52.857011 | 
2026-09-11 05:16:52.857388 | LOOP [Copy inventory file for Zuul]
2026-09-11 05:16:53.702790 | instance | changed:
2026-09-11 05:16:53.703164 | instance | {
2026-09-11 05:16:53.703234 | instance |   "ansible_loop_var": "item",
2026-09-11 05:16:53.703286 | instance |   "changed": false,
2026-09-11 05:16:53.703331 | instance |   "failed": false,
2026-09-11 05:16:53.703379 | instance |   "invocation": {
2026-09-11 05:16:53.703422 | instance |     "module_args": {
2026-09-11 05:16:53.703485 | instance |       "checksum_algorithm": "sha1",
2026-09-11 05:16:53.703528 | instance |       "follow": true,
2026-09-11 05:16:53.703569 | instance |       "get_attributes": true,
2026-09-11 05:16:53.703611 | instance |       "get_checksum": true,
2026-09-11 05:16:53.703647 | instance |       "get_mime": true,
2026-09-11 05:16:53.703685 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-11 05:16:53.703748 | instance |     }
2026-09-11 05:16:53.703788 | instance |   },
2026-09-11 05:16:53.703829 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-11 05:16:53.703869 | instance |   "stat": {
2026-09-11 05:16:53.703906 | instance |     "atime": 1789103795.926091,
2026-09-11 05:16:53.703943 | instance |     "attr_flags": "e",
2026-09-11 05:16:53.703979 | instance |     "attributes": [
2026-09-11 05:16:53.704014 | instance |       "extents"
2026-09-11 05:16:53.704048 | instance |     ],
2026-09-11 05:16:53.704081 | instance |     "block_size": 4096,
2026-09-11 05:16:53.704117 | instance |     "blocks": 8,
2026-09-11 05:16:53.704152 | instance |     "charset": "unknown",
2026-09-11 05:16:53.704191 | instance |     "ctime": 1789103794.7940347,
2026-09-11 05:16:53.704227 | instance |     "dev": 64513,
2026-09-11 05:16:53.704261 | instance |     "device_type": 0,
2026-09-11 05:16:53.704295 | instance |     "executable": true,
2026-09-11 05:16:53.704331 | instance |     "exists": true,
2026-09-11 05:16:53.704364 | instance |     "gid": 1000,
2026-09-11 05:16:53.704396 | instance |     "gr_name": "zuul",
2026-09-11 05:16:53.704428 | instance |     "inode": 1049223,
2026-09-11 05:16:53.704555 | instance |     "isblk": false,
2026-09-11 05:16:53.704650 | instance |     "ischr": false,
2026-09-11 05:16:53.704695 | instance |     "isdir": true,
2026-09-11 05:16:53.704733 | instance |     "isfifo": false,
2026-09-11 05:16:53.704767 | instance |     "isgid": false,
2026-09-11 05:16:53.704802 | instance |     "islnk": false,
2026-09-11 05:16:53.704835 | instance |     "isreg": false,
2026-09-11 05:16:53.704869 | instance |     "issock": false,
2026-09-11 05:16:53.704902 | instance |     "isuid": false,
2026-09-11 05:16:53.704934 | instance |     "mimetype": "unknown",
2026-09-11 05:16:53.704966 | instance |     "mode": "0755",
2026-09-11 05:16:53.704998 | instance |     "mtime": 1789103794.7940347,
2026-09-11 05:16:53.705032 | instance |     "nlink": 22,
2026-09-11 05:16:53.705067 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-11 05:16:53.705101 | instance |     "pw_name": "zuul",
2026-09-11 05:16:53.705136 | instance |     "readable": true,
2026-09-11 05:16:53.705169 | instance |     "rgrp": true,
2026-09-11 05:16:53.705204 | instance |     "roth": true,
2026-09-11 05:16:53.705239 | instance |     "rusr": true,
2026-09-11 05:16:53.705274 | instance |     "size": 4096,
2026-09-11 05:16:53.705308 | instance |     "uid": 1000,
2026-09-11 05:16:53.705342 | instance |     "version": "3229149314",
2026-09-11 05:16:53.705376 | instance |     "wgrp": false,
2026-09-11 05:16:53.705415 | instance |     "woth": false,
2026-09-11 05:16:53.705482 | instance |     "writeable": true,
2026-09-11 05:16:53.705525 | instance |     "wusr": true,
2026-09-11 05:16:53.705559 | instance |     "xgrp": true,
2026-09-11 05:16:53.705591 | instance |     "xoth": true,
2026-09-11 05:16:53.705994 | instance |     "xusr": true
2026-09-11 05:16:53.706071 | instance |   }
2026-09-11 05:16:53.706115 | instance | }
2026-09-11 05:16:53.706192 | instance | ok: All items complete
2026-09-11 05:16:53.706239 | 
2026-09-11 05:16:53.708273 | instance | skipping: Conditional result was False
2026-09-11 05:16:53.709137 | instance | skipping: Conditional result was False
2026-09-11 05:16:53.726526 | 
2026-09-11 05:16:53.726710 | LOOP [Switch "ansible_host" to private IP]
2026-09-11 05:16:54.085643 | instance | changed: 1 replacements made
2026-09-11 05:16:54.088176 | instance | skipping: Conditional result was False
2026-09-11 05:16:54.095663 | instance | skipping: Conditional result was False
2026-09-11 05:16:54.109639 | 
2026-09-11 05:16:54.109773 | TASK [Run Molecule scenario]
2026-09-11 05:16:55.008412 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-11 05:16:55.435865 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-11 05:16:55.435949 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-11 05:17:06.484371 | instance | INFO     [csi > dependency] Executing
2026-09-11 05:17:06.484702 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-11 05:17:06.485861 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-11 05:17:06.486091 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-11 05:17:06.500594 | instance | INFO     [csi > cleanup] Executing
2026-09-11 05:17:06.501251 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-11 05:17:06.515323 | instance | INFO     [csi > destroy] Executing
2026-09-11 05:17:06.515361 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-11 05:17:06.515450 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-11 05:17:06.524244 | instance | INFO     [csi > syntax] Executing
2026-09-11 05:17:07.961212 | instance |
2026-09-11 05:17:07.961467 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-11 05:17:08.016956 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-11 05:17:08.031763 | instance | INFO     [csi > create] Executing
2026-09-11 05:17:08.035328 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-11 05:17:08.049613 | instance | INFO     [csi > prepare] Executing
2026-09-11 05:17:08.892045 | instance |
2026-09-11 05:17:08.892265 | instance | PLAY [Prepare] *****************************************************************
2026-09-11 05:17:08.892480 | instance |
2026-09-11 05:17:08.892715 | instance | TASK [Gathering Facts] *********************************************************
2026-09-11 05:17:08.892975 | instance | Friday 11 September 2026  05:17:08 +0000 (0:00:00.024)       0:00:00.024 ******
2026-09-11 05:17:10.127220 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-11 05:17:10.127589 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-11 05:17:10.127965 | instance | interpreter could change the meaning of that path. See
2026-09-11 05:17:10.128366 | instance | https://docs.ansible.com/ansible-
2026-09-11 05:17:10.128743 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-11 05:17:10.138171 | instance | ok: [instance]
2026-09-11 05:17:10.138464 | instance |
2026-09-11 05:17:10.138780 | instance | TASK [Configure short hostname] ************************************************
2026-09-11 05:17:10.139114 | instance | Friday 11 September 2026  05:17:10 +0000 (0:00:01.246)       0:00:01.271 ******
2026-09-11 05:17:10.876041 | instance | changed: [instance]
2026-09-11 05:17:10.876255 | instance |
2026-09-11 05:17:10.876502 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-11 05:17:10.876741 | instance | Friday 11 September 2026  05:17:10 +0000 (0:00:00.737)       0:00:02.009 ******
2026-09-11 05:17:11.159816 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-11 05:17:11.160071 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-11 05:17:11.160320 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-11 05:17:11.168486 | instance | changed: [instance]
2026-09-11 05:17:11.168727 | instance |
2026-09-11 05:17:11.169066 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-11 05:17:11.169325 | instance | Friday 11 September 2026  05:17:11 +0000 (0:00:00.292)       0:00:02.301 ******
2026-09-11 05:17:11.940433 | instance | ok: [instance]
2026-09-11 05:17:11.940588 | instance |
2026-09-11 05:17:11.940818 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-11 05:17:11.941121 | instance |
2026-09-11 05:17:11.941354 | instance | TASK [Gathering Facts] *********************************************************
2026-09-11 05:17:11.941582 | instance | Friday 11 September 2026  05:17:11 +0000 (0:00:00.771)       0:00:03.073 ******
2026-09-11 05:17:12.700511 | instance | ok: [instance]
2026-09-11 05:17:12.700675 | instance |
2026-09-11 05:17:12.700901 | instance | TASK [Install depedencies] *****************************************************
2026-09-11 05:17:12.701212 | instance | Friday 11 September 2026  05:17:12 +0000 (0:00:00.760)       0:00:03.833 ******
2026-09-11 05:17:34.908527 | instance | changed: [instance]
2026-09-11 05:17:34.908743 | instance |
2026-09-11 05:17:34.908869 | instance | TASK [Start up service] ********************************************************
2026-09-11 05:17:34.909158 | instance | Friday 11 September 2026  05:17:34 +0000 (0:00:22.207)       0:00:26.041 ******
2026-09-11 05:17:35.443544 | instance | ok: [instance]
2026-09-11 05:17:35.443605 | instance |
2026-09-11 05:17:35.443724 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-11 05:17:35.443834 | instance | Friday 11 September 2026  05:17:35 +0000 (0:00:00.535)       0:00:26.577 ******
2026-09-11 05:17:35.761899 | instance | ok: [instance]
2026-09-11 05:17:35.762111 | instance |
2026-09-11 05:17:35.762346 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-11 05:17:35.762579 | instance | Friday 11 September 2026  05:17:35 +0000 (0:00:00.317)       0:00:26.894 ******
2026-09-11 05:17:36.446384 | instance | changed: [instance]
2026-09-11 05:17:36.446421 | instance |
2026-09-11 05:17:36.446427 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-11 05:17:36.446435 | instance | Friday 11 September 2026  05:17:36 +0000 (0:00:00.684)       0:00:27.579 ******
2026-09-11 05:17:36.638759 | instance | ok: [instance]
2026-09-11 05:17:36.638790 | instance |
2026-09-11 05:17:36.638796 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-11 05:17:36.638801 | instance | Friday 11 September 2026  05:17:36 +0000 (0:00:00.192)       0:00:27.772 ******
2026-09-11 05:17:36.652313 | instance | skipping: [instance]
2026-09-11 05:17:36.652348 | instance |
2026-09-11 05:17:36.652353 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-11 05:17:36.652358 | instance | Friday 11 September 2026  05:17:36 +0000 (0:00:00.013)       0:00:27.785 ******
2026-09-11 05:17:37.194375 | instance | changed: [instance] => (item=osd0)
2026-09-11 05:17:37.194429 | instance | changed: [instance] => (item=osd1)
2026-09-11 05:17:37.194439 | instance | changed: [instance] => (item=osd2)
2026-09-11 05:17:37.194449 | instance |
2026-09-11 05:17:37.194459 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-11 05:17:37.194468 | instance | Friday 11 September 2026  05:17:37 +0000 (0:00:00.540)       0:00:28.326 ******
2026-09-11 05:17:37.847724 | instance | changed: [instance] => (item=osd0)
2026-09-11 05:17:37.848992 | instance | changed: [instance] => (item=osd1)
2026-09-11 05:17:37.849035 | instance | changed: [instance] => (item=osd2)
2026-09-11 05:17:37.849042 | instance |
2026-09-11 05:17:37.849048 | instance | TASK [Start loop devices] ******************************************************
2026-09-11 05:17:37.849060 | instance | Friday 11 September 2026  05:17:37 +0000 (0:00:00.654)       0:00:28.980 ******
2026-09-11 05:17:38.555749 | instance | changed: [instance] => (item=osd0)
2026-09-11 05:17:38.556486 | instance | changed: [instance] => (item=osd1)
2026-09-11 05:17:38.556528 | instance | changed: [instance] => (item=osd2)
2026-09-11 05:17:38.556536 | instance |
2026-09-11 05:17:38.556542 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-11 05:17:38.556548 | instance | Friday 11 September 2026  05:17:38 +0000 (0:00:00.708)       0:00:29.689 ******
2026-09-11 05:17:41.899648 | instance | changed: [instance] => (item=osd0)
2026-09-11 05:17:41.900759 | instance | changed: [instance] => (item=osd1)
2026-09-11 05:17:41.900817 | instance | changed: [instance] => (item=osd2)
2026-09-11 05:17:41.900829 | instance |
2026-09-11 05:17:41.900839 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-11 05:17:41.900849 | instance | Friday 11 September 2026  05:17:41 +0000 (0:00:03.343)       0:00:33.032 ******
2026-09-11 05:17:43.923982 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-11 05:17:43.924034 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-11 05:17:43.924060 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-11 05:17:43.924070 | instance |
2026-09-11 05:17:43.924080 | instance | PLAY RECAP *********************************************************************
2026-09-11 05:17:43.924203 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-11 05:17:43.924585 | instance |
2026-09-11 05:17:43.924992 | instance | Friday 11 September 2026  05:17:43 +0000 (0:00:02.023)       0:00:35.056 ******
2026-09-11 05:17:43.925310 | instance | ===============================================================================
2026-09-11 05:17:43.925678 | instance | Install depedencies ---------------------------------------------------- 22.21s
2026-09-11 05:17:43.926107 | instance | Create a volume group for each loop device ------------------------------ 3.34s
2026-09-11 05:17:43.926416 | instance | Create a logical volume for each loop device ---------------------------- 2.02s
2026-09-11 05:17:43.926705 | instance | Gathering Facts --------------------------------------------------------- 1.25s
2026-09-11 05:17:43.927018 | instance | Purge "snapd" package --------------------------------------------------- 0.77s
2026-09-11 05:17:43.927309 | instance | Gathering Facts --------------------------------------------------------- 0.76s
2026-09-11 05:17:43.927595 | instance | Configure short hostname ------------------------------------------------ 0.74s
2026-09-11 05:17:43.927880 | instance | Start loop devices ------------------------------------------------------ 0.71s
2026-09-11 05:17:43.928190 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.68s
2026-09-11 05:17:43.928477 | instance | Set permissions on loopback devices ------------------------------------- 0.65s
2026-09-11 05:17:43.928761 | instance | Create devices for Ceph ------------------------------------------------- 0.54s
2026-09-11 05:17:43.929101 | instance | Start up service -------------------------------------------------------- 0.54s
2026-09-11 05:17:43.929393 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s
2026-09-11 05:17:43.929686 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-11 05:17:43.929998 | instance | Get list of all loopback devices ---------------------------------------- 0.19s
2026-09-11 05:17:43.930288 | instance | Fail if there is any existing loopback devices -------------------------- 0.01s
2026-09-11 05:17:44.007674 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-11 05:17:44.021042 | instance | INFO     [csi > converge] Executing
2026-09-11 05:17:45.321192 | instance |
2026-09-11 05:17:45.321318 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-11 05:17:45.321333 | instance |
2026-09-11 05:17:45.321514 | instance | TASK [Gathering Facts] *********************************************************
2026-09-11 05:17:45.321661 | instance | Friday 11 September 2026  05:17:45 +0000 (0:00:00.006)       0:00:00.006 ******
2026-09-11 05:17:46.439212 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-11 05:17:46.439386 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-11 05:17:46.439627 | instance | interpreter could change the meaning of that path. See
2026-09-11 05:17:46.439853 | instance | https://docs.ansible.com/ansible-
2026-09-11 05:17:46.440107 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-11 05:17:46.450892 | instance | ok: [instance]
2026-09-11 05:17:46.451132 | instance |
2026-09-11 05:17:46.451362 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-11 05:17:46.451591 | instance | Friday 11 September 2026  05:17:46 +0000 (0:00:01.129)       0:00:01.136 ******
2026-09-11 05:17:46.513396 | instance | ok: [instance] => {
2026-09-11 05:17:46.513596 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-11 05:17:46.513819 | instance | }
2026-09-11 05:17:46.514080 | instance |
2026-09-11 05:17:46.514291 | instance | PLAY [all] *********************************************************************
2026-09-11 05:17:46.514504 | instance |
2026-09-11 05:17:46.514729 | instance | TASK [Gathering Facts] *********************************************************
2026-09-11 05:17:46.514972 | instance | Friday 11 September 2026  05:17:46 +0000 (0:00:00.062)       0:00:01.198 ******
2026-09-11 05:17:47.496394 | instance | ok: [instance]
2026-09-11 05:17:47.496743 | instance |
2026-09-11 05:17:47.497106 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-11 05:17:47.497409 | instance | Friday 11 September 2026  05:17:47 +0000 (0:00:00.982)       0:00:02.181 ******
2026-09-11 05:17:47.704294 | instance | ok: [instance]
2026-09-11 05:17:47.704609 | instance |
2026-09-11 05:17:47.704912 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-11 05:17:47.705285 | instance |
2026-09-11 05:17:47.705585 | instance | TASK [Gathering Facts] *********************************************************
2026-09-11 05:17:47.705878 | instance | Friday 11 September 2026  05:17:47 +0000 (0:00:00.209)       0:00:02.390 ******
2026-09-11 05:17:48.632604 | instance | ok: [instance]
2026-09-11 05:17:48.632651 | instance |
2026-09-11 05:17:48.632663 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-11 05:17:48.632681 | instance | Friday 11 September 2026  05:17:48 +0000 (0:00:00.926)       0:00:03.317 ******
2026-09-11 05:17:48.943802 | instance | ok: [instance]
2026-09-11 05:17:48.943844 | instance |
2026-09-11 05:17:48.943851 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-11 05:17:48.943858 | instance | Friday 11 September 2026  05:17:48 +0000 (0:00:00.312)       0:00:03.629 ******
2026-09-11 05:17:48.978403 | instance | skipping: [instance]
2026-09-11 05:17:48.978442 | instance |
2026-09-11 05:17:48.978452 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-11 05:17:48.978461 | instance | Friday 11 September 2026  05:17:48 +0000 (0:00:00.034)       0:00:03.663 ******
2026-09-11 05:17:49.297096 | instance | changed: [instance]
2026-09-11 05:17:49.297203 | instance |
2026-09-11 05:17:49.297214 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-11 05:17:49.297271 | instance | Friday 11 September 2026  05:17:49 +0000 (0:00:00.318)       0:00:03.981 ******
2026-09-11 05:17:49.389584 | instance | ok: [instance] => {
2026-09-11 05:17:49.389902 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-11 05:17:49.390240 | instance | }
2026-09-11 05:17:49.390541 | instance |
2026-09-11 05:17:49.390860 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-11 05:17:49.391249 | instance | Friday 11 September 2026  05:17:49 +0000 (0:00:00.092)       0:00:04.074 ******
2026-09-11 05:17:49.996280 | instance | changed: [instance]
2026-09-11 05:17:49.996609 | instance |
2026-09-11 05:17:49.996908 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-11 05:17:49.997274 | instance | Friday 11 September 2026  05:17:49 +0000 (0:00:00.606)       0:00:04.681 ******
2026-09-11 05:17:50.059211 | instance | skipping: [instance]
2026-09-11 05:17:50.059338 | instance |
2026-09-11 05:17:50.059614 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-11 05:17:50.059812 | instance | Friday 11 September 2026  05:17:50 +0000 (0:00:00.063)       0:00:04.744 ******
2026-09-11 05:17:50.125663 | instance | skipping: [instance]
2026-09-11 05:17:50.125768 | instance |
2026-09-11 05:17:50.125972 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-11 05:17:50.126212 | instance | Friday 11 September 2026  05:17:50 +0000 (0:00:00.066)       0:00:04.810 ******
2026-09-11 05:17:50.377623 | instance | ok: [instance]
2026-09-11 05:17:50.377761 | instance |
2026-09-11 05:17:50.378032 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-11 05:17:50.378227 | instance | Friday 11 September 2026  05:17:50 +0000 (0:00:00.251)       0:00:05.062 ******
2026-09-11 05:17:51.763552 | instance | ok: [instance]
2026-09-11 05:17:51.763615 | instance |
2026-09-11 05:17:51.763623 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-11 05:17:51.763630 | instance | Friday 11 September 2026  05:17:51 +0000 (0:00:01.385)       0:00:06.448 ******
2026-09-11 05:17:51.855830 | instance | ok: [instance] => {
2026-09-11 05:17:51.855899 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-11 05:17:51.855951 | instance | }
2026-09-11 05:17:51.855962 | instance |
2026-09-11 05:17:51.855971 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-11 05:17:51.855981 | instance | Friday 11 September 2026  05:17:51 +0000 (0:00:00.091)       0:00:06.539 ******
2026-09-11 05:17:52.505939 | instance | changed: [instance]
2026-09-11 05:17:52.506166 | instance |
2026-09-11 05:17:52.506640 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-11 05:17:52.506701 | instance | Friday 11 September 2026  05:17:52 +0000 (0:00:00.651)       0:00:07.191 ******
2026-09-11 05:17:55.412962 | instance | changed: [instance]
2026-09-11 05:17:55.412991 | instance |
2026-09-11 05:17:55.413228 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-11 05:17:55.413239 | instance | Friday 11 September 2026  05:17:55 +0000 (0:00:02.907)       0:00:10.098 ******
2026-09-11 05:17:55.456370 | instance | skipping: [instance]
2026-09-11 05:17:55.456409 | instance |
2026-09-11 05:17:55.456423 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-11 05:17:55.456434 | instance | Friday 11 September 2026  05:17:55 +0000 (0:00:00.042)       0:00:10.140 ******
2026-09-11 05:17:55.496976 | instance | skipping: [instance]
2026-09-11 05:17:55.497008 | instance |
2026-09-11 05:17:55.497019 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-11 05:17:55.497322 | instance | Friday 11 September 2026  05:17:55 +0000 (0:00:00.040)       0:00:10.181 ******
2026-09-11 05:17:55.536117 | instance | skipping: [instance]
2026-09-11 05:17:55.536523 | instance |
2026-09-11 05:17:55.536971 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-11 05:17:55.537375 | instance | Friday 11 September 2026  05:17:55 +0000 (0:00:00.039)       0:00:10.221 ******
2026-09-11 05:18:00.824808 | instance | changed: [instance]
2026-09-11 05:18:00.825183 | instance |
2026-09-11 05:18:00.825512 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-11 05:18:00.825856 | instance | Friday 11 September 2026  05:18:00 +0000 (0:00:05.288)       0:00:15.509 ******
2026-09-11 05:18:01.498347 | instance | changed: [instance]
2026-09-11 05:18:01.498405 | instance |
2026-09-11 05:18:01.498416 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-11 05:18:01.498426 | instance | Friday 11 September 2026  05:18:01 +0000 (0:00:00.672)       0:00:16.182 ******
2026-09-11 05:18:02.472329 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-11 05:18:02.472396 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-11 05:18:02.472422 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-11 05:18:02.472433 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-11 05:18:02.472443 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-11 05:18:02.472454 | instance |
2026-09-11 05:18:02.472470 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-11 05:18:02.472480 | instance | Friday 11 September 2026  05:18:02 +0000 (0:00:00.972)       0:00:17.155 ******
2026-09-11 05:18:03.129110 | instance | changed: [instance]
2026-09-11 05:18:03.129175 | instance |
2026-09-11 05:18:03.129187 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-11 05:18:03.129196 | instance | Friday 11 September 2026  05:18:03 +0000 (0:00:00.630)       0:00:17.785 ******
2026-09-11 05:18:03.129205 | instance |
2026-09-11 05:18:03.129222 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-11 05:18:03.129338 | instance | Friday 11 September 2026  05:18:03 +0000 (0:00:00.028)       0:00:17.814 ******
2026-09-11 05:18:04.088043 | instance | ok: [instance]
2026-09-11 05:18:04.088110 | instance |
2026-09-11 05:18:04.088337 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-11 05:18:04.088373 | instance | Friday 11 September 2026  05:18:04 +0000 (0:00:00.959)       0:00:18.773 ******
2026-09-11 05:18:04.578966 | instance | changed: [instance]
2026-09-11 05:18:04.579069 | instance |
2026-09-11 05:18:04.579086 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-11 05:18:04.579100 | instance | Friday 11 September 2026  05:18:04 +0000 (0:00:00.488)       0:00:19.262 ******
2026-09-11 05:18:05.211602 | instance | changed: [instance]
2026-09-11 05:18:05.211660 | instance |
2026-09-11 05:18:05.211672 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-11 05:18:05.211683 | instance | Friday 11 September 2026  05:18:05 +0000 (0:00:00.633)       0:00:19.895 ******
2026-09-11 05:18:05.441763 | instance | ok: [instance]
2026-09-11 05:18:05.442812 | instance |
2026-09-11 05:18:05.442852 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-11 05:18:05.442867 | instance | Friday 11 September 2026  05:18:05 +0000 (0:00:00.230)       0:00:20.126 ******
2026-09-11 05:18:05.493916 | instance | ok: [instance] => {
2026-09-11 05:18:05.495632 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-11 05:18:05.495670 | instance | }
2026-09-11 05:18:05.495685 | instance |
2026-09-11 05:18:05.495698 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-11 05:18:05.495711 | instance | Friday 11 September 2026  05:18:05 +0000 (0:00:00.052)       0:00:20.179 ******
2026-09-11 05:18:06.336338 | instance | changed: [instance]
2026-09-11 05:18:06.336991 | instance |
2026-09-11 05:18:06.337028 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-11 05:18:06.337268 | instance | Friday 11 September 2026  05:18:06 +0000 (0:00:00.841)       0:00:21.021 ******
2026-09-11 05:18:10.691881 | instance | changed: [instance]
2026-09-11 05:18:10.692027 | instance |
2026-09-11 05:18:10.692071 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-11 05:18:10.692285 | instance | Friday 11 September 2026  05:18:10 +0000 (0:00:04.355)       0:00:25.376 ******
2026-09-11 05:18:11.815650 | instance | ok: [instance]
2026-09-11 05:18:11.815764 | instance |
2026-09-11 05:18:11.815777 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-11 05:18:11.815911 | instance | Friday 11 September 2026  05:18:11 +0000 (0:00:01.124)       0:00:26.501 ******
2026-09-11 05:18:12.176236 | instance | changed: [instance]
2026-09-11 05:18:12.176989 | instance |
2026-09-11 05:18:12.177042 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-11 05:18:12.177054 | instance | Friday 11 September 2026  05:18:12 +0000 (0:00:00.359)       0:00:26.861 ******
2026-09-11 05:18:12.682673 | instance | changed: [instance]
2026-09-11 05:18:12.683463 | instance |
2026-09-11 05:18:12.683549 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-11 05:18:12.683561 | instance | Friday 11 September 2026  05:18:12 +0000 (0:00:00.505)       0:00:27.367 ******
2026-09-11 05:18:13.311612 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-11 05:18:13.311789 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-11 05:18:13.312751 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-11 05:18:13.312815 | instance |
2026-09-11 05:18:13.312827 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-11 05:18:13.312837 | instance | Friday 11 September 2026  05:18:13 +0000 (0:00:00.629)       0:00:27.996 ******
2026-09-11 05:18:13.786681 | instance | changed: [instance]
2026-09-11 05:18:13.786783 | instance |
2026-09-11 05:18:13.787100 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-11 05:18:13.787165 | instance | Friday 11 September 2026  05:18:13 +0000 (0:00:00.475)       0:00:28.472 ******
2026-09-11 05:18:14.274183 | instance | changed: [instance]
2026-09-11 05:18:14.274260 | instance |
2026-09-11 05:18:14.274269 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-11 05:18:14.274276 | instance | Friday 11 September 2026  05:18:14 +0000 (0:00:00.454)       0:00:28.927 ******
2026-09-11 05:18:14.274282 | instance |
2026-09-11 05:18:14.274288 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-11 05:18:14.274294 | instance | Friday 11 September 2026  05:18:14 +0000 (0:00:00.031)       0:00:28.958 ******
2026-09-11 05:18:14.992541 | instance | ok: [instance]
2026-09-11 05:18:14.992641 | instance |
2026-09-11 05:18:14.992822 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-11 05:18:14.993010 | instance | Friday 11 September 2026  05:18:14 +0000 (0:00:00.719)       0:00:29.678 ******
2026-09-11 05:18:15.934940 | instance | changed: [instance]
2026-09-11 05:18:15.935716 | instance |
2026-09-11 05:18:15.935774 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-11 05:18:15.935786 | instance | Friday 11 September 2026  05:18:15 +0000 (0:00:00.941)       0:00:30.620 ******
2026-09-11 05:18:16.557654 | instance | changed: [instance]
2026-09-11 05:18:16.558226 | instance |
2026-09-11 05:18:16.558250 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-11 05:18:16.558259 | instance | Friday 11 September 2026  05:18:16 +0000 (0:00:00.623)       0:00:31.243 ******
2026-09-11 05:18:16.634586 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-11 05:18:16.635529 | instance |
2026-09-11 05:18:16.635565 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-11 05:18:16.635578 | instance | Friday 11 September 2026  05:18:16 +0000 (0:00:00.076)       0:00:31.319 ******
2026-09-11 05:18:21.897765 | instance | changed: [instance]
2026-09-11 05:18:21.898316 | instance |
2026-09-11 05:18:21.898556 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-11 05:18:21.898566 | instance | Friday 11 September 2026  05:18:21 +0000 (0:00:05.263)       0:00:36.583 ******
2026-09-11 05:18:22.568459 | instance | ok: [instance] => (item=chronyd)
2026-09-11 05:18:22.568576 | instance | ok: [instance] => (item=sshd)
2026-09-11 05:18:22.568588 | instance |
2026-09-11 05:18:22.568831 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-11 05:18:22.568883 | instance | Friday 11 September 2026  05:18:22 +0000 (0:00:00.671)       0:00:37.254 ******
2026-09-11 05:18:28.446543 | instance | changed: [instance]
2026-09-11 05:18:28.447244 | instance |
2026-09-11 05:18:28.447276 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-11 05:18:28.447288 | instance | Friday 11 September 2026  05:18:28 +0000 (0:00:05.877)       0:00:43.131 ******
2026-09-11 05:18:28.672835 | instance | ok: [instance]
2026-09-11 05:18:28.672888 | instance |
2026-09-11 05:18:28.673166 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-11 05:18:28.673183 | instance | Friday 11 September 2026  05:18:28 +0000 (0:00:00.226)       0:00:43.358 ******
2026-09-11 05:18:29.138328 | instance | changed: [instance]
2026-09-11 05:18:29.139063 | instance |
2026-09-11 05:18:29.139118 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-11 05:18:29.139130 | instance | Friday 11 September 2026  05:18:29 +0000 (0:00:00.464)       0:00:43.823 ******
2026-09-11 05:18:29.541727 | instance | changed: [instance]
2026-09-11 05:18:29.541834 | instance |
2026-09-11 05:18:29.542196 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-11 05:18:29.542270 | instance | Friday 11 September 2026  05:18:29 +0000 (0:00:00.403)       0:00:44.226 ******
2026-09-11 05:18:29.674969 | instance | ok: [instance]
2026-09-11 05:18:29.675082 | instance |
2026-09-11 05:18:29.675615 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-11 05:18:29.675656 | instance | Friday 11 September 2026  05:18:29 +0000 (0:00:00.132)       0:00:44.359 ******
2026-09-11 05:18:29.952508 | instance | ok: [instance] => (item=instance)
2026-09-11 05:18:29.952761 | instance |
2026-09-11 05:18:29.952775 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-11 05:18:29.952786 | instance | Friday 11 September 2026  05:18:29 +0000 (0:00:00.276)       0:00:44.636 ******
2026-09-11 05:18:30.014861 | instance | ok: [instance]
2026-09-11 05:18:30.015317 | instance |
2026-09-11 05:18:30.015363 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-11 05:18:30.015371 | instance | Friday 11 September 2026  05:18:30 +0000 (0:00:00.063)       0:00:44.700 ******
2026-09-11 05:18:30.100784 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-11 05:18:30.101123 | instance |
2026-09-11 05:18:30.101159 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-11 05:18:30.101171 | instance | Friday 11 September 2026  05:18:30 +0000 (0:00:00.086)       0:00:44.786 ******
2026-09-11 05:18:30.420836 | instance | changed: [instance]
2026-09-11 05:18:30.420888 | instance |
2026-09-11 05:18:30.421017 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-11 05:18:30.421125 | instance | Friday 11 September 2026  05:18:30 +0000 (0:00:00.320)       0:00:45.106 ******
2026-09-11 05:18:31.159597 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-11 05:18:31.159830 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-11 05:18:31.160067 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-11 05:18:31.160274 | instance |
2026-09-11 05:18:31.160494 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-11 05:18:31.160742 | instance | Friday 11 September 2026  05:18:31 +0000 (0:00:00.738)       0:00:45.844 ******
2026-09-11 05:20:02.610877 | instance | ok: [instance]
2026-09-11 05:20:02.610940 | instance |
2026-09-11 05:20:02.610952 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-11 05:20:02.610962 | instance | Friday 11 September 2026  05:20:02 +0000 (0:01:31.450)       0:02:17.295 ******
2026-09-11 05:20:02.851547 | instance | changed: [instance]
2026-09-11 05:20:02.851585 | instance |
2026-09-11 05:20:02.851591 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-11 05:20:02.851596 | instance | Friday 11 September 2026  05:20:02 +0000 (0:00:00.242)       0:02:17.537 ******
2026-09-11 05:20:02.898184 | instance | ok: [instance]
2026-09-11 05:20:02.898212 | instance |
2026-09-11 05:20:02.898219 | instance | TASK [Install Ceph host] *******************************************************
2026-09-11 05:20:02.898225 | instance | Friday 11 September 2026  05:20:02 +0000 (0:00:00.046)       0:02:17.583 ******
2026-09-11 05:20:02.976101 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-11 05:20:02.976133 | instance |
2026-09-11 05:20:02.976139 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-11 05:20:02.976144 | instance | Friday 11 September 2026  05:20:02 +0000 (0:00:00.077)       0:02:17.661 ******
2026-09-11 05:20:04.631366 | instance | ok: [instance]
2026-09-11 05:20:04.632078 | instance |
2026-09-11 05:20:04.632133 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-11 05:20:04.632145 | instance | Friday 11 September 2026  05:20:04 +0000 (0:00:01.654)       0:02:19.316 ******
2026-09-11 05:20:04.706496 | instance | ok: [instance] => (item=instance)
2026-09-11 05:20:04.706613 | instance |
2026-09-11 05:20:04.706810 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-11 05:20:04.706984 | instance | Friday 11 September 2026  05:20:04 +0000 (0:00:00.075)       0:02:19.391 ******
2026-09-11 05:20:05.193185 | instance | ok: [instance]
2026-09-11 05:20:05.193575 | instance |
2026-09-11 05:20:05.194028 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-11 05:20:05.194393 | instance | Friday 11 September 2026  05:20:05 +0000 (0:00:00.484)       0:02:19.876 ******
2026-09-11 05:20:07.567184 | instance | ok: [instance]
2026-09-11 05:20:07.567396 | instance |
2026-09-11 05:20:07.567639 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-11 05:20:07.567874 | instance | Friday 11 September 2026  05:20:07 +0000 (0:00:02.376)       0:02:22.252 ******
2026-09-11 05:21:00.208476 | 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-11 05:21:00.208884 | 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_p513ks6e/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_p513ks6e/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_p513ks6e/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_p513ks6e/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-11T05:21:00.010+0000 7f0e659f9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBbj6Nqi/B2NyAARksqerPhCkLtZrs+/xbP4AdXLS3228x62BN2aO7kM9A=: Malformed input [buffer:3]\n2026-09-11T05:21:00.010+0000 7f0e659f9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-11T05:21:00.014+0000 7f0e659f9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBbj6Nqi/B2NyAARksqerPhCkLtZrs+/xbP4AdXLS3228x62BN2aO7kM9A=: Malformed input [buffer:3]\n2026-09-11T05:21:00.014+0000 7f0e659f9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-11T05:21:00.014+0000 7f0e659f9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBbj6Nqi/B2NyAARksqerPhCkLtZrs+/xbP4AdXLS3228x62BN2aO7kM9A=: Malformed input [buffer:3]\n2026-09-11T05:21:00.014+0000 7f0e659f9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-11T05:21:00.014+0000 7f0e659f9640 -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-11 05:21:00.211307 | instance |
2026-09-11 05:21:00.211547 | instance | PLAY RECAP *********************************************************************
2026-09-11 05:21:00.211843 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-11 05:21:00.212063 | instance |
2026-09-11 05:21:00.212302 | instance | Friday 11 September 2026  05:21:00 +0000 (0:00:52.645)       0:03:14.897 ******
2026-09-11 05:21:00.212524 | instance | ===============================================================================
2026-09-11 05:21:00.212780 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 91.45s
2026-09-11 05:21:00.213118 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 52.65s
2026-09-11 05:21:00.213421 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 5.88s
2026-09-11 05:21:00.213744 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.29s
2026-09-11 05:21:00.214040 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.26s
2026-09-11 05:21:00.214342 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.36s
2026-09-11 05:21:00.214646 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.91s
2026-09-11 05:21:00.214913 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.38s
2026-09-11 05:21:00.215150 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.68s
2026-09-11 05:21:00.215383 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.65s
2026-09-11 05:21:00.215611 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.39s
2026-09-11 05:21:00.215869 | instance | Gathering Facts --------------------------------------------------------- 1.13s
2026-09-11 05:21:00.216108 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.12s
2026-09-11 05:21:00.216341 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-11 05:21:00.216571 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.97s
2026-09-11 05:21:00.216827 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.94s
2026-09-11 05:21:00.217194 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-09-11 05:21:00.217429 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.84s
2026-09-11 05:21:00.217701 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.74s
2026-09-11 05:21:00.217948 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.67s
2026-09-11 05:21:00.339036 | 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-11 05:21:00.339162 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-11 05:21:00.339292 | 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-11 05:21:00.470141 | instance | ERROR
2026-09-11 05:21:00.470412 | instance | {
2026-09-11 05:21:00.470468 | instance |   "delta": "0:04:05.949689",
2026-09-11 05:21:00.470508 | instance |   "end": "2026-09-11 05:21:00.417485",
2026-09-11 05:21:00.470544 | instance |   "msg": "non-zero return code",
2026-09-11 05:21:00.470578 | instance |   "rc": 2,
2026-09-11 05:21:00.470612 | instance |   "start": "2026-09-11 05:16:54.467796"
2026-09-11 05:21:00.470646 | instance | }
failure
2026-09-11 05:21:00.480858 | 
2026-09-11 05:21:00.480976 | PLAY RECAP
2026-09-11 05:21:00.481065 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-11 05:21:00.481110 | 
2026-09-11 05:21:00.596595 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-11 05:21:00.598482 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-11 05:21:01.256262 | 
2026-09-11 05:21:01.256372 | PLAY [all]
2026-09-11 05:21:01.269826 | 
2026-09-11 05:21:01.269914 | TASK [gather-host-logs : creating directory for system status]
2026-09-11 05:21:01.699127 | instance | changed
2026-09-11 05:21:01.709710 | 
2026-09-11 05:21:01.709883 | TASK [gather-host-logs : Get logs for each host]
2026-09-11 05:21:02.092320 | instance | + systemd-cgls --full --all --no-pager
2026-09-11 05:21:02.102784 | instance | + ip addr
2026-09-11 05:21:02.105169 | instance | + ip route
2026-09-11 05:21:02.107551 | instance | + lsblk
2026-09-11 05:21:02.110926 | instance | + mount
2026-09-11 05:21:02.113512 | instance | + docker images
2026-09-11 05:21:02.139036 | instance | + brctl show
2026-09-11 05:21:02.139535 | instance | /bin/bash: line 8: brctl: command not found
2026-09-11 05:21:02.139927 | instance | + ps aux --sort=-%mem
2026-09-11 05:21:02.159241 | instance | + dpkg -l
2026-09-11 05:21:02.172498 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-11 05:21:02.173213 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-11 05:21:02.196850 | instance | + '[' '!' -z '' ']'
2026-09-11 05:21:02.261283 | instance | ok: Runtime: 0:00:00.109348
2026-09-11 05:21:02.268914 | 
2026-09-11 05:21:02.269054 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-11 05:21:02.915403 | instance | changed:
2026-09-11 05:21:02.915659 | instance | created directory /var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/work/logs/instance
2026-09-11 05:21:02.915720 | instance | cd+++++++++ system/
2026-09-11 05:21:02.915767 | instance | >f+++++++++ system/brctl-show.txt
2026-09-11 05:21:02.915812 | instance | >f+++++++++ system/docker-images.txt
2026-09-11 05:21:02.915853 | instance | >f+++++++++ system/ip-addr.txt
2026-09-11 05:21:02.915901 | instance | >f+++++++++ system/ip-route.txt
2026-09-11 05:21:02.915946 | instance | >f+++++++++ system/lsblk.txt
2026-09-11 05:21:02.915988 | instance | >f+++++++++ system/mount.txt
2026-09-11 05:21:02.916043 | instance | >f+++++++++ system/packages.txt
2026-09-11 05:21:02.916156 | instance | >f+++++++++ system/ps.txt
2026-09-11 05:21:02.916252 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-11 05:21:02.938183 | 
2026-09-11 05:21:02.938395 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-11 05:21:03.138568 | instance | changed: "values"
2026-09-11 05:21:03.301037 | instance | changed: "releases"
2026-09-11 05:21:03.313500 | 
2026-09-11 05:21:03.313638 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-11 05:21:03.543284 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-11 05:21:03.844183 | instance | ok: Runtime: 0:00:00.005672
2026-09-11 05:21:03.851200 | 
2026-09-11 05:21:03.851291 | TASK [helm-release-status : Downloads logs to executor]
2026-09-11 05:21:04.398800 | instance | changed:
2026-09-11 05:21:04.398976 | instance | cd+++++++++ helm/
2026-09-11 05:21:04.399010 | instance | cd+++++++++ helm/releases/
2026-09-11 05:21:04.399036 | instance | cd+++++++++ helm/values/
2026-09-11 05:21:04.409453 | 
2026-09-11 05:21:04.409534 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-11 05:21:04.611592 | instance | changed
2026-09-11 05:21:04.618637 | 
2026-09-11 05:21:04.618754 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-11 05:21:04.897404 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-11 05:21:04.898388 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-11 05:21:04.902328 | instance | environment: line 1: kubectl: command not found
2026-09-11 05:21:04.903761 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-11 05:21:04.904286 | instance | environment: line 1: kubectl: command not found
2026-09-11 05:21:04.904947 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-11 05:21:04.907196 | instance | environment: line 1: kubectl: command not found
2026-09-11 05:21:04.907328 | instance | environment: line 1: kubectl: command not found
2026-09-11 05:21:04.907557 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-11 05:21:04.909401 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-11 05:21:04.910439 | instance | environment: line 1: kubectl: command not found
2026-09-11 05:21:04.911277 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-11 05:21:05.157679 | instance | ok: Runtime: 0:00:00.021408
2026-09-11 05:21:05.166296 | 
2026-09-11 05:21:05.166368 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-11 05:21:05.376442 | instance | changed
2026-09-11 05:21:05.381863 | 
2026-09-11 05:21:05.381933 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-11 05:21:05.600108 | instance | environment: line 5: kubectl: command not found
2026-09-11 05:21:05.600585 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-11 05:21:05.601089 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-11 05:21:05.602068 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-11 05:21:05.920335 | instance | ok: Runtime: 0:00:00.006348
2026-09-11 05:21:05.931601 | 
2026-09-11 05:21:05.931746 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-11 05:21:06.477853 | instance | changed:
2026-09-11 05:21:06.478137 | instance | cd+++++++++ objects/
2026-09-11 05:21:06.478226 | instance | cd+++++++++ objects/cluster/
2026-09-11 05:21:06.478271 | instance | cd+++++++++ objects/namespaced/
2026-09-11 05:21:06.497732 | 
2026-09-11 05:21:06.497881 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-11 05:21:06.693039 | instance | changed
2026-09-11 05:21:06.702313 | 
2026-09-11 05:21:06.702436 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-11 05:21:06.899518 | instance | changed
2026-09-11 05:21:06.909754 | 
2026-09-11 05:21:06.909896 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-11 05:21:07.166385 | instance | environment: line 3: kubectl: command not found
2026-09-11 05:21:07.458372 | instance | ok: Runtime: 0:00:00.009100
2026-09-11 05:21:07.468781 | 
2026-09-11 05:21:07.468969 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-11 05:21:07.983618 | instance | changed:
2026-09-11 05:21:07.983857 | instance | cd+++++++++ pod-logs/
2026-09-11 05:21:07.983914 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-11 05:21:08.002049 | 
2026-09-11 05:21:08.002198 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-11 05:21:08.194528 | instance | changed
2026-09-11 05:21:08.200831 | 
2026-09-11 05:21:08.200906 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-11 05:21:08.452673 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-11 05:21:08.740404 | instance | ok: Runtime: 0:00:00.052370
2026-09-11 05:21:08.749451 | 
2026-09-11 05:21:08.749575 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-11 05:21:08.991646 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-11 05:21:09.038029 | instance | ceph-mgr endpoints:
2026-09-11 05:21:09.291666 | instance | ok: Runtime: 0:00:00.053040
2026-09-11 05:21:09.295926 | 
2026-09-11 05:21:09.295983 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-11 05:21:09.514493 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-11 05:21:09.822283 | instance | ok: Runtime: 0:00:00.028363
2026-09-11 05:21:09.827370 | 
2026-09-11 05:21:09.827467 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-11 05:21:10.341782 | instance | changed: cd+++++++++ prometheus/
2026-09-11 05:21:10.362006 | 
2026-09-11 05:21:10.362207 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-11 05:21:10.590993 | instance | changed
2026-09-11 05:21:10.602042 | 
2026-09-11 05:21:10.602234 | TASK [gather-selenium-data : Get selenium data]
2026-09-11 05:21:10.874449 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-11 05:21:10.876008 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-11 05:21:11.152275 | instance | ERROR
2026-09-11 05:21:11.152557 | instance | {
2026-09-11 05:21:11.152630 | instance |   "delta": "0:00:00.006031",
2026-09-11 05:21:11.152689 | instance |   "end": "2026-09-11 05:21:10.876323",
2026-09-11 05:21:11.152781 | instance |   "msg": "non-zero return code",
2026-09-11 05:21:11.152847 | instance |   "rc": 1,
2026-09-11 05:21:11.152893 | instance |   "start": "2026-09-11 05:21:10.870292"
2026-09-11 05:21:11.152940 | instance | }
2026-09-11 05:21:11.152997 | instance | ERROR: Ignoring Errors
2026-09-11 05:21:11.163201 | 
2026-09-11 05:21:11.163390 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-11 05:21:11.649487 | instance | changed: cd+++++++++ selenium/
2026-09-11 05:21:11.660189 | 
2026-09-11 05:21:11.660291 | PLAY RECAP
2026-09-11 05:21:11.660377 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-11 05:21:11.660422 | 
2026-09-11 05:21:11.779641 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-11 05:21:11.783659 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-11 05:21:12.356606 | 
2026-09-11 05:21:12.356727 | PLAY [all]
2026-09-11 05:21:12.368220 | 
2026-09-11 05:21:12.368354 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-11 05:21:12.421825 | instance | skipping: Conditional result was False
2026-09-11 05:21:12.426565 | 
2026-09-11 05:21:12.426649 | TASK [fetch-output : Set log path for single node]
2026-09-11 05:21:12.464294 | instance | ok
2026-09-11 05:21:12.470464 | 
2026-09-11 05:21:12.470558 | LOOP [fetch-output : Ensure local output dirs]
2026-09-11 05:21:12.834533 | instance -> localhost | ok: "/var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/work/logs"
2026-09-11 05:21:13.054237 | instance -> localhost | changed: "/var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/work/artifacts"
2026-09-11 05:21:13.284195 | instance -> localhost | changed: "/var/lib/zuul/builds/12167a748f354b23b1cfc4be4f19e6bd/work/docs"
2026-09-11 05:21:13.294857 | 
2026-09-11 05:21:13.294990 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-11 05:21:13.989382 | instance | changed: .d..t...... ./
2026-09-11 05:21:13.989607 | instance | changed: All items complete
2026-09-11 05:21:13.989649 | 
2026-09-11 05:21:14.503322 | instance | changed: .d..t...... ./
2026-09-11 05:21:15.010031 | instance | changed: .d..t...... ./
2026-09-11 05:21:15.042258 | 
2026-09-11 05:21:15.042434 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-11 05:21:15.439210 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006583
2026-09-11 05:21:15.672709 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007901
2026-09-11 05:21:15.691135 | 
2026-09-11 05:21:15.691317 | PLAY [all]
2026-09-11 05:21:15.702613 | 
2026-09-11 05:21:15.702757 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-11 05:21:16.142123 | instance | changed
2026-09-11 05:21:16.151219 | 
2026-09-11 05:21:16.151412 | PLAY RECAP
2026-09-11 05:21:16.151494 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-11 05:21:16.151531 | 
2026-09-11 05:21:16.251396 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-11 05:21:16.255840 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-11 05:21:16.831273 | 
2026-09-11 05:21:16.831388 | PLAY [localhost]
2026-09-11 05:21:16.841190 | 
2026-09-11 05:21:16.841282 | TASK [Generate Zuul manifest]
2026-09-11 05:21:16.857711 | localhost | ok
2026-09-11 05:21:16.872256 | 
2026-09-11 05:21:16.872375 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-11 05:21:17.307644 | localhost | changed
2026-09-11 05:21:17.326531 | 
2026-09-11 05:21:17.326693 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-11 05:21:17.358839 | localhost | ok
2026-09-11 05:21:17.371741 | 
2026-09-11 05:21:17.371866 | TASK [Upload logs]
2026-09-11 05:21:17.389996 | localhost | ok
2026-09-11 05:21:17.438871 | 
2026-09-11 05:21:17.438977 | TASK [Set zuul-log-path fact]
2026-09-11 05:21:17.454822 | localhost | ok
2026-09-11 05:21:17.464552 | 
2026-09-11 05:21:17.464621 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-11 05:21:17.501579 | localhost | ok
2026-09-11 05:21:17.509845 | 
2026-09-11 05:21:17.509945 | TASK [upload-logs : Create log directories]
2026-09-11 05:21:17.920453 | localhost | changed
2026-09-11 05:21:17.924539 | 
2026-09-11 05:21:17.924602 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-11 05:21:18.370632 | localhost -> localhost | ok: Runtime: 0:00:00.005347
2026-09-11 05:21:18.380427 | 
2026-09-11 05:21:18.380587 | TASK [upload-logs : Upload logs to log server]
2026-09-11 05:21:18.840369 | localhost | Output suppressed because no_log was given
2026-09-11 05:21:18.898348 | 
2026-09-11 05:21:18.898483 | LOOP [upload-logs : Compress console log and json output]
2026-09-11 05:21:18.946908 | localhost | skipping: Conditional result was False
2026-09-11 05:21:18.954308 | localhost | skipping: Conditional result was False
2026-09-11 05:21:18.971424 | 
2026-09-11 05:21:18.971612 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-11 05:21:19.011066 | localhost | skipping: Conditional result was False
2026-09-11 05:21:19.011461 | 
2026-09-11 05:21:19.014724 | localhost | skipping: Conditional result was False
2026-09-11 05:21:19.026327 | 
2026-09-11 05:21:19.026500 | LOOP [upload-logs : Upload console log and json output]