2026-09-01 03:43:01.991903 | Job console starting 2026-09-01 03:43:02.000300 | Updating git repos 2026-09-01 03:43:05.221169 | Cloning repos into workspace 2026-09-01 03:43:24.957349 | Restoring repo states 2026-09-01 03:43:32.837785 | Merging changes 2026-09-01 03:43:33.863604 | Checking out repos 2026-09-01 03:43:35.581885 | Preparing playbooks 2026-09-01 03:43:37.956140 | Running Ansible setup 2026-09-01 03:43:41.694677 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 03:43:42.339206 | 2026-09-01 03:43:42.503490 | PLAY [localhost] 2026-09-01 03:43:42.518244 | 2026-09-01 03:43:42.518336 | TASK [Gathering Facts] 2026-09-01 03:43:43.469329 | localhost | ok 2026-09-01 03:43:43.478037 | 2026-09-01 03:43:43.478131 | TASK [Setup log path fact] 2026-09-01 03:43:43.497381 | localhost | ok 2026-09-01 03:43:43.508618 | 2026-09-01 03:43:43.508687 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 03:43:43.538806 | localhost | ok 2026-09-01 03:43:43.547715 | 2026-09-01 03:43:43.547817 | TASK [emit-job-header : Print job information] 2026-09-01 03:43:43.588889 | # Job Information 2026-09-01 03:43:43.589088 | Ansible Version: 2.16.18 2026-09-01 03:43:43.589136 | Job: atmosphere-molecule-csi-rbd 2026-09-01 03:43:43.589168 | Pipeline: check 2026-09-01 03:43:43.589198 | Executor: 2d72f0692154 2026-09-01 03:43:43.589227 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4232 2026-09-01 03:43:43.589261 | Event ID: b6282070-a5b6-11f1-9344-51735f37e29e 2026-09-01 03:43:43.593528 | 2026-09-01 03:43:43.593622 | LOOP [emit-job-header : Print node information] 2026-09-01 03:43:43.681599 | localhost | ok: 2026-09-01 03:43:43.682047 | localhost | # Node Information 2026-09-01 03:43:43.682081 | localhost | Inventory Hostname: instance 2026-09-01 03:43:43.682105 | localhost | Hostname: np0000191649 2026-09-01 03:43:43.682126 | localhost | Username: zuul 2026-09-01 03:43:43.682149 | localhost | Distro: Ubuntu 22.04 2026-09-01 03:43:43.682170 | localhost | Provider: yul1 2026-09-01 03:43:43.682189 | localhost | Region: ca-ymq-1 2026-09-01 03:43:43.682208 | localhost | Label: ubuntu-jammy 2026-09-01 03:43:43.682228 | localhost | Product Name: OpenStack Nova 2026-09-01 03:43:43.682246 | localhost | Interface IP: 199.204.45.58 2026-09-01 03:43:43.697893 | 2026-09-01 03:43:43.698006 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-01 03:43:44.056568 | localhost -> localhost | changed 2026-09-01 03:43:44.061715 | 2026-09-01 03:43:44.061784 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-01 03:43:44.893501 | localhost -> localhost | changed 2026-09-01 03:43:44.902589 | 2026-09-01 03:43:44.902672 | PLAY [all] 2026-09-01 03:43:44.912729 | 2026-09-01 03:43:44.912811 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-01 03:43:45.149748 | instance -> localhost | ok 2026-09-01 03:43:45.159790 | 2026-09-01 03:43:45.159884 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-01 03:43:45.191163 | instance | ok 2026-09-01 03:43:45.206370 | instance | included: /var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-01 03:43:45.212042 | 2026-09-01 03:43:45.212102 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-01 03:43:46.016496 | instance -> localhost | Generating public/private rsa key pair. 2026-09-01 03:43:46.016769 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/work/4a3a183ea5d64932b23c499f7cc0f801_id_rsa 2026-09-01 03:43:46.016815 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/work/4a3a183ea5d64932b23c499f7cc0f801_id_rsa.pub 2026-09-01 03:43:46.016850 | instance -> localhost | The key fingerprint is: 2026-09-01 03:43:46.016883 | instance -> localhost | SHA256:HKD9EzdW8FNsJw4oXhIdrJ+EuR50w0Ty4BNDv7XbkZU zuul-build-sshkey 2026-09-01 03:43:46.016930 | instance -> localhost | The key's randomart image is: 2026-09-01 03:43:46.016982 | instance -> localhost | +---[RSA 3072]----+ 2026-09-01 03:43:46.017024 | instance -> localhost | | ..B=++... | 2026-09-01 03:43:46.017067 | instance -> localhost | | o ooB*o..+ o| 2026-09-01 03:43:46.017099 | instance -> localhost | | . ..=X* ++ E.| 2026-09-01 03:43:46.017129 | instance -> localhost | | o**=+ o.o | 2026-09-01 03:43:46.017159 | instance -> localhost | | .S=.o. o | 2026-09-01 03:43:46.017189 | instance -> localhost | | o.o o . | 2026-09-01 03:43:46.017217 | instance -> localhost | | . . . . | 2026-09-01 03:43:46.017246 | instance -> localhost | | . | 2026-09-01 03:43:46.017279 | instance -> localhost | | | 2026-09-01 03:43:46.017309 | instance -> localhost | +----[SHA256]-----+ 2026-09-01 03:43:46.017376 | instance -> localhost | ok: Runtime: 0:00:00.374652 2026-09-01 03:43:46.024833 | 2026-09-01 03:43:46.024899 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-01 03:43:46.056876 | instance | ok 2026-09-01 03:43:46.066763 | instance | included: /var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-01 03:43:46.074045 | 2026-09-01 03:43:46.074107 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-01 03:43:46.099197 | instance | skipping: Conditional result was False 2026-09-01 03:43:46.107718 | 2026-09-01 03:43:46.107809 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-01 03:43:46.596136 | instance | changed 2026-09-01 03:43:46.603109 | 2026-09-01 03:43:46.603175 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-01 03:43:46.808964 | instance | ok 2026-09-01 03:43:46.886636 | 2026-09-01 03:43:46.886704 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-01 03:43:47.368549 | instance | changed 2026-09-01 03:43:47.373734 | 2026-09-01 03:43:47.373803 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-01 03:43:47.903081 | instance | changed 2026-09-01 03:43:47.909996 | 2026-09-01 03:43:47.910066 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-01 03:43:47.935927 | instance | skipping: Conditional result was False 2026-09-01 03:43:47.945691 | 2026-09-01 03:43:47.945786 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-01 03:43:48.309420 | instance -> localhost | changed 2026-09-01 03:43:48.324323 | 2026-09-01 03:43:48.324388 | TASK [add-build-sshkey : Add back temp key] 2026-09-01 03:43:48.603766 | instance -> localhost | Identity added: /var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/work/4a3a183ea5d64932b23c499f7cc0f801_id_rsa (zuul-build-sshkey) 2026-09-01 03:43:48.603986 | instance -> localhost | ok: Runtime: 0:00:00.018286 2026-09-01 03:43:48.608987 | 2026-09-01 03:43:48.609070 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-01 03:43:48.942365 | instance | ok 2026-09-01 03:43:48.947659 | 2026-09-01 03:43:48.947737 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-01 03:43:48.972849 | instance | skipping: Conditional result was False 2026-09-01 03:43:48.985696 | 2026-09-01 03:43:48.985765 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-01 03:43:49.347383 | instance | ok 2026-09-01 03:43:49.501153 | 2026-09-01 03:43:49.501368 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-01 03:43:53.033919 | instance | Output suppressed because no_log was given 2026-09-01 03:43:53.044179 | 2026-09-01 03:43:53.044248 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-01 03:43:53.256266 | instance | ok: "logs" 2026-09-01 03:43:53.256537 | instance | ok: All items complete 2026-09-01 03:43:53.256566 | 2026-09-01 03:43:53.422784 | instance | ok: "artifacts" 2026-09-01 03:43:53.587239 | instance | ok: "docs" 2026-09-01 03:43:53.601725 | 2026-09-01 03:43:53.601904 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-01 03:43:53.793856 | instance | changed: "logs" 2026-09-01 03:43:53.947335 | instance | changed: "artifacts" 2026-09-01 03:43:54.093414 | instance | changed: "docs" 2026-09-01 03:43:54.114223 | 2026-09-01 03:43:54.114361 | PLAY RECAP 2026-09-01 03:43:54.114412 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-01 03:43:54.114440 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:43:54.114461 | 2026-09-01 03:43:54.278920 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 03:43:54.281233 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 03:43:54.905986 | 2026-09-01 03:43:54.906136 | PLAY [all] 2026-09-01 03:43:54.917230 | 2026-09-01 03:43:54.917305 | TASK [setup-uv : Extract archive] 2026-09-01 03:43:58.307202 | instance | changed 2026-09-01 03:43:58.313060 | 2026-09-01 03:43:58.313162 | TASK [setup-uv : Print version] 2026-09-01 03:43:58.692690 | instance | uv 0.8.13 2026-09-01 03:43:58.853488 | instance | ok: Runtime: 0:00:00.015629 2026-09-01 03:43:58.865360 | 2026-09-01 03:43:58.865442 | TASK [Install Ansible collection siblings] 2026-09-01 03:44:08.831445 | instance | changed: 2026-09-01 03:44:08.831637 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-01 03:44:08.831667 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-01 03:44:08.831688 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-01 03:44:08.831709 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-01 03:44:08.831728 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-01 03:44:08.831752 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-01 03:44:08.831773 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-01 03:44:08.831795 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-01 03:44:08.831815 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-01 03:44:08.831835 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-01 03:44:08.831854 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-01 03:44:08.831916 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-01 03:44:08.831942 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-01 03:44:08.831965 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-01 03:44:08.831990 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-01 03:44:08.832014 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-01 03:44:08.832037 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-01 03:44:08.832060 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-01 03:44:08.832083 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-01 03:44:08.832106 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-01 03:44:08.832128 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-01 03:44:08.832151 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-01 03:44:08.832175 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 03:44:08.832227 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-01 03:44:08.832253 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-01 03:44:08.832277 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 03:44:08.832296 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 03:44:08.832315 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 03:44:08.832333 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-01 03:44:08.832351 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-01 03:44:08.832369 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 03:44:08.832388 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 03:44:08.832407 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-01 03:44:08.832426 | 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-01 03:44:08.832452 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.832470 | instance | Process install dependency map 2026-09-01 03:44:08.832488 | instance | Starting collection install process 2026-09-01 03:44:08.832507 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-01 03:44:08.832525 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-01 03:44:08.832543 | instance | ansible.netcommon:* was installed successfully 2026-09-01 03:44:08.832561 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-01 03:44:08.832578 | instance | Creating virtual environment at: .venv 2026-09-01 03:44:08.832596 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 03:44:08.832615 | instance | Downloading pydantic-core (2.0MiB) 2026-09-01 03:44:08.832632 | instance | Downloading kubernetes (1.9MiB) 2026-09-01 03:44:08.832651 | instance | Downloading openstacksdk (1.7MiB) 2026-09-01 03:44:08.832670 | instance | Downloading pygments (1.2MiB) 2026-09-01 03:44:08.832688 | instance | Downloading ansible-core (2.1MiB) 2026-09-01 03:44:08.832707 | instance | Downloading netaddr (2.2MiB) 2026-09-01 03:44:08.832725 | instance | Downloading rjsonnet (1.2MiB) 2026-09-01 03:44:08.832744 | instance | Downloading setuptools (1.1MiB) 2026-09-01 03:44:08.832762 | instance | Downloading cryptography (4.2MiB) 2026-09-01 03:44:08.832780 | instance | Building pyperclip==1.9.0 2026-09-01 03:44:08.832799 | instance | Downloading rjsonnet 2026-09-01 03:44:08.832818 | instance | Downloading pydantic-core 2026-09-01 03:44:08.832836 | instance | Downloading netaddr 2026-09-01 03:44:08.832854 | instance | Downloading pygments 2026-09-01 03:44:08.832872 | instance | Downloading cryptography 2026-09-01 03:44:08.832890 | instance | Downloading setuptools 2026-09-01 03:44:08.832908 | instance | Downloading kubernetes 2026-09-01 03:44:08.832926 | instance | Downloading ansible-core 2026-09-01 03:44:08.832944 | instance | Downloading openstacksdk 2026-09-01 03:44:08.832980 | instance | Built pyperclip==1.9.0 2026-09-01 03:44:08.833001 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 03:44:08.833019 | instance | Installed 83 packages in 55ms 2026-09-01 03:44:08.833037 | instance | Checked out ansible.posix to 1.6.0 2026-09-01 03:44:08.833055 | 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-01 03:44:08.833073 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.833091 | instance | Process install dependency map 2026-09-01 03:44:08.833114 | instance | Starting collection install process 2026-09-01 03:44:08.833133 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-01 03:44:08.833151 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-01 03:44:08.833169 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-01 03:44:08.833187 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.833204 | instance | 2.17.12 2026-09-01 03:44:08.833223 | instance | Checked out ansible.utils to v6.1.0 2026-09-01 03:44:08.833241 | 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-01 03:44:08.833259 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.833277 | instance | Process install dependency map 2026-09-01 03:44:08.833295 | instance | Starting collection install process 2026-09-01 03:44:08.833313 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-01 03:44:08.833332 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-01 03:44:08.833350 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-01 03:44:08.833368 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.833386 | instance | 2.17.12 2026-09-01 03:44:08.833404 | instance | Checked out atmosphere.common to v0.7.0 2026-09-01 03:44:08.833422 | 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-01 03:44:08.833441 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.833458 | instance | Process install dependency map 2026-09-01 03:44:08.833476 | instance | Starting collection install process 2026-09-01 03:44:08.833494 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-01 03:44:08.833513 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-01 03:44:08.833531 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-01 03:44:08.833549 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.833567 | instance | 2.17.12 2026-09-01 03:44:08.833586 | instance | Checked out community.crypto to 2.2.3 2026-09-01 03:44:08.833604 | 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-01 03:44:08.833622 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.833640 | instance | Process install dependency map 2026-09-01 03:44:08.833658 | instance | Starting collection install process 2026-09-01 03:44:08.833676 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-01 03:44:08.833694 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-01 03:44:08.833713 | instance | community.crypto:2.2.3 was installed successfully 2026-09-01 03:44:08.833731 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.833749 | instance | 2.17.12 2026-09-01 03:44:08.833766 | instance | Checked out community.general to 7.3.0 2026-09-01 03:44:08.833785 | 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-01 03:44:08.833809 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.833827 | instance | Process install dependency map 2026-09-01 03:44:08.833845 | instance | Starting collection install process 2026-09-01 03:44:08.833863 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-01 03:44:08.833881 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-01 03:44:08.833900 | instance | community.general:7.3.0 was installed successfully 2026-09-01 03:44:08.833918 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.833936 | instance | 2.17.12 2026-09-01 03:44:08.833954 | instance | Checked out community.mysql to 3.6.0 2026-09-01 03:44:08.833974 | 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-01 03:44:08.833993 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.834012 | instance | Process install dependency map 2026-09-01 03:44:08.834030 | instance | Starting collection install process 2026-09-01 03:44:08.834048 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-01 03:44:08.834067 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-01 03:44:08.834085 | instance | community.mysql:3.6.0 was installed successfully 2026-09-01 03:44:08.834104 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.834122 | instance | 2.17.12 2026-09-01 03:44:08.834140 | instance | Checked out kubernetes.core to 2.4.0 2026-09-01 03:44:08.834158 | 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-01 03:44:08.834177 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.834197 | instance | Process install dependency map 2026-09-01 03:44:08.834218 | instance | Starting collection install process 2026-09-01 03:44:08.834237 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-01 03:44:08.834255 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-01 03:44:08.834273 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-01 03:44:08.834291 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.834310 | instance | 2.17.12 2026-09-01 03:44:08.834327 | instance | Checked out openstack.cloud to 2.6.0 2026-09-01 03:44:08.834346 | 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-01 03:44:08.834364 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.834382 | instance | Process install dependency map 2026-09-01 03:44:08.834399 | instance | Starting collection install process 2026-09-01 03:44:08.834417 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-01 03:44:08.834435 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-01 03:44:08.834452 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-01 03:44:08.834470 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.834488 | instance | 2.17.12 2026-09-01 03:44:08.834513 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-01 03:44:08.834531 | 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-01 03:44:08.834549 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.834567 | instance | Process install dependency map 2026-09-01 03:44:08.834585 | instance | Starting collection install process 2026-09-01 03:44:08.834603 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-01 03:44:08.834621 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-01 03:44:08.834639 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-01 03:44:08.834657 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.834674 | instance | 2.17.12 2026-09-01 03:44:08.834692 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-01 03:44:08.834709 | 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-01 03:44:08.834727 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.834745 | instance | Process install dependency map 2026-09-01 03:44:08.834763 | instance | Starting collection install process 2026-09-01 03:44:08.834782 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-01 03:44:08.834800 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-01 03:44:08.834818 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-01 03:44:08.834837 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.834855 | instance | 2.17.12 2026-09-01 03:44:08.834872 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-01 03:44:08.834890 | 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-01 03:44:08.834908 | instance | Starting galaxy collection install process 2026-09-01 03:44:08.834925 | instance | Process install dependency map 2026-09-01 03:44:08.834943 | instance | Starting collection install process 2026-09-01 03:44:08.834961 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-01 03:44:08.834980 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-01 03:44:08.834997 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-01 03:44:08.835015 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:44:08.835033 | instance | 2.17.12 2026-09-01 03:44:08.840819 | 2026-09-01 03:44:08.840867 | PLAY RECAP 2026-09-01 03:44:08.840915 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:44:08.840936 | 2026-09-01 03:44:08.962101 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 03:44:08.966377 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 03:44:09.531966 | 2026-09-01 03:44:09.532100 | PLAY [all] 2026-09-01 03:44:09.543011 | 2026-09-01 03:44:09.543086 | TASK [Install "jq" for log collection] 2026-09-01 03:44:20.187154 | instance | changed 2026-09-01 03:44:20.189682 | 2026-09-01 03:44:20.189766 | PLAY RECAP 2026-09-01 03:44:20.189836 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:44:20.189957 | 2026-09-01 03:44:20.320047 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 03:44:20.322804 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 03:44:20.917370 | 2026-09-01 03:44:20.917500 | PLAY [all] 2026-09-01 03:44:20.928378 | 2026-09-01 03:44:20.928461 | LOOP [Check inventory directories] 2026-09-01 03:44:21.260511 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-01 03:44:21.424114 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-01 03:44:21.612330 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-01 03:44:21.630418 | 2026-09-01 03:44:21.630660 | LOOP [Copy inventory file for Zuul] 2026-09-01 03:44:22.462076 | instance | changed: 2026-09-01 03:44:22.462316 | instance | { 2026-09-01 03:44:22.462356 | instance | "ansible_loop_var": "item", 2026-09-01 03:44:22.462389 | instance | "changed": false, 2026-09-01 03:44:22.462417 | instance | "failed": false, 2026-09-01 03:44:22.462449 | instance | "invocation": { 2026-09-01 03:44:22.462476 | instance | "module_args": { 2026-09-01 03:44:22.462504 | instance | "checksum_algorithm": "sha1", 2026-09-01 03:44:22.462530 | instance | "follow": true, 2026-09-01 03:44:22.462557 | instance | "get_attributes": true, 2026-09-01 03:44:22.462585 | instance | "get_checksum": true, 2026-09-01 03:44:22.462612 | instance | "get_mime": true, 2026-09-01 03:44:22.462637 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-01 03:44:22.462663 | instance | } 2026-09-01 03:44:22.462688 | instance | }, 2026-09-01 03:44:22.462713 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-01 03:44:22.462738 | instance | "stat": { 2026-09-01 03:44:22.462763 | instance | "atime": 1788234241.2963903, 2026-09-01 03:44:22.462788 | instance | "attr_flags": "e", 2026-09-01 03:44:22.462813 | instance | "attributes": [ 2026-09-01 03:44:22.462838 | instance | "extents" 2026-09-01 03:44:22.462863 | instance | ], 2026-09-01 03:44:22.462887 | instance | "block_size": 4096, 2026-09-01 03:44:22.462912 | instance | "blocks": 8, 2026-09-01 03:44:22.462937 | instance | "charset": "unknown", 2026-09-01 03:44:22.462964 | instance | "ctime": 1788234239.7843091, 2026-09-01 03:44:22.462990 | instance | "dev": 64513, 2026-09-01 03:44:22.463016 | instance | "device_type": 0, 2026-09-01 03:44:22.463041 | instance | "executable": true, 2026-09-01 03:44:22.463065 | instance | "exists": true, 2026-09-01 03:44:22.463090 | instance | "gid": 1000, 2026-09-01 03:44:22.463115 | instance | "gr_name": "zuul", 2026-09-01 03:44:22.463139 | instance | "inode": 1048418, 2026-09-01 03:44:22.463163 | instance | "isblk": false, 2026-09-01 03:44:22.463188 | instance | "ischr": false, 2026-09-01 03:44:22.463213 | instance | "isdir": true, 2026-09-01 03:44:22.463238 | instance | "isfifo": false, 2026-09-01 03:44:22.463262 | instance | "isgid": false, 2026-09-01 03:44:22.463286 | instance | "islnk": false, 2026-09-01 03:44:22.463311 | instance | "isreg": false, 2026-09-01 03:44:22.463335 | instance | "issock": false, 2026-09-01 03:44:22.463359 | instance | "isuid": false, 2026-09-01 03:44:22.463383 | instance | "mimetype": "unknown", 2026-09-01 03:44:22.463407 | instance | "mode": "0755", 2026-09-01 03:44:22.463431 | instance | "mtime": 1788234239.7843091, 2026-09-01 03:44:22.463455 | instance | "nlink": 23, 2026-09-01 03:44:22.463479 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-01 03:44:22.463503 | instance | "pw_name": "zuul", 2026-09-01 03:44:22.463530 | instance | "readable": true, 2026-09-01 03:44:22.463554 | instance | "rgrp": true, 2026-09-01 03:44:22.463579 | instance | "roth": true, 2026-09-01 03:44:22.463622 | instance | "rusr": true, 2026-09-01 03:44:22.463676 | instance | "size": 4096, 2026-09-01 03:44:22.463708 | instance | "uid": 1000, 2026-09-01 03:44:22.463733 | instance | "version": "2731533150", 2026-09-01 03:44:22.463759 | instance | "wgrp": false, 2026-09-01 03:44:22.463786 | instance | "woth": false, 2026-09-01 03:44:22.463812 | instance | "writeable": true, 2026-09-01 03:44:22.463838 | instance | "wusr": true, 2026-09-01 03:44:22.463863 | instance | "xgrp": true, 2026-09-01 03:44:22.463887 | instance | "xoth": true, 2026-09-01 03:44:22.463912 | instance | "xusr": true 2026-09-01 03:44:22.463936 | instance | } 2026-09-01 03:44:22.463961 | instance | } 2026-09-01 03:44:22.464007 | instance | ok: All items complete 2026-09-01 03:44:22.464037 | 2026-09-01 03:44:22.465485 | instance | skipping: Conditional result was False 2026-09-01 03:44:22.466069 | instance | skipping: Conditional result was False 2026-09-01 03:44:22.482884 | 2026-09-01 03:44:22.483022 | LOOP [Switch "ansible_host" to private IP] 2026-09-01 03:44:22.880155 | instance | changed: 1 replacements made 2026-09-01 03:44:22.881516 | instance | skipping: Conditional result was False 2026-09-01 03:44:22.882182 | instance | skipping: Conditional result was False 2026-09-01 03:44:22.901086 | 2026-09-01 03:44:22.901279 | TASK [Run Molecule scenario] 2026-09-01 03:44:23.906813 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-01 03:44:24.341125 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-01 03:44:24.341232 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-01 03:44:35.753419 | instance | INFO [csi > dependency] Executing 2026-09-01 03:44:35.753924 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-01 03:44:35.754399 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-01 03:44:35.754656 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-01 03:44:35.770909 | instance | INFO [csi > cleanup] Executing 2026-09-01 03:44:35.771607 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 03:44:35.784683 | instance | INFO [csi > destroy] Executing 2026-09-01 03:44:35.784725 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-01 03:44:35.784861 | instance | INFO [csi > destroy] Executed: Successful 2026-09-01 03:44:35.793896 | instance | INFO [csi > syntax] Executing 2026-09-01 03:44:37.408169 | instance | 2026-09-01 03:44:37.408391 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 03:44:37.498532 | instance | INFO [csi > syntax] Executed: Successful 2026-09-01 03:44:37.514278 | instance | INFO [csi > create] Executing 2026-09-01 03:44:37.517916 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 03:44:37.532532 | instance | INFO [csi > prepare] Executing 2026-09-01 03:44:38.317455 | instance | 2026-09-01 03:44:38.317777 | instance | PLAY [Prepare] ***************************************************************** 2026-09-01 03:44:38.318145 | instance | 2026-09-01 03:44:38.318466 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:44:38.318747 | instance | Tuesday 01 September 2026 03:44:38 +0000 (0:00:00.030) 0:00:00.030 ***** 2026-09-01 03:44:39.634702 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 03:44:39.634935 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 03:44:39.635209 | instance | interpreter could change the meaning of that path. See 2026-09-01 03:44:39.635499 | instance | https://docs.ansible.com/ansible- 2026-09-01 03:44:39.635774 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 03:44:39.645997 | instance | ok: [instance] 2026-09-01 03:44:39.646310 | instance | 2026-09-01 03:44:39.646609 | instance | TASK [Configure short hostname] ************************************************ 2026-09-01 03:44:39.646866 | instance | Tuesday 01 September 2026 03:44:39 +0000 (0:00:01.329) 0:00:01.359 ***** 2026-09-01 03:44:40.429558 | instance | changed: [instance] 2026-09-01 03:44:40.429747 | instance | 2026-09-01 03:44:40.429999 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-01 03:44:40.430270 | instance | Tuesday 01 September 2026 03:44:40 +0000 (0:00:00.783) 0:00:02.143 ***** 2026-09-01 03:44:40.727293 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-01 03:44:40.727478 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-01 03:44:40.727725 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-01 03:44:40.737555 | instance | changed: [instance] 2026-09-01 03:44:40.737798 | instance | 2026-09-01 03:44:40.738059 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-01 03:44:40.738327 | instance | Tuesday 01 September 2026 03:44:40 +0000 (0:00:00.308) 0:00:02.451 ***** 2026-09-01 03:44:41.583523 | instance | ok: [instance] 2026-09-01 03:44:41.583734 | instance | 2026-09-01 03:44:41.583964 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-01 03:44:41.584203 | instance | 2026-09-01 03:44:41.584451 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:44:41.584685 | instance | Tuesday 01 September 2026 03:44:41 +0000 (0:00:00.845) 0:00:03.296 ***** 2026-09-01 03:44:42.419793 | instance | ok: [instance] 2026-09-01 03:44:42.420166 | instance | 2026-09-01 03:44:42.420483 | instance | TASK [Install depedencies] ***************************************************** 2026-09-01 03:44:42.420791 | instance | Tuesday 01 September 2026 03:44:42 +0000 (0:00:00.836) 0:00:04.133 ***** 2026-09-01 03:45:07.439397 | instance | changed: [instance] 2026-09-01 03:45:07.439526 | instance | 2026-09-01 03:45:07.439695 | instance | TASK [Start up service] ******************************************************** 2026-09-01 03:45:07.439929 | instance | Tuesday 01 September 2026 03:45:07 +0000 (0:00:25.019) 0:00:29.153 ***** 2026-09-01 03:45:08.033732 | instance | ok: [instance] 2026-09-01 03:45:08.033954 | instance | 2026-09-01 03:45:08.034075 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-01 03:45:08.034376 | instance | Tuesday 01 September 2026 03:45:08 +0000 (0:00:00.593) 0:00:29.747 ***** 2026-09-01 03:45:08.345843 | instance | ok: [instance] 2026-09-01 03:45:08.345935 | instance | 2026-09-01 03:45:08.346079 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-01 03:45:08.346212 | instance | Tuesday 01 September 2026 03:45:08 +0000 (0:00:00.312) 0:00:30.059 ***** 2026-09-01 03:45:09.081286 | instance | changed: [instance] 2026-09-01 03:45:09.081475 | instance | 2026-09-01 03:45:09.081717 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-01 03:45:09.081889 | instance | Tuesday 01 September 2026 03:45:09 +0000 (0:00:00.734) 0:00:30.794 ***** 2026-09-01 03:45:09.308290 | instance | ok: [instance] 2026-09-01 03:45:09.308458 | instance | 2026-09-01 03:45:09.308784 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-01 03:45:09.309090 | instance | Tuesday 01 September 2026 03:45:09 +0000 (0:00:00.227) 0:00:31.021 ***** 2026-09-01 03:45:09.331196 | instance | skipping: [instance] 2026-09-01 03:45:09.332348 | instance | 2026-09-01 03:45:09.332900 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-01 03:45:09.333023 | instance | Tuesday 01 September 2026 03:45:09 +0000 (0:00:00.023) 0:00:31.045 ***** 2026-09-01 03:45:09.920744 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:45:09.920820 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:45:09.920967 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:45:09.921116 | instance | 2026-09-01 03:45:09.921279 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-01 03:45:09.921427 | instance | Tuesday 01 September 2026 03:45:09 +0000 (0:00:00.589) 0:00:31.634 ***** 2026-09-01 03:45:10.616567 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:45:10.616637 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:45:10.616783 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:45:10.616894 | instance | 2026-09-01 03:45:10.617009 | instance | TASK [Start loop devices] ****************************************************** 2026-09-01 03:45:10.617195 | instance | Tuesday 01 September 2026 03:45:10 +0000 (0:00:00.695) 0:00:32.330 ***** 2026-09-01 03:45:11.345933 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:45:11.346023 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:45:11.346167 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:45:11.346324 | instance | 2026-09-01 03:45:11.346512 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-01 03:45:11.346641 | instance | Tuesday 01 September 2026 03:45:11 +0000 (0:00:00.728) 0:00:33.059 ***** 2026-09-01 03:45:14.793360 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:45:14.793422 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:45:14.793433 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:45:14.793442 | instance | 2026-09-01 03:45:14.794064 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-01 03:45:14.794151 | instance | Tuesday 01 September 2026 03:45:14 +0000 (0:00:03.445) 0:00:36.504 ***** 2026-09-01 03:45:16.892400 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-01 03:45:16.893279 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-01 03:45:16.893327 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-01 03:45:16.893341 | instance | 2026-09-01 03:45:16.893353 | instance | PLAY RECAP ********************************************************************* 2026-09-01 03:45:16.893371 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-01 03:45:16.894679 | instance | 2026-09-01 03:45:16.894710 | instance | Tuesday 01 September 2026 03:45:16 +0000 (0:00:02.102) 0:00:38.607 ***** 2026-09-01 03:45:16.894721 | instance | =============================================================================== 2026-09-01 03:45:16.894730 | instance | Install depedencies ---------------------------------------------------- 25.02s 2026-09-01 03:45:16.894738 | instance | Create a volume group for each loop device ------------------------------ 3.45s 2026-09-01 03:45:16.894747 | instance | Create a logical volume for each loop device ---------------------------- 2.10s 2026-09-01 03:45:16.894756 | instance | Gathering Facts --------------------------------------------------------- 1.33s 2026-09-01 03:45:16.894765 | instance | Purge "snapd" package --------------------------------------------------- 0.85s 2026-09-01 03:45:16.894778 | instance | Gathering Facts --------------------------------------------------------- 0.84s 2026-09-01 03:45:16.895816 | instance | Configure short hostname ------------------------------------------------ 0.78s 2026-09-01 03:45:16.895908 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.73s 2026-09-01 03:45:16.895920 | instance | Start loop devices ------------------------------------------------------ 0.73s 2026-09-01 03:45:16.895930 | instance | Set permissions on loopback devices ------------------------------------- 0.70s 2026-09-01 03:45:16.895940 | instance | Start up service -------------------------------------------------------- 0.59s 2026-09-01 03:45:16.895950 | instance | Create devices for Ceph ------------------------------------------------- 0.59s 2026-09-01 03:45:16.895971 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s 2026-09-01 03:45:16.896493 | instance | Ensure hostname inside hosts file --------------------------------------- 0.31s 2026-09-01 03:45:16.896570 | instance | Get list of all loopback devices ---------------------------------------- 0.23s 2026-09-01 03:45:16.896579 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-01 03:45:16.973227 | instance | INFO [csi > prepare] Executed: Successful 2026-09-01 03:45:16.980317 | instance | INFO [csi > converge] Executing 2026-09-01 03:45:18.480137 | instance | 2026-09-01 03:45:18.480279 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-01 03:45:18.480557 | instance | 2026-09-01 03:45:18.480789 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:45:18.481021 | instance | Tuesday 01 September 2026 03:45:18 +0000 (0:00:00.007) 0:00:00.007 ***** 2026-09-01 03:45:19.599954 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 03:45:19.600036 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 03:45:19.600056 | instance | interpreter could change the meaning of that path. See 2026-09-01 03:45:19.600066 | instance | https://docs.ansible.com/ansible- 2026-09-01 03:45:19.600149 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 03:45:19.609709 | instance | ok: [instance] 2026-09-01 03:45:19.609897 | instance | 2026-09-01 03:45:19.610066 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-01 03:45:19.610238 | instance | Tuesday 01 September 2026 03:45:19 +0000 (0:00:01.129) 0:00:01.137 ***** 2026-09-01 03:45:19.673388 | instance | ok: [instance] => { 2026-09-01 03:45:19.673489 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-01 03:45:19.673545 | instance | } 2026-09-01 03:45:19.673651 | instance | 2026-09-01 03:45:19.673762 | instance | PLAY [all] ********************************************************************* 2026-09-01 03:45:19.673855 | instance | 2026-09-01 03:45:19.673965 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:45:19.674060 | instance | Tuesday 01 September 2026 03:45:19 +0000 (0:00:00.063) 0:00:01.201 ***** 2026-09-01 03:45:20.674125 | instance | ok: [instance] 2026-09-01 03:45:20.674372 | instance | 2026-09-01 03:45:20.674739 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-01 03:45:20.675064 | instance | Tuesday 01 September 2026 03:45:20 +0000 (0:00:01.000) 0:00:02.201 ***** 2026-09-01 03:45:20.729788 | instance | skipping: [instance] 2026-09-01 03:45:20.730068 | instance | 2026-09-01 03:45:20.730328 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-01 03:45:20.730626 | instance | Tuesday 01 September 2026 03:45:20 +0000 (0:00:00.056) 0:00:02.257 ***** 2026-09-01 03:45:20.944928 | instance | ok: [instance] 2026-09-01 03:45:20.945208 | instance | 2026-09-01 03:45:20.945538 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-01 03:45:20.945793 | instance | 2026-09-01 03:45:20.946133 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:45:20.946467 | instance | Tuesday 01 September 2026 03:45:20 +0000 (0:00:00.214) 0:00:02.472 ***** 2026-09-01 03:45:21.915343 | instance | ok: [instance] 2026-09-01 03:45:21.915429 | instance | 2026-09-01 03:45:21.915836 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:45:21.915948 | instance | Tuesday 01 September 2026 03:45:21 +0000 (0:00:00.969) 0:00:03.442 ***** 2026-09-01 03:45:22.315720 | instance | ok: [instance] 2026-09-01 03:45:22.315808 | instance | 2026-09-01 03:45:22.315981 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:45:22.316126 | instance | Tuesday 01 September 2026 03:45:22 +0000 (0:00:00.400) 0:00:03.842 ***** 2026-09-01 03:45:22.372326 | instance | skipping: [instance] 2026-09-01 03:45:22.372415 | instance | 2026-09-01 03:45:22.372580 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-01 03:45:22.372741 | instance | Tuesday 01 September 2026 03:45:22 +0000 (0:00:00.056) 0:00:03.899 ***** 2026-09-01 03:45:22.780709 | instance | changed: [instance] 2026-09-01 03:45:22.780808 | instance | 2026-09-01 03:45:22.780937 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:45:22.781097 | instance | Tuesday 01 September 2026 03:45:22 +0000 (0:00:00.408) 0:00:04.308 ***** 2026-09-01 03:45:22.860215 | instance | ok: [instance] => { 2026-09-01 03:45:22.860342 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-01 03:45:22.860594 | instance | } 2026-09-01 03:45:22.860779 | instance | 2026-09-01 03:45:22.860938 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:45:22.861090 | instance | Tuesday 01 September 2026 03:45:22 +0000 (0:00:00.079) 0:00:04.387 ***** 2026-09-01 03:45:23.629649 | instance | changed: [instance] 2026-09-01 03:45:23.629897 | instance | 2026-09-01 03:45:23.630177 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:45:23.630466 | instance | Tuesday 01 September 2026 03:45:23 +0000 (0:00:00.769) 0:00:05.157 ***** 2026-09-01 03:45:23.689283 | instance | skipping: [instance] 2026-09-01 03:45:23.689590 | instance | 2026-09-01 03:45:23.689935 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:45:23.690226 | instance | Tuesday 01 September 2026 03:45:23 +0000 (0:00:00.059) 0:00:05.216 ***** 2026-09-01 03:45:23.734465 | instance | skipping: [instance] 2026-09-01 03:45:23.734724 | instance | 2026-09-01 03:45:23.735024 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:45:23.735275 | instance | Tuesday 01 September 2026 03:45:23 +0000 (0:00:00.045) 0:00:05.262 ***** 2026-09-01 03:45:23.959853 | instance | ok: [instance] 2026-09-01 03:45:23.960011 | instance | 2026-09-01 03:45:23.960237 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:45:23.960511 | instance | Tuesday 01 September 2026 03:45:23 +0000 (0:00:00.225) 0:00:05.487 ***** 2026-09-01 03:45:25.411037 | instance | ok: [instance] 2026-09-01 03:45:25.411552 | instance | 2026-09-01 03:45:25.412069 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:45:25.412500 | instance | Tuesday 01 September 2026 03:45:25 +0000 (0:00:01.450) 0:00:06.938 ***** 2026-09-01 03:45:25.495823 | instance | ok: [instance] => { 2026-09-01 03:45:25.496102 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-01 03:45:25.496320 | instance | } 2026-09-01 03:45:25.496529 | instance | 2026-09-01 03:45:25.496730 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:45:25.496927 | instance | Tuesday 01 September 2026 03:45:25 +0000 (0:00:00.082) 0:00:07.020 ***** 2026-09-01 03:45:26.277449 | instance | changed: [instance] 2026-09-01 03:45:26.277733 | instance | 2026-09-01 03:45:26.277882 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:45:26.278025 | instance | Tuesday 01 September 2026 03:45:26 +0000 (0:00:00.784) 0:00:07.804 ***** 2026-09-01 03:45:29.310076 | instance | changed: [instance] 2026-09-01 03:45:29.310373 | instance | 2026-09-01 03:45:29.310615 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-01 03:45:29.310888 | instance | Tuesday 01 September 2026 03:45:29 +0000 (0:00:03.032) 0:00:10.837 ***** 2026-09-01 03:45:29.340696 | instance | skipping: [instance] 2026-09-01 03:45:29.341082 | instance | 2026-09-01 03:45:29.341760 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-01 03:45:29.342040 | instance | Tuesday 01 September 2026 03:45:29 +0000 (0:00:00.030) 0:00:10.867 ***** 2026-09-01 03:45:29.384839 | instance | skipping: [instance] 2026-09-01 03:45:29.385170 | instance | 2026-09-01 03:45:29.385474 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-01 03:45:29.385738 | instance | Tuesday 01 September 2026 03:45:29 +0000 (0:00:00.043) 0:00:10.911 ***** 2026-09-01 03:45:29.428232 | instance | skipping: [instance] 2026-09-01 03:45:29.428553 | instance | 2026-09-01 03:45:29.428803 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-01 03:45:29.429040 | instance | Tuesday 01 September 2026 03:45:29 +0000 (0:00:00.043) 0:00:10.955 ***** 2026-09-01 03:45:35.907781 | instance | changed: [instance] 2026-09-01 03:45:35.908575 | instance | 2026-09-01 03:45:35.908643 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-01 03:45:35.908655 | instance | Tuesday 01 September 2026 03:45:35 +0000 (0:00:06.479) 0:00:17.435 ***** 2026-09-01 03:45:36.548745 | instance | changed: [instance] 2026-09-01 03:45:36.548807 | instance | 2026-09-01 03:45:36.548819 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-01 03:45:36.548830 | instance | Tuesday 01 September 2026 03:45:36 +0000 (0:00:00.639) 0:00:18.074 ***** 2026-09-01 03:45:37.545995 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-01 03:45:37.546043 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-01 03:45:37.546049 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-01 03:45:37.546059 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-01 03:45:37.546065 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-01 03:45:37.546069 | instance | 2026-09-01 03:45:37.546074 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-01 03:45:37.546078 | instance | Tuesday 01 September 2026 03:45:37 +0000 (0:00:00.998) 0:00:19.073 ***** 2026-09-01 03:45:38.205444 | instance | changed: [instance] 2026-09-01 03:45:38.205560 | instance | 2026-09-01 03:45:38.206385 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-01 03:45:38.206456 | instance | Tuesday 01 September 2026 03:45:38 +0000 (0:00:00.633) 0:00:19.706 ***** 2026-09-01 03:45:38.206467 | instance | 2026-09-01 03:45:38.206476 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 03:45:38.206484 | instance | Tuesday 01 September 2026 03:45:38 +0000 (0:00:00.025) 0:00:19.732 ***** 2026-09-01 03:45:39.313922 | instance | ok: [instance] 2026-09-01 03:45:39.314012 | instance | 2026-09-01 03:45:39.314435 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-01 03:45:39.314482 | instance | Tuesday 01 September 2026 03:45:39 +0000 (0:00:01.108) 0:00:20.841 ***** 2026-09-01 03:45:39.802258 | instance | changed: [instance] 2026-09-01 03:45:39.802377 | instance | 2026-09-01 03:45:39.802674 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-01 03:45:39.802730 | instance | Tuesday 01 September 2026 03:45:39 +0000 (0:00:00.488) 0:00:21.329 ***** 2026-09-01 03:45:40.414216 | instance | changed: [instance] 2026-09-01 03:45:40.414981 | instance | 2026-09-01 03:45:40.415303 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:45:40.415324 | instance | Tuesday 01 September 2026 03:45:40 +0000 (0:00:00.611) 0:00:21.941 ***** 2026-09-01 03:45:40.644201 | instance | ok: [instance] 2026-09-01 03:45:40.644890 | instance | 2026-09-01 03:45:40.644958 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:45:40.644969 | instance | Tuesday 01 September 2026 03:45:40 +0000 (0:00:00.229) 0:00:22.171 ***** 2026-09-01 03:45:40.737434 | instance | ok: [instance] => { 2026-09-01 03:45:40.738241 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-01 03:45:40.738317 | instance | } 2026-09-01 03:45:40.738337 | instance | 2026-09-01 03:45:40.738764 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:45:40.738956 | instance | Tuesday 01 September 2026 03:45:40 +0000 (0:00:00.092) 0:00:22.264 ***** 2026-09-01 03:45:43.788341 | instance | changed: [instance] 2026-09-01 03:45:43.789153 | instance | 2026-09-01 03:45:43.789190 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:45:43.789207 | instance | Tuesday 01 September 2026 03:45:43 +0000 (0:00:03.051) 0:00:25.315 ***** 2026-09-01 03:45:49.235767 | instance | changed: [instance] 2026-09-01 03:45:49.236374 | instance | 2026-09-01 03:45:49.236431 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-01 03:45:49.236439 | instance | Tuesday 01 September 2026 03:45:49 +0000 (0:00:05.447) 0:00:30.762 ***** 2026-09-01 03:45:50.871235 | instance | ok: [instance] 2026-09-01 03:45:50.871308 | instance | 2026-09-01 03:45:50.871592 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-01 03:45:50.871832 | instance | Tuesday 01 September 2026 03:45:50 +0000 (0:00:01.636) 0:00:32.399 ***** 2026-09-01 03:45:51.274389 | instance | changed: [instance] 2026-09-01 03:45:51.274478 | instance | 2026-09-01 03:45:51.274813 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-01 03:45:51.274888 | instance | Tuesday 01 September 2026 03:45:51 +0000 (0:00:00.402) 0:00:32.802 ***** 2026-09-01 03:45:51.774209 | instance | changed: [instance] 2026-09-01 03:45:51.774322 | instance | 2026-09-01 03:45:51.774729 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-01 03:45:51.774784 | instance | Tuesday 01 September 2026 03:45:51 +0000 (0:00:00.499) 0:00:33.301 ***** 2026-09-01 03:45:52.424999 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-01 03:45:52.425081 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-01 03:45:52.425621 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-01 03:45:52.425670 | instance | 2026-09-01 03:45:52.425678 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-01 03:45:52.425683 | instance | Tuesday 01 September 2026 03:45:52 +0000 (0:00:00.650) 0:00:33.952 ***** 2026-09-01 03:45:52.954140 | instance | changed: [instance] 2026-09-01 03:45:52.955155 | instance | 2026-09-01 03:45:52.955259 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-01 03:45:52.955275 | instance | Tuesday 01 September 2026 03:45:52 +0000 (0:00:00.528) 0:00:34.481 ***** 2026-09-01 03:45:53.523029 | instance | changed: [instance] 2026-09-01 03:45:53.523106 | instance | 2026-09-01 03:45:53.523766 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-01 03:45:53.523893 | instance | Tuesday 01 September 2026 03:45:53 +0000 (0:00:00.542) 0:00:35.023 ***** 2026-09-01 03:45:53.523907 | instance | 2026-09-01 03:45:53.523923 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 03:45:53.524106 | instance | Tuesday 01 September 2026 03:45:53 +0000 (0:00:00.027) 0:00:35.050 ***** 2026-09-01 03:45:54.327364 | instance | ok: [instance] 2026-09-01 03:45:54.327433 | instance | 2026-09-01 03:45:54.327693 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-01 03:45:54.327743 | instance | Tuesday 01 September 2026 03:45:54 +0000 (0:00:00.804) 0:00:35.855 ***** 2026-09-01 03:45:55.618072 | instance | changed: [instance] 2026-09-01 03:45:55.618182 | instance | 2026-09-01 03:45:55.618538 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-01 03:45:55.618607 | instance | Tuesday 01 September 2026 03:45:55 +0000 (0:00:01.290) 0:00:37.145 ***** 2026-09-01 03:45:56.301417 | instance | changed: [instance] 2026-09-01 03:45:56.301510 | instance | 2026-09-01 03:45:56.301899 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-01 03:45:56.301956 | instance | Tuesday 01 September 2026 03:45:56 +0000 (0:00:00.683) 0:00:37.829 ***** 2026-09-01 03:45:56.372682 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-01 03:45:56.372821 | instance | 2026-09-01 03:45:56.372834 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-01 03:45:56.372917 | instance | Tuesday 01 September 2026 03:45:56 +0000 (0:00:00.071) 0:00:37.900 ***** 2026-09-01 03:46:03.629339 | instance | changed: [instance] 2026-09-01 03:46:03.629770 | instance | 2026-09-01 03:46:03.629787 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-01 03:46:03.629794 | instance | Tuesday 01 September 2026 03:46:03 +0000 (0:00:07.256) 0:00:45.157 ***** 2026-09-01 03:46:04.351037 | instance | ok: [instance] => (item=chronyd) 2026-09-01 03:46:04.351117 | instance | ok: [instance] => (item=sshd) 2026-09-01 03:46:04.351615 | instance | 2026-09-01 03:46:04.351683 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-01 03:46:04.351692 | instance | Tuesday 01 September 2026 03:46:04 +0000 (0:00:00.721) 0:00:45.878 ***** 2026-09-01 03:46:05.750226 | instance | changed: [instance] 2026-09-01 03:46:05.751072 | instance | 2026-09-01 03:46:05.751144 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-01 03:46:05.751157 | instance | Tuesday 01 September 2026 03:46:05 +0000 (0:00:01.398) 0:00:47.277 ***** 2026-09-01 03:46:05.981983 | instance | ok: [instance] 2026-09-01 03:46:05.982461 | instance | 2026-09-01 03:46:05.982481 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-01 03:46:05.982488 | instance | Tuesday 01 September 2026 03:46:05 +0000 (0:00:00.231) 0:00:47.509 ***** 2026-09-01 03:46:06.480132 | instance | changed: [instance] 2026-09-01 03:46:06.480214 | instance | 2026-09-01 03:46:06.480524 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-01 03:46:06.480587 | instance | Tuesday 01 September 2026 03:46:06 +0000 (0:00:00.498) 0:00:48.007 ***** 2026-09-01 03:46:06.964256 | instance | changed: [instance] 2026-09-01 03:46:06.964367 | instance | 2026-09-01 03:46:06.964384 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-01 03:46:06.964557 | instance | Tuesday 01 September 2026 03:46:06 +0000 (0:00:00.483) 0:00:48.491 ***** 2026-09-01 03:46:07.098941 | instance | ok: [instance] 2026-09-01 03:46:07.099058 | instance | 2026-09-01 03:46:07.099074 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-01 03:46:07.099286 | instance | Tuesday 01 September 2026 03:46:07 +0000 (0:00:00.134) 0:00:48.626 ***** 2026-09-01 03:46:07.373218 | instance | ok: [instance] => (item=instance) 2026-09-01 03:46:07.373295 | instance | 2026-09-01 03:46:07.373513 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-01 03:46:07.373573 | instance | Tuesday 01 September 2026 03:46:07 +0000 (0:00:00.274) 0:00:48.901 ***** 2026-09-01 03:46:07.456917 | instance | ok: [instance] 2026-09-01 03:46:07.457017 | instance | 2026-09-01 03:46:07.457032 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-01 03:46:07.457046 | instance | Tuesday 01 September 2026 03:46:07 +0000 (0:00:00.081) 0:00:48.982 ***** 2026-09-01 03:46:07.535103 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-01 03:46:07.535175 | instance | 2026-09-01 03:46:07.535402 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-01 03:46:07.535421 | instance | Tuesday 01 September 2026 03:46:07 +0000 (0:00:00.080) 0:00:49.063 ***** 2026-09-01 03:46:07.848277 | instance | changed: [instance] 2026-09-01 03:46:07.849075 | instance | 2026-09-01 03:46:07.849108 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-01 03:46:07.849120 | instance | Tuesday 01 September 2026 03:46:07 +0000 (0:00:00.313) 0:00:49.376 ***** 2026-09-01 03:46:08.587046 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-01 03:46:08.587812 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-01 03:46:08.587885 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-01 03:46:08.588774 | instance | 2026-09-01 03:46:08.588844 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-01 03:46:08.588857 | instance | Tuesday 01 September 2026 03:46:08 +0000 (0:00:00.738) 0:00:50.114 ***** 2026-09-01 03:47:42.679762 | instance | ok: [instance] 2026-09-01 03:47:42.679862 | instance | 2026-09-01 03:47:42.680584 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-01 03:47:42.680615 | instance | Tuesday 01 September 2026 03:47:42 +0000 (0:01:34.091) 0:02:24.205 ***** 2026-09-01 03:47:42.922225 | instance | changed: [instance] 2026-09-01 03:47:42.922298 | instance | 2026-09-01 03:47:42.922310 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-01 03:47:42.922320 | instance | Tuesday 01 September 2026 03:47:42 +0000 (0:00:00.242) 0:02:24.448 ***** 2026-09-01 03:47:42.957202 | instance | ok: [instance] 2026-09-01 03:47:42.957263 | instance | 2026-09-01 03:47:42.957276 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-01 03:47:42.957287 | instance | Tuesday 01 September 2026 03:47:42 +0000 (0:00:00.035) 0:02:24.483 ***** 2026-09-01 03:47:43.025621 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-01 03:47:43.025697 | instance | 2026-09-01 03:47:43.025711 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-01 03:47:43.025721 | instance | Tuesday 01 September 2026 03:47:43 +0000 (0:00:00.068) 0:02:24.552 ***** 2026-09-01 03:47:44.755713 | instance | ok: [instance] 2026-09-01 03:47:44.755761 | instance | 2026-09-01 03:47:44.755773 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-01 03:47:44.755821 | instance | Tuesday 01 September 2026 03:47:44 +0000 (0:00:01.730) 0:02:26.283 ***** 2026-09-01 03:47:44.827030 | instance | ok: [instance] => (item=instance) 2026-09-01 03:47:44.827086 | instance | 2026-09-01 03:47:44.827097 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-01 03:47:44.827105 | instance | Tuesday 01 September 2026 03:47:44 +0000 (0:00:00.070) 0:02:26.353 ***** 2026-09-01 03:47:45.225547 | instance | ok: [instance] 2026-09-01 03:47:45.225593 | instance | 2026-09-01 03:47:45.225599 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-01 03:47:45.225603 | instance | Tuesday 01 September 2026 03:47:45 +0000 (0:00:00.399) 0:02:26.753 ***** 2026-09-01 03:47:47.639807 | instance | ok: [instance] 2026-09-01 03:47:47.639891 | instance | 2026-09-01 03:47:47.640058 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-01 03:47:47.640214 | instance | Tuesday 01 September 2026 03:47:47 +0000 (0:00:02.414) 0:02:29.167 ***** 2026-09-01 03:48:38.287570 | 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-01 03:48:38.288076 | 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_azklsbla/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_azklsbla/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_azklsbla/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_azklsbla/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-01T03:48:38.075+0000 7fdc551c2640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCvSpZq5OMYKyAATpWv1P9KSeTdTVs/X8fSg8Z5Y4E/jztA+Xdpea8VMwg=: Malformed input [buffer:3]\n2026-09-01T03:48:38.075+0000 7fdc551c2640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T03:48:38.075+0000 7fdc551c2640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCvSpZq5OMYKyAATpWv1P9KSeTdTVs/X8fSg8Z5Y4E/jztA+Xdpea8VMwg=: Malformed input [buffer:3]\n2026-09-01T03:48:38.075+0000 7fdc551c2640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T03:48:38.075+0000 7fdc551c2640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCvSpZq5OMYKyAATpWv1P9KSeTdTVs/X8fSg8Z5Y4E/jztA+Xdpea8VMwg=: Malformed input [buffer:3]\n2026-09-01T03:48:38.075+0000 7fdc551c2640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T03:48:38.075+0000 7fdc551c2640 -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-01 03:48:38.289046 | instance | 2026-09-01 03:48:38.289293 | instance | PLAY RECAP ********************************************************************* 2026-09-01 03:48:38.289613 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-01 03:48:38.289849 | instance | 2026-09-01 03:48:38.290101 | instance | Tuesday 01 September 2026 03:48:38 +0000 (0:00:50.650) 0:03:19.817 ***** 2026-09-01 03:48:38.290337 | instance | =============================================================================== 2026-09-01 03:48:38.290614 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 94.09s 2026-09-01 03:48:38.290840 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 50.65s 2026-09-01 03:48:38.291085 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 7.26s 2026-09-01 03:48:38.291337 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.48s 2026-09-01 03:48:38.291593 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 5.45s 2026-09-01 03:48:38.291961 | instance | vexxhost.containers.download_artifact : Download item ------------------- 3.05s 2026-09-01 03:48:38.292235 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.03s 2026-09-01 03:48:38.292496 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.41s 2026-09-01 03:48:38.292729 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.91s 2026-09-01 03:48:38.292962 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.73s 2026-09-01 03:48:38.293205 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.64s 2026-09-01 03:48:38.293458 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.45s 2026-09-01 03:48:38.293702 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.40s 2026-09-01 03:48:38.293934 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.29s 2026-09-01 03:48:38.294177 | instance | Gathering Facts --------------------------------------------------------- 1.13s 2026-09-01 03:48:38.294408 | instance | Gathering Facts --------------------------------------------------------- 1.00s 2026-09-01 03:48:38.294642 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.00s 2026-09-01 03:48:38.294864 | instance | Gathering Facts --------------------------------------------------------- 0.97s 2026-09-01 03:48:38.295075 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.78s 2026-09-01 03:48:38.295293 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s 2026-09-01 03:48:38.405312 | 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-01 03:48:38.405486 | instance | ERROR [csi > converge] Executed: Failed 2026-09-01 03:48:38.405632 | 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-01 03:48:38.789280 | instance | ERROR 2026-09-01 03:48:38.789586 | instance | { 2026-09-01 03:48:38.789650 | instance | "delta": "0:04:15.249192", 2026-09-01 03:48:38.789697 | instance | "end": "2026-09-01 03:48:38.512250", 2026-09-01 03:48:38.789739 | instance | "msg": "non-zero return code", 2026-09-01 03:48:38.789779 | instance | "rc": 2, 2026-09-01 03:48:38.789819 | instance | "start": "2026-09-01 03:44:23.263058" 2026-09-01 03:48:38.789859 | instance | } failure 2026-09-01 03:48:38.795917 | 2026-09-01 03:48:38.795968 | PLAY RECAP 2026-09-01 03:48:38.796013 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:48:38.796036 | 2026-09-01 03:48:38.952642 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 03:48:38.956538 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 03:48:39.567430 | 2026-09-01 03:48:39.567561 | PLAY [all] 2026-09-01 03:48:39.581355 | 2026-09-01 03:48:39.581431 | TASK [gather-host-logs : creating directory for system status] 2026-09-01 03:48:39.919875 | instance | changed 2026-09-01 03:48:39.925183 | 2026-09-01 03:48:39.925268 | TASK [gather-host-logs : Get logs for each host] 2026-09-01 03:48:40.254511 | instance | + systemd-cgls --full --all --no-pager 2026-09-01 03:48:40.272782 | instance | + ip addr 2026-09-01 03:48:40.275433 | instance | + ip route 2026-09-01 03:48:40.277946 | instance | + lsblk 2026-09-01 03:48:40.284238 | instance | + mount 2026-09-01 03:48:40.287587 | instance | + docker images 2026-09-01 03:48:40.306290 | instance | + brctl show 2026-09-01 03:48:40.306881 | instance | /bin/bash: line 8: brctl: command not found 2026-09-01 03:48:40.307160 | instance | + ps aux --sort=-%mem 2026-09-01 03:48:40.329770 | instance | + dpkg -l 2026-09-01 03:48:40.338627 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-01 03:48:40.339432 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-01 03:48:40.363202 | instance | + '[' '!' -z '' ']' 2026-09-01 03:48:40.464205 | instance | ok: Runtime: 0:00:00.113839 2026-09-01 03:48:40.471980 | 2026-09-01 03:48:40.472053 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-01 03:48:41.093595 | instance | changed: 2026-09-01 03:48:41.093818 | instance | created directory /var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/work/logs/instance 2026-09-01 03:48:41.093860 | instance | cd+++++++++ system/ 2026-09-01 03:48:41.093892 | instance | >f+++++++++ system/brctl-show.txt 2026-09-01 03:48:41.093924 | instance | >f+++++++++ system/docker-images.txt 2026-09-01 03:48:41.093954 | instance | >f+++++++++ system/ip-addr.txt 2026-09-01 03:48:41.093989 | instance | >f+++++++++ system/ip-route.txt 2026-09-01 03:48:41.094023 | instance | >f+++++++++ system/lsblk.txt 2026-09-01 03:48:41.094053 | instance | >f+++++++++ system/mount.txt 2026-09-01 03:48:41.094083 | instance | >f+++++++++ system/packages.txt 2026-09-01 03:48:41.094112 | instance | >f+++++++++ system/ps.txt 2026-09-01 03:48:41.094144 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-01 03:48:41.108196 | 2026-09-01 03:48:41.108310 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-01 03:48:41.330200 | instance | changed: "values" 2026-09-01 03:48:41.535687 | instance | changed: "releases" 2026-09-01 03:48:41.550075 | 2026-09-01 03:48:41.550235 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-01 03:48:41.764426 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-01 03:48:42.088207 | instance | ok: Runtime: 0:00:00.005089 2026-09-01 03:48:42.097541 | 2026-09-01 03:48:42.097607 | TASK [helm-release-status : Downloads logs to executor] 2026-09-01 03:48:42.587897 | instance | changed: 2026-09-01 03:48:42.588095 | instance | cd+++++++++ helm/ 2026-09-01 03:48:42.588135 | instance | cd+++++++++ helm/releases/ 2026-09-01 03:48:42.588168 | instance | cd+++++++++ helm/values/ 2026-09-01 03:48:42.598777 | 2026-09-01 03:48:42.598847 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-01 03:48:42.821546 | instance | changed 2026-09-01 03:48:42.829520 | 2026-09-01 03:48:42.829618 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-01 03:48:43.111499 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:48:43.111581 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:48:43.117406 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:48:43.118116 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:48:43.118933 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:48:43.119610 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:48:43.121757 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:48:43.122302 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:48:43.125653 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:48:43.127102 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:48:43.127123 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:48:43.127829 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:48:43.362746 | instance | ok: Runtime: 0:00:00.026789 2026-09-01 03:48:43.369105 | 2026-09-01 03:48:43.369171 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-01 03:48:43.604824 | instance | changed 2026-09-01 03:48:43.611034 | 2026-09-01 03:48:43.611113 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-01 03:48:43.842647 | instance | environment: line 5: kubectl: command not found 2026-09-01 03:48:43.843433 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:48:43.843523 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:48:43.843656 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:48:44.148250 | instance | ok: Runtime: 0:00:00.006703 2026-09-01 03:48:44.154797 | 2026-09-01 03:48:44.154866 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-01 03:48:44.644477 | instance | changed: 2026-09-01 03:48:44.645268 | instance | cd+++++++++ objects/ 2026-09-01 03:48:44.645318 | instance | cd+++++++++ objects/cluster/ 2026-09-01 03:48:44.645353 | instance | cd+++++++++ objects/namespaced/ 2026-09-01 03:48:44.655144 | 2026-09-01 03:48:44.655212 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-01 03:48:44.882175 | instance | changed 2026-09-01 03:48:44.888439 | 2026-09-01 03:48:44.888505 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-01 03:48:45.106559 | instance | changed 2026-09-01 03:48:45.111404 | 2026-09-01 03:48:45.111488 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-01 03:48:45.336640 | instance | environment: line 3: kubectl: command not found 2026-09-01 03:48:45.648203 | instance | ok: Runtime: 0:00:00.007076 2026-09-01 03:48:45.655124 | 2026-09-01 03:48:45.655190 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-01 03:48:46.146302 | instance | changed: 2026-09-01 03:48:46.146536 | instance | cd+++++++++ pod-logs/ 2026-09-01 03:48:46.146576 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-01 03:48:46.158098 | 2026-09-01 03:48:46.158164 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-01 03:48:46.364359 | instance | changed 2026-09-01 03:48:46.371577 | 2026-09-01 03:48:46.371679 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-01 03:48:46.574226 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 03:48:46.911007 | instance | ok: Runtime: 0:00:00.028693 2026-09-01 03:48:46.917708 | 2026-09-01 03:48:46.917780 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-01 03:48:47.156738 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 03:48:47.200269 | instance | ceph-mgr endpoints: 2026-09-01 03:48:47.457649 | instance | ok: Runtime: 0:00:00.049665 2026-09-01 03:48:47.465328 | 2026-09-01 03:48:47.465425 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-01 03:48:47.686675 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-01 03:48:48.002689 | instance | ok: Runtime: 0:00:00.037203 2026-09-01 03:48:48.009777 | 2026-09-01 03:48:48.009845 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-01 03:48:48.521466 | instance | changed: cd+++++++++ prometheus/ 2026-09-01 03:48:48.532656 | 2026-09-01 03:48:48.532747 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-01 03:48:48.732546 | instance | changed 2026-09-01 03:48:48.737499 | 2026-09-01 03:48:48.737566 | TASK [gather-selenium-data : Get selenium data] 2026-09-01 03:48:48.993366 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-01 03:48:48.994847 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-01 03:48:49.274218 | instance | ERROR 2026-09-01 03:48:49.274499 | instance | { 2026-09-01 03:48:49.274543 | instance | "delta": "0:00:00.007108", 2026-09-01 03:48:49.274572 | instance | "end": "2026-09-01 03:48:48.995178", 2026-09-01 03:48:49.274707 | instance | "msg": "non-zero return code", 2026-09-01 03:48:49.274746 | instance | "rc": 1, 2026-09-01 03:48:49.274774 | instance | "start": "2026-09-01 03:48:48.988070" 2026-09-01 03:48:49.274800 | instance | } 2026-09-01 03:48:49.274835 | instance | ERROR: Ignoring Errors 2026-09-01 03:48:49.281644 | 2026-09-01 03:48:49.281800 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-01 03:48:49.784604 | instance | changed: cd+++++++++ selenium/ 2026-09-01 03:48:49.790630 | 2026-09-01 03:48:49.790680 | PLAY RECAP 2026-09-01 03:48:49.790725 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-01 03:48:49.790748 | 2026-09-01 03:48:49.945778 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 03:48:49.950466 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 03:48:50.547487 | 2026-09-01 03:48:50.547707 | PLAY [all] 2026-09-01 03:48:50.559549 | 2026-09-01 03:48:50.559631 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-01 03:48:50.604483 | instance | skipping: Conditional result was False 2026-09-01 03:48:50.614931 | 2026-09-01 03:48:50.615029 | TASK [fetch-output : Set log path for single node] 2026-09-01 03:48:50.659287 | instance | ok 2026-09-01 03:48:50.663919 | 2026-09-01 03:48:50.663987 | LOOP [fetch-output : Ensure local output dirs] 2026-09-01 03:48:51.040322 | instance -> localhost | ok: "/var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/work/logs" 2026-09-01 03:48:51.245152 | instance -> localhost | changed: "/var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/work/artifacts" 2026-09-01 03:48:51.453767 | instance -> localhost | changed: "/var/lib/zuul/builds/4a3a183ea5d64932b23c499f7cc0f801/work/docs" 2026-09-01 03:48:51.468859 | 2026-09-01 03:48:51.469072 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-01 03:48:52.143971 | instance | changed: .d..t...... ./ 2026-09-01 03:48:52.144243 | instance | changed: All items complete 2026-09-01 03:48:52.144281 | 2026-09-01 03:48:52.612438 | instance | changed: .d..t...... ./ 2026-09-01 03:48:53.083227 | instance | changed: .d..t...... ./ 2026-09-01 03:48:53.107702 | 2026-09-01 03:48:53.107836 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-01 03:48:53.533755 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008812 2026-09-01 03:48:53.761066 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007510 2026-09-01 03:48:53.780779 | 2026-09-01 03:48:53.780918 | PLAY [all] 2026-09-01 03:48:53.787404 | 2026-09-01 03:48:53.787478 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-01 03:48:54.205442 | instance | changed 2026-09-01 03:48:54.212198 | 2026-09-01 03:48:54.212248 | PLAY RECAP 2026-09-01 03:48:54.212292 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-01 03:48:54.212314 | 2026-09-01 03:48:54.356992 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 03:48:54.361682 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-01 03:48:54.965427 | 2026-09-01 03:48:54.965600 | PLAY [localhost] 2026-09-01 03:48:54.976324 | 2026-09-01 03:48:54.976401 | TASK [Generate Zuul manifest] 2026-09-01 03:48:54.998848 | localhost | ok 2026-09-01 03:48:55.017227 | 2026-09-01 03:48:55.017315 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-01 03:48:55.393240 | localhost | changed 2026-09-01 03:48:55.409791 | 2026-09-01 03:48:55.409898 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-01 03:48:55.442990 | localhost | ok 2026-09-01 03:48:55.452237 | 2026-09-01 03:48:55.452302 | TASK [Upload logs] 2026-09-01 03:48:55.476831 | localhost | ok 2026-09-01 03:48:55.537763 | 2026-09-01 03:48:55.537895 | TASK [Set zuul-log-path fact] 2026-09-01 03:48:55.560258 | localhost | ok 2026-09-01 03:48:55.573556 | 2026-09-01 03:48:55.573623 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 03:48:55.605464 | localhost | ok 2026-09-01 03:48:55.613888 | 2026-09-01 03:48:55.613958 | TASK [upload-logs : Create log directories] 2026-09-01 03:48:56.031999 | localhost | changed 2026-09-01 03:48:56.038357 | 2026-09-01 03:48:56.038428 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-01 03:48:56.449086 | localhost -> localhost | ok: Runtime: 0:00:00.006116 2026-09-01 03:48:56.454632 | 2026-09-01 03:48:56.454699 | TASK [upload-logs : Upload logs to log server] 2026-09-01 03:48:57.005616 | localhost | Output suppressed because no_log was given 2026-09-01 03:48:57.045749 | 2026-09-01 03:48:57.045889 | LOOP [upload-logs : Compress console log and json output] 2026-09-01 03:48:57.093094 | localhost | skipping: Conditional result was False 2026-09-01 03:48:57.099617 | localhost | skipping: Conditional result was False 2026-09-01 03:48:57.113940 | 2026-09-01 03:48:57.114087 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-01 03:48:57.157130 | localhost | skipping: Conditional result was False 2026-09-01 03:48:57.157537 | 2026-09-01 03:48:57.161144 | localhost | skipping: Conditional result was False 2026-09-01 03:48:57.177173 | 2026-09-01 03:48:57.177324 | LOOP [upload-logs : Upload console log and json output]