2026-09-02 01:04:37.671654 | Job console starting 2026-09-02 01:04:37.681290 | Updating git repos 2026-09-02 01:04:40.271909 | Cloning repos into workspace 2026-09-02 01:05:03.380928 | Restoring repo states 2026-09-02 01:05:12.835084 | Merging changes 2026-09-02 01:05:13.964262 | Checking out repos 2026-09-02 01:05:15.734130 | Preparing playbooks 2026-09-02 01:05:18.232062 | Running Ansible setup 2026-09-02 01:05:22.298673 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 01:05:23.030562 | 2026-09-02 01:05:23.030689 | PLAY [localhost] 2026-09-02 01:05:23.041972 | 2026-09-02 01:05:23.042048 | TASK [Gathering Facts] 2026-09-02 01:05:23.944072 | localhost | ok 2026-09-02 01:05:23.953288 | 2026-09-02 01:05:23.953372 | TASK [Setup log path fact] 2026-09-02 01:05:23.984311 | localhost | ok 2026-09-02 01:05:23.996641 | 2026-09-02 01:05:23.996718 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 01:05:24.028380 | localhost | ok 2026-09-02 01:05:24.036879 | 2026-09-02 01:05:24.037005 | TASK [emit-job-header : Print job information] 2026-09-02 01:05:24.080352 | # Job Information 2026-09-02 01:05:24.080635 | Ansible Version: 2.16.18 2026-09-02 01:05:24.080704 | Job: atmosphere-molecule-csi-rbd 2026-09-02 01:05:24.080752 | Pipeline: check 2026-09-02 01:05:24.080797 | Executor: 2d72f0692154 2026-09-02 01:05:24.080841 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-09-02 01:05:24.080891 | Event ID: 34e7f630-a669-11f1-8b96-0a8685479862 2026-09-02 01:05:24.085015 | 2026-09-02 01:05:24.085089 | LOOP [emit-job-header : Print node information] 2026-09-02 01:05:24.188077 | localhost | ok: 2026-09-02 01:05:24.188407 | localhost | # Node Information 2026-09-02 01:05:24.188471 | localhost | Inventory Hostname: instance 2026-09-02 01:05:24.188519 | localhost | Hostname: np0000191802 2026-09-02 01:05:24.188565 | localhost | Username: zuul 2026-09-02 01:05:24.188619 | localhost | Distro: Ubuntu 22.04 2026-09-02 01:05:24.188665 | localhost | Provider: yul1 2026-09-02 01:05:24.188707 | localhost | Region: ca-ymq-1 2026-09-02 01:05:24.188750 | localhost | Label: ubuntu-jammy 2026-09-02 01:05:24.188792 | localhost | Product Name: OpenStack Nova 2026-09-02 01:05:24.188833 | localhost | Interface IP: 162.253.55.27 2026-09-02 01:05:24.207383 | 2026-09-02 01:05:24.207631 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-02 01:05:24.578475 | localhost -> localhost | changed 2026-09-02 01:05:24.588713 | 2026-09-02 01:05:24.588868 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-02 01:05:25.497524 | localhost -> localhost | changed 2026-09-02 01:05:25.509750 | 2026-09-02 01:05:25.509808 | PLAY [all] 2026-09-02 01:05:25.518809 | 2026-09-02 01:05:25.518874 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-02 01:05:25.775912 | instance -> localhost | ok 2026-09-02 01:05:25.782139 | 2026-09-02 01:05:25.782212 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-02 01:05:25.814841 | instance | ok 2026-09-02 01:05:25.830722 | instance | included: /var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-02 01:05:25.883179 | 2026-09-02 01:05:25.883267 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-02 01:05:26.722111 | instance -> localhost | Generating public/private rsa key pair. 2026-09-02 01:05:26.722404 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/work/64cf4fa0d6cc4a59a9e4239013da1598_id_rsa 2026-09-02 01:05:26.722471 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/work/64cf4fa0d6cc4a59a9e4239013da1598_id_rsa.pub 2026-09-02 01:05:26.722522 | instance -> localhost | The key fingerprint is: 2026-09-02 01:05:26.722570 | instance -> localhost | SHA256:JgOXLhKm245kJjig0ZGPTWL0hCe2EI6XkcvmuSva7mc zuul-build-sshkey 2026-09-02 01:05:26.722633 | instance -> localhost | The key's randomart image is: 2026-09-02 01:05:26.722681 | instance -> localhost | +---[RSA 3072]----+ 2026-09-02 01:05:26.722736 | instance -> localhost | |..oo. | 2026-09-02 01:05:26.722785 | instance -> localhost | |+.=*. . | 2026-09-02 01:05:26.722831 | instance -> localhost | |.=X=+ o | 2026-09-02 01:05:26.722877 | instance -> localhost | | *=O + | 2026-09-02 01:05:26.722921 | instance -> localhost | |+o+.+ + S | 2026-09-02 01:05:26.722965 | instance -> localhost | |++o. . + | 2026-09-02 01:05:26.723008 | instance -> localhost | |*+.. | 2026-09-02 01:05:26.723051 | instance -> localhost | |*=. E | 2026-09-02 01:05:26.723125 | instance -> localhost | |++*+ | 2026-09-02 01:05:26.723172 | instance -> localhost | +----[SHA256]-----+ 2026-09-02 01:05:26.723274 | instance -> localhost | ok: Runtime: 0:00:00.392647 2026-09-02 01:05:26.730781 | 2026-09-02 01:05:26.730851 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-02 01:05:26.765296 | instance | ok 2026-09-02 01:05:26.776196 | instance | included: /var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-02 01:05:26.783510 | 2026-09-02 01:05:26.783574 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-02 01:05:26.808410 | instance | skipping: Conditional result was False 2026-09-02 01:05:26.818372 | 2026-09-02 01:05:26.818441 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-02 01:05:27.305652 | instance | changed 2026-09-02 01:05:27.312085 | 2026-09-02 01:05:27.312152 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-02 01:05:27.499379 | instance | ok 2026-09-02 01:05:27.504476 | 2026-09-02 01:05:27.504547 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-02 01:05:28.055001 | instance | changed 2026-09-02 01:05:28.060918 | 2026-09-02 01:05:28.061002 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-02 01:05:28.520496 | instance | changed 2026-09-02 01:05:28.528830 | 2026-09-02 01:05:28.528913 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-02 01:05:28.556745 | instance | skipping: Conditional result was False 2026-09-02 01:05:28.572073 | 2026-09-02 01:05:28.572222 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-02 01:05:28.945408 | instance -> localhost | changed 2026-09-02 01:05:28.960660 | 2026-09-02 01:05:28.960787 | TASK [add-build-sshkey : Add back temp key] 2026-09-02 01:05:29.279640 | instance -> localhost | Identity added: /var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/work/64cf4fa0d6cc4a59a9e4239013da1598_id_rsa (zuul-build-sshkey) 2026-09-02 01:05:29.279854 | instance -> localhost | ok: Runtime: 0:00:00.014351 2026-09-02 01:05:29.285257 | 2026-09-02 01:05:29.285321 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-02 01:05:29.621530 | instance | ok 2026-09-02 01:05:29.626828 | 2026-09-02 01:05:29.626900 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-02 01:05:29.652813 | instance | skipping: Conditional result was False 2026-09-02 01:05:29.667671 | 2026-09-02 01:05:29.667738 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-02 01:05:30.042786 | instance | ok 2026-09-02 01:05:30.049306 | 2026-09-02 01:05:30.049379 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-02 01:05:33.930857 | instance | Output suppressed because no_log was given 2026-09-02 01:05:34.013443 | 2026-09-02 01:05:34.013572 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-02 01:05:34.220001 | instance | ok: "logs" 2026-09-02 01:05:34.220289 | instance | ok: All items complete 2026-09-02 01:05:34.220319 | 2026-09-02 01:05:34.346067 | instance | ok: "artifacts" 2026-09-02 01:05:34.525524 | instance | ok: "docs" 2026-09-02 01:05:34.536691 | 2026-09-02 01:05:34.536848 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-02 01:05:34.754995 | instance | changed: "logs" 2026-09-02 01:05:34.908035 | instance | changed: "artifacts" 2026-09-02 01:05:35.078511 | instance | changed: "docs" 2026-09-02 01:05:35.092895 | 2026-09-02 01:05:35.093039 | PLAY RECAP 2026-09-02 01:05:35.093101 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-02 01:05:35.093133 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 01:05:35.093156 | 2026-09-02 01:05:35.240663 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 01:05:35.244053 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-02 01:05:35.861648 | 2026-09-02 01:05:35.861771 | PLAY [all] 2026-09-02 01:05:35.872882 | 2026-09-02 01:05:35.872980 | TASK [setup-uv : Extract archive] 2026-09-02 01:05:38.214909 | instance | changed 2026-09-02 01:05:38.222995 | 2026-09-02 01:05:38.223101 | TASK [setup-uv : Print version] 2026-09-02 01:05:38.626411 | instance | uv 0.8.13 2026-09-02 01:05:38.760850 | instance | ok: Runtime: 0:00:00.014355 2026-09-02 01:05:38.771781 | 2026-09-02 01:05:38.771852 | TASK [Install Ansible collection siblings] 2026-09-02 01:05:48.790373 | instance | changed: 2026-09-02 01:05:48.790592 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-02 01:05:48.790632 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-02 01:05:48.790663 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-02 01:05:48.790691 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-02 01:05:48.790720 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-02 01:05:48.790752 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-02 01:05:48.790782 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-02 01:05:48.790812 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-02 01:05:48.790840 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-02 01:05:48.790868 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-02 01:05:48.790895 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-02 01:05:48.790922 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-02 01:05:48.790952 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-02 01:05:48.790979 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-02 01:05:48.791005 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-02 01:05:48.791031 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-02 01:05:48.791057 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-02 01:05:48.791083 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-02 01:05:48.791108 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-02 01:05:48.791134 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-02 01:05:48.791159 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-02 01:05:48.791185 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-02 01:05:48.791213 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-02 01:05:48.791256 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-02 01:05:48.791284 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-02 01:05:48.791310 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-02 01:05:48.791336 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-02 01:05:48.791361 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-02 01:05:48.791386 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-02 01:05:48.791411 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-02 01:05:48.791436 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-02 01:05:48.791463 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-02 01:05:48.791488 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-02 01:05:48.791514 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-02 01:05:48.791548 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.791573 | instance | Process install dependency map 2026-09-02 01:05:48.791598 | instance | Starting collection install process 2026-09-02 01:05:48.791624 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-02 01:05:48.791648 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-02 01:05:48.791673 | instance | ansible.netcommon:* was installed successfully 2026-09-02 01:05:48.791697 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-02 01:05:48.791722 | instance | Creating virtual environment at: .venv 2026-09-02 01:05:48.791747 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-02 01:05:48.791772 | instance | Downloading setuptools (1.1MiB) 2026-09-02 01:05:48.791797 | instance | Downloading pydantic-core (2.0MiB) 2026-09-02 01:05:48.791826 | instance | Downloading rjsonnet (1.2MiB) 2026-09-02 01:05:48.791852 | instance | Downloading ansible-core (2.1MiB) 2026-09-02 01:05:48.791877 | instance | Downloading cryptography (4.2MiB) 2026-09-02 01:05:48.791902 | instance | Downloading netaddr (2.2MiB) 2026-09-02 01:05:48.791932 | instance | Downloading kubernetes (1.9MiB) 2026-09-02 01:05:48.791958 | instance | Downloading pygments (1.2MiB) 2026-09-02 01:05:48.791983 | instance | Downloading openstacksdk (1.7MiB) 2026-09-02 01:05:48.792008 | instance | Building pyperclip==1.9.0 2026-09-02 01:05:48.792035 | instance | Downloading rjsonnet 2026-09-02 01:05:48.792061 | instance | Downloading pydantic-core 2026-09-02 01:05:48.792087 | instance | Downloading netaddr 2026-09-02 01:05:48.792113 | instance | Downloading pygments 2026-09-02 01:05:48.792138 | instance | Downloading cryptography 2026-09-02 01:05:48.792163 | instance | Downloading setuptools 2026-09-02 01:05:48.792188 | instance | Downloading kubernetes 2026-09-02 01:05:48.792213 | instance | Downloading ansible-core 2026-09-02 01:05:48.792238 | instance | Downloading openstacksdk 2026-09-02 01:05:48.792263 | instance | Built pyperclip==1.9.0 2026-09-02 01:05:48.792292 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-02 01:05:48.792318 | instance | Installed 83 packages in 131ms 2026-09-02 01:05:48.792397 | instance | Checked out ansible.posix to 1.6.0 2026-09-02 01:05:48.792433 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-02 01:05:48.792463 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.792488 | instance | Process install dependency map 2026-09-02 01:05:48.792522 | instance | Starting collection install process 2026-09-02 01:05:48.792548 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-02 01:05:48.792573 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-02 01:05:48.792598 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-02 01:05:48.792623 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.792649 | instance | 2.17.12 2026-09-02 01:05:48.792674 | instance | Checked out ansible.utils to v6.1.0 2026-09-02 01:05:48.792699 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-02 01:05:48.792724 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.792749 | instance | Process install dependency map 2026-09-02 01:05:48.792774 | instance | Starting collection install process 2026-09-02 01:05:48.792800 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-02 01:05:48.792825 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-02 01:05:48.792851 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-02 01:05:48.792877 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.792903 | instance | 2.17.12 2026-09-02 01:05:48.792926 | instance | Checked out atmosphere.common to v0.7.0 2026-09-02 01:05:48.792944 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-02 01:05:48.792964 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.793001 | instance | Process install dependency map 2026-09-02 01:05:48.793020 | instance | Starting collection install process 2026-09-02 01:05:48.793039 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-02 01:05:48.793057 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-02 01:05:48.793075 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-02 01:05:48.793094 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.793112 | instance | 2.17.12 2026-09-02 01:05:48.793130 | instance | Checked out community.crypto to 2.2.3 2026-09-02 01:05:48.793149 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-02 01:05:48.793167 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.793185 | instance | Process install dependency map 2026-09-02 01:05:48.793204 | instance | Starting collection install process 2026-09-02 01:05:48.793221 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-02 01:05:48.793239 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-02 01:05:48.793256 | instance | community.crypto:2.2.3 was installed successfully 2026-09-02 01:05:48.793274 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.793291 | instance | 2.17.12 2026-09-02 01:05:48.793309 | instance | Checked out community.general to 7.3.0 2026-09-02 01:05:48.793328 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-02 01:05:48.793352 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.793371 | instance | Process install dependency map 2026-09-02 01:05:48.793388 | instance | Starting collection install process 2026-09-02 01:05:48.793406 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-02 01:05:48.793424 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-02 01:05:48.793442 | instance | community.general:7.3.0 was installed successfully 2026-09-02 01:05:48.793460 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.793479 | instance | 2.17.12 2026-09-02 01:05:48.793497 | instance | Checked out community.mysql to 3.6.0 2026-09-02 01:05:48.793517 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-02 01:05:48.793536 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.793601 | instance | Process install dependency map 2026-09-02 01:05:48.793649 | instance | Starting collection install process 2026-09-02 01:05:48.793672 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-02 01:05:48.793692 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-02 01:05:48.793711 | instance | community.mysql:3.6.0 was installed successfully 2026-09-02 01:05:48.793729 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.793747 | instance | 2.17.12 2026-09-02 01:05:48.793766 | instance | Checked out kubernetes.core to 2.4.0 2026-09-02 01:05:48.793784 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-02 01:05:48.793803 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.793820 | instance | Process install dependency map 2026-09-02 01:05:48.793839 | instance | Starting collection install process 2026-09-02 01:05:48.793857 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-02 01:05:48.793875 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-02 01:05:48.793893 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-02 01:05:48.793911 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.793928 | instance | 2.17.12 2026-09-02 01:05:48.793945 | instance | Checked out openstack.cloud to 2.6.0 2026-09-02 01:05:48.793963 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-02 01:05:48.793981 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.793999 | instance | Process install dependency map 2026-09-02 01:05:48.794017 | instance | Starting collection install process 2026-09-02 01:05:48.794035 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-02 01:05:48.794052 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-02 01:05:48.794070 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-02 01:05:48.794087 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.794105 | instance | 2.17.12 2026-09-02 01:05:48.794129 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-02 01:05:48.794148 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-02 01:05:48.794166 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.794184 | instance | Process install dependency map 2026-09-02 01:05:48.794201 | instance | Starting collection install process 2026-09-02 01:05:48.794218 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-02 01:05:48.794236 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-02 01:05:48.794253 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-02 01:05:48.794270 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.794288 | instance | 2.17.12 2026-09-02 01:05:48.794305 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-02 01:05:48.794323 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-02 01:05:48.794341 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.794359 | instance | Process install dependency map 2026-09-02 01:05:48.794376 | instance | Starting collection install process 2026-09-02 01:05:48.794393 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-02 01:05:48.794410 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-02 01:05:48.794428 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-02 01:05:48.794446 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.794464 | instance | 2.17.12 2026-09-02 01:05:48.794481 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-02 01:05:48.794499 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-02 01:05:48.794517 | instance | Starting galaxy collection install process 2026-09-02 01:05:48.794535 | instance | Process install dependency map 2026-09-02 01:05:48.794682 | instance | Starting collection install process 2026-09-02 01:05:48.794722 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-02 01:05:48.794743 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-02 01:05:48.794763 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-02 01:05:48.794781 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 01:05:48.794799 | instance | 2.17.12 2026-09-02 01:05:48.805629 | 2026-09-02 01:05:48.805679 | PLAY RECAP 2026-09-02 01:05:48.805732 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 01:05:48.805753 | 2026-09-02 01:05:48.941185 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-02 01:05:48.945470 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-02 01:05:49.553022 | 2026-09-02 01:05:49.553822 | PLAY [all] 2026-09-02 01:05:49.565244 | 2026-09-02 01:05:49.565318 | TASK [Install "jq" for log collection] 2026-09-02 01:05:59.131076 | instance | changed 2026-09-02 01:05:59.133126 | 2026-09-02 01:05:59.133184 | PLAY RECAP 2026-09-02 01:05:59.133235 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 01:05:59.133293 | 2026-09-02 01:05:59.272294 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-02 01:05:59.276567 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-02 01:05:59.856019 | 2026-09-02 01:05:59.856145 | PLAY [all] 2026-09-02 01:05:59.867116 | 2026-09-02 01:05:59.867188 | LOOP [Check inventory directories] 2026-09-02 01:06:00.227646 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-02 01:06:00.402632 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-02 01:06:00.772104 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-02 01:06:00.787020 | 2026-09-02 01:06:00.787291 | LOOP [Copy inventory file for Zuul] 2026-09-02 01:06:01.632799 | instance | changed: 2026-09-02 01:06:01.633074 | instance | { 2026-09-02 01:06:01.633104 | instance | "ansible_loop_var": "item", 2026-09-02 01:06:01.633128 | instance | "changed": false, 2026-09-02 01:06:01.633149 | instance | "failed": false, 2026-09-02 01:06:01.633172 | instance | "invocation": { 2026-09-02 01:06:01.633191 | instance | "module_args": { 2026-09-02 01:06:01.633209 | instance | "checksum_algorithm": "sha1", 2026-09-02 01:06:01.633228 | instance | "follow": true, 2026-09-02 01:06:01.633247 | instance | "get_attributes": true, 2026-09-02 01:06:01.633266 | instance | "get_checksum": true, 2026-09-02 01:06:01.633285 | instance | "get_mime": true, 2026-09-02 01:06:01.633303 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-02 01:06:01.633321 | instance | } 2026-09-02 01:06:01.633339 | instance | }, 2026-09-02 01:06:01.633359 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-02 01:06:01.633380 | instance | "stat": { 2026-09-02 01:06:01.633398 | instance | "atime": 1788311141.0197132, 2026-09-02 01:06:01.633416 | instance | "attr_flags": "e", 2026-09-02 01:06:01.633435 | instance | "attributes": [ 2026-09-02 01:06:01.633453 | instance | "extents" 2026-09-02 01:06:01.633472 | instance | ], 2026-09-02 01:06:01.633490 | instance | "block_size": 4096, 2026-09-02 01:06:01.633507 | instance | "blocks": 8, 2026-09-02 01:06:01.633525 | instance | "charset": "unknown", 2026-09-02 01:06:01.633544 | instance | "ctime": 1788311139.535624, 2026-09-02 01:06:01.633562 | instance | "dev": 64513, 2026-09-02 01:06:01.633580 | instance | "device_type": 0, 2026-09-02 01:06:01.633597 | instance | "executable": true, 2026-09-02 01:06:01.633615 | instance | "exists": true, 2026-09-02 01:06:01.633632 | instance | "gid": 1000, 2026-09-02 01:06:01.633650 | instance | "gr_name": "zuul", 2026-09-02 01:06:01.633667 | instance | "inode": 1048294, 2026-09-02 01:06:01.633684 | instance | "isblk": false, 2026-09-02 01:06:01.633703 | instance | "ischr": false, 2026-09-02 01:06:01.633720 | instance | "isdir": true, 2026-09-02 01:06:01.633737 | instance | "isfifo": false, 2026-09-02 01:06:01.633755 | instance | "isgid": false, 2026-09-02 01:06:01.633771 | instance | "islnk": false, 2026-09-02 01:06:01.633788 | instance | "isreg": false, 2026-09-02 01:06:01.633805 | instance | "issock": false, 2026-09-02 01:06:01.633822 | instance | "isuid": false, 2026-09-02 01:06:01.633850 | instance | "mimetype": "unknown", 2026-09-02 01:06:01.633869 | instance | "mode": "0755", 2026-09-02 01:06:01.633887 | instance | "mtime": 1788311139.535624, 2026-09-02 01:06:01.633904 | instance | "nlink": 23, 2026-09-02 01:06:01.633922 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-02 01:06:01.633940 | instance | "pw_name": "zuul", 2026-09-02 01:06:01.633960 | instance | "readable": true, 2026-09-02 01:06:01.633977 | instance | "rgrp": true, 2026-09-02 01:06:01.633995 | instance | "roth": true, 2026-09-02 01:06:01.634025 | instance | "rusr": true, 2026-09-02 01:06:01.634043 | instance | "size": 4096, 2026-09-02 01:06:01.634060 | instance | "uid": 1000, 2026-09-02 01:06:01.634077 | instance | "version": "723444940", 2026-09-02 01:06:01.634095 | instance | "wgrp": false, 2026-09-02 01:06:01.634114 | instance | "woth": false, 2026-09-02 01:06:01.634131 | instance | "writeable": true, 2026-09-02 01:06:01.634149 | instance | "wusr": true, 2026-09-02 01:06:01.634166 | instance | "xgrp": true, 2026-09-02 01:06:01.634183 | instance | "xoth": true, 2026-09-02 01:06:01.634200 | instance | "xusr": true 2026-09-02 01:06:01.634217 | instance | } 2026-09-02 01:06:01.634234 | instance | } 2026-09-02 01:06:01.634273 | instance | ok: All items complete 2026-09-02 01:06:01.634305 | 2026-09-02 01:06:01.635640 | instance | skipping: Conditional result was False 2026-09-02 01:06:01.636254 | instance | skipping: Conditional result was False 2026-09-02 01:06:01.650391 | 2026-09-02 01:06:01.650574 | LOOP [Switch "ansible_host" to private IP] 2026-09-02 01:06:01.981786 | instance | changed: 1 replacements made 2026-09-02 01:06:01.984784 | instance | skipping: Conditional result was False 2026-09-02 01:06:01.988608 | instance | skipping: Conditional result was False 2026-09-02 01:06:02.004106 | 2026-09-02 01:06:02.004249 | TASK [Run Molecule scenario] 2026-09-02 01:06:02.928844 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-02 01:06:03.275185 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-02 01:06:03.275284 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-02 01:06:13.622755 | instance | INFO [csi > dependency] Executing 2026-09-02 01:06:13.623138 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-02 01:06:13.623546 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-02 01:06:13.623776 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-02 01:06:13.641058 | instance | INFO [csi > cleanup] Executing 2026-09-02 01:06:13.641786 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-02 01:06:13.654317 | instance | INFO [csi > destroy] Executing 2026-09-02 01:06:13.654462 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-02 01:06:13.654581 | instance | INFO [csi > destroy] Executed: Successful 2026-09-02 01:06:13.664565 | instance | INFO [csi > syntax] Executing 2026-09-02 01:06:15.146906 | instance | 2026-09-02 01:06:15.147192 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-02 01:06:15.225923 | instance | INFO [csi > syntax] Executed: Successful 2026-09-02 01:06:15.242491 | instance | INFO [csi > create] Executing 2026-09-02 01:06:15.246258 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-02 01:06:15.262249 | instance | INFO [csi > prepare] Executing 2026-09-02 01:06:16.090029 | instance | 2026-09-02 01:06:16.090253 | instance | PLAY [Prepare] ***************************************************************** 2026-09-02 01:06:16.090495 | instance | 2026-09-02 01:06:16.090792 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 01:06:16.091037 | instance | Wednesday 02 September 2026 01:06:16 +0000 (0:00:00.030) 0:00:00.030 *** 2026-09-02 01:06:17.350418 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-02 01:06:17.350672 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-02 01:06:17.350956 | instance | interpreter could change the meaning of that path. See 2026-09-02 01:06:17.351245 | instance | https://docs.ansible.com/ansible- 2026-09-02 01:06:17.351561 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-02 01:06:17.360766 | instance | ok: [instance] 2026-09-02 01:06:17.360977 | instance | 2026-09-02 01:06:17.361215 | instance | TASK [Configure short hostname] ************************************************ 2026-09-02 01:06:17.361497 | instance | Wednesday 02 September 2026 01:06:17 +0000 (0:00:01.271) 0:00:01.302 *** 2026-09-02 01:06:18.098582 | instance | changed: [instance] 2026-09-02 01:06:18.098909 | instance | 2026-09-02 01:06:18.099341 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-02 01:06:18.099724 | instance | Wednesday 02 September 2026 01:06:18 +0000 (0:00:00.736) 0:00:02.038 *** 2026-09-02 01:06:18.383011 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-02 01:06:18.383099 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-02 01:06:18.383229 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-02 01:06:18.394062 | instance | changed: [instance] 2026-09-02 01:06:18.394347 | instance | 2026-09-02 01:06:18.394601 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-02 01:06:18.394839 | instance | Wednesday 02 September 2026 01:06:18 +0000 (0:00:00.296) 0:00:02.335 *** 2026-09-02 01:06:19.144879 | instance | ok: [instance] 2026-09-02 01:06:19.145023 | instance | 2026-09-02 01:06:19.145213 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-02 01:06:19.145391 | instance | 2026-09-02 01:06:19.145564 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 01:06:19.145756 | instance | Wednesday 02 September 2026 01:06:19 +0000 (0:00:00.750) 0:00:03.085 *** 2026-09-02 01:06:19.937798 | instance | ok: [instance] 2026-09-02 01:06:19.937873 | instance | 2026-09-02 01:06:19.937951 | instance | TASK [Install depedencies] ***************************************************** 2026-09-02 01:06:19.938062 | instance | Wednesday 02 September 2026 01:06:19 +0000 (0:00:00.793) 0:00:03.879 *** 2026-09-02 01:06:44.031871 | instance | changed: [instance] 2026-09-02 01:06:44.032854 | instance | 2026-09-02 01:06:44.033091 | instance | TASK [Start up service] ******************************************************** 2026-09-02 01:06:44.033117 | instance | Wednesday 02 September 2026 01:06:44 +0000 (0:00:24.093) 0:00:27.972 *** 2026-09-02 01:06:44.592859 | instance | ok: [instance] 2026-09-02 01:06:44.593616 | instance | 2026-09-02 01:06:44.593664 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-02 01:06:44.593676 | instance | Wednesday 02 September 2026 01:06:44 +0000 (0:00:00.561) 0:00:28.533 *** 2026-09-02 01:06:44.929555 | instance | ok: [instance] 2026-09-02 01:06:44.929989 | instance | 2026-09-02 01:06:44.930004 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-02 01:06:44.930009 | instance | Wednesday 02 September 2026 01:06:44 +0000 (0:00:00.337) 0:00:28.871 *** 2026-09-02 01:06:45.598595 | instance | changed: [instance] 2026-09-02 01:06:45.598653 | instance | 2026-09-02 01:06:45.598666 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-02 01:06:45.598677 | instance | Wednesday 02 September 2026 01:06:45 +0000 (0:00:00.667) 0:00:29.538 *** 2026-09-02 01:06:45.827594 | instance | ok: [instance] 2026-09-02 01:06:45.827639 | instance | 2026-09-02 01:06:45.827651 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-02 01:06:45.827661 | instance | Wednesday 02 September 2026 01:06:45 +0000 (0:00:00.229) 0:00:29.768 *** 2026-09-02 01:06:45.854702 | instance | skipping: [instance] 2026-09-02 01:06:45.854746 | instance | 2026-09-02 01:06:45.854757 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-02 01:06:45.854767 | instance | Wednesday 02 September 2026 01:06:45 +0000 (0:00:00.027) 0:00:29.795 *** 2026-09-02 01:06:46.455347 | instance | changed: [instance] => (item=osd0) 2026-09-02 01:06:46.455457 | instance | changed: [instance] => (item=osd1) 2026-09-02 01:06:46.455870 | instance | changed: [instance] => (item=osd2) 2026-09-02 01:06:46.455935 | instance | 2026-09-02 01:06:46.455942 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-02 01:06:46.455956 | instance | Wednesday 02 September 2026 01:06:46 +0000 (0:00:00.601) 0:00:30.396 *** 2026-09-02 01:06:47.100888 | instance | changed: [instance] => (item=osd0) 2026-09-02 01:06:47.101091 | instance | changed: [instance] => (item=osd1) 2026-09-02 01:06:47.101631 | instance | changed: [instance] => (item=osd2) 2026-09-02 01:06:47.101649 | instance | 2026-09-02 01:06:47.101656 | instance | TASK [Start loop devices] ****************************************************** 2026-09-02 01:06:47.101662 | instance | Wednesday 02 September 2026 01:06:47 +0000 (0:00:00.645) 0:00:31.042 *** 2026-09-02 01:06:47.820002 | instance | changed: [instance] => (item=osd0) 2026-09-02 01:06:47.820130 | instance | changed: [instance] => (item=osd1) 2026-09-02 01:06:47.820288 | instance | changed: [instance] => (item=osd2) 2026-09-02 01:06:47.820435 | instance | 2026-09-02 01:06:47.820684 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-02 01:06:47.820804 | instance | Wednesday 02 September 2026 01:06:47 +0000 (0:00:00.719) 0:00:31.761 *** 2026-09-02 01:06:51.191319 | instance | changed: [instance] => (item=osd0) 2026-09-02 01:06:51.191857 | instance | changed: [instance] => (item=osd1) 2026-09-02 01:06:51.191949 | instance | changed: [instance] => (item=osd2) 2026-09-02 01:06:51.192502 | instance | 2026-09-02 01:06:51.192559 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-02 01:06:51.192571 | instance | Wednesday 02 September 2026 01:06:51 +0000 (0:00:03.370) 0:00:35.132 *** 2026-09-02 01:06:53.063867 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-02 01:06:53.064062 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-02 01:06:53.064091 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-02 01:06:53.064098 | instance | 2026-09-02 01:06:53.064104 | instance | PLAY RECAP ********************************************************************* 2026-09-02 01:06:53.064110 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-02 01:06:53.064116 | instance | 2026-09-02 01:06:53.064121 | instance | Wednesday 02 September 2026 01:06:53 +0000 (0:00:01.870) 0:00:37.003 *** 2026-09-02 01:06:53.064131 | instance | =============================================================================== 2026-09-02 01:06:53.064312 | instance | Install depedencies ---------------------------------------------------- 24.09s 2026-09-02 01:06:53.064648 | instance | Create a volume group for each loop device ------------------------------ 3.37s 2026-09-02 01:06:53.064960 | instance | Create a logical volume for each loop device ---------------------------- 1.87s 2026-09-02 01:06:53.065242 | instance | Gathering Facts --------------------------------------------------------- 1.27s 2026-09-02 01:06:53.065524 | instance | Gathering Facts --------------------------------------------------------- 0.79s 2026-09-02 01:06:53.065802 | instance | Purge "snapd" package --------------------------------------------------- 0.75s 2026-09-02 01:06:53.066101 | instance | Configure short hostname ------------------------------------------------ 0.74s 2026-09-02 01:06:53.066379 | instance | Start loop devices ------------------------------------------------------ 0.72s 2026-09-02 01:06:53.066657 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.67s 2026-09-02 01:06:53.066956 | instance | Set permissions on loopback devices ------------------------------------- 0.65s 2026-09-02 01:06:53.067238 | instance | Create devices for Ceph ------------------------------------------------- 0.60s 2026-09-02 01:06:53.067513 | instance | Start up service -------------------------------------------------------- 0.56s 2026-09-02 01:06:53.067840 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s 2026-09-02 01:06:53.068165 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s 2026-09-02 01:06:53.068446 | instance | Get list of all loopback devices ---------------------------------------- 0.23s 2026-09-02 01:06:53.068726 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-02 01:06:53.137866 | instance | INFO [csi > prepare] Executed: Successful 2026-09-02 01:06:53.153397 | instance | INFO [csi > converge] Executing 2026-09-02 01:06:54.527385 | instance | 2026-09-02 01:06:54.527659 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-02 01:06:54.527902 | instance | 2026-09-02 01:06:54.528111 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 01:06:54.528323 | instance | Wednesday 02 September 2026 01:06:54 +0000 (0:00:00.006) 0:00:00.006 *** 2026-09-02 01:06:55.599011 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-02 01:06:55.599258 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-02 01:06:55.599455 | instance | interpreter could change the meaning of that path. See 2026-09-02 01:06:55.599638 | instance | https://docs.ansible.com/ansible- 2026-09-02 01:06:55.599876 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-02 01:06:55.615317 | instance | ok: [instance] 2026-09-02 01:06:55.615639 | instance | 2026-09-02 01:06:55.616008 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-02 01:06:55.616302 | instance | Wednesday 02 September 2026 01:06:55 +0000 (0:00:01.084) 0:00:01.091 *** 2026-09-02 01:06:55.669601 | instance | ok: [instance] => { 2026-09-02 01:06:55.669880 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-02 01:06:55.670053 | instance | } 2026-09-02 01:06:55.670208 | instance | 2026-09-02 01:06:55.670379 | instance | PLAY [all] ********************************************************************* 2026-09-02 01:06:55.670523 | instance | 2026-09-02 01:06:55.670706 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 01:06:55.670927 | instance | Wednesday 02 September 2026 01:06:55 +0000 (0:00:00.056) 0:00:01.148 *** 2026-09-02 01:06:56.585597 | instance | ok: [instance] 2026-09-02 01:06:56.585952 | instance | 2026-09-02 01:06:56.586262 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-02 01:06:56.586677 | instance | Wednesday 02 September 2026 01:06:56 +0000 (0:00:00.916) 0:00:02.064 *** 2026-09-02 01:06:56.628489 | instance | skipping: [instance] 2026-09-02 01:06:56.628806 | instance | 2026-09-02 01:06:56.629130 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-02 01:06:56.629424 | instance | Wednesday 02 September 2026 01:06:56 +0000 (0:00:00.043) 0:00:02.107 *** 2026-09-02 01:06:56.863189 | instance | ok: [instance] 2026-09-02 01:06:56.863520 | instance | 2026-09-02 01:06:56.863969 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-02 01:06:56.864357 | instance | 2026-09-02 01:06:56.864760 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 01:06:56.865205 | instance | Wednesday 02 September 2026 01:06:56 +0000 (0:00:00.234) 0:00:02.342 *** 2026-09-02 01:06:57.766890 | instance | ok: [instance] 2026-09-02 01:06:57.767235 | instance | 2026-09-02 01:06:57.767547 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 01:06:57.767921 | instance | Wednesday 02 September 2026 01:06:57 +0000 (0:00:00.903) 0:00:03.246 *** 2026-09-02 01:06:58.088297 | instance | ok: [instance] 2026-09-02 01:06:58.088562 | instance | 2026-09-02 01:06:58.088737 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 01:06:58.088896 | instance | Wednesday 02 September 2026 01:06:58 +0000 (0:00:00.320) 0:00:03.566 *** 2026-09-02 01:06:58.128385 | instance | skipping: [instance] 2026-09-02 01:06:58.128459 | instance | 2026-09-02 01:06:58.128662 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-02 01:06:58.128927 | instance | Wednesday 02 September 2026 01:06:58 +0000 (0:00:00.040) 0:00:03.606 *** 2026-09-02 01:06:58.488631 | instance | changed: [instance] 2026-09-02 01:06:58.488734 | instance | 2026-09-02 01:06:58.488958 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 01:06:58.489221 | instance | Wednesday 02 September 2026 01:06:58 +0000 (0:00:00.360) 0:00:03.967 *** 2026-09-02 01:06:58.588548 | instance | ok: [instance] => { 2026-09-02 01:06:58.588778 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-02 01:06:58.588937 | instance | } 2026-09-02 01:06:58.589071 | instance | 2026-09-02 01:06:58.589211 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 01:06:58.589351 | instance | Wednesday 02 September 2026 01:06:58 +0000 (0:00:00.098) 0:00:04.066 *** 2026-09-02 01:06:59.248317 | instance | changed: [instance] 2026-09-02 01:06:59.248558 | instance | 2026-09-02 01:06:59.248771 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 01:06:59.248995 | instance | Wednesday 02 September 2026 01:06:59 +0000 (0:00:00.659) 0:00:04.725 *** 2026-09-02 01:06:59.296768 | instance | skipping: [instance] 2026-09-02 01:06:59.297262 | instance | 2026-09-02 01:06:59.297683 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 01:06:59.298128 | instance | Wednesday 02 September 2026 01:06:59 +0000 (0:00:00.048) 0:00:04.774 *** 2026-09-02 01:06:59.336259 | instance | skipping: [instance] 2026-09-02 01:06:59.336431 | instance | 2026-09-02 01:06:59.336568 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 01:06:59.336716 | instance | Wednesday 02 September 2026 01:06:59 +0000 (0:00:00.039) 0:00:04.813 *** 2026-09-02 01:06:59.582428 | instance | ok: [instance] 2026-09-02 01:06:59.582755 | instance | 2026-09-02 01:06:59.583080 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 01:06:59.583367 | instance | Wednesday 02 September 2026 01:06:59 +0000 (0:00:00.247) 0:00:05.061 *** 2026-09-02 01:07:00.846551 | instance | ok: [instance] 2026-09-02 01:07:00.846603 | instance | 2026-09-02 01:07:00.846616 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 01:07:00.846641 | instance | Wednesday 02 September 2026 01:07:00 +0000 (0:00:01.263) 0:00:06.324 *** 2026-09-02 01:07:00.944084 | instance | ok: [instance] => { 2026-09-02 01:07:00.944139 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-02 01:07:00.944155 | instance | } 2026-09-02 01:07:00.944169 | instance | 2026-09-02 01:07:00.944179 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 01:07:00.944188 | instance | Wednesday 02 September 2026 01:07:00 +0000 (0:00:00.096) 0:00:06.421 *** 2026-09-02 01:07:01.715862 | instance | changed: [instance] 2026-09-02 01:07:01.715922 | instance | 2026-09-02 01:07:01.716422 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 01:07:01.716453 | instance | Wednesday 02 September 2026 01:07:01 +0000 (0:00:00.772) 0:00:07.194 *** 2026-09-02 01:07:04.496701 | instance | changed: [instance] 2026-09-02 01:07:04.496940 | instance | 2026-09-02 01:07:04.497132 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-02 01:07:04.497319 | instance | Wednesday 02 September 2026 01:07:04 +0000 (0:00:02.781) 0:00:09.975 *** 2026-09-02 01:07:04.525951 | instance | skipping: [instance] 2026-09-02 01:07:04.526168 | instance | 2026-09-02 01:07:04.526544 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-02 01:07:04.526761 | instance | Wednesday 02 September 2026 01:07:04 +0000 (0:00:00.029) 0:00:10.005 *** 2026-09-02 01:07:04.566238 | instance | skipping: [instance] 2026-09-02 01:07:04.566452 | instance | 2026-09-02 01:07:04.566647 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-02 01:07:04.566863 | instance | Wednesday 02 September 2026 01:07:04 +0000 (0:00:00.039) 0:00:10.045 *** 2026-09-02 01:07:04.596310 | instance | skipping: [instance] 2026-09-02 01:07:04.596624 | instance | 2026-09-02 01:07:04.596993 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-02 01:07:04.597290 | instance | Wednesday 02 September 2026 01:07:04 +0000 (0:00:00.030) 0:00:10.075 *** 2026-09-02 01:07:10.667471 | instance | changed: [instance] 2026-09-02 01:07:10.667547 | instance | 2026-09-02 01:07:10.667564 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-02 01:07:10.667579 | instance | Wednesday 02 September 2026 01:07:10 +0000 (0:00:06.069) 0:00:16.145 *** 2026-09-02 01:07:11.262197 | instance | changed: [instance] 2026-09-02 01:07:11.262898 | instance | 2026-09-02 01:07:11.262944 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-02 01:07:11.262956 | instance | Wednesday 02 September 2026 01:07:11 +0000 (0:00:00.596) 0:00:16.741 *** 2026-09-02 01:07:12.192287 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-02 01:07:12.194117 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-02 01:07:12.194168 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-02 01:07:12.194188 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-02 01:07:12.194198 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-02 01:07:12.194207 | instance | 2026-09-02 01:07:12.194217 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-02 01:07:12.194226 | instance | Wednesday 02 September 2026 01:07:12 +0000 (0:00:00.929) 0:00:17.671 *** 2026-09-02 01:07:12.806474 | instance | changed: [instance] 2026-09-02 01:07:12.806527 | instance | 2026-09-02 01:07:12.807093 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-02 01:07:12.807132 | instance | Wednesday 02 September 2026 01:07:12 +0000 (0:00:00.601) 0:00:18.272 *** 2026-09-02 01:07:12.807137 | instance | 2026-09-02 01:07:12.807142 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-02 01:07:12.807146 | instance | Wednesday 02 September 2026 01:07:12 +0000 (0:00:00.013) 0:00:18.286 *** 2026-09-02 01:07:13.903098 | instance | ok: [instance] 2026-09-02 01:07:13.903851 | instance | 2026-09-02 01:07:13.904054 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-02 01:07:13.904067 | instance | Wednesday 02 September 2026 01:07:13 +0000 (0:00:01.095) 0:00:19.381 *** 2026-09-02 01:07:14.378466 | instance | changed: [instance] 2026-09-02 01:07:14.378862 | instance | 2026-09-02 01:07:14.379265 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-02 01:07:14.379583 | instance | Wednesday 02 September 2026 01:07:14 +0000 (0:00:00.474) 0:00:19.856 *** 2026-09-02 01:07:14.957785 | instance | changed: [instance] 2026-09-02 01:07:14.957990 | instance | 2026-09-02 01:07:14.958226 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 01:07:14.958456 | instance | Wednesday 02 September 2026 01:07:14 +0000 (0:00:00.580) 0:00:20.437 *** 2026-09-02 01:07:15.167836 | instance | ok: [instance] 2026-09-02 01:07:15.168061 | instance | 2026-09-02 01:07:15.168320 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 01:07:15.168576 | instance | Wednesday 02 September 2026 01:07:15 +0000 (0:00:00.210) 0:00:20.647 *** 2026-09-02 01:07:15.218191 | instance | ok: [instance] => { 2026-09-02 01:07:15.218325 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-02 01:07:15.218466 | instance | } 2026-09-02 01:07:15.218643 | instance | 2026-09-02 01:07:15.218787 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 01:07:15.218930 | instance | Wednesday 02 September 2026 01:07:15 +0000 (0:00:00.050) 0:00:20.697 *** 2026-09-02 01:07:16.096370 | instance | changed: [instance] 2026-09-02 01:07:16.096603 | instance | 2026-09-02 01:07:16.096842 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 01:07:16.097073 | instance | Wednesday 02 September 2026 01:07:16 +0000 (0:00:00.877) 0:00:21.575 *** 2026-09-02 01:07:20.406818 | instance | changed: [instance] 2026-09-02 01:07:20.406977 | instance | 2026-09-02 01:07:20.406993 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-02 01:07:20.407130 | instance | Wednesday 02 September 2026 01:07:20 +0000 (0:00:04.310) 0:00:25.885 *** 2026-09-02 01:07:22.068300 | instance | ok: [instance] 2026-09-02 01:07:22.068403 | instance | 2026-09-02 01:07:22.068412 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-02 01:07:22.068419 | instance | Wednesday 02 September 2026 01:07:22 +0000 (0:00:01.659) 0:00:27.545 *** 2026-09-02 01:07:22.484781 | instance | changed: [instance] 2026-09-02 01:07:22.484893 | instance | 2026-09-02 01:07:22.485260 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-02 01:07:22.485320 | instance | Wednesday 02 September 2026 01:07:22 +0000 (0:00:00.418) 0:00:27.963 *** 2026-09-02 01:07:22.997103 | instance | changed: [instance] 2026-09-02 01:07:22.997205 | instance | 2026-09-02 01:07:22.997217 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-02 01:07:22.997359 | instance | Wednesday 02 September 2026 01:07:22 +0000 (0:00:00.512) 0:00:28.476 *** 2026-09-02 01:07:23.613277 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-02 01:07:23.614564 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-02 01:07:23.614645 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-02 01:07:23.614656 | instance | 2026-09-02 01:07:23.614667 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-02 01:07:23.614676 | instance | Wednesday 02 September 2026 01:07:23 +0000 (0:00:00.614) 0:00:29.091 *** 2026-09-02 01:07:24.098082 | instance | changed: [instance] 2026-09-02 01:07:24.098145 | instance | 2026-09-02 01:07:24.098324 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-02 01:07:24.098381 | instance | Wednesday 02 September 2026 01:07:24 +0000 (0:00:00.486) 0:00:29.577 *** 2026-09-02 01:07:24.624242 | instance | changed: [instance] 2026-09-02 01:07:24.624375 | instance | 2026-09-02 01:07:24.625134 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-02 01:07:24.625194 | instance | Wednesday 02 September 2026 01:07:24 +0000 (0:00:00.503) 0:00:30.081 *** 2026-09-02 01:07:24.625202 | instance | 2026-09-02 01:07:24.625209 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-02 01:07:24.625220 | instance | Wednesday 02 September 2026 01:07:24 +0000 (0:00:00.022) 0:00:30.103 *** 2026-09-02 01:07:25.403880 | instance | ok: [instance] 2026-09-02 01:07:25.404946 | instance | 2026-09-02 01:07:25.405002 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-02 01:07:25.405014 | instance | Wednesday 02 September 2026 01:07:25 +0000 (0:00:00.779) 0:00:30.882 *** 2026-09-02 01:07:26.324636 | instance | changed: [instance] 2026-09-02 01:07:26.324719 | instance | 2026-09-02 01:07:26.325121 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-02 01:07:26.325190 | instance | Wednesday 02 September 2026 01:07:26 +0000 (0:00:00.921) 0:00:31.803 *** 2026-09-02 01:07:26.968195 | instance | changed: [instance] 2026-09-02 01:07:26.968967 | instance | 2026-09-02 01:07:26.968991 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-02 01:07:26.969000 | instance | Wednesday 02 September 2026 01:07:26 +0000 (0:00:00.643) 0:00:32.447 *** 2026-09-02 01:07:27.030003 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-02 01:07:27.030741 | instance | 2026-09-02 01:07:27.030793 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-02 01:07:27.030804 | instance | Wednesday 02 September 2026 01:07:27 +0000 (0:00:00.061) 0:00:32.508 *** 2026-09-02 01:07:32.956215 | instance | changed: [instance] 2026-09-02 01:07:32.956428 | instance | 2026-09-02 01:07:32.956680 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-02 01:07:32.956919 | instance | Wednesday 02 September 2026 01:07:32 +0000 (0:00:05.925) 0:00:38.434 *** 2026-09-02 01:07:33.609748 | instance | ok: [instance] => (item=chronyd) 2026-09-02 01:07:33.609973 | instance | ok: [instance] => (item=sshd) 2026-09-02 01:07:33.610218 | instance | 2026-09-02 01:07:33.610458 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-02 01:07:33.610688 | instance | Wednesday 02 September 2026 01:07:33 +0000 (0:00:00.654) 0:00:39.089 *** 2026-09-02 01:07:34.586795 | instance | changed: [instance] 2026-09-02 01:07:34.586991 | instance | 2026-09-02 01:07:34.587239 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-02 01:07:34.587471 | instance | Wednesday 02 September 2026 01:07:34 +0000 (0:00:00.976) 0:00:40.065 *** 2026-09-02 01:07:34.845312 | instance | ok: [instance] 2026-09-02 01:07:34.845485 | instance | 2026-09-02 01:07:34.845718 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-02 01:07:34.845937 | instance | Wednesday 02 September 2026 01:07:34 +0000 (0:00:00.258) 0:00:40.323 *** 2026-09-02 01:07:35.366023 | instance | changed: [instance] 2026-09-02 01:07:35.366229 | instance | 2026-09-02 01:07:35.366478 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-02 01:07:35.366705 | instance | Wednesday 02 September 2026 01:07:35 +0000 (0:00:00.520) 0:00:40.844 *** 2026-09-02 01:07:35.737505 | instance | changed: [instance] 2026-09-02 01:07:35.737558 | instance | 2026-09-02 01:07:35.737568 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-02 01:07:35.737576 | instance | Wednesday 02 September 2026 01:07:35 +0000 (0:00:00.370) 0:00:41.215 *** 2026-09-02 01:07:35.957790 | instance | ok: [instance] 2026-09-02 01:07:35.957845 | instance | 2026-09-02 01:07:35.958156 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-02 01:07:35.958174 | instance | Wednesday 02 September 2026 01:07:35 +0000 (0:00:00.221) 0:00:41.437 *** 2026-09-02 01:07:36.203162 | instance | ok: [instance] => (item=instance) 2026-09-02 01:07:36.203849 | instance | 2026-09-02 01:07:36.204030 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-02 01:07:36.204194 | instance | Wednesday 02 September 2026 01:07:36 +0000 (0:00:00.244) 0:00:41.681 *** 2026-09-02 01:07:36.275097 | instance | ok: [instance] 2026-09-02 01:07:36.275370 | instance | 2026-09-02 01:07:36.275738 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-02 01:07:36.275796 | instance | Wednesday 02 September 2026 01:07:36 +0000 (0:00:00.072) 0:00:41.753 *** 2026-09-02 01:07:36.374545 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-02 01:07:36.374963 | instance | 2026-09-02 01:07:36.374985 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-02 01:07:36.374993 | instance | Wednesday 02 September 2026 01:07:36 +0000 (0:00:00.099) 0:00:41.853 *** 2026-09-02 01:07:36.642015 | instance | changed: [instance] 2026-09-02 01:07:36.642461 | instance | 2026-09-02 01:07:36.642480 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-02 01:07:36.642488 | instance | Wednesday 02 September 2026 01:07:36 +0000 (0:00:00.267) 0:00:42.121 *** 2026-09-02 01:07:37.317297 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-02 01:07:37.317802 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-02 01:07:37.317819 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-02 01:07:37.317824 | instance | 2026-09-02 01:07:37.317829 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-02 01:07:37.317833 | instance | Wednesday 02 September 2026 01:07:37 +0000 (0:00:00.675) 0:00:42.796 *** 2026-09-02 01:09:15.483540 | instance | ok: [instance] 2026-09-02 01:09:15.484141 | instance | 2026-09-02 01:09:15.484151 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-02 01:09:15.484158 | instance | Wednesday 02 September 2026 01:09:15 +0000 (0:01:38.164) 0:02:20.961 *** 2026-09-02 01:09:15.708551 | instance | changed: [instance] 2026-09-02 01:09:15.708762 | instance | 2026-09-02 01:09:15.709081 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-02 01:09:15.709388 | instance | Wednesday 02 September 2026 01:09:15 +0000 (0:00:00.225) 0:02:21.187 *** 2026-09-02 01:09:15.751074 | instance | ok: [instance] 2026-09-02 01:09:15.751251 | instance | 2026-09-02 01:09:15.751479 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-02 01:09:15.751712 | instance | Wednesday 02 September 2026 01:09:15 +0000 (0:00:00.042) 0:02:21.230 *** 2026-09-02 01:09:15.837454 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-02 01:09:15.837529 | instance | 2026-09-02 01:09:15.837584 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-02 01:09:15.837714 | instance | Wednesday 02 September 2026 01:09:15 +0000 (0:00:00.086) 0:02:21.317 *** 2026-09-02 01:09:17.533100 | instance | ok: [instance] 2026-09-02 01:09:17.533263 | instance | 2026-09-02 01:09:17.533499 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-02 01:09:17.533702 | instance | Wednesday 02 September 2026 01:09:17 +0000 (0:00:01.695) 0:02:23.012 *** 2026-09-02 01:09:17.615556 | instance | ok: [instance] => (item=instance) 2026-09-02 01:09:17.615718 | instance | 2026-09-02 01:09:17.616102 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-02 01:09:17.616347 | instance | Wednesday 02 September 2026 01:09:17 +0000 (0:00:00.082) 0:02:23.094 *** 2026-09-02 01:09:17.992360 | instance | ok: [instance] 2026-09-02 01:09:17.992584 | instance | 2026-09-02 01:09:17.993087 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-02 01:09:17.993108 | instance | Wednesday 02 September 2026 01:09:17 +0000 (0:00:00.376) 0:02:23.471 *** 2026-09-02 01:09:20.379917 | instance | ok: [instance] 2026-09-02 01:09:20.380003 | instance | 2026-09-02 01:09:20.380013 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-02 01:09:20.380024 | instance | Wednesday 02 September 2026 01:09:20 +0000 (0:00:02.386) 0:02:25.857 *** 2026-09-02 01:10:22.852745 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-02 01:10:22.852844 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n File \"\", line 107, in \n File \"\", line 99, in _ansiballz_main\n File \"\", 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_r9vl7lx5/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in \n File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_r9vl7lx5/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_r9vl7lx5/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_r9vl7lx5/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-02T01:10:22.685+0000 7fdea2433640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgANd5dqDY8zEiAAZBWxXFp4VnTlzn6vXVakjVVoqk/9+btgFlt1J/tK//A=: Malformed input [buffer:3]\n2026-09-02T01:10:22.685+0000 7fdea2433640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:10:22.689+0000 7fdea2433640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgANd5dqDY8zEiAAZBWxXFp4VnTlzn6vXVakjVVoqk/9+btgFlt1J/tK//A=: Malformed input [buffer:3]\n2026-09-02T01:10:22.689+0000 7fdea2433640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:10:22.689+0000 7fdea2433640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgANd5dqDY8zEiAAZBWxXFp4VnTlzn6vXVakjVVoqk/9+btgFlt1J/tK//A=: Malformed input [buffer:3]\n2026-09-02T01:10:22.689+0000 7fdea2433640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:10:22.689+0000 7fdea2433640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-02 01:10:22.854809 | instance | 2026-09-02 01:10:22.855845 | instance | PLAY RECAP ********************************************************************* 2026-09-02 01:10:22.855899 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-02 01:10:22.855908 | instance | 2026-09-02 01:10:22.859869 | instance | Wednesday 02 September 2026 01:10:22 +0000 (0:01:02.476) 0:03:28.334 *** 2026-09-02 01:10:22.859942 | instance | =============================================================================== 2026-09-02 01:10:22.859980 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 98.16s 2026-09-02 01:10:22.859994 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 62.48s 2026-09-02 01:10:22.860007 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.07s 2026-09-02 01:10:22.860019 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.93s 2026-09-02 01:10:22.860031 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.31s 2026-09-02 01:10:22.860042 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.78s 2026-09-02 01:10:22.860072 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.39s 2026-09-02 01:10:22.860084 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.87s 2026-09-02 01:10:22.860097 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.70s 2026-09-02 01:10:22.860109 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.66s 2026-09-02 01:10:22.860121 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.26s 2026-09-02 01:10:22.860132 | instance | Gathering Facts --------------------------------------------------------- 1.08s 2026-09-02 01:10:22.860144 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 0.98s 2026-09-02 01:10:22.860157 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.93s 2026-09-02 01:10:22.860169 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.92s 2026-09-02 01:10:22.860190 | instance | Gathering Facts --------------------------------------------------------- 0.92s 2026-09-02 01:10:22.860365 | instance | Gathering Facts --------------------------------------------------------- 0.90s 2026-09-02 01:10:22.860679 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.88s 2026-09-02 01:10:22.860978 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s 2026-09-02 01:10:22.861263 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.68s 2026-09-02 01:10:22.960351 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-02 01:10:22.960618 | instance | ERROR [csi > converge] Executed: Failed 2026-09-02 01:10:22.960795 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-02 01:10:23.378206 | instance | ERROR 2026-09-02 01:10:23.378413 | instance | { 2026-09-02 01:10:23.378454 | instance | "delta": "0:04:20.684256", 2026-09-02 01:10:23.378483 | instance | "end": "2026-09-02 01:10:23.036934", 2026-09-02 01:10:23.378510 | instance | "msg": "non-zero return code", 2026-09-02 01:10:23.378535 | instance | "rc": 2, 2026-09-02 01:10:23.378561 | instance | "start": "2026-09-02 01:06:02.352678" 2026-09-02 01:10:23.378588 | instance | } failure 2026-09-02 01:10:23.385217 | 2026-09-02 01:10:23.385271 | PLAY RECAP 2026-09-02 01:10:23.385315 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-02 01:10:23.385337 | 2026-09-02 01:10:23.525771 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-02 01:10:23.530166 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-02 01:10:24.171999 | 2026-09-02 01:10:24.172136 | PLAY [all] 2026-09-02 01:10:24.187256 | 2026-09-02 01:10:24.187335 | TASK [gather-host-logs : creating directory for system status] 2026-09-02 01:10:24.878900 | instance | changed 2026-09-02 01:10:24.884381 | 2026-09-02 01:10:24.884467 | TASK [gather-host-logs : Get logs for each host] 2026-09-02 01:10:25.238498 | instance | + systemd-cgls --full --all --no-pager 2026-09-02 01:10:25.254020 | instance | + ip addr 2026-09-02 01:10:25.257202 | instance | + ip route 2026-09-02 01:10:25.260331 | instance | + lsblk 2026-09-02 01:10:25.266063 | instance | + mount 2026-09-02 01:10:25.269092 | instance | + docker images 2026-09-02 01:10:25.294740 | instance | + brctl show 2026-09-02 01:10:25.295272 | instance | /bin/bash: line 8: brctl: command not found 2026-09-02 01:10:25.295621 | instance | + ps aux --sort=-%mem 2026-09-02 01:10:25.317823 | instance | + dpkg -l 2026-09-02 01:10:25.331516 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-02 01:10:25.332093 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-02 01:10:25.350492 | instance | + '[' '!' -z '' ']' 2026-09-02 01:10:25.432535 | instance | ok: Runtime: 0:00:00.116227 2026-09-02 01:10:25.440301 | 2026-09-02 01:10:25.440374 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-02 01:10:26.089133 | instance | changed: 2026-09-02 01:10:26.089332 | instance | created directory /var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/work/logs/instance 2026-09-02 01:10:26.089362 | instance | cd+++++++++ system/ 2026-09-02 01:10:26.089385 | instance | >f+++++++++ system/brctl-show.txt 2026-09-02 01:10:26.089406 | instance | >f+++++++++ system/docker-images.txt 2026-09-02 01:10:26.089427 | instance | >f+++++++++ system/ip-addr.txt 2026-09-02 01:10:26.089449 | instance | >f+++++++++ system/ip-route.txt 2026-09-02 01:10:26.089471 | instance | >f+++++++++ system/lsblk.txt 2026-09-02 01:10:26.089491 | instance | >f+++++++++ system/mount.txt 2026-09-02 01:10:26.089511 | instance | >f+++++++++ system/packages.txt 2026-09-02 01:10:26.089530 | instance | >f+++++++++ system/ps.txt 2026-09-02 01:10:26.089552 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-02 01:10:26.098527 | 2026-09-02 01:10:26.098597 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-02 01:10:26.311888 | instance | changed: "values" 2026-09-02 01:10:26.499060 | instance | changed: "releases" 2026-09-02 01:10:26.511531 | 2026-09-02 01:10:26.511693 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-02 01:10:26.741025 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-02 01:10:27.053575 | instance | ok: Runtime: 0:00:00.005719 2026-09-02 01:10:27.060437 | 2026-09-02 01:10:27.060533 | TASK [helm-release-status : Downloads logs to executor] 2026-09-02 01:10:27.570883 | instance | changed: 2026-09-02 01:10:27.571124 | instance | cd+++++++++ helm/ 2026-09-02 01:10:27.571163 | instance | cd+++++++++ helm/releases/ 2026-09-02 01:10:27.571193 | instance | cd+++++++++ helm/values/ 2026-09-02 01:10:27.581031 | 2026-09-02 01:10:27.581100 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-02 01:10:27.798562 | instance | changed 2026-09-02 01:10:27.806186 | 2026-09-02 01:10:27.806266 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-02 01:10:28.076839 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 01:10:28.077170 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 01:10:28.083636 | instance | environment: line 1: kubectl: command not found 2026-09-02 01:10:28.084811 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 01:10:28.085478 | instance | environment: line 1: kubectl: command not found 2026-09-02 01:10:28.087229 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 01:10:28.087900 | instance | environment: line 1: kubectl: command not found 2026-09-02 01:10:28.089523 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 01:10:28.090943 | instance | environment: line 1: kubectl: command not found 2026-09-02 01:10:28.092356 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 01:10:28.094108 | instance | environment: line 1: kubectl: command not found 2026-09-02 01:10:28.095171 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 01:10:28.349751 | instance | ok: Runtime: 0:00:00.026092 2026-09-02 01:10:28.356093 | 2026-09-02 01:10:28.356162 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-02 01:10:28.582611 | instance | changed 2026-09-02 01:10:28.588858 | 2026-09-02 01:10:28.588934 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-02 01:10:28.822945 | instance | environment: line 5: kubectl: command not found 2026-09-02 01:10:28.823512 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 01:10:28.824683 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 01:10:28.824951 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 01:10:29.129269 | instance | ok: Runtime: 0:00:00.008220 2026-09-02 01:10:29.136608 | 2026-09-02 01:10:29.136678 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-02 01:10:29.658004 | instance | changed: 2026-09-02 01:10:29.658223 | instance | cd+++++++++ objects/ 2026-09-02 01:10:29.658264 | instance | cd+++++++++ objects/cluster/ 2026-09-02 01:10:29.658297 | instance | cd+++++++++ objects/namespaced/ 2026-09-02 01:10:29.668381 | 2026-09-02 01:10:29.668443 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-02 01:10:29.875384 | instance | changed 2026-09-02 01:10:29.881845 | 2026-09-02 01:10:29.881910 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-02 01:10:30.089506 | instance | changed 2026-09-02 01:10:30.094236 | 2026-09-02 01:10:30.094301 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-02 01:10:30.319333 | instance | environment: line 3: kubectl: command not found 2026-09-02 01:10:30.631689 | instance | ok: Runtime: 0:00:00.008092 2026-09-02 01:10:30.637037 | 2026-09-02 01:10:30.637104 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-02 01:10:31.150471 | instance | changed: 2026-09-02 01:10:31.150638 | instance | cd+++++++++ pod-logs/ 2026-09-02 01:10:31.150665 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-02 01:10:31.159895 | 2026-09-02 01:10:31.159960 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-02 01:10:31.413914 | instance | changed 2026-09-02 01:10:31.420826 | 2026-09-02 01:10:31.420947 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-02 01:10:31.629118 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-02 01:10:31.956231 | instance | ok: Runtime: 0:00:00.034198 2026-09-02 01:10:32.176775 | 2026-09-02 01:10:32.176919 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-02 01:10:32.390328 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-02 01:10:32.434386 | instance | ceph-mgr endpoints: 2026-09-02 01:10:32.715597 | instance | ok: Runtime: 0:00:00.049523 2026-09-02 01:10:32.720752 | 2026-09-02 01:10:32.720822 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-02 01:10:32.949522 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-02 01:10:33.256634 | instance | ok: Runtime: 0:00:00.056491 2026-09-02 01:10:33.263749 | 2026-09-02 01:10:33.265595 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-02 01:10:33.765074 | instance | changed: cd+++++++++ prometheus/ 2026-09-02 01:10:33.774926 | 2026-09-02 01:10:33.774985 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-02 01:10:34.000638 | instance | changed 2026-09-02 01:10:34.006170 | 2026-09-02 01:10:34.006232 | TASK [gather-selenium-data : Get selenium data] 2026-09-02 01:10:34.298928 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-02 01:10:34.301390 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-02 01:10:34.541530 | instance | ERROR 2026-09-02 01:10:34.541747 | instance | { 2026-09-02 01:10:34.541790 | instance | "delta": "0:00:00.009512", 2026-09-02 01:10:34.541827 | instance | "end": "2026-09-02 01:10:34.301882", 2026-09-02 01:10:34.541872 | instance | "msg": "non-zero return code", 2026-09-02 01:10:34.541917 | instance | "rc": 1, 2026-09-02 01:10:34.541961 | instance | "start": "2026-09-02 01:10:34.292370" 2026-09-02 01:10:34.542003 | instance | } 2026-09-02 01:10:34.542048 | instance | ERROR: Ignoring Errors 2026-09-02 01:10:34.548241 | 2026-09-02 01:10:34.548303 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-02 01:10:35.048173 | instance | changed: cd+++++++++ selenium/ 2026-09-02 01:10:35.206069 | 2026-09-02 01:10:35.206149 | PLAY RECAP 2026-09-02 01:10:35.206223 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-02 01:10:35.206256 | 2026-09-02 01:10:35.350475 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-02 01:10:35.354836 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 01:10:36.181336 | 2026-09-02 01:10:36.181470 | PLAY [all] 2026-09-02 01:10:36.193130 | 2026-09-02 01:10:36.193206 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-02 01:10:36.238862 | instance | skipping: Conditional result was False 2026-09-02 01:10:36.247690 | 2026-09-02 01:10:36.247799 | TASK [fetch-output : Set log path for single node] 2026-09-02 01:10:36.291415 | instance | ok 2026-09-02 01:10:36.297502 | 2026-09-02 01:10:36.297577 | LOOP [fetch-output : Ensure local output dirs] 2026-09-02 01:10:36.697480 | instance -> localhost | ok: "/var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/work/logs" 2026-09-02 01:10:36.938698 | instance -> localhost | changed: "/var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/work/artifacts" 2026-09-02 01:10:37.290022 | instance -> localhost | changed: "/var/lib/zuul/builds/64cf4fa0d6cc4a59a9e4239013da1598/work/docs" 2026-09-02 01:10:37.310985 | 2026-09-02 01:10:37.311126 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-02 01:10:37.959589 | instance | changed: .d..t...... ./ 2026-09-02 01:10:37.959812 | instance | changed: All items complete 2026-09-02 01:10:37.959840 | 2026-09-02 01:10:38.416468 | instance | changed: .d..t...... ./ 2026-09-02 01:10:38.894742 | instance | changed: .d..t...... ./ 2026-09-02 01:10:38.912854 | 2026-09-02 01:10:38.913010 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-02 01:10:39.338145 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008246 2026-09-02 01:10:39.565895 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008170 2026-09-02 01:10:39.583759 | 2026-09-02 01:10:39.583871 | PLAY [all] 2026-09-02 01:10:39.592701 | 2026-09-02 01:10:39.592769 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-02 01:10:40.057389 | instance | changed 2026-09-02 01:10:40.064458 | 2026-09-02 01:10:40.064509 | PLAY RECAP 2026-09-02 01:10:40.064556 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-02 01:10:40.064579 | 2026-09-02 01:10:40.218277 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 01:10:40.222595 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-02 01:10:40.803876 | 2026-09-02 01:10:40.804017 | PLAY [localhost] 2026-09-02 01:10:40.814160 | 2026-09-02 01:10:40.814229 | TASK [Generate Zuul manifest] 2026-09-02 01:10:40.836187 | localhost | ok 2026-09-02 01:10:40.856932 | 2026-09-02 01:10:40.857064 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-02 01:10:41.222578 | localhost | changed 2026-09-02 01:10:41.234315 | 2026-09-02 01:10:41.234389 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-02 01:10:41.276604 | localhost | ok 2026-09-02 01:10:41.285419 | 2026-09-02 01:10:41.285773 | TASK [Upload logs] 2026-09-02 01:10:41.307740 | localhost | ok 2026-09-02 01:10:41.368876 | 2026-09-02 01:10:41.369010 | TASK [Set zuul-log-path fact] 2026-09-02 01:10:41.391204 | localhost | ok 2026-09-02 01:10:41.403381 | 2026-09-02 01:10:41.403447 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 01:10:41.434763 | localhost | ok 2026-09-02 01:10:41.443094 | 2026-09-02 01:10:41.443167 | TASK [upload-logs : Create log directories] 2026-09-02 01:10:41.850779 | localhost | changed 2026-09-02 01:10:41.857222 | 2026-09-02 01:10:41.857317 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-02 01:10:42.255253 | localhost -> localhost | ok: Runtime: 0:00:00.007089 2026-09-02 01:10:42.261987 | 2026-09-02 01:10:42.262061 | TASK [upload-logs : Upload logs to log server] 2026-09-02 01:10:42.734568 | localhost | Output suppressed because no_log was given 2026-09-02 01:10:42.775455 | 2026-09-02 01:10:42.775619 | LOOP [upload-logs : Compress console log and json output] 2026-09-02 01:10:42.825204 | localhost | skipping: Conditional result was False 2026-09-02 01:10:42.832480 | localhost | skipping: Conditional result was False 2026-09-02 01:10:42.850844 | 2026-09-02 01:10:42.851042 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-02 01:10:42.896064 | localhost | skipping: Conditional result was False 2026-09-02 01:10:42.896446 | 2026-09-02 01:10:42.900273 | localhost | skipping: Conditional result was False 2026-09-02 01:10:42.915626 | 2026-09-02 01:10:42.915765 | LOOP [upload-logs : Upload console log and json output]