2026-09-01 18:15:55.445663 | Job console starting 2026-09-01 18:15:55.458400 | Updating git repos 2026-09-01 18:16:02.313046 | Cloning repos into workspace 2026-09-01 18:16:18.739734 | Restoring repo states 2026-09-01 18:16:26.872897 | Merging changes 2026-09-01 18:16:28.342574 | Checking out repos 2026-09-01 18:16:29.877023 | Preparing playbooks 2026-09-01 18:16:31.783697 | Running Ansible setup 2026-09-01 18:16:35.386236 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 18:16:36.035066 | 2026-09-01 18:16:36.035237 | PLAY [localhost] 2026-09-01 18:16:36.043263 | 2026-09-01 18:16:36.043344 | TASK [Gathering Facts] 2026-09-01 18:16:37.120908 | localhost | ok 2026-09-01 18:16:37.128460 | 2026-09-01 18:16:37.128605 | TASK [Setup log path fact] 2026-09-01 18:16:37.149338 | localhost | ok 2026-09-01 18:16:37.164079 | 2026-09-01 18:16:37.164149 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 18:16:37.198023 | localhost | ok 2026-09-01 18:16:37.207223 | 2026-09-01 18:16:37.207330 | TASK [emit-job-header : Print job information] 2026-09-01 18:16:37.248527 | # Job Information 2026-09-01 18:16:37.248817 | Ansible Version: 2.16.18 2026-09-01 18:16:37.248876 | Job: atmosphere-molecule-csi-rbd 2026-09-01 18:16:37.248910 | Pipeline: check 2026-09-01 18:16:37.248937 | Executor: 2d72f0692154 2026-09-01 18:16:37.248989 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-09-01 18:16:37.249025 | Event ID: 87e61580-a630-11f1-9118-1bace0e3318d 2026-09-01 18:16:37.253471 | 2026-09-01 18:16:37.253563 | LOOP [emit-job-header : Print node information] 2026-09-01 18:16:37.351901 | localhost | ok: 2026-09-01 18:16:37.352128 | localhost | # Node Information 2026-09-01 18:16:37.352176 | localhost | Inventory Hostname: instance 2026-09-01 18:16:37.352207 | localhost | Hostname: np0000191746 2026-09-01 18:16:37.352241 | localhost | Username: zuul 2026-09-01 18:16:37.352279 | localhost | Distro: Ubuntu 22.04 2026-09-01 18:16:37.352313 | localhost | Provider: yul1 2026-09-01 18:16:37.352345 | localhost | Region: ca-ymq-1 2026-09-01 18:16:37.352376 | localhost | Label: ubuntu-jammy 2026-09-01 18:16:37.352407 | localhost | Product Name: OpenStack Nova 2026-09-01 18:16:37.352437 | localhost | Interface IP: 162.253.55.175 2026-09-01 18:16:37.366838 | 2026-09-01 18:16:37.366997 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-01 18:16:37.757964 | localhost -> localhost | changed 2026-09-01 18:16:37.770671 | 2026-09-01 18:16:37.770783 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-01 18:16:38.993835 | localhost -> localhost | changed 2026-09-01 18:16:39.006572 | 2026-09-01 18:16:39.006674 | PLAY [all] 2026-09-01 18:16:39.022793 | 2026-09-01 18:16:39.022928 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-01 18:16:39.275115 | instance -> localhost | ok 2026-09-01 18:16:39.281358 | 2026-09-01 18:16:39.281427 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-01 18:16:39.310772 | instance | ok 2026-09-01 18:16:39.324631 | instance | included: /var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-01 18:16:39.330341 | 2026-09-01 18:16:39.330411 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-01 18:16:40.390138 | instance -> localhost | Generating public/private rsa key pair. 2026-09-01 18:16:40.390353 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/work/c749bc6b9de54a6eab187eccbbe10d9b_id_rsa 2026-09-01 18:16:40.390393 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/work/c749bc6b9de54a6eab187eccbbe10d9b_id_rsa.pub 2026-09-01 18:16:40.390425 | instance -> localhost | The key fingerprint is: 2026-09-01 18:16:40.390454 | instance -> localhost | SHA256:If2LQpL0xVAoi55kQPR9cQayzAfmUIxDsHDg0Qkv46E zuul-build-sshkey 2026-09-01 18:16:40.390497 | instance -> localhost | The key's randomart image is: 2026-09-01 18:16:40.390527 | instance -> localhost | +---[RSA 3072]----+ 2026-09-01 18:16:40.390561 | instance -> localhost | |=B*o==.=oo | 2026-09-01 18:16:40.390591 | instance -> localhost | |+.==O.=++ | 2026-09-01 18:16:40.390620 | instance -> localhost | | B +oO.o= | 2026-09-01 18:16:40.390649 | instance -> localhost | |o B..ooo o | 2026-09-01 18:16:40.390676 | instance -> localhost | |E= .o o S . | 2026-09-01 18:16:40.390704 | instance -> localhost | | o o . . | 2026-09-01 18:16:40.390731 | instance -> localhost | | . . . | 2026-09-01 18:16:40.390758 | instance -> localhost | | . | 2026-09-01 18:16:40.390789 | instance -> localhost | | | 2026-09-01 18:16:40.390817 | instance -> localhost | +----[SHA256]-----+ 2026-09-01 18:16:40.390877 | instance -> localhost | ok: Runtime: 0:00:00.593979 2026-09-01 18:16:40.398646 | 2026-09-01 18:16:40.398709 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-01 18:16:40.428303 | instance | ok 2026-09-01 18:16:40.438514 | instance | included: /var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-01 18:16:40.447390 | 2026-09-01 18:16:40.447455 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-01 18:16:40.472708 | instance | skipping: Conditional result was False 2026-09-01 18:16:40.482600 | 2026-09-01 18:16:40.482717 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-01 18:16:40.943960 | instance | changed 2026-09-01 18:16:40.952339 | 2026-09-01 18:16:40.952405 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-01 18:16:41.163429 | instance | ok 2026-09-01 18:16:41.169193 | 2026-09-01 18:16:41.169262 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-01 18:16:41.688774 | instance | changed 2026-09-01 18:16:41.698789 | 2026-09-01 18:16:41.698875 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-01 18:16:42.179279 | instance | changed 2026-09-01 18:16:42.189677 | 2026-09-01 18:16:42.189917 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-01 18:16:42.207509 | instance | skipping: Conditional result was False 2026-09-01 18:16:42.218570 | 2026-09-01 18:16:42.218672 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-01 18:16:42.602503 | instance -> localhost | changed 2026-09-01 18:16:42.616535 | 2026-09-01 18:16:42.616598 | TASK [add-build-sshkey : Add back temp key] 2026-09-01 18:16:42.938672 | instance -> localhost | Identity added: /var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/work/c749bc6b9de54a6eab187eccbbe10d9b_id_rsa (zuul-build-sshkey) 2026-09-01 18:16:42.938956 | instance -> localhost | ok: Runtime: 0:00:00.014148 2026-09-01 18:16:42.945763 | 2026-09-01 18:16:42.945828 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-01 18:16:43.274157 | instance | ok 2026-09-01 18:16:43.279347 | 2026-09-01 18:16:43.279426 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-01 18:16:43.304008 | instance | skipping: Conditional result was False 2026-09-01 18:16:43.318186 | 2026-09-01 18:16:43.318254 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-01 18:16:43.665826 | instance | ok 2026-09-01 18:16:43.674219 | 2026-09-01 18:16:43.674360 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-01 18:16:47.320356 | instance | Output suppressed because no_log was given 2026-09-01 18:16:47.330264 | 2026-09-01 18:16:47.330329 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-01 18:16:47.542146 | instance | ok: "logs" 2026-09-01 18:16:47.542436 | instance | ok: All items complete 2026-09-01 18:16:47.542465 | 2026-09-01 18:16:47.707685 | instance | ok: "artifacts" 2026-09-01 18:16:47.891255 | instance | ok: "docs" 2026-09-01 18:16:47.909040 | 2026-09-01 18:16:47.909236 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-01 18:16:48.105622 | instance | changed: "logs" 2026-09-01 18:16:48.282687 | instance | changed: "artifacts" 2026-09-01 18:16:48.461159 | instance | changed: "docs" 2026-09-01 18:16:48.481650 | 2026-09-01 18:16:48.481801 | PLAY RECAP 2026-09-01 18:16:48.481870 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-01 18:16:48.481910 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 18:16:48.481939 | 2026-09-01 18:16:48.617098 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 18:16:48.621280 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 18:16:49.280459 | 2026-09-01 18:16:49.280599 | PLAY [all] 2026-09-01 18:16:49.291337 | 2026-09-01 18:16:49.291411 | TASK [setup-uv : Extract archive] 2026-09-01 18:16:51.634874 | instance | changed 2026-09-01 18:16:51.641719 | 2026-09-01 18:16:51.641801 | TASK [setup-uv : Print version] 2026-09-01 18:16:52.001093 | instance | uv 0.8.13 2026-09-01 18:16:52.181175 | instance | ok: Runtime: 0:00:00.011471 2026-09-01 18:16:52.192264 | 2026-09-01 18:16:52.192335 | TASK [Install Ansible collection siblings] 2026-09-01 18:17:00.646862 | instance | changed: 2026-09-01 18:17:00.647052 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-01 18:17:00.647082 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-01 18:17:00.647104 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-01 18:17:00.647125 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-01 18:17:00.647145 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-01 18:17:00.647169 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-01 18:17:00.647192 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-01 18:17:00.647214 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-01 18:17:00.647234 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-01 18:17:00.647254 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-01 18:17:00.647274 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-01 18:17:00.647293 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-01 18:17:00.647312 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-01 18:17:00.647331 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-01 18:17:00.647350 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-01 18:17:00.647368 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-01 18:17:00.647387 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-01 18:17:00.647406 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-01 18:17:00.647425 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-01 18:17:00.647443 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-01 18:17:00.647462 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-01 18:17:00.647480 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-01 18:17:00.647501 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 18:17:00.647530 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-01 18:17:00.647550 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-01 18:17:00.647569 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 18:17:00.647587 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 18:17:00.647605 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 18:17:00.647623 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-01 18:17:00.647641 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-01 18:17:00.647660 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 18:17:00.647678 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 18:17:00.647697 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-01 18:17:00.647716 | 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 18:17:00.647742 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.647761 | instance | Process install dependency map 2026-09-01 18:17:00.647780 | instance | Starting collection install process 2026-09-01 18:17:00.647799 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-01 18:17:00.647817 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-01 18:17:00.647837 | instance | ansible.netcommon:* was installed successfully 2026-09-01 18:17:00.647855 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-01 18:17:00.647873 | instance | Creating virtual environment at: .venv 2026-09-01 18:17:00.647892 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 18:17:00.647910 | instance | Downloading setuptools (1.1MiB) 2026-09-01 18:17:00.647929 | instance | Downloading netaddr (2.2MiB) 2026-09-01 18:17:00.647956 | instance | Downloading rjsonnet (1.2MiB) 2026-09-01 18:17:00.647975 | instance | Downloading cryptography (4.2MiB) 2026-09-01 18:17:00.647994 | instance | Downloading openstacksdk (1.7MiB) 2026-09-01 18:17:00.648013 | instance | Downloading kubernetes (1.9MiB) 2026-09-01 18:17:00.648032 | instance | Downloading pygments (1.2MiB) 2026-09-01 18:17:00.648053 | instance | Downloading pydantic-core (2.0MiB) 2026-09-01 18:17:00.648074 | instance | Downloading ansible-core (2.1MiB) 2026-09-01 18:17:00.648093 | instance | Downloading rjsonnet 2026-09-01 18:17:00.648114 | instance | Building pyperclip==1.9.0 2026-09-01 18:17:00.648133 | instance | Downloading pydantic-core 2026-09-01 18:17:00.648152 | instance | Downloading netaddr 2026-09-01 18:17:00.648170 | instance | Downloading cryptography 2026-09-01 18:17:00.648189 | instance | Downloading pygments 2026-09-01 18:17:00.648208 | instance | Downloading setuptools 2026-09-01 18:17:00.648227 | instance | Downloading kubernetes 2026-09-01 18:17:00.648245 | instance | Downloading ansible-core 2026-09-01 18:17:00.648264 | instance | Downloading openstacksdk 2026-09-01 18:17:00.648283 | instance | Built pyperclip==1.9.0 2026-09-01 18:17:00.648302 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 18:17:00.648321 | instance | Installed 83 packages in 51ms 2026-09-01 18:17:00.648339 | instance | Checked out ansible.posix to 1.6.0 2026-09-01 18:17:00.648358 | 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 18:17:00.648377 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.648395 | instance | Process install dependency map 2026-09-01 18:17:00.648419 | instance | Starting collection install process 2026-09-01 18:17:00.648439 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-01 18:17:00.648458 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-01 18:17:00.648477 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-01 18:17:00.648495 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.648514 | instance | 2.17.12 2026-09-01 18:17:00.648532 | instance | Checked out ansible.utils to v6.1.0 2026-09-01 18:17:00.649744 | 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 18:17:00.649787 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.649810 | instance | Process install dependency map 2026-09-01 18:17:00.649830 | instance | Starting collection install process 2026-09-01 18:17:00.649850 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-01 18:17:00.649870 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-01 18:17:00.649890 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-01 18:17:00.649910 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.649929 | instance | 2.17.12 2026-09-01 18:17:00.649948 | instance | Checked out atmosphere.common to v0.7.0 2026-09-01 18:17:00.649967 | 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 18:17:00.649986 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.650004 | instance | Process install dependency map 2026-09-01 18:17:00.650023 | instance | Starting collection install process 2026-09-01 18:17:00.650042 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-01 18:17:00.650061 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-01 18:17:00.650079 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-01 18:17:00.650098 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.650116 | instance | 2.17.12 2026-09-01 18:17:00.650134 | instance | Checked out community.crypto to 2.2.3 2026-09-01 18:17:00.650153 | 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 18:17:00.650172 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.650365 | instance | Process install dependency map 2026-09-01 18:17:00.650383 | instance | Starting collection install process 2026-09-01 18:17:00.650401 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-01 18:17:00.650420 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-01 18:17:00.650438 | instance | community.crypto:2.2.3 was installed successfully 2026-09-01 18:17:00.650456 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.650474 | instance | 2.17.12 2026-09-01 18:17:00.650492 | instance | Checked out community.general to 7.3.0 2026-09-01 18:17:00.650510 | 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 18:17:00.650536 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.650556 | instance | Process install dependency map 2026-09-01 18:17:00.650575 | instance | Starting collection install process 2026-09-01 18:17:00.650593 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-01 18:17:00.650611 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-01 18:17:00.650630 | instance | community.general:7.3.0 was installed successfully 2026-09-01 18:17:00.650649 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.650667 | instance | 2.17.12 2026-09-01 18:17:00.650686 | instance | Checked out community.mysql to 3.6.0 2026-09-01 18:17:00.650706 | 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 18:17:00.650726 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.650745 | instance | Process install dependency map 2026-09-01 18:17:00.650764 | instance | Starting collection install process 2026-09-01 18:17:00.650783 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-01 18:17:00.650802 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-01 18:17:00.650821 | instance | community.mysql:3.6.0 was installed successfully 2026-09-01 18:17:00.650840 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.650858 | instance | 2.17.12 2026-09-01 18:17:00.650877 | instance | Checked out kubernetes.core to 2.4.0 2026-09-01 18:17:00.650895 | 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 18:17:00.650914 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.650932 | instance | Process install dependency map 2026-09-01 18:17:00.650951 | instance | Starting collection install process 2026-09-01 18:17:00.650970 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-01 18:17:00.650988 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-01 18:17:00.651007 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-01 18:17:00.651027 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.651048 | instance | 2.17.12 2026-09-01 18:17:00.651068 | instance | Checked out openstack.cloud to 2.6.0 2026-09-01 18:17:00.651087 | 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 18:17:00.651106 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.651125 | instance | Process install dependency map 2026-09-01 18:17:00.651143 | instance | Starting collection install process 2026-09-01 18:17:00.651162 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-01 18:17:00.651180 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-01 18:17:00.651199 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-01 18:17:00.651217 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.651235 | instance | 2.17.12 2026-09-01 18:17:00.651260 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-01 18:17:00.651280 | 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 18:17:00.651298 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.651317 | instance | Process install dependency map 2026-09-01 18:17:00.651335 | instance | Starting collection install process 2026-09-01 18:17:00.651353 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-01 18:17:00.651371 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-01 18:17:00.651390 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-01 18:17:00.651409 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.651427 | instance | 2.17.12 2026-09-01 18:17:00.651445 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-01 18:17:00.651464 | 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 18:17:00.651482 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.651500 | instance | Process install dependency map 2026-09-01 18:17:00.651519 | instance | Starting collection install process 2026-09-01 18:17:00.651538 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-01 18:17:00.651556 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-01 18:17:00.651575 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-01 18:17:00.651594 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.651613 | instance | 2.17.12 2026-09-01 18:17:00.651631 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-01 18:17:00.651649 | 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 18:17:00.651668 | instance | Starting galaxy collection install process 2026-09-01 18:17:00.651686 | instance | Process install dependency map 2026-09-01 18:17:00.651705 | instance | Starting collection install process 2026-09-01 18:17:00.651723 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-01 18:17:00.651742 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-01 18:17:00.651761 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-01 18:17:00.651779 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 18:17:00.651798 | instance | 2.17.12 2026-09-01 18:17:00.657637 | 2026-09-01 18:17:00.657685 | PLAY RECAP 2026-09-01 18:17:00.657734 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 18:17:00.657755 | 2026-09-01 18:17:00.820145 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 18:17:00.823235 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 18:17:01.456225 | 2026-09-01 18:17:01.456358 | PLAY [all] 2026-09-01 18:17:01.467516 | 2026-09-01 18:17:01.467590 | TASK [Install "jq" for log collection] 2026-09-01 18:17:10.396216 | instance | changed 2026-09-01 18:17:10.399333 | 2026-09-01 18:17:10.399467 | PLAY RECAP 2026-09-01 18:17:10.399543 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 18:17:10.399622 | 2026-09-01 18:17:10.620045 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 18:17:10.629916 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 18:17:11.202037 | 2026-09-01 18:17:11.202167 | PLAY [all] 2026-09-01 18:17:11.212893 | 2026-09-01 18:17:11.212988 | LOOP [Check inventory directories] 2026-09-01 18:17:11.585446 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-01 18:17:11.729447 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-01 18:17:11.891667 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-01 18:17:11.903263 | 2026-09-01 18:17:11.903460 | LOOP [Copy inventory file for Zuul] 2026-09-01 18:17:12.748148 | instance | changed: 2026-09-01 18:17:12.748380 | instance | { 2026-09-01 18:17:12.748420 | instance | "ansible_loop_var": "item", 2026-09-01 18:17:12.748452 | instance | "changed": false, 2026-09-01 18:17:12.748480 | instance | "failed": false, 2026-09-01 18:17:12.748512 | instance | "invocation": { 2026-09-01 18:17:12.748540 | instance | "module_args": { 2026-09-01 18:17:12.748565 | instance | "checksum_algorithm": "sha1", 2026-09-01 18:17:12.748591 | instance | "follow": true, 2026-09-01 18:17:12.748618 | instance | "get_attributes": true, 2026-09-01 18:17:12.748644 | instance | "get_checksum": true, 2026-09-01 18:17:12.748670 | instance | "get_mime": true, 2026-09-01 18:17:12.748696 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-01 18:17:12.748766 | instance | } 2026-09-01 18:17:12.748795 | instance | }, 2026-09-01 18:17:12.748821 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-01 18:17:12.748846 | instance | "stat": { 2026-09-01 18:17:12.748872 | instance | "atime": 1788286614.0098317, 2026-09-01 18:17:12.748908 | instance | "attr_flags": "e", 2026-09-01 18:17:12.748936 | instance | "attributes": [ 2026-09-01 18:17:12.748982 | instance | "extents" 2026-09-01 18:17:12.749010 | instance | ], 2026-09-01 18:17:12.749036 | instance | "block_size": 4096, 2026-09-01 18:17:12.749061 | instance | "blocks": 8, 2026-09-01 18:17:12.749087 | instance | "charset": "unknown", 2026-09-01 18:17:12.749142 | instance | "ctime": 1788286613.0578146, 2026-09-01 18:17:12.749172 | instance | "dev": 64513, 2026-09-01 18:17:12.749199 | instance | "device_type": 0, 2026-09-01 18:17:12.749224 | instance | "executable": true, 2026-09-01 18:17:12.749249 | instance | "exists": true, 2026-09-01 18:17:12.749274 | instance | "gid": 1000, 2026-09-01 18:17:12.749299 | instance | "gr_name": "zuul", 2026-09-01 18:17:12.749324 | instance | "inode": 1048007, 2026-09-01 18:17:12.749349 | instance | "isblk": false, 2026-09-01 18:17:12.749375 | instance | "ischr": false, 2026-09-01 18:17:12.749400 | instance | "isdir": true, 2026-09-01 18:17:12.749424 | instance | "isfifo": false, 2026-09-01 18:17:12.749448 | instance | "isgid": false, 2026-09-01 18:17:12.749472 | instance | "islnk": false, 2026-09-01 18:17:12.749496 | instance | "isreg": false, 2026-09-01 18:17:12.749520 | instance | "issock": false, 2026-09-01 18:17:12.749544 | instance | "isuid": false, 2026-09-01 18:17:12.749567 | instance | "mimetype": "unknown", 2026-09-01 18:17:12.749592 | instance | "mode": "0755", 2026-09-01 18:17:12.749617 | instance | "mtime": 1788286613.0578146, 2026-09-01 18:17:12.749641 | instance | "nlink": 23, 2026-09-01 18:17:12.749666 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-01 18:17:12.749752 | instance | "pw_name": "zuul", 2026-09-01 18:17:12.749787 | instance | "readable": true, 2026-09-01 18:17:12.749813 | instance | "rgrp": true, 2026-09-01 18:17:12.749839 | instance | "roth": true, 2026-09-01 18:17:12.749875 | instance | "rusr": true, 2026-09-01 18:17:12.749901 | instance | "size": 4096, 2026-09-01 18:17:12.749925 | instance | "uid": 1000, 2026-09-01 18:17:12.749950 | instance | "version": "3560263618", 2026-09-01 18:17:12.749975 | instance | "wgrp": false, 2026-09-01 18:17:12.750002 | instance | "woth": false, 2026-09-01 18:17:12.750027 | instance | "writeable": true, 2026-09-01 18:17:12.750051 | instance | "wusr": true, 2026-09-01 18:17:12.750074 | instance | "xgrp": true, 2026-09-01 18:17:12.750098 | instance | "xoth": true, 2026-09-01 18:17:12.750122 | instance | "xusr": true 2026-09-01 18:17:12.750146 | instance | } 2026-09-01 18:17:12.750170 | instance | } 2026-09-01 18:17:12.750216 | instance | ok: All items complete 2026-09-01 18:17:12.750246 | 2026-09-01 18:17:12.751999 | instance | skipping: Conditional result was False 2026-09-01 18:17:12.752842 | instance | skipping: Conditional result was False 2026-09-01 18:17:12.764557 | 2026-09-01 18:17:12.897700 | LOOP [Switch "ansible_host" to private IP] 2026-09-01 18:17:13.248221 | instance | changed: 1 replacements made 2026-09-01 18:17:13.250068 | instance | skipping: Conditional result was False 2026-09-01 18:17:13.250943 | instance | skipping: Conditional result was False 2026-09-01 18:17:13.276193 | 2026-09-01 18:17:13.276378 | TASK [Run Molecule scenario] 2026-09-01 18:17:14.161376 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-01 18:17:14.547908 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-01 18:17:14.547993 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-01 18:17:24.391317 | instance | INFO [csi > dependency] Executing 2026-09-01 18:17:24.391745 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-01 18:17:24.392118 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-01 18:17:24.392329 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-01 18:17:24.406385 | instance | INFO [csi > cleanup] Executing 2026-09-01 18:17:24.406978 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 18:17:24.421626 | instance | INFO [csi > destroy] Executing 2026-09-01 18:17:24.421776 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-01 18:17:24.421904 | instance | INFO [csi > destroy] Executed: Successful 2026-09-01 18:17:24.431570 | instance | INFO [csi > syntax] Executing 2026-09-01 18:17:25.844112 | instance | 2026-09-01 18:17:25.844431 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 18:17:25.900545 | instance | INFO [csi > syntax] Executed: Successful 2026-09-01 18:17:25.916370 | instance | INFO [csi > create] Executing 2026-09-01 18:17:25.920087 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 18:17:25.935111 | instance | INFO [csi > prepare] Executing 2026-09-01 18:17:26.660083 | instance | 2026-09-01 18:17:26.660298 | instance | PLAY [Prepare] ***************************************************************** 2026-09-01 18:17:26.660557 | instance | 2026-09-01 18:17:26.660830 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 18:17:26.661073 | instance | Tuesday 01 September 2026 18:17:26 +0000 (0:00:00.022) 0:00:00.022 ***** 2026-09-01 18:17:27.875925 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 18:17:27.876134 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 18:17:27.876370 | instance | interpreter could change the meaning of that path. See 2026-09-01 18:17:27.876786 | instance | https://docs.ansible.com/ansible- 2026-09-01 18:17:27.876866 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 18:17:27.884000 | instance | ok: [instance] 2026-09-01 18:17:27.884212 | instance | 2026-09-01 18:17:27.884480 | instance | TASK [Configure short hostname] ************************************************ 2026-09-01 18:17:27.884711 | instance | Tuesday 01 September 2026 18:17:27 +0000 (0:00:01.224) 0:00:01.246 ***** 2026-09-01 18:17:28.591368 | instance | changed: [instance] 2026-09-01 18:17:28.591613 | instance | 2026-09-01 18:17:28.591846 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-01 18:17:28.592070 | instance | Tuesday 01 September 2026 18:17:28 +0000 (0:00:00.707) 0:00:01.953 ***** 2026-09-01 18:17:28.852201 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-01 18:17:28.852456 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-01 18:17:28.852692 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-01 18:17:28.860556 | instance | changed: [instance] 2026-09-01 18:17:28.860784 | instance | 2026-09-01 18:17:28.861016 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-01 18:17:28.861243 | instance | Tuesday 01 September 2026 18:17:28 +0000 (0:00:00.269) 0:00:02.223 ***** 2026-09-01 18:17:29.586229 | instance | ok: [instance] 2026-09-01 18:17:29.586341 | instance | 2026-09-01 18:17:29.586565 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-01 18:17:29.586779 | instance | 2026-09-01 18:17:29.587008 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 18:17:29.587266 | instance | Tuesday 01 September 2026 18:17:29 +0000 (0:00:00.725) 0:00:02.948 ***** 2026-09-01 18:17:30.335780 | instance | ok: [instance] 2026-09-01 18:17:30.335968 | instance | 2026-09-01 18:17:30.336202 | instance | TASK [Install depedencies] ***************************************************** 2026-09-01 18:17:30.336468 | instance | Tuesday 01 September 2026 18:17:30 +0000 (0:00:00.749) 0:00:03.698 ***** 2026-09-01 18:17:52.596927 | instance | changed: [instance] 2026-09-01 18:17:52.597113 | instance | 2026-09-01 18:17:52.597351 | instance | TASK [Start up service] ******************************************************** 2026-09-01 18:17:52.597581 | instance | Tuesday 01 September 2026 18:17:52 +0000 (0:00:22.261) 0:00:25.959 ***** 2026-09-01 18:17:53.136165 | instance | ok: [instance] 2026-09-01 18:17:53.136356 | instance | 2026-09-01 18:17:53.136593 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-01 18:17:53.136847 | instance | Tuesday 01 September 2026 18:17:53 +0000 (0:00:00.539) 0:00:26.498 ***** 2026-09-01 18:17:53.451317 | instance | ok: [instance] 2026-09-01 18:17:53.451612 | instance | 2026-09-01 18:17:53.451998 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-01 18:17:53.452356 | instance | Tuesday 01 September 2026 18:17:53 +0000 (0:00:00.315) 0:00:26.813 ***** 2026-09-01 18:17:54.140270 | instance | changed: [instance] 2026-09-01 18:17:54.140326 | instance | 2026-09-01 18:17:54.140338 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-01 18:17:54.140349 | instance | Tuesday 01 September 2026 18:17:54 +0000 (0:00:00.688) 0:00:27.501 ***** 2026-09-01 18:17:54.335557 | instance | ok: [instance] 2026-09-01 18:17:54.335620 | instance | 2026-09-01 18:17:54.335874 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-01 18:17:54.335905 | instance | Tuesday 01 September 2026 18:17:54 +0000 (0:00:00.196) 0:00:27.698 ***** 2026-09-01 18:17:54.360608 | instance | skipping: [instance] 2026-09-01 18:17:54.361407 | instance | 2026-09-01 18:17:54.361445 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-01 18:17:54.361457 | instance | Tuesday 01 September 2026 18:17:54 +0000 (0:00:00.024) 0:00:27.722 ***** 2026-09-01 18:17:54.919012 | instance | changed: [instance] => (item=osd0) 2026-09-01 18:17:54.920258 | instance | changed: [instance] => (item=osd1) 2026-09-01 18:17:54.920311 | instance | changed: [instance] => (item=osd2) 2026-09-01 18:17:54.920322 | instance | 2026-09-01 18:17:54.920332 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-01 18:17:54.920341 | instance | Tuesday 01 September 2026 18:17:54 +0000 (0:00:00.558) 0:00:28.281 ***** 2026-09-01 18:17:55.560669 | instance | changed: [instance] => (item=osd0) 2026-09-01 18:17:55.561874 | instance | changed: [instance] => (item=osd1) 2026-09-01 18:17:55.561926 | instance | changed: [instance] => (item=osd2) 2026-09-01 18:17:55.561937 | instance | 2026-09-01 18:17:55.561947 | instance | TASK [Start loop devices] ****************************************************** 2026-09-01 18:17:55.561957 | instance | Tuesday 01 September 2026 18:17:55 +0000 (0:00:00.641) 0:00:28.922 ***** 2026-09-01 18:17:56.300383 | instance | changed: [instance] => (item=osd0) 2026-09-01 18:17:56.300436 | instance | changed: [instance] => (item=osd1) 2026-09-01 18:17:56.300447 | instance | changed: [instance] => (item=osd2) 2026-09-01 18:17:56.300456 | instance | 2026-09-01 18:17:56.300466 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-01 18:17:56.300475 | instance | Tuesday 01 September 2026 18:17:56 +0000 (0:00:00.738) 0:00:29.661 ***** 2026-09-01 18:17:59.603230 | instance | changed: [instance] => (item=osd0) 2026-09-01 18:17:59.604437 | instance | changed: [instance] => (item=osd1) 2026-09-01 18:17:59.604485 | instance | changed: [instance] => (item=osd2) 2026-09-01 18:17:59.604497 | instance | 2026-09-01 18:17:59.604507 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-01 18:17:59.604516 | instance | Tuesday 01 September 2026 18:17:59 +0000 (0:00:03.303) 0:00:32.965 ***** 2026-09-01 18:18:01.475178 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-01 18:18:01.475234 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-01 18:18:01.475260 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-01 18:18:01.475270 | instance | 2026-09-01 18:18:01.475279 | instance | PLAY RECAP ********************************************************************* 2026-09-01 18:18:01.475289 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-01 18:18:01.475299 | instance | 2026-09-01 18:18:01.475307 | instance | Tuesday 01 September 2026 18:18:01 +0000 (0:00:01.868) 0:00:34.834 ***** 2026-09-01 18:18:01.475316 | instance | =============================================================================== 2026-09-01 18:18:01.475325 | instance | Install depedencies ---------------------------------------------------- 22.26s 2026-09-01 18:18:01.475334 | instance | Create a volume group for each loop device ------------------------------ 3.30s 2026-09-01 18:18:01.475342 | instance | Create a logical volume for each loop device ---------------------------- 1.87s 2026-09-01 18:18:01.475351 | instance | Gathering Facts --------------------------------------------------------- 1.22s 2026-09-01 18:18:01.475360 | instance | Gathering Facts --------------------------------------------------------- 0.75s 2026-09-01 18:18:01.475369 | instance | Start loop devices ------------------------------------------------------ 0.74s 2026-09-01 18:18:01.475377 | instance | Purge "snapd" package --------------------------------------------------- 0.73s 2026-09-01 18:18:01.475386 | instance | Configure short hostname ------------------------------------------------ 0.71s 2026-09-01 18:18:01.475395 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.69s 2026-09-01 18:18:01.475408 | instance | Set permissions on loopback devices ------------------------------------- 0.64s 2026-09-01 18:18:01.475492 | instance | Create devices for Ceph ------------------------------------------------- 0.56s 2026-09-01 18:18:01.475830 | instance | Start up service -------------------------------------------------------- 0.54s 2026-09-01 18:18:01.476127 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s 2026-09-01 18:18:01.476421 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s 2026-09-01 18:18:01.476730 | instance | Get list of all loopback devices ---------------------------------------- 0.20s 2026-09-01 18:18:01.477027 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-01 18:18:01.553034 | instance | INFO [csi > prepare] Executed: Successful 2026-09-01 18:18:01.567382 | instance | INFO [csi > converge] Executing 2026-09-01 18:18:02.903550 | instance | 2026-09-01 18:18:02.903954 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-01 18:18:02.904248 | instance | 2026-09-01 18:18:02.904545 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 18:18:02.904924 | instance | Tuesday 01 September 2026 18:18:02 +0000 (0:00:00.006) 0:00:00.006 ***** 2026-09-01 18:18:03.922409 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 18:18:03.922463 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 18:18:03.922474 | instance | interpreter could change the meaning of that path. See 2026-09-01 18:18:03.922484 | instance | https://docs.ansible.com/ansible- 2026-09-01 18:18:03.922493 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 18:18:03.935438 | instance | ok: [instance] 2026-09-01 18:18:03.935468 | instance | 2026-09-01 18:18:03.935479 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-01 18:18:03.935489 | instance | Tuesday 01 September 2026 18:18:03 +0000 (0:00:01.031) 0:00:01.037 ***** 2026-09-01 18:18:03.997210 | instance | ok: [instance] => { 2026-09-01 18:18:03.997240 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-01 18:18:03.997250 | instance | } 2026-09-01 18:18:03.997259 | instance | 2026-09-01 18:18:03.997267 | instance | PLAY [all] ********************************************************************* 2026-09-01 18:18:03.997276 | instance | 2026-09-01 18:18:03.997285 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 18:18:03.997310 | instance | Tuesday 01 September 2026 18:18:03 +0000 (0:00:00.061) 0:00:01.098 ***** 2026-09-01 18:18:04.934250 | instance | ok: [instance] 2026-09-01 18:18:04.934309 | instance | 2026-09-01 18:18:04.934322 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-01 18:18:04.934332 | instance | Tuesday 01 September 2026 18:18:04 +0000 (0:00:00.937) 0:00:02.036 ***** 2026-09-01 18:18:04.982366 | instance | skipping: [instance] 2026-09-01 18:18:04.983115 | instance | 2026-09-01 18:18:04.983169 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-01 18:18:04.983180 | instance | Tuesday 01 September 2026 18:18:04 +0000 (0:00:00.049) 0:00:02.085 ***** 2026-09-01 18:18:05.219422 | instance | ok: [instance] 2026-09-01 18:18:05.219502 | instance | 2026-09-01 18:18:05.219922 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-01 18:18:05.219961 | instance | 2026-09-01 18:18:05.219966 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 18:18:05.219977 | instance | Tuesday 01 September 2026 18:18:05 +0000 (0:00:00.237) 0:00:02.323 ***** 2026-09-01 18:18:06.180458 | instance | ok: [instance] 2026-09-01 18:18:06.180621 | instance | 2026-09-01 18:18:06.180977 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 18:18:06.181271 | instance | Tuesday 01 September 2026 18:18:06 +0000 (0:00:00.960) 0:00:03.283 ***** 2026-09-01 18:18:06.536094 | instance | ok: [instance] 2026-09-01 18:18:06.536285 | instance | 2026-09-01 18:18:06.536778 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 18:18:06.537083 | instance | Tuesday 01 September 2026 18:18:06 +0000 (0:00:00.355) 0:00:03.638 ***** 2026-09-01 18:18:06.591335 | instance | skipping: [instance] 2026-09-01 18:18:06.591659 | instance | 2026-09-01 18:18:06.591993 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-01 18:18:06.592290 | instance | Tuesday 01 September 2026 18:18:06 +0000 (0:00:00.055) 0:00:03.694 ***** 2026-09-01 18:18:06.943722 | instance | changed: [instance] 2026-09-01 18:18:06.943969 | instance | 2026-09-01 18:18:06.944178 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 18:18:06.944377 | instance | Tuesday 01 September 2026 18:18:06 +0000 (0:00:00.352) 0:00:04.046 ***** 2026-09-01 18:18:06.999565 | instance | ok: [instance] => { 2026-09-01 18:18:06.999824 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-01 18:18:07.000008 | instance | } 2026-09-01 18:18:07.000198 | instance | 2026-09-01 18:18:07.000383 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 18:18:07.000566 | instance | Tuesday 01 September 2026 18:18:06 +0000 (0:00:00.054) 0:00:04.100 ***** 2026-09-01 18:18:07.678838 | instance | changed: [instance] 2026-09-01 18:18:07.678881 | instance | 2026-09-01 18:18:07.678893 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 18:18:07.678903 | instance | Tuesday 01 September 2026 18:18:07 +0000 (0:00:00.680) 0:00:04.781 ***** 2026-09-01 18:18:07.737403 | instance | skipping: [instance] 2026-09-01 18:18:07.737427 | instance | 2026-09-01 18:18:07.737434 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 18:18:07.737441 | instance | Tuesday 01 September 2026 18:18:07 +0000 (0:00:00.059) 0:00:04.840 ***** 2026-09-01 18:18:07.788972 | instance | skipping: [instance] 2026-09-01 18:18:07.788995 | instance | 2026-09-01 18:18:07.789001 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 18:18:07.789008 | instance | Tuesday 01 September 2026 18:18:07 +0000 (0:00:00.051) 0:00:04.892 ***** 2026-09-01 18:18:08.016035 | instance | ok: [instance] 2026-09-01 18:18:08.016077 | instance | 2026-09-01 18:18:08.016084 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 18:18:08.016091 | instance | Tuesday 01 September 2026 18:18:08 +0000 (0:00:00.226) 0:00:05.118 ***** 2026-09-01 18:18:09.251183 | instance | ok: [instance] 2026-09-01 18:18:09.251907 | instance | 2026-09-01 18:18:09.251961 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 18:18:09.251991 | instance | Tuesday 01 September 2026 18:18:09 +0000 (0:00:01.235) 0:00:06.353 ***** 2026-09-01 18:18:09.335803 | instance | ok: [instance] => { 2026-09-01 18:18:09.336966 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-01 18:18:09.337020 | instance | } 2026-09-01 18:18:09.337032 | instance | 2026-09-01 18:18:09.337042 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 18:18:09.337051 | instance | Tuesday 01 September 2026 18:18:09 +0000 (0:00:00.084) 0:00:06.438 ***** 2026-09-01 18:18:10.099150 | instance | changed: [instance] 2026-09-01 18:18:10.099202 | instance | 2026-09-01 18:18:10.099214 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 18:18:10.099225 | instance | Tuesday 01 September 2026 18:18:10 +0000 (0:00:00.762) 0:00:07.201 ***** 2026-09-01 18:18:12.920475 | instance | changed: [instance] 2026-09-01 18:18:12.920550 | instance | 2026-09-01 18:18:12.921064 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-01 18:18:12.921106 | instance | Tuesday 01 September 2026 18:18:12 +0000 (0:00:02.822) 0:00:10.023 ***** 2026-09-01 18:18:12.961371 | instance | skipping: [instance] 2026-09-01 18:18:12.962568 | instance | 2026-09-01 18:18:12.962605 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-01 18:18:12.962617 | instance | Tuesday 01 September 2026 18:18:12 +0000 (0:00:00.040) 0:00:10.064 ***** 2026-09-01 18:18:12.999693 | instance | skipping: [instance] 2026-09-01 18:18:12.999895 | instance | 2026-09-01 18:18:13.000220 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-01 18:18:13.000516 | instance | Tuesday 01 September 2026 18:18:12 +0000 (0:00:00.038) 0:00:10.102 ***** 2026-09-01 18:18:13.038992 | instance | skipping: [instance] 2026-09-01 18:18:13.039277 | instance | 2026-09-01 18:18:13.039563 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-01 18:18:13.039777 | instance | Tuesday 01 September 2026 18:18:13 +0000 (0:00:00.039) 0:00:10.141 ***** 2026-09-01 18:18:18.829107 | instance | changed: [instance] 2026-09-01 18:18:18.829319 | instance | 2026-09-01 18:18:18.829332 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-01 18:18:18.829489 | instance | Tuesday 01 September 2026 18:18:18 +0000 (0:00:05.790) 0:00:15.932 ***** 2026-09-01 18:18:19.439730 | instance | changed: [instance] 2026-09-01 18:18:19.439911 | instance | 2026-09-01 18:18:19.440253 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-01 18:18:19.440561 | instance | Tuesday 01 September 2026 18:18:19 +0000 (0:00:00.610) 0:00:16.542 ***** 2026-09-01 18:18:20.453003 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-01 18:18:20.453267 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-01 18:18:20.453281 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-01 18:18:20.453301 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-01 18:18:20.453312 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-01 18:18:20.453322 | instance | 2026-09-01 18:18:20.453332 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-01 18:18:20.453341 | instance | Tuesday 01 September 2026 18:18:20 +0000 (0:00:01.011) 0:00:17.553 ***** 2026-09-01 18:18:21.087533 | instance | changed: [instance] 2026-09-01 18:18:21.087673 | instance | 2026-09-01 18:18:21.087743 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-01 18:18:21.088321 | instance | Tuesday 01 September 2026 18:18:21 +0000 (0:00:00.609) 0:00:18.163 ***** 2026-09-01 18:18:21.088373 | instance | 2026-09-01 18:18:21.088381 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 18:18:21.088387 | instance | Tuesday 01 September 2026 18:18:21 +0000 (0:00:00.027) 0:00:18.190 ***** 2026-09-01 18:18:22.076692 | instance | ok: [instance] 2026-09-01 18:18:22.077386 | instance | 2026-09-01 18:18:22.077432 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-01 18:18:22.077444 | instance | Tuesday 01 September 2026 18:18:22 +0000 (0:00:00.988) 0:00:19.179 ***** 2026-09-01 18:18:22.566572 | instance | changed: [instance] 2026-09-01 18:18:22.567218 | instance | 2026-09-01 18:18:22.567299 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-01 18:18:22.567321 | instance | Tuesday 01 September 2026 18:18:22 +0000 (0:00:00.489) 0:00:19.668 ***** 2026-09-01 18:18:23.190588 | instance | changed: [instance] 2026-09-01 18:18:23.190700 | instance | 2026-09-01 18:18:23.191008 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 18:18:23.191061 | instance | Tuesday 01 September 2026 18:18:23 +0000 (0:00:00.624) 0:00:20.293 ***** 2026-09-01 18:18:23.431485 | instance | ok: [instance] 2026-09-01 18:18:23.432422 | instance | 2026-09-01 18:18:23.432480 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 18:18:23.432493 | instance | Tuesday 01 September 2026 18:18:23 +0000 (0:00:00.240) 0:00:20.534 ***** 2026-09-01 18:18:23.506302 | instance | ok: [instance] => { 2026-09-01 18:18:23.507197 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-01 18:18:23.507261 | instance | } 2026-09-01 18:18:23.507274 | instance | 2026-09-01 18:18:23.507623 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 18:18:23.507660 | instance | Tuesday 01 September 2026 18:18:23 +0000 (0:00:00.074) 0:00:20.608 ***** 2026-09-01 18:18:24.250409 | instance | changed: [instance] 2026-09-01 18:18:24.251162 | instance | 2026-09-01 18:18:24.251219 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 18:18:24.251231 | instance | Tuesday 01 September 2026 18:18:24 +0000 (0:00:00.744) 0:00:21.353 ***** 2026-09-01 18:18:28.549287 | instance | changed: [instance] 2026-09-01 18:18:28.549403 | instance | 2026-09-01 18:18:28.549735 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-01 18:18:28.549773 | instance | Tuesday 01 September 2026 18:18:28 +0000 (0:00:04.299) 0:00:25.652 ***** 2026-09-01 18:18:29.561229 | instance | ok: [instance] 2026-09-01 18:18:29.561436 | instance | 2026-09-01 18:18:29.561943 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-01 18:18:29.561994 | instance | Tuesday 01 September 2026 18:18:29 +0000 (0:00:01.011) 0:00:26.664 ***** 2026-09-01 18:18:29.927058 | instance | changed: [instance] 2026-09-01 18:18:29.927813 | instance | 2026-09-01 18:18:29.927851 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-01 18:18:29.927863 | instance | Tuesday 01 September 2026 18:18:29 +0000 (0:00:00.365) 0:00:27.030 ***** 2026-09-01 18:18:30.427708 | instance | changed: [instance] 2026-09-01 18:18:30.428414 | instance | 2026-09-01 18:18:30.428486 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-01 18:18:30.428498 | instance | Tuesday 01 September 2026 18:18:30 +0000 (0:00:00.500) 0:00:27.530 ***** 2026-09-01 18:18:31.040346 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-01 18:18:31.041095 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-01 18:18:31.041140 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-01 18:18:31.041147 | instance | 2026-09-01 18:18:31.041154 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-01 18:18:31.041159 | instance | Tuesday 01 September 2026 18:18:31 +0000 (0:00:00.612) 0:00:28.143 ***** 2026-09-01 18:18:31.521381 | instance | changed: [instance] 2026-09-01 18:18:31.522128 | instance | 2026-09-01 18:18:31.522181 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-01 18:18:31.522193 | instance | Tuesday 01 September 2026 18:18:31 +0000 (0:00:00.480) 0:00:28.624 ***** 2026-09-01 18:18:32.024225 | instance | changed: [instance] 2026-09-01 18:18:32.025148 | instance | 2026-09-01 18:18:32.025190 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-01 18:18:32.025211 | instance | Tuesday 01 September 2026 18:18:31 +0000 (0:00:00.476) 0:00:29.100 ***** 2026-09-01 18:18:32.025217 | instance | 2026-09-01 18:18:32.025223 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 18:18:32.025234 | instance | Tuesday 01 September 2026 18:18:32 +0000 (0:00:00.027) 0:00:29.127 ***** 2026-09-01 18:18:32.782845 | instance | ok: [instance] 2026-09-01 18:18:32.782900 | instance | 2026-09-01 18:18:32.782911 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-01 18:18:32.782921 | instance | Tuesday 01 September 2026 18:18:32 +0000 (0:00:00.757) 0:00:29.885 ***** 2026-09-01 18:18:33.623848 | instance | changed: [instance] 2026-09-01 18:18:33.624596 | instance | 2026-09-01 18:18:33.624650 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-01 18:18:33.624662 | instance | Tuesday 01 September 2026 18:18:33 +0000 (0:00:00.841) 0:00:30.726 ***** 2026-09-01 18:18:34.202113 | instance | changed: [instance] 2026-09-01 18:18:34.202765 | instance | 2026-09-01 18:18:34.202788 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-01 18:18:34.202798 | instance | Tuesday 01 September 2026 18:18:34 +0000 (0:00:00.578) 0:00:31.305 ***** 2026-09-01 18:18:34.275763 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-01 18:18:34.276748 | instance | 2026-09-01 18:18:34.276785 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-01 18:18:34.276800 | instance | Tuesday 01 September 2026 18:18:34 +0000 (0:00:00.073) 0:00:31.378 ***** 2026-09-01 18:18:39.367743 | instance | changed: [instance] 2026-09-01 18:18:39.368511 | instance | 2026-09-01 18:18:39.368558 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-01 18:18:39.368571 | instance | Tuesday 01 September 2026 18:18:39 +0000 (0:00:05.091) 0:00:36.470 ***** 2026-09-01 18:18:40.025850 | instance | ok: [instance] => (item=chronyd) 2026-09-01 18:18:40.025906 | instance | ok: [instance] => (item=sshd) 2026-09-01 18:18:40.026252 | instance | 2026-09-01 18:18:40.026289 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-01 18:18:40.026295 | instance | Tuesday 01 September 2026 18:18:40 +0000 (0:00:00.658) 0:00:37.129 ***** 2026-09-01 18:18:40.335176 | instance | changed: [instance] 2026-09-01 18:18:40.335492 | instance | 2026-09-01 18:18:40.335738 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-01 18:18:40.335978 | instance | Tuesday 01 September 2026 18:18:40 +0000 (0:00:00.308) 0:00:37.438 ***** 2026-09-01 18:18:40.557364 | instance | ok: [instance] 2026-09-01 18:18:40.557575 | instance | 2026-09-01 18:18:40.557796 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-01 18:18:40.558030 | instance | Tuesday 01 September 2026 18:18:40 +0000 (0:00:00.222) 0:00:37.660 ***** 2026-09-01 18:18:41.016588 | instance | changed: [instance] 2026-09-01 18:18:41.016786 | instance | 2026-09-01 18:18:41.017046 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-01 18:18:41.017275 | instance | Tuesday 01 September 2026 18:18:41 +0000 (0:00:00.459) 0:00:38.119 ***** 2026-09-01 18:18:41.380190 | instance | changed: [instance] 2026-09-01 18:18:41.380416 | instance | 2026-09-01 18:18:41.380657 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-01 18:18:41.380884 | instance | Tuesday 01 September 2026 18:18:41 +0000 (0:00:00.364) 0:00:38.483 ***** 2026-09-01 18:18:41.577590 | instance | ok: [instance] 2026-09-01 18:18:41.577647 | instance | 2026-09-01 18:18:41.577660 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-01 18:18:41.577670 | instance | Tuesday 01 September 2026 18:18:41 +0000 (0:00:00.196) 0:00:38.680 ***** 2026-09-01 18:18:41.801258 | instance | ok: [instance] => (item=instance) 2026-09-01 18:18:41.801952 | instance | 2026-09-01 18:18:41.801985 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-01 18:18:41.801996 | instance | Tuesday 01 September 2026 18:18:41 +0000 (0:00:00.224) 0:00:38.904 ***** 2026-09-01 18:18:41.853509 | instance | ok: [instance] 2026-09-01 18:18:41.854180 | instance | 2026-09-01 18:18:41.854210 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-01 18:18:41.854220 | instance | Tuesday 01 September 2026 18:18:41 +0000 (0:00:00.052) 0:00:38.956 ***** 2026-09-01 18:18:41.935102 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-01 18:18:41.935239 | instance | 2026-09-01 18:18:41.935409 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-01 18:18:41.935549 | instance | Tuesday 01 September 2026 18:18:41 +0000 (0:00:00.082) 0:00:39.038 ***** 2026-09-01 18:18:42.219010 | instance | changed: [instance] 2026-09-01 18:18:42.219827 | instance | 2026-09-01 18:18:42.219879 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-01 18:18:42.219891 | instance | Tuesday 01 September 2026 18:18:42 +0000 (0:00:00.283) 0:00:39.321 ***** 2026-09-01 18:18:42.847750 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-01 18:18:42.847785 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-01 18:18:42.847790 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-01 18:18:42.847795 | instance | 2026-09-01 18:18:42.847800 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-01 18:18:42.847804 | instance | Tuesday 01 September 2026 18:18:42 +0000 (0:00:00.628) 0:00:39.950 ***** 2026-09-01 18:20:11.606556 | instance | ok: [instance] 2026-09-01 18:20:11.607058 | instance | 2026-09-01 18:20:11.607146 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-01 18:20:11.607266 | instance | Tuesday 01 September 2026 18:20:11 +0000 (0:01:28.758) 0:02:08.709 ***** 2026-09-01 18:20:11.855282 | instance | changed: [instance] 2026-09-01 18:20:11.855509 | instance | 2026-09-01 18:20:11.855880 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-01 18:20:11.856164 | instance | Tuesday 01 September 2026 18:20:11 +0000 (0:00:00.248) 0:02:08.957 ***** 2026-09-01 18:20:11.904839 | instance | ok: [instance] 2026-09-01 18:20:11.905054 | instance | 2026-09-01 18:20:11.905425 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-01 18:20:11.905776 | instance | Tuesday 01 September 2026 18:20:11 +0000 (0:00:00.049) 0:02:09.007 ***** 2026-09-01 18:20:11.996846 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-01 18:20:11.996913 | instance | 2026-09-01 18:20:11.997101 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-01 18:20:11.997139 | instance | Tuesday 01 September 2026 18:20:11 +0000 (0:00:00.092) 0:02:09.100 ***** 2026-09-01 18:20:13.573287 | instance | ok: [instance] 2026-09-01 18:20:13.574016 | instance | 2026-09-01 18:20:13.574078 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-01 18:20:13.574091 | instance | Tuesday 01 September 2026 18:20:13 +0000 (0:00:01.575) 0:02:10.675 ***** 2026-09-01 18:20:13.646386 | instance | ok: [instance] => (item=instance) 2026-09-01 18:20:13.646609 | instance | 2026-09-01 18:20:13.646870 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-01 18:20:13.647131 | instance | Tuesday 01 September 2026 18:20:13 +0000 (0:00:00.073) 0:02:10.749 ***** 2026-09-01 18:20:14.020471 | instance | ok: [instance] 2026-09-01 18:20:14.020707 | instance | 2026-09-01 18:20:14.020979 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-01 18:20:14.021216 | instance | Tuesday 01 September 2026 18:20:14 +0000 (0:00:00.374) 0:02:11.123 ***** 2026-09-01 18:20:16.455370 | instance | ok: [instance] 2026-09-01 18:20:16.456104 | instance | 2026-09-01 18:20:16.456156 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-01 18:20:16.456167 | instance | Tuesday 01 September 2026 18:20:16 +0000 (0:00:02.434) 0:02:13.558 ***** 2026-09-01 18:21:08.210298 | 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 18:21:08.211137 | 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_nh2xduh4/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_nh2xduh4/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_nh2xduh4/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_nh2xduh4/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-01T18:21:08.024+0000 7f688f695640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA0F5dqCs7nMyAAW97JkXMVEvPoIpV9cT5XlBjN5xBwPydWIjHSHROpx5k=: Malformed input [buffer:3]\n2026-09-01T18:21:08.024+0000 7f688f695640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:21:08.024+0000 7f688f695640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA0F5dqCs7nMyAAW97JkXMVEvPoIpV9cT5XlBjN5xBwPydWIjHSHROpx5k=: Malformed input [buffer:3]\n2026-09-01T18:21:08.024+0000 7f688f695640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:21:08.024+0000 7f688f695640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA0F5dqCs7nMyAAW97JkXMVEvPoIpV9cT5XlBjN5xBwPydWIjHSHROpx5k=: Malformed input [buffer:3]\n2026-09-01T18:21:08.024+0000 7f688f695640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:21:08.024+0000 7f688f695640 -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 18:21:08.213744 | instance | 2026-09-01 18:21:08.214013 | instance | PLAY RECAP ********************************************************************* 2026-09-01 18:21:08.215188 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-01 18:21:08.215249 | instance | 2026-09-01 18:21:08.215262 | instance | Tuesday 01 September 2026 18:21:08 +0000 (0:00:51.759) 0:03:05.317 ***** 2026-09-01 18:21:08.215272 | instance | =============================================================================== 2026-09-01 18:21:08.215286 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 88.76s 2026-09-01 18:21:08.219178 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.76s 2026-09-01 18:21:08.219232 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.79s 2026-09-01 18:21:08.219243 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.09s 2026-09-01 18:21:08.219252 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.30s 2026-09-01 18:21:08.219261 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.82s 2026-09-01 18:21:08.219286 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.43s 2026-09-01 18:21:08.219296 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.75s 2026-09-01 18:21:08.219304 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.58s 2026-09-01 18:21:08.219313 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.24s 2026-09-01 18:21:08.219321 | instance | Gathering Facts --------------------------------------------------------- 1.03s 2026-09-01 18:21:08.219330 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.01s 2026-09-01 18:21:08.219339 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-01 18:21:08.219347 | instance | Gathering Facts --------------------------------------------------------- 0.96s 2026-09-01 18:21:08.219356 | instance | Gathering Facts --------------------------------------------------------- 0.94s 2026-09-01 18:21:08.219364 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.84s 2026-09-01 18:21:08.219373 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.76s 2026-09-01 18:21:08.219388 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.74s 2026-09-01 18:21:08.219499 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.68s 2026-09-01 18:21:08.219839 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.66s 2026-09-01 18:21:08.305568 | 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 18:21:08.305846 | instance | ERROR [csi > converge] Executed: Failed 2026-09-01 18:21:08.306015 | 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 18:21:09.070552 | instance | ERROR 2026-09-01 18:21:09.149959 | instance | { 2026-09-01 18:21:09.150066 | instance | "delta": "0:03:54.733700", 2026-09-01 18:21:09.150120 | instance | "end": "2026-09-01 18:21:08.366373", 2026-09-01 18:21:09.150164 | instance | "msg": "non-zero return code", 2026-09-01 18:21:09.150204 | instance | "rc": 2, 2026-09-01 18:21:09.150243 | instance | "start": "2026-09-01 18:17:13.632673" 2026-09-01 18:21:09.150282 | instance | } failure 2026-09-01 18:21:09.161431 | 2026-09-01 18:21:09.162048 | PLAY RECAP 2026-09-01 18:21:09.162978 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-01 18:21:09.163025 | 2026-09-01 18:21:09.402430 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 18:21:09.527715 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 18:21:10.207364 | 2026-09-01 18:21:10.207505 | PLAY [all] 2026-09-01 18:21:10.222553 | 2026-09-01 18:21:10.222655 | TASK [gather-host-logs : creating directory for system status] 2026-09-01 18:21:10.599534 | instance | changed 2026-09-01 18:21:10.750168 | 2026-09-01 18:21:10.750449 | TASK [gather-host-logs : Get logs for each host] 2026-09-01 18:21:11.121219 | instance | + systemd-cgls --full --all --no-pager 2026-09-01 18:21:11.135471 | instance | + ip addr 2026-09-01 18:21:11.137871 | instance | + ip route 2026-09-01 18:21:11.140471 | instance | + lsblk 2026-09-01 18:21:11.145107 | instance | + mount 2026-09-01 18:21:11.147642 | instance | + docker images 2026-09-01 18:21:11.170292 | instance | + brctl show 2026-09-01 18:21:11.170951 | instance | /bin/bash: line 8: brctl: command not found 2026-09-01 18:21:11.171347 | instance | + ps aux --sort=-%mem 2026-09-01 18:21:11.190754 | instance | + dpkg -l 2026-09-01 18:21:11.202368 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-01 18:21:11.202874 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-01 18:21:11.222394 | instance | + '[' '!' -z '' ']' 2026-09-01 18:21:11.291494 | instance | ok: Runtime: 0:00:00.105813 2026-09-01 18:21:11.297965 | 2026-09-01 18:21:11.298040 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-01 18:21:11.939886 | instance | changed: 2026-09-01 18:21:11.940096 | instance | created directory /var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/work/logs/instance 2026-09-01 18:21:11.940137 | instance | cd+++++++++ system/ 2026-09-01 18:21:11.940169 | instance | >f+++++++++ system/brctl-show.txt 2026-09-01 18:21:11.940200 | instance | >f+++++++++ system/docker-images.txt 2026-09-01 18:21:11.940228 | instance | >f+++++++++ system/ip-addr.txt 2026-09-01 18:21:11.940261 | instance | >f+++++++++ system/ip-route.txt 2026-09-01 18:21:11.940291 | instance | >f+++++++++ system/lsblk.txt 2026-09-01 18:21:11.940321 | instance | >f+++++++++ system/mount.txt 2026-09-01 18:21:11.940349 | instance | >f+++++++++ system/packages.txt 2026-09-01 18:21:11.940377 | instance | >f+++++++++ system/ps.txt 2026-09-01 18:21:11.940422 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-01 18:21:11.954927 | 2026-09-01 18:21:11.955065 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-01 18:21:12.130222 | instance | changed: "values" 2026-09-01 18:21:12.311102 | instance | changed: "releases" 2026-09-01 18:21:12.329488 | 2026-09-01 18:21:12.329700 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-01 18:21:12.552378 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-01 18:21:12.870979 | instance | ok: Runtime: 0:00:00.006677 2026-09-01 18:21:12.878081 | 2026-09-01 18:21:12.878175 | TASK [helm-release-status : Downloads logs to executor] 2026-09-01 18:21:13.377982 | instance | changed: 2026-09-01 18:21:13.378151 | instance | cd+++++++++ helm/ 2026-09-01 18:21:13.378179 | instance | cd+++++++++ helm/releases/ 2026-09-01 18:21:13.378201 | instance | cd+++++++++ helm/values/ 2026-09-01 18:21:13.387316 | 2026-09-01 18:21:13.387388 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-01 18:21:13.591824 | instance | changed 2026-09-01 18:21:13.597994 | 2026-09-01 18:21:13.598077 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-01 18:21:13.878141 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 18:21:13.878492 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 18:21:13.883498 | instance | environment: line 1: kubectl: command not found 2026-09-01 18:21:13.884505 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 18:21:13.885812 | instance | environment: line 1: kubectl: command not found 2026-09-01 18:21:13.887116 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 18:21:13.888028 | instance | environment: line 1: kubectl: command not found 2026-09-01 18:21:13.889456 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 18:21:13.891179 | instance | environment: line 1: kubectl: command not found 2026-09-01 18:21:13.892576 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 18:21:13.893208 | instance | environment: line 1: kubectl: command not found 2026-09-01 18:21:13.894361 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 18:21:14.138093 | instance | ok: Runtime: 0:00:00.023595 2026-09-01 18:21:14.146828 | 2026-09-01 18:21:14.281229 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-01 18:21:14.494058 | instance | changed 2026-09-01 18:21:14.500211 | 2026-09-01 18:21:14.500366 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-01 18:21:14.737896 | instance | environment: line 5: kubectl: command not found 2026-09-01 18:21:14.738254 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 18:21:14.738952 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 18:21:14.739390 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 18:21:15.041542 | instance | ok: Runtime: 0:00:00.007844 2026-09-01 18:21:15.049270 | 2026-09-01 18:21:15.049363 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-01 18:21:15.534726 | instance | changed: 2026-09-01 18:21:15.534932 | instance | cd+++++++++ objects/ 2026-09-01 18:21:15.534971 | instance | cd+++++++++ objects/cluster/ 2026-09-01 18:21:15.535001 | instance | cd+++++++++ objects/namespaced/ 2026-09-01 18:21:15.548755 | 2026-09-01 18:21:15.548821 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-01 18:21:15.778863 | instance | changed 2026-09-01 18:21:15.843256 | 2026-09-01 18:21:15.843357 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-01 18:21:16.060227 | instance | changed 2026-09-01 18:21:16.065550 | 2026-09-01 18:21:16.065637 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-01 18:21:16.288567 | instance | environment: line 3: kubectl: command not found 2026-09-01 18:21:16.598842 | instance | ok: Runtime: 0:00:00.008149 2026-09-01 18:21:16.604300 | 2026-09-01 18:21:16.604367 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-01 18:21:17.137679 | instance | changed: 2026-09-01 18:21:17.137915 | instance | cd+++++++++ pod-logs/ 2026-09-01 18:21:17.137955 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-01 18:21:17.151565 | 2026-09-01 18:21:17.151631 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-01 18:21:17.364018 | instance | changed 2026-09-01 18:21:17.369097 | 2026-09-01 18:21:17.369170 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-01 18:21:17.594542 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 18:21:17.902685 | instance | ok: Runtime: 0:00:00.050158 2026-09-01 18:21:17.907966 | 2026-09-01 18:21:17.908036 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-01 18:21:18.138817 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 18:21:18.180577 | instance | ceph-mgr endpoints: 2026-09-01 18:21:18.444648 | instance | ok: Runtime: 0:00:00.047173 2026-09-01 18:21:18.452057 | 2026-09-01 18:21:18.452152 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-01 18:21:18.670475 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-01 18:21:19.097182 | instance | ok: Runtime: 0:00:00.049507 2026-09-01 18:21:19.103929 | 2026-09-01 18:21:19.104028 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-01 18:21:19.616179 | instance | changed: cd+++++++++ prometheus/ 2026-09-01 18:21:19.625188 | 2026-09-01 18:21:19.625252 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-01 18:21:19.832455 | instance | changed 2026-09-01 18:21:19.837442 | 2026-09-01 18:21:19.837506 | TASK [gather-selenium-data : Get selenium data] 2026-09-01 18:21:20.139811 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-01 18:21:20.141317 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-01 18:21:20.371468 | instance | ERROR 2026-09-01 18:21:20.371714 | instance | { 2026-09-01 18:21:20.371761 | instance | "delta": "0:00:00.006146", 2026-09-01 18:21:20.371792 | instance | "end": "2026-09-01 18:21:20.141645", 2026-09-01 18:21:20.371891 | instance | "msg": "non-zero return code", 2026-09-01 18:21:20.371952 | instance | "rc": 1, 2026-09-01 18:21:20.371984 | instance | "start": "2026-09-01 18:21:20.135499" 2026-09-01 18:21:20.372012 | instance | } 2026-09-01 18:21:20.372048 | instance | ERROR: Ignoring Errors 2026-09-01 18:21:20.379696 | 2026-09-01 18:21:20.379788 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-01 18:21:21.016670 | instance | changed: cd+++++++++ selenium/ 2026-09-01 18:21:21.024853 | 2026-09-01 18:21:21.024912 | PLAY RECAP 2026-09-01 18:21:21.024978 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-01 18:21:21.025006 | 2026-09-01 18:21:21.165876 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 18:21:21.168847 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 18:21:21.829223 | 2026-09-01 18:21:21.829403 | PLAY [all] 2026-09-01 18:21:21.842261 | 2026-09-01 18:21:21.842358 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-01 18:21:21.888136 | instance | skipping: Conditional result was False 2026-09-01 18:21:21.897555 | 2026-09-01 18:21:21.897672 | TASK [fetch-output : Set log path for single node] 2026-09-01 18:21:21.943062 | instance | ok 2026-09-01 18:21:21.947839 | 2026-09-01 18:21:21.947904 | LOOP [fetch-output : Ensure local output dirs] 2026-09-01 18:21:22.420789 | instance -> localhost | ok: "/var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/work/logs" 2026-09-01 18:21:22.659873 | instance -> localhost | changed: "/var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/work/artifacts" 2026-09-01 18:21:22.880532 | instance -> localhost | changed: "/var/lib/zuul/builds/c749bc6b9de54a6eab187eccbbe10d9b/work/docs" 2026-09-01 18:21:22.897436 | 2026-09-01 18:21:22.897594 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-01 18:21:23.709585 | instance | changed: .d..t...... ./ 2026-09-01 18:21:23.874914 | instance | changed: All items complete 2026-09-01 18:21:23.875060 | 2026-09-01 18:21:24.243840 | instance | changed: .d..t...... ./ 2026-09-01 18:21:24.760398 | instance | changed: .d..t...... ./ 2026-09-01 18:21:24.777021 | 2026-09-01 18:21:24.777183 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-01 18:21:25.261750 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008598 2026-09-01 18:21:25.490239 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008003 2026-09-01 18:21:25.504851 | 2026-09-01 18:21:25.504908 | PLAY [all] 2026-09-01 18:21:25.510731 | 2026-09-01 18:21:25.510793 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-01 18:21:25.956117 | instance | changed 2026-09-01 18:21:25.969599 | 2026-09-01 18:21:25.969815 | PLAY RECAP 2026-09-01 18:21:25.970244 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-01 18:21:25.970307 | 2026-09-01 18:21:26.151257 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 18:21:26.175713 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-01 18:21:26.813003 | 2026-09-01 18:21:26.813140 | PLAY [localhost] 2026-09-01 18:21:26.823114 | 2026-09-01 18:21:26.823189 | TASK [Generate Zuul manifest] 2026-09-01 18:21:26.855020 | localhost | ok 2026-09-01 18:21:26.874705 | 2026-09-01 18:21:26.874814 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-01 18:21:27.276742 | localhost | changed 2026-09-01 18:21:27.286736 | 2026-09-01 18:21:27.286811 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-01 18:21:27.318178 | localhost | ok 2026-09-01 18:21:27.328143 | 2026-09-01 18:21:27.328206 | TASK [Upload logs] 2026-09-01 18:21:27.358653 | localhost | ok 2026-09-01 18:21:27.455695 | 2026-09-01 18:21:27.455839 | TASK [Set zuul-log-path fact] 2026-09-01 18:21:27.478588 | localhost | ok 2026-09-01 18:21:27.496389 | 2026-09-01 18:21:27.496535 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 18:21:27.529120 | localhost | ok 2026-09-01 18:21:27.539629 | 2026-09-01 18:21:27.539757 | TASK [upload-logs : Create log directories] 2026-09-01 18:21:28.088879 | localhost | changed 2026-09-01 18:21:28.098193 | 2026-09-01 18:21:28.098277 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-01 18:21:28.537922 | localhost -> localhost | ok: Runtime: 0:00:00.006202 2026-09-01 18:21:28.543582 | 2026-09-01 18:21:28.543651 | TASK [upload-logs : Upload logs to log server] 2026-09-01 18:21:29.046929 | localhost | Output suppressed because no_log was given 2026-09-01 18:21:29.095727 | 2026-09-01 18:21:29.095869 | LOOP [upload-logs : Compress console log and json output] 2026-09-01 18:21:29.152202 | localhost | skipping: Conditional result was False 2026-09-01 18:21:29.159619 | localhost | skipping: Conditional result was False 2026-09-01 18:21:29.170960 | 2026-09-01 18:21:29.171101 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-01 18:21:29.213006 | localhost | skipping: Conditional result was False 2026-09-01 18:21:29.213415 | 2026-09-01 18:21:29.217238 | localhost | skipping: Conditional result was False 2026-09-01 18:21:29.233881 | 2026-09-01 18:21:29.234033 | LOOP [upload-logs : Upload console log and json output]