2026-09-15 02:15:01.704870 | Job console starting 2026-09-15 02:15:01.719228 | Updating git repos 2026-09-15 02:15:05.744792 | Cloning repos into workspace 2026-09-15 02:15:22.668641 | Restoring repo states 2026-09-15 02:15:34.532891 | Merging changes 2026-09-15 02:15:35.606706 | Checking out repos 2026-09-15 02:15:37.449583 | Preparing playbooks 2026-09-15 02:15:39.716176 | Running Ansible setup 2026-09-15 02:15:43.212463 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-15 02:15:43.823137 | 2026-09-15 02:15:43.823270 | PLAY [localhost] 2026-09-15 02:15:43.830310 | 2026-09-15 02:15:43.830375 | TASK [Gathering Facts] 2026-09-15 02:15:44.751483 | localhost | ok 2026-09-15 02:15:44.774188 | 2026-09-15 02:15:44.774338 | TASK [Setup log path fact] 2026-09-15 02:15:44.794517 | localhost | ok 2026-09-15 02:15:44.813573 | 2026-09-15 02:15:44.813674 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-15 02:15:44.853219 | localhost | ok 2026-09-15 02:15:44.864280 | 2026-09-15 02:15:44.864442 | TASK [emit-job-header : Print job information] 2026-09-15 02:15:44.918620 | # Job Information 2026-09-15 02:15:44.918954 | Ansible Version: 2.16.18 2026-09-15 02:15:44.919050 | Job: atmosphere-molecule-csi-rbd 2026-09-15 02:15:44.919121 | Pipeline: check 2026-09-15 02:15:44.919184 | Executor: 2d72f0692154 2026-09-15 02:15:44.919243 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4232 2026-09-15 02:15:44.919307 | Event ID: a2620de0-b0aa-11f1-8d40-98aa680c1fbb 2026-09-15 02:15:44.927565 | 2026-09-15 02:15:44.927733 | LOOP [emit-job-header : Print node information] 2026-09-15 02:15:45.043952 | localhost | ok: 2026-09-15 02:15:45.044239 | localhost | # Node Information 2026-09-15 02:15:45.044300 | localhost | Inventory Hostname: instance 2026-09-15 02:15:45.044342 | localhost | Hostname: np0000192764 2026-09-15 02:15:45.044381 | localhost | Username: zuul 2026-09-15 02:15:45.044457 | localhost | Distro: Ubuntu 22.04 2026-09-15 02:15:45.044531 | localhost | Provider: yul1 2026-09-15 02:15:45.044590 | localhost | Region: ca-ymq-1 2026-09-15 02:15:45.044629 | localhost | Label: ubuntu-jammy 2026-09-15 02:15:45.044664 | localhost | Product Name: OpenStack Nova 2026-09-15 02:15:45.044700 | localhost | Interface IP: 162.253.55.203 2026-09-15 02:15:45.066254 | 2026-09-15 02:15:45.066444 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-15 02:15:45.543683 | localhost -> localhost | changed 2026-09-15 02:15:45.552596 | 2026-09-15 02:15:45.552745 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-15 02:15:46.448524 | localhost -> localhost | changed 2026-09-15 02:15:46.456278 | 2026-09-15 02:15:46.456345 | PLAY [all] 2026-09-15 02:15:46.466356 | 2026-09-15 02:15:46.466455 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-15 02:15:46.713025 | instance -> localhost | ok 2026-09-15 02:15:46.723678 | 2026-09-15 02:15:46.723808 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-15 02:15:46.756419 | instance | ok 2026-09-15 02:15:46.780413 | instance | included: /var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-15 02:15:46.787944 | 2026-09-15 02:15:46.788019 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-15 02:15:48.032660 | instance -> localhost | Generating public/private rsa key pair. 2026-09-15 02:15:48.032807 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/work/ea19a83084c44f4ab785fc05ae9b29b2_id_rsa 2026-09-15 02:15:48.032833 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/work/ea19a83084c44f4ab785fc05ae9b29b2_id_rsa.pub 2026-09-15 02:15:48.032852 | instance -> localhost | The key fingerprint is: 2026-09-15 02:15:48.032871 | instance -> localhost | SHA256:I7W8+hIAGR3aHGiYC1inrxKvLEt/BqmGFsIYv72d+q8 zuul-build-sshkey 2026-09-15 02:15:48.032899 | instance -> localhost | The key's randomart image is: 2026-09-15 02:15:48.032917 | instance -> localhost | +---[RSA 3072]----+ 2026-09-15 02:15:48.032937 | instance -> localhost | |.+o=+. | 2026-09-15 02:15:48.032956 | instance -> localhost | |= =*.. | 2026-09-15 02:15:48.032974 | instance -> localhost | |.oo.o . | 2026-09-15 02:15:48.032992 | instance -> localhost | |o .. o . | 2026-09-15 02:15:48.033010 | instance -> localhost | |+o o.. S | 2026-09-15 02:15:48.033027 | instance -> localhost | |o=.+ .. o | 2026-09-15 02:15:48.033044 | instance -> localhost | |+.=o. .. | 2026-09-15 02:15:48.033060 | instance -> localhost | |=*o .+.o | 2026-09-15 02:15:48.033079 | instance -> localhost | |*o .=+E=o | 2026-09-15 02:15:48.033097 | instance -> localhost | +----[SHA256]-----+ 2026-09-15 02:15:48.033140 | instance -> localhost | ok: Runtime: 0:00:00.699903 2026-09-15 02:15:48.038341 | 2026-09-15 02:15:48.038407 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-15 02:15:48.066441 | instance | ok 2026-09-15 02:15:48.076296 | instance | included: /var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-15 02:15:48.084942 | 2026-09-15 02:15:48.085021 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-15 02:15:48.108774 | instance | skipping: Conditional result was False 2026-09-15 02:15:48.118542 | 2026-09-15 02:15:48.118657 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-15 02:15:48.581080 | instance | changed 2026-09-15 02:15:48.588735 | 2026-09-15 02:15:48.588821 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-15 02:15:48.768656 | instance | ok 2026-09-15 02:15:48.773026 | 2026-09-15 02:15:48.773087 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-15 02:15:49.234608 | instance | changed 2026-09-15 02:15:49.238738 | 2026-09-15 02:15:49.238794 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-15 02:15:49.739540 | instance | changed 2026-09-15 02:15:49.751273 | 2026-09-15 02:15:49.751425 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-15 02:15:49.776202 | instance | skipping: Conditional result was False 2026-09-15 02:15:49.786140 | 2026-09-15 02:15:49.786271 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-15 02:15:50.158201 | instance -> localhost | changed 2026-09-15 02:15:50.170141 | 2026-09-15 02:15:50.170231 | TASK [add-build-sshkey : Add back temp key] 2026-09-15 02:15:50.495117 | instance -> localhost | Identity added: /var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/work/ea19a83084c44f4ab785fc05ae9b29b2_id_rsa (zuul-build-sshkey) 2026-09-15 02:15:50.495426 | instance -> localhost | ok: Runtime: 0:00:00.016287 2026-09-15 02:15:50.504310 | 2026-09-15 02:15:50.504452 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-15 02:15:50.845382 | instance | ok 2026-09-15 02:15:50.853883 | 2026-09-15 02:15:50.854014 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-15 02:15:50.889143 | instance | skipping: Conditional result was False 2026-09-15 02:15:50.907085 | 2026-09-15 02:15:50.907219 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-15 02:15:51.246431 | instance | ok 2026-09-15 02:15:51.270747 | 2026-09-15 02:15:51.271006 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-15 02:15:54.673936 | instance | Output suppressed because no_log was given 2026-09-15 02:15:54.691555 | 2026-09-15 02:15:54.691668 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-15 02:15:54.955585 | instance | ok: "logs" 2026-09-15 02:15:54.955987 | instance | ok: All items complete 2026-09-15 02:15:54.956052 | 2026-09-15 02:15:55.101110 | instance | ok: "artifacts" 2026-09-15 02:15:55.259571 | instance | ok: "docs" 2026-09-15 02:15:55.286790 | 2026-09-15 02:15:55.288039 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-15 02:15:55.504115 | instance | changed: "logs" 2026-09-15 02:15:55.680164 | instance | changed: "artifacts" 2026-09-15 02:15:55.844580 | instance | changed: "docs" 2026-09-15 02:15:55.865917 | 2026-09-15 02:15:55.866077 | PLAY RECAP 2026-09-15 02:15:55.866158 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-15 02:15:55.866212 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-15 02:15:55.866253 | 2026-09-15 02:15:56.041965 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-15 02:15:56.045516 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-15 02:15:56.615171 | 2026-09-15 02:15:56.615293 | PLAY [all] 2026-09-15 02:15:56.625184 | 2026-09-15 02:15:56.625258 | TASK [setup-uv : Extract archive] 2026-09-15 02:15:59.080150 | instance | changed 2026-09-15 02:15:59.106027 | 2026-09-15 02:15:59.106169 | TASK [setup-uv : Print version] 2026-09-15 02:15:59.526478 | instance | uv 0.8.13 2026-09-15 02:15:59.650294 | instance | ok: Runtime: 0:00:00.012028 2026-09-15 02:15:59.667672 | 2026-09-15 02:15:59.667805 | TASK [Install Ansible collection siblings] 2026-09-15 02:16:10.200307 | instance | changed: 2026-09-15 02:16:10.200677 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-15 02:16:10.200743 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-15 02:16:10.200786 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-15 02:16:10.200826 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-15 02:16:10.200864 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-15 02:16:10.200909 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-15 02:16:10.200950 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-15 02:16:10.200994 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-15 02:16:10.201034 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-15 02:16:10.201072 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-15 02:16:10.201111 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-15 02:16:10.201147 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-15 02:16:10.201183 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-15 02:16:10.201219 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-15 02:16:10.201256 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-15 02:16:10.201293 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-15 02:16:10.201329 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-15 02:16:10.201366 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-15 02:16:10.201402 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-15 02:16:10.201437 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-15 02:16:10.201497 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-15 02:16:10.201534 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-15 02:16:10.201574 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-15 02:16:10.201632 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-15 02:16:10.201671 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-15 02:16:10.201708 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-15 02:16:10.201743 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-15 02:16:10.201777 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-15 02:16:10.201812 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-15 02:16:10.201847 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-15 02:16:10.201882 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-15 02:16:10.201919 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-15 02:16:10.201954 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-15 02:16:10.201990 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-15 02:16:10.202037 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.202074 | instance | Process install dependency map 2026-09-15 02:16:10.202111 | instance | Starting collection install process 2026-09-15 02:16:10.202147 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-15 02:16:10.202183 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-15 02:16:10.202219 | instance | ansible.netcommon:* was installed successfully 2026-09-15 02:16:10.202254 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-15 02:16:10.202291 | instance | Creating virtual environment at: .venv 2026-09-15 02:16:10.202325 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-15 02:16:10.202361 | instance | Downloading netaddr (2.2MiB) 2026-09-15 02:16:10.202396 | instance | Downloading kubernetes (1.9MiB) 2026-09-15 02:16:10.202465 | instance | Downloading setuptools (1.1MiB) 2026-09-15 02:16:10.202507 | instance | Downloading cryptography (4.2MiB) 2026-09-15 02:16:10.202544 | instance | Downloading pygments (1.2MiB) 2026-09-15 02:16:10.202580 | instance | Downloading openstacksdk (1.7MiB) 2026-09-15 02:16:10.202623 | instance | Downloading pydantic-core (2.0MiB) 2026-09-15 02:16:10.202680 | instance | Downloading ansible-core (2.1MiB) 2026-09-15 02:16:10.202737 | instance | Downloading rjsonnet (1.2MiB) 2026-09-15 02:16:10.202796 | instance | Building pyperclip==1.9.0 2026-09-15 02:16:10.202860 | instance | Downloading rjsonnet 2026-09-15 02:16:10.202921 | instance | Downloading pydantic-core 2026-09-15 02:16:10.202978 | instance | Downloading pygments 2026-09-15 02:16:10.203030 | instance | Downloading netaddr 2026-09-15 02:16:10.203084 | instance | Downloading cryptography 2026-09-15 02:16:10.203129 | instance | Downloading setuptools 2026-09-15 02:16:10.203165 | instance | Downloading kubernetes 2026-09-15 02:16:10.203202 | instance | Downloading ansible-core 2026-09-15 02:16:10.203238 | instance | Downloading openstacksdk 2026-09-15 02:16:10.203274 | instance | Built pyperclip==1.9.0 2026-09-15 02:16:10.203311 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-15 02:16:10.203347 | instance | Installed 83 packages in 55ms 2026-09-15 02:16:10.203398 | instance | Checked out ansible.posix to 1.6.0 2026-09-15 02:16:10.203476 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-15 02:16:10.203540 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.203599 | instance | Process install dependency map 2026-09-15 02:16:10.203673 | instance | Starting collection install process 2026-09-15 02:16:10.203734 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-15 02:16:10.203781 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-15 02:16:10.203834 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-15 02:16:10.203885 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.203928 | instance | 2.17.12 2026-09-15 02:16:10.203964 | instance | Checked out ansible.utils to v6.1.0 2026-09-15 02:16:10.203998 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-15 02:16:10.204034 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.204069 | instance | Process install dependency map 2026-09-15 02:16:10.204106 | instance | Starting collection install process 2026-09-15 02:16:10.204161 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-15 02:16:10.204215 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-15 02:16:10.204273 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-15 02:16:10.204331 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.204389 | instance | 2.17.12 2026-09-15 02:16:10.204465 | instance | Checked out atmosphere.common to v0.7.0 2026-09-15 02:16:10.204581 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-15 02:16:10.204627 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.204664 | instance | Process install dependency map 2026-09-15 02:16:10.204699 | instance | Starting collection install process 2026-09-15 02:16:10.204733 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-15 02:16:10.204768 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-15 02:16:10.204803 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-15 02:16:10.204838 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.204872 | instance | 2.17.12 2026-09-15 02:16:10.204905 | instance | Checked out community.crypto to 2.2.3 2026-09-15 02:16:10.204940 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-15 02:16:10.204977 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.205026 | instance | Process install dependency map 2026-09-15 02:16:10.205072 | instance | Starting collection install process 2026-09-15 02:16:10.205167 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-15 02:16:10.205237 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-15 02:16:10.205291 | instance | community.crypto:2.2.3 was installed successfully 2026-09-15 02:16:10.205345 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.205399 | instance | 2.17.12 2026-09-15 02:16:10.205481 | instance | Checked out community.general to 7.3.0 2026-09-15 02:16:10.205532 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-15 02:16:10.205598 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.205656 | instance | Process install dependency map 2026-09-15 02:16:10.205708 | instance | Starting collection install process 2026-09-15 02:16:10.205746 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-15 02:16:10.205780 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-15 02:16:10.205814 | instance | community.general:7.3.0 was installed successfully 2026-09-15 02:16:10.205849 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.205884 | instance | 2.17.12 2026-09-15 02:16:10.205919 | instance | Checked out community.mysql to 3.6.0 2026-09-15 02:16:10.205972 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-15 02:16:10.206025 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.206082 | instance | Process install dependency map 2026-09-15 02:16:10.206134 | instance | Starting collection install process 2026-09-15 02:16:10.206185 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-15 02:16:10.206235 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-15 02:16:10.206290 | instance | community.mysql:3.6.0 was installed successfully 2026-09-15 02:16:10.206341 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.206389 | instance | 2.17.12 2026-09-15 02:16:10.206467 | instance | Checked out kubernetes.core to 2.4.0 2026-09-15 02:16:10.206527 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-15 02:16:10.206567 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.206604 | instance | Process install dependency map 2026-09-15 02:16:10.206640 | instance | Starting collection install process 2026-09-15 02:16:10.206676 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-15 02:16:10.206711 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-15 02:16:10.206746 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-15 02:16:10.206782 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.206816 | instance | 2.17.12 2026-09-15 02:16:10.206851 | instance | Checked out openstack.cloud to 2.6.0 2026-09-15 02:16:10.206885 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-15 02:16:10.206920 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.206954 | instance | Process install dependency map 2026-09-15 02:16:10.206988 | instance | Starting collection install process 2026-09-15 02:16:10.207022 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-15 02:16:10.207056 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-15 02:16:10.207089 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-15 02:16:10.207123 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.207157 | instance | 2.17.12 2026-09-15 02:16:10.207201 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-15 02:16:10.207237 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-15 02:16:10.207279 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.207332 | instance | Process install dependency map 2026-09-15 02:16:10.207384 | instance | Starting collection install process 2026-09-15 02:16:10.207422 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-15 02:16:10.207482 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-15 02:16:10.207520 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-15 02:16:10.207555 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.207589 | instance | 2.17.12 2026-09-15 02:16:10.207624 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-15 02:16:10.207658 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-15 02:16:10.207692 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.207728 | instance | Process install dependency map 2026-09-15 02:16:10.207762 | instance | Starting collection install process 2026-09-15 02:16:10.207808 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-15 02:16:10.207861 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-15 02:16:10.207919 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-15 02:16:10.207978 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.208035 | instance | 2.17.12 2026-09-15 02:16:10.208092 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-15 02:16:10.208148 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-15 02:16:10.208203 | instance | Starting galaxy collection install process 2026-09-15 02:16:10.208260 | instance | Process install dependency map 2026-09-15 02:16:10.208317 | instance | Starting collection install process 2026-09-15 02:16:10.208373 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-15 02:16:10.208428 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-15 02:16:10.208531 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-15 02:16:10.208578 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-15 02:16:10.208629 | instance | 2.17.12 2026-09-15 02:16:10.219440 | 2026-09-15 02:16:10.219566 | PLAY RECAP 2026-09-15 02:16:10.219654 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-15 02:16:10.219699 | 2026-09-15 02:16:10.385374 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-15 02:16:10.387504 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-15 02:16:10.949848 | 2026-09-15 02:16:10.949981 | PLAY [all] 2026-09-15 02:16:10.959952 | 2026-09-15 02:16:10.960023 | TASK [Install "jq" for log collection] 2026-09-15 02:16:20.902450 | instance | changed 2026-09-15 02:16:20.906586 | 2026-09-15 02:16:20.906722 | PLAY RECAP 2026-09-15 02:16:20.906871 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-15 02:16:20.907340 | 2026-09-15 02:16:21.045315 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-15 02:16:21.052328 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-15 02:16:21.669486 | 2026-09-15 02:16:21.669653 | PLAY [all] 2026-09-15 02:16:21.680116 | 2026-09-15 02:16:21.680219 | LOOP [Check inventory directories] 2026-09-15 02:16:22.047414 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-15 02:16:22.199588 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-15 02:16:22.368945 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-15 02:16:22.382481 | 2026-09-15 02:16:22.386312 | LOOP [Copy inventory file for Zuul] 2026-09-15 02:16:23.282687 | instance | changed: 2026-09-15 02:16:23.283013 | instance | { 2026-09-15 02:16:23.283074 | instance | "ansible_loop_var": "item", 2026-09-15 02:16:23.283118 | instance | "changed": false, 2026-09-15 02:16:23.283156 | instance | "failed": false, 2026-09-15 02:16:23.283199 | instance | "invocation": { 2026-09-15 02:16:23.283235 | instance | "module_args": { 2026-09-15 02:16:23.283270 | instance | "checksum_algorithm": "sha1", 2026-09-15 02:16:23.283304 | instance | "follow": true, 2026-09-15 02:16:23.283337 | instance | "get_attributes": true, 2026-09-15 02:16:23.283374 | instance | "get_checksum": true, 2026-09-15 02:16:23.283408 | instance | "get_mime": true, 2026-09-15 02:16:23.283441 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-15 02:16:23.283497 | instance | } 2026-09-15 02:16:23.283535 | instance | }, 2026-09-15 02:16:23.283569 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-15 02:16:23.283602 | instance | "stat": { 2026-09-15 02:16:23.283635 | instance | "atime": 1789438562.89877, 2026-09-15 02:16:23.283669 | instance | "attr_flags": "e", 2026-09-15 02:16:23.283703 | instance | "attributes": [ 2026-09-15 02:16:23.283736 | instance | "extents" 2026-09-15 02:16:23.283770 | instance | ], 2026-09-15 02:16:23.283803 | instance | "block_size": 4096, 2026-09-15 02:16:23.283836 | instance | "blocks": 8, 2026-09-15 02:16:23.283869 | instance | "charset": "unknown", 2026-09-15 02:16:23.283905 | instance | "ctime": 1789438560.5746677, 2026-09-15 02:16:23.283940 | instance | "dev": 64513, 2026-09-15 02:16:23.283974 | instance | "device_type": 0, 2026-09-15 02:16:23.284006 | instance | "executable": true, 2026-09-15 02:16:23.284039 | instance | "exists": true, 2026-09-15 02:16:23.284072 | instance | "gid": 1000, 2026-09-15 02:16:23.284105 | instance | "gr_name": "zuul", 2026-09-15 02:16:23.284138 | instance | "inode": 1048352, 2026-09-15 02:16:23.284171 | instance | "isblk": false, 2026-09-15 02:16:23.284205 | instance | "ischr": false, 2026-09-15 02:16:23.284238 | instance | "isdir": true, 2026-09-15 02:16:23.284271 | instance | "isfifo": false, 2026-09-15 02:16:23.284304 | instance | "isgid": false, 2026-09-15 02:16:23.284338 | instance | "islnk": false, 2026-09-15 02:16:23.284369 | instance | "isreg": false, 2026-09-15 02:16:23.284401 | instance | "issock": false, 2026-09-15 02:16:23.284432 | instance | "isuid": false, 2026-09-15 02:16:23.284463 | instance | "mimetype": "unknown", 2026-09-15 02:16:23.284576 | instance | "mode": "0755", 2026-09-15 02:16:23.284612 | instance | "mtime": 1789438560.5746677, 2026-09-15 02:16:23.284645 | instance | "nlink": 23, 2026-09-15 02:16:23.284678 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-15 02:16:23.284711 | instance | "pw_name": "zuul", 2026-09-15 02:16:23.284745 | instance | "readable": true, 2026-09-15 02:16:23.284778 | instance | "rgrp": true, 2026-09-15 02:16:23.284811 | instance | "roth": true, 2026-09-15 02:16:23.284865 | instance | "rusr": true, 2026-09-15 02:16:23.284899 | instance | "size": 4096, 2026-09-15 02:16:23.284931 | instance | "uid": 1000, 2026-09-15 02:16:23.284963 | instance | "version": "3872467835", 2026-09-15 02:16:23.284996 | instance | "wgrp": false, 2026-09-15 02:16:23.285031 | instance | "woth": false, 2026-09-15 02:16:23.285064 | instance | "writeable": true, 2026-09-15 02:16:23.285096 | instance | "wusr": true, 2026-09-15 02:16:23.285128 | instance | "xgrp": true, 2026-09-15 02:16:23.285161 | instance | "xoth": true, 2026-09-15 02:16:23.285193 | instance | "xusr": true 2026-09-15 02:16:23.285225 | instance | } 2026-09-15 02:16:23.285258 | instance | } 2026-09-15 02:16:23.285324 | instance | ok: All items complete 2026-09-15 02:16:23.285366 | 2026-09-15 02:16:23.287696 | instance | skipping: Conditional result was False 2026-09-15 02:16:23.288871 | instance | skipping: Conditional result was False 2026-09-15 02:16:23.303930 | 2026-09-15 02:16:23.304141 | LOOP [Switch "ansible_host" to private IP] 2026-09-15 02:16:23.668262 | instance | changed: 1 replacements made 2026-09-15 02:16:23.670857 | instance | skipping: Conditional result was False 2026-09-15 02:16:23.675526 | instance | skipping: Conditional result was False 2026-09-15 02:16:23.698271 | 2026-09-15 02:16:23.698525 | TASK [Run Molecule scenario] 2026-09-15 02:16:24.631490 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-15 02:16:25.041641 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-15 02:16:25.041704 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-15 02:16:35.382630 | instance | INFO [csi > dependency] Executing 2026-09-15 02:16:35.382978 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-15 02:16:35.383445 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-15 02:16:35.383647 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-15 02:16:35.398307 | instance | INFO [csi > cleanup] Executing 2026-09-15 02:16:35.398847 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-15 02:16:35.412627 | instance | INFO [csi > destroy] Executing 2026-09-15 02:16:35.412701 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-15 02:16:35.412713 | instance | INFO [csi > destroy] Executed: Successful 2026-09-15 02:16:35.422327 | instance | INFO [csi > syntax] Executing 2026-09-15 02:16:36.947849 | instance | 2026-09-15 02:16:36.948179 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-15 02:16:37.008963 | instance | INFO [csi > syntax] Executed: Successful 2026-09-15 02:16:37.024509 | instance | INFO [csi > create] Executing 2026-09-15 02:16:37.027999 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-15 02:16:37.042120 | instance | INFO [csi > prepare] Executing 2026-09-15 02:16:37.770518 | instance | 2026-09-15 02:16:37.770718 | instance | PLAY [Prepare] ***************************************************************** 2026-09-15 02:16:37.770937 | instance | 2026-09-15 02:16:37.771295 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-15 02:16:37.771546 | instance | Tuesday 15 September 2026 02:16:37 +0000 (0:00:00.024) 0:00:00.024 ***** 2026-09-15 02:16:39.062465 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-15 02:16:39.062658 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-15 02:16:39.062895 | instance | interpreter could change the meaning of that path. See 2026-09-15 02:16:39.063166 | instance | https://docs.ansible.com/ansible- 2026-09-15 02:16:39.063423 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-15 02:16:39.074176 | instance | ok: [instance] 2026-09-15 02:16:39.074348 | instance | 2026-09-15 02:16:39.074596 | instance | TASK [Configure short hostname] ************************************************ 2026-09-15 02:16:39.074832 | instance | Tuesday 15 September 2026 02:16:39 +0000 (0:00:01.304) 0:00:01.328 ***** 2026-09-15 02:16:39.846835 | instance | changed: [instance] 2026-09-15 02:16:39.847028 | instance | 2026-09-15 02:16:39.847329 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-15 02:16:39.847570 | instance | Tuesday 15 September 2026 02:16:39 +0000 (0:00:00.772) 0:00:02.100 ***** 2026-09-15 02:16:40.132583 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-15 02:16:40.132810 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-15 02:16:40.133175 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-15 02:16:40.141622 | instance | changed: [instance] 2026-09-15 02:16:40.141859 | instance | 2026-09-15 02:16:40.142127 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-15 02:16:40.142400 | instance | Tuesday 15 September 2026 02:16:40 +0000 (0:00:00.295) 0:00:02.396 ***** 2026-09-15 02:16:40.927970 | instance | ok: [instance] 2026-09-15 02:16:40.928202 | instance | 2026-09-15 02:16:40.928457 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-15 02:16:40.928668 | instance | 2026-09-15 02:16:40.928895 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-15 02:16:40.929156 | instance | Tuesday 15 September 2026 02:16:40 +0000 (0:00:00.786) 0:00:03.182 ***** 2026-09-15 02:16:41.639410 | instance | ok: [instance] 2026-09-15 02:16:41.639650 | instance | 2026-09-15 02:16:41.640077 | instance | TASK [Install depedencies] ***************************************************** 2026-09-15 02:16:41.640410 | instance | Tuesday 15 September 2026 02:16:41 +0000 (0:00:00.710) 0:00:03.893 ***** 2026-09-15 02:17:05.339240 | instance | changed: [instance] 2026-09-15 02:17:05.339297 | instance | 2026-09-15 02:17:05.339758 | instance | TASK [Start up service] ******************************************************** 2026-09-15 02:17:05.339820 | instance | Tuesday 15 September 2026 02:17:05 +0000 (0:00:23.699) 0:00:27.592 ***** 2026-09-15 02:17:05.888715 | instance | ok: [instance] 2026-09-15 02:17:05.890097 | instance | 2026-09-15 02:17:05.890145 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-15 02:17:05.890156 | instance | Tuesday 15 September 2026 02:17:05 +0000 (0:00:00.549) 0:00:28.142 ***** 2026-09-15 02:17:06.218373 | instance | ok: [instance] 2026-09-15 02:17:06.218508 | instance | 2026-09-15 02:17:06.218675 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-15 02:17:06.218901 | instance | Tuesday 15 September 2026 02:17:06 +0000 (0:00:00.330) 0:00:28.473 ***** 2026-09-15 02:17:06.927243 | instance | changed: [instance] 2026-09-15 02:17:06.927330 | instance | 2026-09-15 02:17:06.927547 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-15 02:17:06.927808 | instance | Tuesday 15 September 2026 02:17:06 +0000 (0:00:00.708) 0:00:29.181 ***** 2026-09-15 02:17:07.143467 | instance | ok: [instance] 2026-09-15 02:17:07.143957 | instance | 2026-09-15 02:17:07.144374 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-15 02:17:07.144705 | instance | Tuesday 15 September 2026 02:17:07 +0000 (0:00:00.215) 0:00:29.397 ***** 2026-09-15 02:17:07.167955 | instance | skipping: [instance] 2026-09-15 02:17:07.168473 | instance | 2026-09-15 02:17:07.168860 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-15 02:17:07.169181 | instance | Tuesday 15 September 2026 02:17:07 +0000 (0:00:00.022) 0:00:29.420 ***** 2026-09-15 02:17:07.735617 | instance | changed: [instance] => (item=osd0) 2026-09-15 02:17:07.735797 | instance | changed: [instance] => (item=osd1) 2026-09-15 02:17:07.735940 | instance | changed: [instance] => (item=osd2) 2026-09-15 02:17:07.736070 | instance | 2026-09-15 02:17:07.736207 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-15 02:17:07.736358 | instance | Tuesday 15 September 2026 02:17:07 +0000 (0:00:00.568) 0:00:29.988 ***** 2026-09-15 02:17:08.412211 | instance | changed: [instance] => (item=osd0) 2026-09-15 02:17:08.412628 | instance | changed: [instance] => (item=osd1) 2026-09-15 02:17:08.412934 | instance | changed: [instance] => (item=osd2) 2026-09-15 02:17:08.413215 | instance | 2026-09-15 02:17:08.413542 | instance | TASK [Start loop devices] ****************************************************** 2026-09-15 02:17:08.413860 | instance | Tuesday 15 September 2026 02:17:08 +0000 (0:00:00.676) 0:00:30.665 ***** 2026-09-15 02:17:09.138169 | instance | changed: [instance] => (item=osd0) 2026-09-15 02:17:09.138295 | instance | changed: [instance] => (item=osd1) 2026-09-15 02:17:09.138914 | instance | changed: [instance] => (item=osd2) 2026-09-15 02:17:09.138973 | instance | 2026-09-15 02:17:09.138980 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-15 02:17:09.138987 | instance | Tuesday 15 September 2026 02:17:09 +0000 (0:00:00.727) 0:00:31.392 ***** 2026-09-15 02:17:12.430053 | instance | changed: [instance] => (item=osd0) 2026-09-15 02:17:12.431212 | instance | changed: [instance] => (item=osd1) 2026-09-15 02:17:12.431251 | instance | changed: [instance] => (item=osd2) 2026-09-15 02:17:12.431265 | instance | 2026-09-15 02:17:12.431291 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-15 02:17:12.431423 | instance | Tuesday 15 September 2026 02:17:12 +0000 (0:00:03.291) 0:00:34.684 ***** 2026-09-15 02:17:14.472460 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-15 02:17:14.473446 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-15 02:17:14.473483 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-15 02:17:14.473490 | instance | 2026-09-15 02:17:14.473497 | instance | PLAY RECAP ********************************************************************* 2026-09-15 02:17:14.475254 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-15 02:17:14.475317 | instance | 2026-09-15 02:17:14.475331 | instance | Tuesday 15 September 2026 02:17:14 +0000 (0:00:02.043) 0:00:36.727 ***** 2026-09-15 02:17:14.475341 | instance | =============================================================================== 2026-09-15 02:17:14.475351 | instance | Install depedencies ---------------------------------------------------- 23.70s 2026-09-15 02:17:14.475362 | instance | Create a volume group for each loop device ------------------------------ 3.29s 2026-09-15 02:17:14.475371 | instance | Create a logical volume for each loop device ---------------------------- 2.04s 2026-09-15 02:17:14.475390 | instance | Gathering Facts --------------------------------------------------------- 1.30s 2026-09-15 02:17:14.478408 | instance | Purge "snapd" package --------------------------------------------------- 0.79s 2026-09-15 02:17:14.478478 | instance | Configure short hostname ------------------------------------------------ 0.77s 2026-09-15 02:17:14.478489 | instance | Start loop devices ------------------------------------------------------ 0.73s 2026-09-15 02:17:14.478498 | instance | Gathering Facts --------------------------------------------------------- 0.71s 2026-09-15 02:17:14.478507 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.71s 2026-09-15 02:17:14.478516 | instance | Set permissions on loopback devices ------------------------------------- 0.68s 2026-09-15 02:17:14.478525 | instance | Create devices for Ceph ------------------------------------------------- 0.57s 2026-09-15 02:17:14.478536 | instance | Start up service -------------------------------------------------------- 0.55s 2026-09-15 02:17:14.478548 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s 2026-09-15 02:17:14.478565 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s 2026-09-15 02:17:14.478595 | instance | Get list of all loopback devices ---------------------------------------- 0.22s 2026-09-15 02:17:14.478608 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-15 02:17:14.626384 | instance | INFO [csi > prepare] Executed: Successful 2026-09-15 02:17:14.640819 | instance | INFO [csi > converge] Executing 2026-09-15 02:17:16.084773 | instance | 2026-09-15 02:17:16.085153 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-15 02:17:16.085490 | instance | 2026-09-15 02:17:16.085828 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-15 02:17:16.086134 | instance | Tuesday 15 September 2026 02:17:16 +0000 (0:00:00.007) 0:00:00.007 ***** 2026-09-15 02:17:17.123174 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-15 02:17:17.123469 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-15 02:17:17.123685 | instance | interpreter could change the meaning of that path. See 2026-09-15 02:17:17.123877 | instance | https://docs.ansible.com/ansible- 2026-09-15 02:17:17.124056 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-15 02:17:17.136325 | instance | ok: [instance] 2026-09-15 02:17:17.136517 | instance | 2026-09-15 02:17:17.136739 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-15 02:17:17.136932 | instance | Tuesday 15 September 2026 02:17:17 +0000 (0:00:01.051) 0:00:01.058 ***** 2026-09-15 02:17:17.193709 | instance | ok: [instance] => { 2026-09-15 02:17:17.194001 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-15 02:17:17.194191 | instance | } 2026-09-15 02:17:17.194372 | instance | 2026-09-15 02:17:17.194562 | instance | PLAY [all] ********************************************************************* 2026-09-15 02:17:17.194755 | instance | 2026-09-15 02:17:17.194945 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-15 02:17:17.195213 | instance | Tuesday 15 September 2026 02:17:17 +0000 (0:00:00.056) 0:00:01.115 ***** 2026-09-15 02:17:18.128426 | instance | ok: [instance] 2026-09-15 02:17:18.128879 | instance | 2026-09-15 02:17:18.129260 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-15 02:17:18.129569 | instance | Tuesday 15 September 2026 02:17:18 +0000 (0:00:00.934) 0:00:02.049 ***** 2026-09-15 02:17:18.187769 | instance | skipping: [instance] 2026-09-15 02:17:18.188011 | instance | 2026-09-15 02:17:18.188209 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-15 02:17:18.188403 | instance | Tuesday 15 September 2026 02:17:18 +0000 (0:00:00.056) 0:00:02.106 ***** 2026-09-15 02:17:18.395919 | instance | ok: [instance] 2026-09-15 02:17:18.396283 | instance | 2026-09-15 02:17:18.396627 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-15 02:17:18.396924 | instance | 2026-09-15 02:17:18.397234 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-15 02:17:18.397557 | instance | Tuesday 15 September 2026 02:17:18 +0000 (0:00:00.211) 0:00:02.317 ***** 2026-09-15 02:17:19.381000 | instance | ok: [instance] 2026-09-15 02:17:19.381083 | instance | 2026-09-15 02:17:19.381099 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-15 02:17:19.381114 | instance | Tuesday 15 September 2026 02:17:19 +0000 (0:00:00.983) 0:00:03.300 ***** 2026-09-15 02:17:19.676484 | instance | ok: [instance] 2026-09-15 02:17:19.676568 | instance | 2026-09-15 02:17:19.676575 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-15 02:17:19.676582 | instance | Tuesday 15 September 2026 02:17:19 +0000 (0:00:00.296) 0:00:03.597 ***** 2026-09-15 02:17:19.726912 | instance | skipping: [instance] 2026-09-15 02:17:19.726971 | instance | 2026-09-15 02:17:19.726981 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-15 02:17:19.726991 | instance | Tuesday 15 September 2026 02:17:19 +0000 (0:00:00.050) 0:00:03.648 ***** 2026-09-15 02:17:20.126729 | instance | changed: [instance] 2026-09-15 02:17:20.126779 | instance | 2026-09-15 02:17:20.126790 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-15 02:17:20.126800 | instance | Tuesday 15 September 2026 02:17:20 +0000 (0:00:00.399) 0:00:04.047 ***** 2026-09-15 02:17:20.201812 | instance | ok: [instance] => { 2026-09-15 02:17:20.201864 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-15 02:17:20.201875 | instance | } 2026-09-15 02:17:20.201884 | instance | 2026-09-15 02:17:20.201893 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-15 02:17:20.201903 | instance | Tuesday 15 September 2026 02:17:20 +0000 (0:00:00.074) 0:00:04.122 ***** 2026-09-15 02:17:20.810387 | instance | changed: [instance] 2026-09-15 02:17:20.810480 | instance | 2026-09-15 02:17:20.810489 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-15 02:17:20.810497 | instance | Tuesday 15 September 2026 02:17:20 +0000 (0:00:00.608) 0:00:04.731 ***** 2026-09-15 02:17:20.872239 | instance | skipping: [instance] 2026-09-15 02:17:20.872326 | instance | 2026-09-15 02:17:20.872337 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-15 02:17:20.872347 | instance | Tuesday 15 September 2026 02:17:20 +0000 (0:00:00.062) 0:00:04.793 ***** 2026-09-15 02:17:20.925827 | instance | skipping: [instance] 2026-09-15 02:17:20.925916 | instance | 2026-09-15 02:17:20.925924 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-15 02:17:20.925932 | instance | Tuesday 15 September 2026 02:17:20 +0000 (0:00:00.053) 0:00:04.847 ***** 2026-09-15 02:17:21.188972 | instance | ok: [instance] 2026-09-15 02:17:21.189361 | instance | 2026-09-15 02:17:21.189722 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-15 02:17:21.190047 | instance | Tuesday 15 September 2026 02:17:21 +0000 (0:00:00.263) 0:00:05.110 ***** 2026-09-15 02:17:22.505989 | instance | ok: [instance] 2026-09-15 02:17:22.506256 | instance | 2026-09-15 02:17:22.506574 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-15 02:17:22.506837 | instance | Tuesday 15 September 2026 02:17:22 +0000 (0:00:01.317) 0:00:06.428 ***** 2026-09-15 02:17:22.556109 | instance | ok: [instance] => { 2026-09-15 02:17:22.556498 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-15 02:17:22.556802 | instance | } 2026-09-15 02:17:22.557090 | instance | 2026-09-15 02:17:22.557425 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-15 02:17:22.557821 | instance | Tuesday 15 September 2026 02:17:22 +0000 (0:00:00.050) 0:00:06.478 ***** 2026-09-15 02:17:23.272376 | instance | changed: [instance] 2026-09-15 02:17:23.272757 | instance | 2026-09-15 02:17:23.273078 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-15 02:17:23.273700 | instance | Tuesday 15 September 2026 02:17:23 +0000 (0:00:00.715) 0:00:07.194 ***** 2026-09-15 02:17:26.134489 | instance | changed: [instance] 2026-09-15 02:17:26.135023 | instance | 2026-09-15 02:17:26.135402 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-15 02:17:26.135783 | instance | Tuesday 15 September 2026 02:17:26 +0000 (0:00:02.861) 0:00:10.055 ***** 2026-09-15 02:17:26.173545 | instance | skipping: [instance] 2026-09-15 02:17:26.173907 | instance | 2026-09-15 02:17:26.174671 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-15 02:17:26.175065 | instance | Tuesday 15 September 2026 02:17:26 +0000 (0:00:00.039) 0:00:10.095 ***** 2026-09-15 02:17:26.220895 | instance | skipping: [instance] 2026-09-15 02:17:26.221267 | instance | 2026-09-15 02:17:26.221533 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-15 02:17:26.221779 | instance | Tuesday 15 September 2026 02:17:26 +0000 (0:00:00.046) 0:00:10.142 ***** 2026-09-15 02:17:26.261815 | instance | skipping: [instance] 2026-09-15 02:17:26.262250 | instance | 2026-09-15 02:17:26.262565 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-15 02:17:26.262861 | instance | Tuesday 15 September 2026 02:17:26 +0000 (0:00:00.041) 0:00:10.183 ***** 2026-09-15 02:17:31.731235 | instance | changed: [instance] 2026-09-15 02:17:31.731307 | instance | 2026-09-15 02:17:31.731319 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-15 02:17:31.731420 | instance | Tuesday 15 September 2026 02:17:31 +0000 (0:00:05.468) 0:00:15.652 ***** 2026-09-15 02:17:32.342863 | instance | changed: [instance] 2026-09-15 02:17:32.343193 | instance | 2026-09-15 02:17:32.343810 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-15 02:17:32.343856 | instance | Tuesday 15 September 2026 02:17:32 +0000 (0:00:00.612) 0:00:16.264 ***** 2026-09-15 02:17:33.335377 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-15 02:17:33.336498 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-15 02:17:33.336546 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-15 02:17:33.336559 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-15 02:17:33.336566 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-15 02:17:33.336573 | instance | 2026-09-15 02:17:33.336579 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-15 02:17:33.336584 | instance | Tuesday 15 September 2026 02:17:33 +0000 (0:00:00.992) 0:00:17.257 ***** 2026-09-15 02:17:33.937067 | instance | changed: [instance] 2026-09-15 02:17:33.937105 | instance | 2026-09-15 02:17:33.937112 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-15 02:17:33.937119 | instance | Tuesday 15 September 2026 02:17:33 +0000 (0:00:00.577) 0:00:17.835 ***** 2026-09-15 02:17:33.937124 | instance | 2026-09-15 02:17:33.937130 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-15 02:17:33.937135 | instance | Tuesday 15 September 2026 02:17:33 +0000 (0:00:00.022) 0:00:17.858 ***** 2026-09-15 02:17:34.899546 | instance | ok: [instance] 2026-09-15 02:17:34.900319 | instance | 2026-09-15 02:17:34.900557 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-15 02:17:34.900569 | instance | Tuesday 15 September 2026 02:17:34 +0000 (0:00:00.962) 0:00:18.821 ***** 2026-09-15 02:17:35.394174 | instance | changed: [instance] 2026-09-15 02:17:35.394858 | instance | 2026-09-15 02:17:35.394909 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-15 02:17:35.394921 | instance | Tuesday 15 September 2026 02:17:35 +0000 (0:00:00.494) 0:00:19.316 ***** 2026-09-15 02:17:35.978601 | instance | changed: [instance] 2026-09-15 02:17:35.978721 | instance | 2026-09-15 02:17:35.979081 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-15 02:17:35.979142 | instance | Tuesday 15 September 2026 02:17:35 +0000 (0:00:00.584) 0:00:19.900 ***** 2026-09-15 02:17:36.230032 | instance | ok: [instance] 2026-09-15 02:17:36.230760 | instance | 2026-09-15 02:17:36.230833 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-15 02:17:36.230845 | instance | Tuesday 15 September 2026 02:17:36 +0000 (0:00:00.250) 0:00:20.151 ***** 2026-09-15 02:17:36.308600 | instance | ok: [instance] => { 2026-09-15 02:17:36.308639 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-15 02:17:36.308650 | instance | } 2026-09-15 02:17:36.308659 | instance | 2026-09-15 02:17:36.308668 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-15 02:17:36.308853 | instance | Tuesday 15 September 2026 02:17:36 +0000 (0:00:00.077) 0:00:20.229 ***** 2026-09-15 02:17:37.197831 | instance | changed: [instance] 2026-09-15 02:17:37.197980 | instance | 2026-09-15 02:17:37.198586 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-15 02:17:37.198665 | instance | Tuesday 15 September 2026 02:17:37 +0000 (0:00:00.889) 0:00:21.119 ***** 2026-09-15 02:17:41.599097 | instance | changed: [instance] 2026-09-15 02:17:41.599239 | instance | 2026-09-15 02:17:41.599250 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-15 02:17:41.599352 | instance | Tuesday 15 September 2026 02:17:41 +0000 (0:00:04.402) 0:00:25.521 ***** 2026-09-15 02:17:42.873040 | instance | ok: [instance] 2026-09-15 02:17:42.873254 | instance | 2026-09-15 02:17:42.873571 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-15 02:17:42.873902 | instance | Tuesday 15 September 2026 02:17:42 +0000 (0:00:01.272) 0:00:26.794 ***** 2026-09-15 02:17:43.413943 | instance | changed: [instance] 2026-09-15 02:17:43.414142 | instance | 2026-09-15 02:17:43.414414 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-15 02:17:43.414693 | instance | Tuesday 15 September 2026 02:17:43 +0000 (0:00:00.540) 0:00:27.335 ***** 2026-09-15 02:17:44.002683 | instance | changed: [instance] 2026-09-15 02:17:44.002767 | instance | 2026-09-15 02:17:44.002779 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-15 02:17:44.002789 | instance | Tuesday 15 September 2026 02:17:43 +0000 (0:00:00.588) 0:00:27.923 ***** 2026-09-15 02:17:44.618538 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-15 02:17:44.619241 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-15 02:17:44.619436 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-15 02:17:44.619452 | instance | 2026-09-15 02:17:44.619857 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-15 02:17:44.619935 | instance | Tuesday 15 September 2026 02:17:44 +0000 (0:00:00.616) 0:00:28.539 ***** 2026-09-15 02:17:45.136382 | instance | changed: [instance] 2026-09-15 02:17:45.137166 | instance | 2026-09-15 02:17:45.137232 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-15 02:17:45.137244 | instance | Tuesday 15 September 2026 02:17:45 +0000 (0:00:00.518) 0:00:29.058 ***** 2026-09-15 02:17:45.672960 | instance | changed: [instance] 2026-09-15 02:17:45.673089 | instance | 2026-09-15 02:17:45.673101 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-15 02:17:45.673144 | instance | Tuesday 15 September 2026 02:17:45 +0000 (0:00:00.523) 0:00:29.581 ***** 2026-09-15 02:17:45.673492 | instance | 2026-09-15 02:17:45.673553 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-15 02:17:45.673566 | instance | Tuesday 15 September 2026 02:17:45 +0000 (0:00:00.013) 0:00:29.595 ***** 2026-09-15 02:17:46.437912 | instance | ok: [instance] 2026-09-15 02:17:46.437983 | instance | 2026-09-15 02:17:46.437994 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-15 02:17:46.438003 | instance | Tuesday 15 September 2026 02:17:46 +0000 (0:00:00.763) 0:00:30.358 ***** 2026-09-15 02:17:47.330497 | instance | changed: [instance] 2026-09-15 02:17:47.331308 | instance | 2026-09-15 02:17:47.331341 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-15 02:17:47.331352 | instance | Tuesday 15 September 2026 02:17:47 +0000 (0:00:00.893) 0:00:31.252 ***** 2026-09-15 02:17:47.918100 | instance | changed: [instance] 2026-09-15 02:17:47.918199 | instance | 2026-09-15 02:17:47.918573 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-15 02:17:47.918609 | instance | Tuesday 15 September 2026 02:17:47 +0000 (0:00:00.588) 0:00:31.840 ***** 2026-09-15 02:17:47.992948 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-15 02:17:47.993680 | instance | 2026-09-15 02:17:47.993714 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-15 02:17:47.993725 | instance | Tuesday 15 September 2026 02:17:47 +0000 (0:00:00.074) 0:00:31.914 ***** 2026-09-15 02:17:55.792826 | instance | changed: [instance] 2026-09-15 02:17:55.793636 | instance | 2026-09-15 02:17:55.793693 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-15 02:17:55.793705 | instance | Tuesday 15 September 2026 02:17:55 +0000 (0:00:07.799) 0:00:39.714 ***** 2026-09-15 02:17:56.472819 | instance | ok: [instance] => (item=chronyd) 2026-09-15 02:17:56.473371 | instance | ok: [instance] => (item=sshd) 2026-09-15 02:17:56.473388 | instance | 2026-09-15 02:17:56.473394 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-15 02:17:56.473401 | instance | Tuesday 15 September 2026 02:17:56 +0000 (0:00:00.680) 0:00:40.395 ***** 2026-09-15 02:18:13.183887 | instance | changed: [instance] 2026-09-15 02:18:13.184016 | instance | 2026-09-15 02:18:13.184250 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-15 02:18:13.184506 | instance | Tuesday 15 September 2026 02:18:13 +0000 (0:00:16.709) 0:00:57.104 ***** 2026-09-15 02:18:13.410905 | instance | ok: [instance] 2026-09-15 02:18:13.411354 | instance | 2026-09-15 02:18:13.411649 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-15 02:18:13.411907 | instance | Tuesday 15 September 2026 02:18:13 +0000 (0:00:00.226) 0:00:57.331 ***** 2026-09-15 02:18:13.983527 | instance | changed: [instance] 2026-09-15 02:18:13.983654 | instance | 2026-09-15 02:18:13.983819 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-15 02:18:13.983968 | instance | Tuesday 15 September 2026 02:18:13 +0000 (0:00:00.573) 0:00:57.905 ***** 2026-09-15 02:18:14.406153 | instance | changed: [instance] 2026-09-15 02:18:14.406421 | instance | 2026-09-15 02:18:14.406695 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-15 02:18:14.406955 | instance | Tuesday 15 September 2026 02:18:14 +0000 (0:00:00.421) 0:00:58.327 ***** 2026-09-15 02:18:14.540433 | instance | ok: [instance] 2026-09-15 02:18:14.540682 | instance | 2026-09-15 02:18:14.540947 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-15 02:18:14.541229 | instance | Tuesday 15 September 2026 02:18:14 +0000 (0:00:00.134) 0:00:58.462 ***** 2026-09-15 02:18:14.781130 | instance | ok: [instance] => (item=instance) 2026-09-15 02:18:14.781526 | instance | 2026-09-15 02:18:14.781939 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-15 02:18:14.782331 | instance | Tuesday 15 September 2026 02:18:14 +0000 (0:00:00.240) 0:00:58.702 ***** 2026-09-15 02:18:14.853022 | instance | ok: [instance] 2026-09-15 02:18:14.853291 | instance | 2026-09-15 02:18:14.853591 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-15 02:18:14.853846 | instance | Tuesday 15 September 2026 02:18:14 +0000 (0:00:00.072) 0:00:58.774 ***** 2026-09-15 02:18:14.942200 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-15 02:18:14.942543 | instance | 2026-09-15 02:18:14.942841 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-15 02:18:14.943182 | instance | Tuesday 15 September 2026 02:18:14 +0000 (0:00:00.088) 0:00:58.863 ***** 2026-09-15 02:18:15.344959 | instance | changed: [instance] 2026-09-15 02:18:15.345037 | instance | 2026-09-15 02:18:15.345157 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-15 02:18:15.345285 | instance | Tuesday 15 September 2026 02:18:15 +0000 (0:00:00.402) 0:00:59.266 ***** 2026-09-15 02:18:16.159970 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-15 02:18:16.160169 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-15 02:18:16.160326 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-15 02:18:16.160523 | instance | 2026-09-15 02:18:16.160686 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-15 02:18:16.160853 | instance | Tuesday 15 September 2026 02:18:16 +0000 (0:00:00.815) 0:01:00.081 ***** 2026-09-15 02:19:52.002364 | instance | ok: [instance] 2026-09-15 02:19:52.002628 | instance | 2026-09-15 02:19:52.002875 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-15 02:19:52.003162 | instance | Tuesday 15 September 2026 02:19:51 +0000 (0:01:35.841) 0:02:35.923 ***** 2026-09-15 02:19:52.252225 | instance | changed: [instance] 2026-09-15 02:19:52.252442 | instance | 2026-09-15 02:19:52.252720 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-15 02:19:52.252972 | instance | Tuesday 15 September 2026 02:19:52 +0000 (0:00:00.250) 0:02:36.174 ***** 2026-09-15 02:19:52.335905 | instance | ok: [instance] 2026-09-15 02:19:52.336114 | instance | 2026-09-15 02:19:52.336364 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-15 02:19:52.336651 | instance | Tuesday 15 September 2026 02:19:52 +0000 (0:00:00.084) 0:02:36.258 ***** 2026-09-15 02:19:52.424156 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-15 02:19:52.424383 | instance | 2026-09-15 02:19:52.424651 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-15 02:19:52.424892 | instance | Tuesday 15 September 2026 02:19:52 +0000 (0:00:00.088) 0:02:36.346 ***** 2026-09-15 02:19:54.126585 | instance | ok: [instance] 2026-09-15 02:19:54.126945 | instance | 2026-09-15 02:19:54.127470 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-15 02:19:54.127906 | instance | Tuesday 15 September 2026 02:19:54 +0000 (0:00:01.702) 0:02:38.048 ***** 2026-09-15 02:19:54.208893 | instance | ok: [instance] => (item=instance) 2026-09-15 02:19:54.209100 | instance | 2026-09-15 02:19:54.209401 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-15 02:19:54.209795 | instance | Tuesday 15 September 2026 02:19:54 +0000 (0:00:00.081) 0:02:38.130 ***** 2026-09-15 02:19:54.590196 | instance | ok: [instance] 2026-09-15 02:19:54.590370 | instance | 2026-09-15 02:19:54.590752 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-15 02:19:54.591193 | instance | Tuesday 15 September 2026 02:19:54 +0000 (0:00:00.381) 0:02:38.511 ***** 2026-09-15 02:19:56.962314 | instance | ok: [instance] 2026-09-15 02:19:56.962504 | instance | 2026-09-15 02:19:56.962849 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-15 02:19:56.963200 | instance | Tuesday 15 September 2026 02:19:56 +0000 (0:00:02.372) 0:02:40.883 ***** 2026-09-15 02:20:51.608135 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-15 02:20:51.608226 | 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_upqa6pm7/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_upqa6pm7/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_upqa6pm7/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_upqa6pm7/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-15T02:20:51.411+0000 7f49f95a8640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAeq6hq+c+3JCAA4FR4bP9m+MNMy9lAKbRIiiWA5pofJK5TNwi/BRai1Bc=: Malformed input [buffer:3]\n2026-09-15T02:20:51.411+0000 7f49f95a8640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T02:20:51.411+0000 7f49f95a8640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAeq6hq+c+3JCAA4FR4bP9m+MNMy9lAKbRIiiWA5pofJK5TNwi/BRai1Bc=: Malformed input [buffer:3]\n2026-09-15T02:20:51.411+0000 7f49f95a8640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T02:20:51.411+0000 7f49f95a8640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAeq6hq+c+3JCAA4FR4bP9m+MNMy9lAKbRIiiWA5pofJK5TNwi/BRai1Bc=: Malformed input [buffer:3]\n2026-09-15T02:20:51.411+0000 7f49f95a8640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T02:20:51.411+0000 7f49f95a8640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-15 02:20:51.610566 | instance | 2026-09-15 02:20:51.611224 | instance | PLAY RECAP ********************************************************************* 2026-09-15 02:20:51.611390 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-15 02:20:51.615220 | instance | 2026-09-15 02:20:51.615258 | instance | Tuesday 15 September 2026 02:20:51 +0000 (0:00:54.648) 0:03:35.532 ***** 2026-09-15 02:20:51.615275 | instance | =============================================================================== 2026-09-15 02:20:51.615288 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 95.84s 2026-09-15 02:20:51.615302 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 54.65s 2026-09-15 02:20:51.615315 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 16.71s 2026-09-15 02:20:51.615329 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 7.80s 2026-09-15 02:20:51.615342 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.47s 2026-09-15 02:20:51.615355 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.40s 2026-09-15 02:20:51.615387 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.86s 2026-09-15 02:20:51.615403 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.37s 2026-09-15 02:20:51.615415 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.73s 2026-09-15 02:20:51.615428 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.70s 2026-09-15 02:20:51.615441 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.32s 2026-09-15 02:20:51.615454 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.27s 2026-09-15 02:20:51.615467 | instance | Gathering Facts --------------------------------------------------------- 1.05s 2026-09-15 02:20:51.615480 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.99s 2026-09-15 02:20:51.615549 | instance | Gathering Facts --------------------------------------------------------- 0.98s 2026-09-15 02:20:51.615894 | instance | Gathering Facts --------------------------------------------------------- 0.93s 2026-09-15 02:20:51.616271 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.89s 2026-09-15 02:20:51.616588 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.89s 2026-09-15 02:20:51.616910 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.82s 2026-09-15 02:20:51.617193 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.72s 2026-09-15 02:20:51.721442 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-15 02:20:51.721750 | instance | ERROR [csi > converge] Executed: Failed 2026-09-15 02:20:51.721938 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-15 02:20:52.101329 | instance | ERROR 2026-09-15 02:20:52.101649 | instance | { 2026-09-15 02:20:52.101707 | instance | "delta": "0:04:27.717935", 2026-09-15 02:20:52.101746 | instance | "end": "2026-09-15 02:20:51.789905", 2026-09-15 02:20:52.101782 | instance | "msg": "non-zero return code", 2026-09-15 02:20:52.101817 | instance | "rc": 2, 2026-09-15 02:20:52.101853 | instance | "start": "2026-09-15 02:16:24.071970" 2026-09-15 02:20:52.101889 | instance | } failure 2026-09-15 02:20:52.111583 | 2026-09-15 02:20:52.111690 | PLAY RECAP 2026-09-15 02:20:52.111774 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-15 02:20:52.111818 | 2026-09-15 02:20:52.365831 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-15 02:20:52.369935 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-15 02:20:54.126103 | 2026-09-15 02:20:54.126285 | PLAY [all] 2026-09-15 02:20:54.156634 | 2026-09-15 02:20:54.156757 | TASK [gather-host-logs : creating directory for system status] 2026-09-15 02:20:54.678218 | instance | changed 2026-09-15 02:20:54.688786 | 2026-09-15 02:20:54.688979 | TASK [gather-host-logs : Get logs for each host] 2026-09-15 02:20:55.152445 | instance | + systemd-cgls --full --all --no-pager 2026-09-15 02:20:55.179048 | instance | + ip addr 2026-09-15 02:20:55.197966 | instance | + ip route 2026-09-15 02:20:55.200484 | instance | + lsblk 2026-09-15 02:20:55.205943 | instance | + mount 2026-09-15 02:20:55.208451 | instance | + docker images 2026-09-15 02:20:55.235433 | instance | + brctl show 2026-09-15 02:20:55.235970 | instance | /bin/bash: line 8: brctl: command not found 2026-09-15 02:20:55.236219 | instance | + ip neigh 2026-09-15 02:20:55.238415 | instance | + bridge fdb show 2026-09-15 02:20:55.242087 | instance | + ps aux --sort=-%mem 2026-09-15 02:20:55.268005 | instance | + dpkg -l 2026-09-15 02:20:55.276103 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-15 02:20:55.276676 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-15 02:20:55.297588 | instance | + '[' '!' -z '' ']' 2026-09-15 02:20:55.734995 | instance | ok: Runtime: 0:00:00.150908 2026-09-15 02:20:55.746437 | 2026-09-15 02:20:55.746579 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-15 02:20:56.411035 | instance | changed: 2026-09-15 02:20:56.411364 | instance | created directory /var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/work/logs/instance 2026-09-15 02:20:56.411452 | instance | cd+++++++++ system/ 2026-09-15 02:20:56.411521 | instance | >f+++++++++ system/brctl-show.txt 2026-09-15 02:20:56.411584 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-15 02:20:56.411643 | instance | >f+++++++++ system/docker-images.txt 2026-09-15 02:20:56.411710 | instance | >f+++++++++ system/ip-addr.txt 2026-09-15 02:20:56.411776 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-15 02:20:56.411829 | instance | >f+++++++++ system/ip-route.txt 2026-09-15 02:20:56.411873 | instance | >f+++++++++ system/lsblk.txt 2026-09-15 02:20:56.411909 | instance | >f+++++++++ system/mount.txt 2026-09-15 02:20:56.411950 | instance | >f+++++++++ system/packages.txt 2026-09-15 02:20:56.412025 | instance | >f+++++++++ system/ps.txt 2026-09-15 02:20:56.412063 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-15 02:20:56.431382 | 2026-09-15 02:20:56.431549 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-15 02:20:56.663527 | instance | changed: "values" 2026-09-15 02:20:56.894993 | instance | changed: "releases" 2026-09-15 02:20:56.915541 | 2026-09-15 02:20:56.915715 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-15 02:20:57.174517 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-15 02:20:57.464985 | instance | ok: Runtime: 0:00:00.004787 2026-09-15 02:20:57.474701 | 2026-09-15 02:20:57.474839 | TASK [helm-release-status : Downloads logs to executor] 2026-09-15 02:20:58.024301 | instance | changed: 2026-09-15 02:20:58.024536 | instance | cd+++++++++ helm/ 2026-09-15 02:20:58.024574 | instance | cd+++++++++ helm/releases/ 2026-09-15 02:20:58.024601 | instance | cd+++++++++ helm/values/ 2026-09-15 02:20:58.042630 | 2026-09-15 02:20:58.042723 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-15 02:20:58.264132 | instance | changed 2026-09-15 02:20:58.275264 | 2026-09-15 02:20:58.275427 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-15 02:20:58.561899 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-15 02:20:58.561982 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-15 02:20:58.568612 | instance | environment: line 1: kubectl: command not found 2026-09-15 02:20:58.569834 | instance | environment: line 1: kubectl: command not found 2026-09-15 02:20:58.570448 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-15 02:20:58.571880 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-15 02:20:58.574793 | instance | environment: line 1: kubectl: command not found 2026-09-15 02:20:58.576135 | instance | environment: line 1: kubectl: command not found 2026-09-15 02:20:58.576166 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-15 02:20:58.577533 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-15 02:20:58.579980 | instance | environment: line 1: kubectl: command not found 2026-09-15 02:20:58.581604 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-15 02:20:58.817177 | instance | ok: Runtime: 0:00:00.027742 2026-09-15 02:20:58.826256 | 2026-09-15 02:20:58.826380 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-15 02:20:59.063304 | instance | changed 2026-09-15 02:20:59.072782 | 2026-09-15 02:20:59.072925 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-15 02:20:59.340774 | instance | environment: line 5: kubectl: command not found 2026-09-15 02:20:59.341146 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-15 02:20:59.341533 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-15 02:20:59.342409 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-15 02:20:59.622224 | instance | ok: Runtime: 0:00:00.009793 2026-09-15 02:20:59.633031 | 2026-09-15 02:20:59.633214 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-15 02:21:00.194636 | instance | changed: 2026-09-15 02:21:00.194914 | instance | cd+++++++++ objects/ 2026-09-15 02:21:00.194996 | instance | cd+++++++++ objects/cluster/ 2026-09-15 02:21:00.195044 | instance | cd+++++++++ objects/namespaced/ 2026-09-15 02:21:00.211981 | 2026-09-15 02:21:00.212124 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-15 02:21:00.435575 | instance | changed 2026-09-15 02:21:00.439991 | 2026-09-15 02:21:00.440055 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-15 02:21:00.664244 | instance | changed 2026-09-15 02:21:00.671103 | 2026-09-15 02:21:00.671323 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-15 02:21:00.912801 | instance | environment: line 3: kubectl: command not found 2026-09-15 02:21:01.210069 | instance | ok: Runtime: 0:00:00.009369 2026-09-15 02:21:01.219414 | 2026-09-15 02:21:01.219563 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-15 02:21:01.760942 | instance | changed: 2026-09-15 02:21:01.761230 | instance | cd+++++++++ pod-logs/ 2026-09-15 02:21:01.761285 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-15 02:21:01.779442 | 2026-09-15 02:21:01.779614 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-15 02:21:02.007550 | instance | changed 2026-09-15 02:21:02.017283 | 2026-09-15 02:21:02.017447 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-15 02:21:02.279091 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-15 02:21:02.566544 | instance | ok: Runtime: 0:00:00.055057 2026-09-15 02:21:02.576359 | 2026-09-15 02:21:02.576817 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-15 02:21:02.826124 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-15 02:21:02.872673 | instance | ceph-mgr endpoints: 2026-09-15 02:21:03.121018 | instance | ok: Runtime: 0:00:00.053783 2026-09-15 02:21:03.131854 | 2026-09-15 02:21:03.132008 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-15 02:21:03.431067 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-15 02:21:03.679515 | instance | ok: Runtime: 0:00:00.051363 2026-09-15 02:21:03.691895 | 2026-09-15 02:21:03.692102 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-15 02:21:04.265117 | instance | changed: cd+++++++++ prometheus/ 2026-09-15 02:21:04.282902 | 2026-09-15 02:21:04.283037 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-15 02:21:04.511809 | instance | changed 2026-09-15 02:21:04.521854 | 2026-09-15 02:21:04.522012 | TASK [gather-selenium-data : Get selenium data] 2026-09-15 02:21:04.819876 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-15 02:21:04.821354 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-15 02:21:05.073845 | instance | ERROR 2026-09-15 02:21:05.074184 | instance | { 2026-09-15 02:21:05.074246 | instance | "delta": "0:00:00.005986", 2026-09-15 02:21:05.074287 | instance | "end": "2026-09-15 02:21:04.821689", 2026-09-15 02:21:05.074322 | instance | "msg": "non-zero return code", 2026-09-15 02:21:05.074357 | instance | "rc": 1, 2026-09-15 02:21:05.074390 | instance | "start": "2026-09-15 02:21:04.815703" 2026-09-15 02:21:05.074434 | instance | } 2026-09-15 02:21:05.074502 | instance | ERROR: Ignoring Errors 2026-09-15 02:21:05.086167 | 2026-09-15 02:21:05.086337 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-15 02:21:05.651964 | instance | changed: cd+++++++++ selenium/ 2026-09-15 02:21:05.667175 | 2026-09-15 02:21:05.667324 | PLAY RECAP 2026-09-15 02:21:05.667435 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-15 02:21:05.667496 | 2026-09-15 02:21:05.812010 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-15 02:21:05.816021 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-15 02:21:06.426568 | 2026-09-15 02:21:06.426737 | PLAY [all] 2026-09-15 02:21:06.438592 | 2026-09-15 02:21:06.438710 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-15 02:21:06.503068 | instance | skipping: Conditional result was False 2026-09-15 02:21:06.508667 | 2026-09-15 02:21:06.508747 | TASK [fetch-output : Set log path for single node] 2026-09-15 02:21:06.556584 | instance | ok 2026-09-15 02:21:06.560630 | 2026-09-15 02:21:06.560695 | LOOP [fetch-output : Ensure local output dirs] 2026-09-15 02:21:06.981500 | instance -> localhost | ok: "/var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/work/logs" 2026-09-15 02:21:07.210457 | instance -> localhost | changed: "/var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/work/artifacts" 2026-09-15 02:21:07.435890 | instance -> localhost | changed: "/var/lib/zuul/builds/ea19a83084c44f4ab785fc05ae9b29b2/work/docs" 2026-09-15 02:21:07.461507 | 2026-09-15 02:21:07.461709 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-15 02:21:08.164863 | instance | changed: .d..t...... ./ 2026-09-15 02:21:08.165192 | instance | changed: All items complete 2026-09-15 02:21:08.165246 | 2026-09-15 02:21:08.681828 | instance | changed: .d..t...... ./ 2026-09-15 02:21:09.184295 | instance | changed: .d..t...... ./ 2026-09-15 02:21:09.211880 | 2026-09-15 02:21:09.212105 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-15 02:21:09.658757 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.010716 2026-09-15 02:21:09.921639 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006849 2026-09-15 02:21:09.938029 | 2026-09-15 02:21:09.938183 | PLAY [all] 2026-09-15 02:21:09.946540 | 2026-09-15 02:21:09.946640 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-15 02:21:10.342832 | instance | changed 2026-09-15 02:21:10.350496 | 2026-09-15 02:21:10.350588 | PLAY RECAP 2026-09-15 02:21:10.350635 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-15 02:21:10.350657 | 2026-09-15 02:21:10.500868 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-15 02:21:10.504911 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-15 02:21:11.132182 | 2026-09-15 02:21:11.132336 | PLAY [localhost] 2026-09-15 02:21:11.142479 | 2026-09-15 02:21:11.142557 | TASK [Generate Zuul manifest] 2026-09-15 02:21:11.159713 | localhost | ok 2026-09-15 02:21:11.175583 | 2026-09-15 02:21:11.175669 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-15 02:21:11.568402 | localhost | changed 2026-09-15 02:21:11.579544 | 2026-09-15 02:21:11.579623 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-15 02:21:11.605666 | localhost | ok 2026-09-15 02:21:11.612573 | 2026-09-15 02:21:11.612636 | TASK [Upload logs] 2026-09-15 02:21:11.629794 | localhost | ok 2026-09-15 02:21:11.682561 | 2026-09-15 02:21:11.682696 | TASK [Set zuul-log-path fact] 2026-09-15 02:21:11.699180 | localhost | ok 2026-09-15 02:21:11.710307 | 2026-09-15 02:21:11.710376 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-15 02:21:11.736688 | localhost | ok 2026-09-15 02:21:11.742857 | 2026-09-15 02:21:11.742946 | TASK [upload-logs : Create log directories] 2026-09-15 02:21:12.158024 | localhost | changed 2026-09-15 02:21:12.168463 | 2026-09-15 02:21:12.168635 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-15 02:21:12.576049 | localhost -> localhost | ok: Runtime: 0:00:00.004578 2026-09-15 02:21:12.584947 | 2026-09-15 02:21:12.585094 | TASK [upload-logs : Upload logs to log server] 2026-09-15 02:21:13.103251 | localhost | Output suppressed because no_log was given 2026-09-15 02:21:13.158628 | 2026-09-15 02:21:13.158854 | LOOP [upload-logs : Compress console log and json output] 2026-09-15 02:21:13.203872 | localhost | skipping: Conditional result was False 2026-09-15 02:21:13.213309 | localhost | skipping: Conditional result was False 2026-09-15 02:21:13.222132 | 2026-09-15 02:21:13.222347 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-15 02:21:13.282935 | localhost | skipping: Conditional result was False 2026-09-15 02:21:13.283489 | 2026-09-15 02:21:13.288821 | localhost | skipping: Conditional result was False 2026-09-15 02:21:13.306472 | 2026-09-15 02:21:13.306672 | LOOP [upload-logs : Upload console log and json output]