2026-09-01 03:50:40.987770 | Job console starting 2026-09-01 03:50:40.997133 | Updating git repos 2026-09-01 03:50:43.347865 | Cloning repos into workspace 2026-09-01 03:50:58.380371 | Restoring repo states 2026-09-01 03:51:13.686631 | Merging changes 2026-09-01 03:51:14.421239 | Checking out repos 2026-09-01 03:51:15.724946 | Preparing playbooks 2026-09-01 03:51:18.056205 | Running Ansible setup 2026-09-01 03:51:21.923276 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 03:51:22.540602 | 2026-09-01 03:51:22.540784 | PLAY [localhost] 2026-09-01 03:51:22.548694 | 2026-09-01 03:51:22.548767 | TASK [Gathering Facts] 2026-09-01 03:51:23.384823 | localhost | ok 2026-09-01 03:51:23.391968 | 2026-09-01 03:51:23.392049 | TASK [Setup log path fact] 2026-09-01 03:51:23.410071 | localhost | ok 2026-09-01 03:51:23.661787 | 2026-09-01 03:51:23.661887 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 03:51:23.691487 | localhost | ok 2026-09-01 03:51:23.700376 | 2026-09-01 03:51:23.700467 | TASK [emit-job-header : Print job information] 2026-09-01 03:51:23.742862 | # Job Information 2026-09-01 03:51:23.743084 | Ansible Version: 2.16.18 2026-09-01 03:51:23.743154 | Job: atmosphere-molecule-csi-rbd 2026-09-01 03:51:23.743204 | Pipeline: check 2026-09-01 03:51:23.743247 | Executor: 2d72f0692154 2026-09-01 03:51:23.743291 | Triggered by: https://github.com/vexxhost/atmosphere/pull/2727 2026-09-01 03:51:23.743341 | Event ID: bb252280-a5b6-11f1-98d6-89bbd14eacf8 2026-09-01 03:51:23.748241 | 2026-09-01 03:51:23.748330 | LOOP [emit-job-header : Print node information] 2026-09-01 03:51:23.848170 | localhost | ok: 2026-09-01 03:51:23.848409 | localhost | # Node Information 2026-09-01 03:51:23.848452 | localhost | Inventory Hostname: instance 2026-09-01 03:51:23.848484 | localhost | Hostname: np0000191654 2026-09-01 03:51:23.848515 | localhost | Username: zuul 2026-09-01 03:51:23.848549 | localhost | Distro: Ubuntu 22.04 2026-09-01 03:51:23.848580 | localhost | Provider: yul1 2026-09-01 03:51:23.848609 | localhost | Region: ca-ymq-1 2026-09-01 03:51:23.848638 | localhost | Label: ubuntu-jammy 2026-09-01 03:51:23.848666 | localhost | Product Name: OpenStack Nova 2026-09-01 03:51:23.848694 | localhost | Interface IP: 162.253.55.107 2026-09-01 03:51:23.867206 | 2026-09-01 03:51:23.867321 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-01 03:51:24.267678 | localhost -> localhost | changed 2026-09-01 03:51:24.277151 | 2026-09-01 03:51:24.277248 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-01 03:51:25.094528 | localhost -> localhost | changed 2026-09-01 03:51:25.102941 | 2026-09-01 03:51:25.102997 | PLAY [all] 2026-09-01 03:51:25.111529 | 2026-09-01 03:51:25.111596 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-01 03:51:25.329603 | instance -> localhost | ok 2026-09-01 03:51:25.340673 | 2026-09-01 03:51:25.340764 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-01 03:51:25.375297 | instance | ok 2026-09-01 03:51:25.388624 | instance | included: /var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-01 03:51:25.394355 | 2026-09-01 03:51:25.394416 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-01 03:51:26.115796 | instance -> localhost | Generating public/private rsa key pair. 2026-09-01 03:51:26.116030 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/work/41182a2deb084b9a9d9c8a9704956692_id_rsa 2026-09-01 03:51:26.116072 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/work/41182a2deb084b9a9d9c8a9704956692_id_rsa.pub 2026-09-01 03:51:26.116104 | instance -> localhost | The key fingerprint is: 2026-09-01 03:51:26.116133 | instance -> localhost | SHA256:4ZGqHtOpQCj9IdVGaZ5v14C3wdqCCmam++SNjuaQTbg zuul-build-sshkey 2026-09-01 03:51:26.116174 | instance -> localhost | The key's randomart image is: 2026-09-01 03:51:26.116205 | instance -> localhost | +---[RSA 3072]----+ 2026-09-01 03:51:26.116244 | instance -> localhost | | .. | 2026-09-01 03:51:26.116275 | instance -> localhost | | oo . | 2026-09-01 03:51:26.116304 | instance -> localhost | | .oo.+o | 2026-09-01 03:51:26.116332 | instance -> localhost | | + . .oo.o= | 2026-09-01 03:51:26.116359 | instance -> localhost | |+ = . .oS+ = | 2026-09-01 03:51:26.116387 | instance -> localhost | |.*=o +..= + . | 2026-09-01 03:51:26.116414 | instance -> localhost | |E=+.=.o. o | 2026-09-01 03:51:26.116441 | instance -> localhost | |o= =.+ | 2026-09-01 03:51:26.116473 | instance -> localhost | |=== + | 2026-09-01 03:51:26.116501 | instance -> localhost | +----[SHA256]-----+ 2026-09-01 03:51:26.116567 | instance -> localhost | ok: Runtime: 0:00:00.304258 2026-09-01 03:51:26.124883 | 2026-09-01 03:51:26.124970 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-01 03:51:26.159608 | instance | ok 2026-09-01 03:51:26.168405 | instance | included: /var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-01 03:51:26.175986 | 2026-09-01 03:51:26.176049 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-01 03:51:26.201891 | instance | skipping: Conditional result was False 2026-09-01 03:51:26.211134 | 2026-09-01 03:51:26.211222 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-01 03:51:26.861214 | instance | changed 2026-09-01 03:51:26.872541 | 2026-09-01 03:51:26.872617 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-01 03:51:27.077128 | instance | ok 2026-09-01 03:51:27.082891 | 2026-09-01 03:51:27.082960 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-01 03:51:27.583907 | instance | changed 2026-09-01 03:51:27.742065 | 2026-09-01 03:51:27.742189 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-01 03:51:28.242418 | instance | changed 2026-09-01 03:51:28.251550 | 2026-09-01 03:51:28.251631 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-01 03:51:28.277693 | instance | skipping: Conditional result was False 2026-09-01 03:51:28.288077 | 2026-09-01 03:51:28.288144 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-01 03:51:28.637646 | instance -> localhost | changed 2026-09-01 03:51:28.653344 | 2026-09-01 03:51:28.653437 | TASK [add-build-sshkey : Add back temp key] 2026-09-01 03:51:28.930549 | instance -> localhost | Identity added: /var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/work/41182a2deb084b9a9d9c8a9704956692_id_rsa (zuul-build-sshkey) 2026-09-01 03:51:28.930819 | instance -> localhost | ok: Runtime: 0:00:00.012658 2026-09-01 03:51:28.937600 | 2026-09-01 03:51:28.937688 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-01 03:51:29.277230 | instance | ok 2026-09-01 03:51:29.282420 | 2026-09-01 03:51:29.282482 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-01 03:51:29.318526 | instance | skipping: Conditional result was False 2026-09-01 03:51:29.333531 | 2026-09-01 03:51:29.333623 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-01 03:51:29.705064 | instance | ok 2026-09-01 03:51:29.711297 | 2026-09-01 03:51:29.711361 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-01 03:51:33.147407 | instance | Output suppressed because no_log was given 2026-09-01 03:51:33.163336 | 2026-09-01 03:51:33.163524 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-01 03:51:33.377762 | instance | ok: "logs" 2026-09-01 03:51:33.378071 | instance | ok: All items complete 2026-09-01 03:51:33.378099 | 2026-09-01 03:51:33.521693 | instance | ok: "artifacts" 2026-09-01 03:51:33.670070 | instance | ok: "docs" 2026-09-01 03:51:33.691315 | 2026-09-01 03:51:33.691663 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-01 03:51:33.878382 | instance | changed: "logs" 2026-09-01 03:51:34.024721 | instance | changed: "artifacts" 2026-09-01 03:51:34.168880 | instance | changed: "docs" 2026-09-01 03:51:34.189213 | 2026-09-01 03:51:34.189359 | PLAY RECAP 2026-09-01 03:51:34.189423 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-01 03:51:34.189464 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:51:34.189498 | 2026-09-01 03:51:34.345890 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 03:51:34.349139 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 03:51:34.931945 | 2026-09-01 03:51:34.932090 | PLAY [all] 2026-09-01 03:51:34.942804 | 2026-09-01 03:51:34.942883 | TASK [setup-uv : Extract archive] 2026-09-01 03:51:37.415743 | instance | changed 2026-09-01 03:51:37.421737 | 2026-09-01 03:51:37.421815 | TASK [setup-uv : Print version] 2026-09-01 03:51:37.780786 | instance | uv 0.8.13 2026-09-01 03:51:37.961380 | instance | ok: Runtime: 0:00:00.012936 2026-09-01 03:51:37.972180 | 2026-09-01 03:51:37.972248 | TASK [Install Ansible collection siblings] 2026-09-01 03:51:47.799894 | instance | changed: 2026-09-01 03:51:47.800080 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-01 03:51:47.800109 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-01 03:51:47.800131 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-01 03:51:47.800151 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-01 03:51:47.800170 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-01 03:51:47.800193 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-01 03:51:47.800213 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-01 03:51:47.800234 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-01 03:51:47.800254 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-01 03:51:47.800273 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-01 03:51:47.800292 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-01 03:51:47.800311 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-01 03:51:47.800329 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-01 03:51:47.800348 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-01 03:51:47.800366 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-01 03:51:47.800385 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-01 03:51:47.800404 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-01 03:51:47.800423 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-01 03:51:47.800441 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-01 03:51:47.800459 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-01 03:51:47.800478 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-01 03:51:47.800496 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-01 03:51:47.800515 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 03:51:47.800543 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-01 03:51:47.800562 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-01 03:51:47.800580 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 03:51:47.800598 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 03:51:47.800617 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 03:51:47.800635 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-01 03:51:47.800652 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-01 03:51:47.800670 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 03:51:47.800688 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 03:51:47.800707 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-01 03:51:47.800726 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-01 03:51:47.800750 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.800768 | instance | Process install dependency map 2026-09-01 03:51:47.800787 | instance | Starting collection install process 2026-09-01 03:51:47.800805 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-01 03:51:47.800824 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-01 03:51:47.800841 | instance | ansible.netcommon:* was installed successfully 2026-09-01 03:51:47.800859 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-01 03:51:47.800877 | instance | Creating virtual environment at: .venv 2026-09-01 03:51:47.800895 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 03:51:47.800913 | instance | Downloading cryptography (4.2MiB) 2026-09-01 03:51:47.800931 | instance | Downloading rjsonnet (1.2MiB) 2026-09-01 03:51:47.800970 | instance | Downloading ansible-core (2.1MiB) 2026-09-01 03:51:47.800993 | instance | Downloading setuptools (1.1MiB) 2026-09-01 03:51:47.801011 | instance | Downloading pygments (1.2MiB) 2026-09-01 03:51:47.801030 | instance | Downloading openstacksdk (1.7MiB) 2026-09-01 03:51:47.801048 | instance | Downloading pydantic-core (2.0MiB) 2026-09-01 03:51:47.801066 | instance | Downloading netaddr (2.2MiB) 2026-09-01 03:51:47.801084 | instance | Downloading kubernetes (1.9MiB) 2026-09-01 03:51:47.801101 | instance | Building pyperclip==1.9.0 2026-09-01 03:51:47.801120 | instance | Downloading rjsonnet 2026-09-01 03:51:47.801139 | instance | Downloading pydantic-core 2026-09-01 03:51:47.801156 | instance | Downloading netaddr 2026-09-01 03:51:47.801174 | instance | Downloading pygments 2026-09-01 03:51:47.801192 | instance | Downloading cryptography 2026-09-01 03:51:47.801209 | instance | Downloading setuptools 2026-09-01 03:51:47.801226 | instance | Downloading kubernetes 2026-09-01 03:51:47.801244 | instance | Downloading ansible-core 2026-09-01 03:51:47.801261 | instance | Downloading openstacksdk 2026-09-01 03:51:47.801280 | instance | Built pyperclip==1.9.0 2026-09-01 03:51:47.801298 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 03:51:47.801316 | instance | Installed 83 packages in 54ms 2026-09-01 03:51:47.801334 | instance | Checked out ansible.posix to 1.6.0 2026-09-01 03:51:47.801352 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-01 03:51:47.801370 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.801388 | instance | Process install dependency map 2026-09-01 03:51:47.801411 | instance | Starting collection install process 2026-09-01 03:51:47.801429 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-01 03:51:47.801463 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-01 03:51:47.801486 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-01 03:51:47.801505 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.801524 | instance | 2.17.12 2026-09-01 03:51:47.801544 | instance | Checked out ansible.utils to v6.1.0 2026-09-01 03:51:47.801564 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-01 03:51:47.801582 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.801600 | instance | Process install dependency map 2026-09-01 03:51:47.801617 | instance | Starting collection install process 2026-09-01 03:51:47.801635 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-01 03:51:47.801653 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-01 03:51:47.801671 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-01 03:51:47.801692 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.801710 | instance | 2.17.12 2026-09-01 03:51:47.801728 | instance | Checked out atmosphere.common to v0.7.0 2026-09-01 03:51:47.801746 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-01 03:51:47.801763 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.801781 | instance | Process install dependency map 2026-09-01 03:51:47.801799 | instance | Starting collection install process 2026-09-01 03:51:47.801816 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-01 03:51:47.801834 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-01 03:51:47.801852 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-01 03:51:47.801870 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.801887 | instance | 2.17.12 2026-09-01 03:51:47.801905 | instance | Checked out community.crypto to 2.2.3 2026-09-01 03:51:47.801922 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-01 03:51:47.801940 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.801957 | instance | Process install dependency map 2026-09-01 03:51:47.801975 | instance | Starting collection install process 2026-09-01 03:51:47.801992 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-01 03:51:47.802009 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-01 03:51:47.802027 | instance | community.crypto:2.2.3 was installed successfully 2026-09-01 03:51:47.802045 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.802062 | instance | 2.17.12 2026-09-01 03:51:47.802080 | instance | Checked out community.general to 7.3.0 2026-09-01 03:51:47.802097 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-01 03:51:47.802120 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.802138 | instance | Process install dependency map 2026-09-01 03:51:47.802156 | instance | Starting collection install process 2026-09-01 03:51:47.802173 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-01 03:51:47.802191 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-01 03:51:47.802209 | instance | community.general:7.3.0 was installed successfully 2026-09-01 03:51:47.802227 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.802245 | instance | 2.17.12 2026-09-01 03:51:47.802263 | instance | Checked out community.mysql to 3.6.0 2026-09-01 03:51:47.802283 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-01 03:51:47.802302 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.802320 | instance | Process install dependency map 2026-09-01 03:51:47.802338 | instance | Starting collection install process 2026-09-01 03:51:47.802356 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-01 03:51:47.802373 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-01 03:51:47.802391 | instance | community.mysql:3.6.0 was installed successfully 2026-09-01 03:51:47.802409 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.802427 | instance | 2.17.12 2026-09-01 03:51:47.802444 | instance | Checked out kubernetes.core to 2.4.0 2026-09-01 03:51:47.802462 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-01 03:51:47.802480 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.802498 | instance | Process install dependency map 2026-09-01 03:51:47.802515 | instance | Starting collection install process 2026-09-01 03:51:47.802533 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-01 03:51:47.802551 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-01 03:51:47.802569 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-01 03:51:47.802587 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.802604 | instance | 2.17.12 2026-09-01 03:51:47.802622 | instance | Checked out openstack.cloud to 2.6.0 2026-09-01 03:51:47.802640 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-01 03:51:47.802658 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.802675 | instance | Process install dependency map 2026-09-01 03:51:47.802693 | instance | Starting collection install process 2026-09-01 03:51:47.802711 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-01 03:51:47.802729 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-01 03:51:47.802746 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-01 03:51:47.802764 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.802782 | instance | 2.17.12 2026-09-01 03:51:47.802804 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-01 03:51:47.802823 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-01 03:51:47.802841 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.802859 | instance | Process install dependency map 2026-09-01 03:51:47.802877 | instance | Starting collection install process 2026-09-01 03:51:47.802895 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-01 03:51:47.802913 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-01 03:51:47.802931 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-01 03:51:47.802949 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.802967 | instance | 2.17.12 2026-09-01 03:51:47.802984 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-01 03:51:47.803002 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-01 03:51:47.803020 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.803038 | instance | Process install dependency map 2026-09-01 03:51:47.803056 | instance | Starting collection install process 2026-09-01 03:51:47.803074 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-01 03:51:47.803093 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-01 03:51:47.803111 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-01 03:51:47.803129 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.803148 | instance | 2.17.12 2026-09-01 03:51:47.803166 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-01 03:51:47.803184 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-01 03:51:47.803202 | instance | Starting galaxy collection install process 2026-09-01 03:51:47.803219 | instance | Process install dependency map 2026-09-01 03:51:47.803237 | instance | Starting collection install process 2026-09-01 03:51:47.803255 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-01 03:51:47.803273 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-01 03:51:47.803292 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-01 03:51:47.803310 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:51:47.803328 | instance | 2.17.12 2026-09-01 03:51:47.809188 | 2026-09-01 03:51:47.809238 | PLAY RECAP 2026-09-01 03:51:47.809281 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:51:47.809302 | 2026-09-01 03:51:47.934612 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 03:51:47.938733 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 03:51:48.523324 | 2026-09-01 03:51:48.523446 | PLAY [all] 2026-09-01 03:51:48.534658 | 2026-09-01 03:51:48.534729 | TASK [Install "jq" for log collection] 2026-09-01 03:51:59.574812 | instance | changed 2026-09-01 03:51:59.577375 | 2026-09-01 03:51:59.577453 | PLAY RECAP 2026-09-01 03:51:59.577521 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:51:59.577608 | 2026-09-01 03:51:59.693618 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 03:51:59.697666 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 03:52:00.290124 | 2026-09-01 03:52:00.290279 | PLAY [all] 2026-09-01 03:52:00.301783 | 2026-09-01 03:52:00.301866 | LOOP [Check inventory directories] 2026-09-01 03:52:00.890429 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-01 03:52:01.492492 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-01 03:52:02.595029 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-01 03:52:02.612943 | 2026-09-01 03:52:02.613168 | LOOP [Copy inventory file for Zuul] 2026-09-01 03:52:05.407469 | instance | changed: 2026-09-01 03:52:05.407661 | instance | { 2026-09-01 03:52:05.407689 | instance | "ansible_loop_var": "item", 2026-09-01 03:52:05.407711 | instance | "changed": false, 2026-09-01 03:52:05.407730 | instance | "failed": false, 2026-09-01 03:52:05.407752 | instance | "invocation": { 2026-09-01 03:52:05.407771 | instance | "module_args": { 2026-09-01 03:52:05.407789 | instance | "checksum_algorithm": "sha1", 2026-09-01 03:52:05.407807 | instance | "follow": true, 2026-09-01 03:52:05.407825 | instance | "get_attributes": true, 2026-09-01 03:52:05.407844 | instance | "get_checksum": true, 2026-09-01 03:52:05.407863 | instance | "get_mime": true, 2026-09-01 03:52:05.407881 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-01 03:52:05.407899 | instance | } 2026-09-01 03:52:05.407917 | instance | }, 2026-09-01 03:52:05.407935 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-01 03:52:05.407953 | instance | "stat": { 2026-09-01 03:52:05.407970 | instance | "atime": 1788234700.073587, 2026-09-01 03:52:05.407988 | instance | "attr_flags": "e", 2026-09-01 03:52:05.408006 | instance | "attributes": [ 2026-09-01 03:52:05.408024 | instance | "extents" 2026-09-01 03:52:05.408041 | instance | ], 2026-09-01 03:52:05.408059 | instance | "block_size": 4096, 2026-09-01 03:52:05.408077 | instance | "blocks": 8, 2026-09-01 03:52:05.408094 | instance | "charset": "unknown", 2026-09-01 03:52:05.408113 | instance | "ctime": 1788234698.849587, 2026-09-01 03:52:05.408131 | instance | "dev": 64513, 2026-09-01 03:52:05.408149 | instance | "device_type": 0, 2026-09-01 03:52:05.408167 | instance | "executable": true, 2026-09-01 03:52:05.408184 | instance | "exists": true, 2026-09-01 03:52:05.408201 | instance | "gid": 1000, 2026-09-01 03:52:05.408219 | instance | "gr_name": "zuul", 2026-09-01 03:52:05.408237 | instance | "inode": 1048057, 2026-09-01 03:52:05.408254 | instance | "isblk": false, 2026-09-01 03:52:05.408272 | instance | "ischr": false, 2026-09-01 03:52:05.408290 | instance | "isdir": true, 2026-09-01 03:52:05.408307 | instance | "isfifo": false, 2026-09-01 03:52:05.408324 | instance | "isgid": false, 2026-09-01 03:52:05.408342 | instance | "islnk": false, 2026-09-01 03:52:05.408359 | instance | "isreg": false, 2026-09-01 03:52:05.408376 | instance | "issock": false, 2026-09-01 03:52:05.408393 | instance | "isuid": false, 2026-09-01 03:52:05.408410 | instance | "mimetype": "unknown", 2026-09-01 03:52:05.408427 | instance | "mode": "0755", 2026-09-01 03:52:05.408444 | instance | "mtime": 1788234698.849587, 2026-09-01 03:52:05.408461 | instance | "nlink": 23, 2026-09-01 03:52:05.408478 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-01 03:52:05.408495 | instance | "pw_name": "zuul", 2026-09-01 03:52:05.408519 | instance | "readable": true, 2026-09-01 03:52:05.408537 | instance | "rgrp": true, 2026-09-01 03:52:05.408555 | instance | "roth": true, 2026-09-01 03:52:05.408581 | instance | "rusr": true, 2026-09-01 03:52:05.408599 | instance | "size": 4096, 2026-09-01 03:52:05.408617 | instance | "uid": 1000, 2026-09-01 03:52:05.408634 | instance | "version": "475908027", 2026-09-01 03:52:05.408652 | instance | "wgrp": false, 2026-09-01 03:52:05.408671 | instance | "woth": false, 2026-09-01 03:52:05.408689 | instance | "writeable": true, 2026-09-01 03:52:05.408707 | instance | "wusr": true, 2026-09-01 03:52:05.408724 | instance | "xgrp": true, 2026-09-01 03:52:05.408742 | instance | "xoth": true, 2026-09-01 03:52:05.408759 | instance | "xusr": true 2026-09-01 03:52:05.408776 | instance | } 2026-09-01 03:52:05.408794 | instance | } 2026-09-01 03:52:05.408829 | instance | ok: All items complete 2026-09-01 03:52:05.408850 | 2026-09-01 03:52:05.412456 | instance | skipping: Conditional result was False 2026-09-01 03:52:05.413572 | instance | skipping: Conditional result was False 2026-09-01 03:52:05.428908 | 2026-09-01 03:52:05.429200 | LOOP [Switch "ansible_host" to private IP] 2026-09-01 03:52:05.815281 | instance | changed: 1 replacements made 2026-09-01 03:52:05.817055 | instance | skipping: Conditional result was False 2026-09-01 03:52:05.819472 | instance | skipping: Conditional result was False 2026-09-01 03:52:05.832095 | 2026-09-01 03:52:05.832234 | TASK [Run Molecule scenario] 2026-09-01 03:52:06.819834 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-01 03:52:07.237447 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-01 03:52:07.237544 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-01 03:52:17.967022 | instance | INFO [csi > dependency] Executing 2026-09-01 03:52:17.967472 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-01 03:52:17.967926 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-01 03:52:17.968166 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-01 03:52:17.983521 | instance | INFO [csi > cleanup] Executing 2026-09-01 03:52:17.984157 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 03:52:17.998131 | instance | INFO [csi > destroy] Executing 2026-09-01 03:52:17.998171 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-01 03:52:17.998295 | instance | INFO [csi > destroy] Executed: Successful 2026-09-01 03:52:18.008182 | instance | INFO [csi > syntax] Executing 2026-09-01 03:52:19.589707 | instance | 2026-09-01 03:52:19.589937 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 03:52:19.661469 | instance | INFO [csi > syntax] Executed: Successful 2026-09-01 03:52:19.678970 | instance | INFO [csi > create] Executing 2026-09-01 03:52:19.683337 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 03:52:19.699109 | instance | INFO [csi > prepare] Executing 2026-09-01 03:52:20.475135 | instance | 2026-09-01 03:52:20.475282 | instance | PLAY [Prepare] ***************************************************************** 2026-09-01 03:52:20.475448 | instance | 2026-09-01 03:52:20.475635 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:52:20.475817 | instance | Tuesday 01 September 2026 03:52:20 +0000 (0:00:00.024) 0:00:00.024 ***** 2026-09-01 03:52:21.771229 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 03:52:21.771475 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 03:52:21.771806 | instance | interpreter could change the meaning of that path. See 2026-09-01 03:52:21.772099 | instance | https://docs.ansible.com/ansible- 2026-09-01 03:52:21.772377 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 03:52:21.781377 | instance | ok: [instance] 2026-09-01 03:52:21.781596 | instance | 2026-09-01 03:52:21.781820 | instance | TASK [Configure short hostname] ************************************************ 2026-09-01 03:52:21.782072 | instance | Tuesday 01 September 2026 03:52:21 +0000 (0:00:01.306) 0:00:01.331 ***** 2026-09-01 03:52:22.528208 | instance | changed: [instance] 2026-09-01 03:52:22.528443 | instance | 2026-09-01 03:52:22.528696 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-01 03:52:22.528926 | instance | Tuesday 01 September 2026 03:52:22 +0000 (0:00:00.746) 0:00:02.077 ***** 2026-09-01 03:52:22.801910 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-01 03:52:22.802134 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-01 03:52:22.802370 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-01 03:52:22.811579 | instance | changed: [instance] 2026-09-01 03:52:22.811732 | instance | 2026-09-01 03:52:22.811908 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-01 03:52:22.812090 | instance | Tuesday 01 September 2026 03:52:22 +0000 (0:00:00.283) 0:00:02.361 ***** 2026-09-01 03:52:24.710525 | instance | ok: [instance] 2026-09-01 03:52:24.710755 | instance | 2026-09-01 03:52:24.711031 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-01 03:52:24.711295 | instance | 2026-09-01 03:52:24.711540 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:52:24.711804 | instance | Tuesday 01 September 2026 03:52:24 +0000 (0:00:01.899) 0:00:04.260 ***** 2026-09-01 03:52:25.488772 | instance | ok: [instance] 2026-09-01 03:52:25.489028 | instance | 2026-09-01 03:52:25.489329 | instance | TASK [Install depedencies] ***************************************************** 2026-09-01 03:52:25.489602 | instance | Tuesday 01 September 2026 03:52:25 +0000 (0:00:00.777) 0:00:05.038 ***** 2026-09-01 03:52:48.864232 | instance | changed: [instance] 2026-09-01 03:52:48.864435 | instance | 2026-09-01 03:52:48.864738 | instance | TASK [Start up service] ******************************************************** 2026-09-01 03:52:48.865039 | instance | Tuesday 01 September 2026 03:52:48 +0000 (0:00:23.374) 0:00:28.412 ***** 2026-09-01 03:52:49.494028 | instance | ok: [instance] 2026-09-01 03:52:49.494235 | instance | 2026-09-01 03:52:49.494530 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-01 03:52:49.494797 | instance | Tuesday 01 September 2026 03:52:49 +0000 (0:00:00.629) 0:00:29.042 ***** 2026-09-01 03:52:49.844761 | instance | ok: [instance] 2026-09-01 03:52:49.844956 | instance | 2026-09-01 03:52:49.845199 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-01 03:52:49.845437 | instance | Tuesday 01 September 2026 03:52:49 +0000 (0:00:00.351) 0:00:29.393 ***** 2026-09-01 03:52:50.589996 | instance | changed: [instance] 2026-09-01 03:52:50.590379 | instance | 2026-09-01 03:52:50.590753 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-01 03:52:50.591199 | instance | Tuesday 01 September 2026 03:52:50 +0000 (0:00:00.745) 0:00:30.139 ***** 2026-09-01 03:52:50.825411 | instance | ok: [instance] 2026-09-01 03:52:50.825638 | instance | 2026-09-01 03:52:50.826044 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-01 03:52:50.826361 | instance | Tuesday 01 September 2026 03:52:50 +0000 (0:00:00.234) 0:00:30.374 ***** 2026-09-01 03:52:50.863757 | instance | skipping: [instance] 2026-09-01 03:52:50.864150 | instance | 2026-09-01 03:52:50.864471 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-01 03:52:50.864812 | instance | Tuesday 01 September 2026 03:52:50 +0000 (0:00:00.035) 0:00:30.410 ***** 2026-09-01 03:52:51.444289 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:52:51.444346 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:52:51.444352 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:52:51.444452 | instance | 2026-09-01 03:52:51.444752 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-01 03:52:51.445040 | instance | Tuesday 01 September 2026 03:52:51 +0000 (0:00:00.582) 0:00:30.992 ***** 2026-09-01 03:52:52.138625 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:52:52.139013 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:52:52.139458 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:52:52.139814 | instance | 2026-09-01 03:52:52.140142 | instance | TASK [Start loop devices] ****************************************************** 2026-09-01 03:52:52.140419 | instance | Tuesday 01 September 2026 03:52:52 +0000 (0:00:00.695) 0:00:31.687 ***** 2026-09-01 03:52:52.924832 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:52:52.925073 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:52:52.925346 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:52:52.925619 | instance | 2026-09-01 03:52:52.925899 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-01 03:52:52.926176 | instance | Tuesday 01 September 2026 03:52:52 +0000 (0:00:00.786) 0:00:32.474 ***** 2026-09-01 03:52:56.266309 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:52:56.266384 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:52:56.266395 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:52:56.266405 | instance | 2026-09-01 03:52:56.266414 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-01 03:52:56.266424 | instance | Tuesday 01 September 2026 03:52:56 +0000 (0:00:03.340) 0:00:35.814 ***** 2026-09-01 03:52:58.303932 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-01 03:52:58.304627 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-01 03:52:58.304677 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-01 03:52:58.304688 | instance | 2026-09-01 03:52:58.307180 | instance | PLAY RECAP ********************************************************************* 2026-09-01 03:52:58.307275 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-01 03:52:58.307288 | instance | 2026-09-01 03:52:58.307298 | instance | Tuesday 01 September 2026 03:52:58 +0000 (0:00:02.039) 0:00:37.854 ***** 2026-09-01 03:52:58.307308 | instance | =============================================================================== 2026-09-01 03:52:58.307317 | instance | Install depedencies ---------------------------------------------------- 23.37s 2026-09-01 03:52:58.307326 | instance | Create a volume group for each loop device ------------------------------ 3.34s 2026-09-01 03:52:58.307346 | instance | Create a logical volume for each loop device ---------------------------- 2.04s 2026-09-01 03:52:58.307627 | instance | Purge "snapd" package --------------------------------------------------- 1.90s 2026-09-01 03:52:58.308032 | instance | Gathering Facts --------------------------------------------------------- 1.31s 2026-09-01 03:52:58.308350 | instance | Start loop devices ------------------------------------------------------ 0.79s 2026-09-01 03:52:58.308674 | instance | Gathering Facts --------------------------------------------------------- 0.78s 2026-09-01 03:52:58.308975 | instance | Configure short hostname ------------------------------------------------ 0.75s 2026-09-01 03:52:58.309304 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.75s 2026-09-01 03:52:58.309648 | instance | Set permissions on loopback devices ------------------------------------- 0.70s 2026-09-01 03:52:58.309952 | instance | Start up service -------------------------------------------------------- 0.63s 2026-09-01 03:52:58.310280 | instance | Create devices for Ceph ------------------------------------------------- 0.58s 2026-09-01 03:52:58.310614 | instance | Generate lvm.conf ------------------------------------------------------- 0.35s 2026-09-01 03:52:58.310919 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s 2026-09-01 03:52:58.311300 | instance | Get list of all loopback devices ---------------------------------------- 0.23s 2026-09-01 03:52:58.311659 | instance | Fail if there is any existing loopback devices -------------------------- 0.04s 2026-09-01 03:52:58.424664 | instance | INFO [csi > prepare] Executed: Successful 2026-09-01 03:52:58.440210 | instance | INFO [csi > converge] Executing 2026-09-01 03:52:59.854444 | instance | 2026-09-01 03:52:59.854902 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-01 03:52:59.855234 | instance | 2026-09-01 03:52:59.855564 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:52:59.855937 | instance | Tuesday 01 September 2026 03:52:59 +0000 (0:00:00.007) 0:00:00.007 ***** 2026-09-01 03:53:00.945711 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 03:53:00.945803 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 03:53:00.945819 | instance | interpreter could change the meaning of that path. See 2026-09-01 03:53:00.945832 | instance | https://docs.ansible.com/ansible- 2026-09-01 03:53:00.945844 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 03:53:00.960141 | instance | ok: [instance] 2026-09-01 03:53:00.960177 | instance | 2026-09-01 03:53:00.960190 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-01 03:53:00.960200 | instance | Tuesday 01 September 2026 03:53:00 +0000 (0:00:01.104) 0:00:01.112 ***** 2026-09-01 03:53:01.023767 | instance | ok: [instance] => { 2026-09-01 03:53:01.024146 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-01 03:53:01.024461 | instance | } 2026-09-01 03:53:01.024883 | instance | 2026-09-01 03:53:01.025259 | instance | PLAY [all] ********************************************************************* 2026-09-01 03:53:01.025604 | instance | 2026-09-01 03:53:01.026018 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:53:01.026382 | instance | Tuesday 01 September 2026 03:53:01 +0000 (0:00:00.064) 0:00:01.177 ***** 2026-09-01 03:53:01.958056 | instance | ok: [instance] 2026-09-01 03:53:01.958110 | instance | 2026-09-01 03:53:01.958118 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-01 03:53:01.958125 | instance | Tuesday 01 September 2026 03:53:01 +0000 (0:00:00.933) 0:00:02.111 ***** 2026-09-01 03:53:01.993626 | instance | skipping: [instance] 2026-09-01 03:53:01.993647 | instance | 2026-09-01 03:53:01.993653 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-01 03:53:01.993657 | instance | Tuesday 01 September 2026 03:53:01 +0000 (0:00:00.036) 0:00:02.147 ***** 2026-09-01 03:53:02.193779 | instance | ok: [instance] 2026-09-01 03:53:02.193853 | instance | 2026-09-01 03:53:02.193986 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-01 03:53:02.194087 | instance | 2026-09-01 03:53:02.194228 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:53:02.194370 | instance | Tuesday 01 September 2026 03:53:02 +0000 (0:00:00.199) 0:00:02.347 ***** 2026-09-01 03:53:03.197897 | instance | ok: [instance] 2026-09-01 03:53:03.199691 | instance | 2026-09-01 03:53:03.199715 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:53:03.199729 | instance | Tuesday 01 September 2026 03:53:03 +0000 (0:00:01.003) 0:00:03.351 ***** 2026-09-01 03:53:03.591075 | instance | ok: [instance] 2026-09-01 03:53:03.591162 | instance | 2026-09-01 03:53:03.591535 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:53:03.591589 | instance | Tuesday 01 September 2026 03:53:03 +0000 (0:00:00.392) 0:00:03.744 ***** 2026-09-01 03:53:03.651162 | instance | skipping: [instance] 2026-09-01 03:53:03.651415 | instance | 2026-09-01 03:53:03.651728 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-01 03:53:03.651969 | instance | Tuesday 01 September 2026 03:53:03 +0000 (0:00:00.059) 0:00:03.803 ***** 2026-09-01 03:53:04.050874 | instance | changed: [instance] 2026-09-01 03:53:04.051030 | instance | 2026-09-01 03:53:04.051305 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:53:04.051568 | instance | Tuesday 01 September 2026 03:53:04 +0000 (0:00:00.400) 0:00:04.203 ***** 2026-09-01 03:53:04.146745 | instance | ok: [instance] => { 2026-09-01 03:53:04.146927 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-01 03:53:04.147196 | instance | } 2026-09-01 03:53:04.147413 | instance | 2026-09-01 03:53:04.147687 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:53:04.147933 | instance | Tuesday 01 September 2026 03:53:04 +0000 (0:00:00.095) 0:00:04.299 ***** 2026-09-01 03:53:05.059409 | instance | changed: [instance] 2026-09-01 03:53:05.059677 | instance | 2026-09-01 03:53:05.059966 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:53:05.060285 | instance | Tuesday 01 September 2026 03:53:05 +0000 (0:00:00.912) 0:00:05.212 ***** 2026-09-01 03:53:05.120397 | instance | skipping: [instance] 2026-09-01 03:53:05.120608 | instance | 2026-09-01 03:53:05.120878 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:53:05.121126 | instance | Tuesday 01 September 2026 03:53:05 +0000 (0:00:00.060) 0:00:05.273 ***** 2026-09-01 03:53:05.173942 | instance | skipping: [instance] 2026-09-01 03:53:05.174131 | instance | 2026-09-01 03:53:05.174382 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:53:05.174725 | instance | Tuesday 01 September 2026 03:53:05 +0000 (0:00:00.053) 0:00:05.326 ***** 2026-09-01 03:53:05.380967 | instance | ok: [instance] 2026-09-01 03:53:05.381049 | instance | 2026-09-01 03:53:05.381229 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:53:05.381385 | instance | Tuesday 01 September 2026 03:53:05 +0000 (0:00:00.207) 0:00:05.533 ***** 2026-09-01 03:53:06.842549 | instance | ok: [instance] 2026-09-01 03:53:06.843084 | instance | 2026-09-01 03:53:06.843435 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:53:06.843816 | instance | Tuesday 01 September 2026 03:53:06 +0000 (0:00:01.461) 0:00:06.995 ***** 2026-09-01 03:53:06.932058 | instance | ok: [instance] => { 2026-09-01 03:53:06.932322 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-01 03:53:06.932734 | instance | } 2026-09-01 03:53:06.933043 | instance | 2026-09-01 03:53:06.933365 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:53:06.933706 | instance | Tuesday 01 September 2026 03:53:06 +0000 (0:00:00.088) 0:00:07.084 ***** 2026-09-01 03:53:07.687688 | instance | changed: [instance] 2026-09-01 03:53:07.687799 | instance | 2026-09-01 03:53:07.687820 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:53:07.687982 | instance | Tuesday 01 September 2026 03:53:07 +0000 (0:00:00.755) 0:00:07.840 ***** 2026-09-01 03:53:10.742882 | instance | changed: [instance] 2026-09-01 03:53:10.743014 | instance | 2026-09-01 03:53:10.743149 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-01 03:53:10.743334 | instance | Tuesday 01 September 2026 03:53:10 +0000 (0:00:03.056) 0:00:10.896 ***** 2026-09-01 03:53:10.787804 | instance | skipping: [instance] 2026-09-01 03:53:10.788017 | instance | 2026-09-01 03:53:10.788545 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-01 03:53:10.788829 | instance | Tuesday 01 September 2026 03:53:10 +0000 (0:00:00.044) 0:00:10.940 ***** 2026-09-01 03:53:10.829499 | instance | skipping: [instance] 2026-09-01 03:53:10.829678 | instance | 2026-09-01 03:53:10.829922 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-01 03:53:10.830168 | instance | Tuesday 01 September 2026 03:53:10 +0000 (0:00:00.042) 0:00:10.982 ***** 2026-09-01 03:53:10.879094 | instance | skipping: [instance] 2026-09-01 03:53:10.879454 | instance | 2026-09-01 03:53:10.879842 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-01 03:53:10.880038 | instance | Tuesday 01 September 2026 03:53:10 +0000 (0:00:00.048) 0:00:11.031 ***** 2026-09-01 03:53:17.627275 | instance | changed: [instance] 2026-09-01 03:53:17.627563 | instance | 2026-09-01 03:53:17.627922 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-01 03:53:17.628255 | instance | Tuesday 01 September 2026 03:53:17 +0000 (0:00:06.748) 0:00:17.779 ***** 2026-09-01 03:53:18.308774 | instance | changed: [instance] 2026-09-01 03:53:18.309349 | instance | 2026-09-01 03:53:18.309697 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-01 03:53:18.310009 | instance | Tuesday 01 September 2026 03:53:18 +0000 (0:00:00.681) 0:00:18.461 ***** 2026-09-01 03:53:19.335137 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-01 03:53:19.335206 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-01 03:53:19.335225 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-01 03:53:19.335588 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-01 03:53:19.335637 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-01 03:53:19.336132 | instance | 2026-09-01 03:53:19.336237 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-01 03:53:19.336250 | instance | Tuesday 01 September 2026 03:53:19 +0000 (0:00:01.025) 0:00:19.487 ***** 2026-09-01 03:53:20.008380 | instance | changed: [instance] 2026-09-01 03:53:20.008470 | instance | 2026-09-01 03:53:20.009227 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-01 03:53:20.009273 | instance | Tuesday 01 September 2026 03:53:19 +0000 (0:00:00.646) 0:00:20.133 ***** 2026-09-01 03:53:20.009281 | instance | 2026-09-01 03:53:20.009288 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 03:53:20.009293 | instance | Tuesday 01 September 2026 03:53:20 +0000 (0:00:00.028) 0:00:20.161 ***** 2026-09-01 03:53:21.049407 | instance | ok: [instance] 2026-09-01 03:53:21.049501 | instance | 2026-09-01 03:53:21.049803 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-01 03:53:21.049824 | instance | Tuesday 01 September 2026 03:53:21 +0000 (0:00:01.041) 0:00:21.202 ***** 2026-09-01 03:53:21.570252 | instance | changed: [instance] 2026-09-01 03:53:21.571181 | instance | 2026-09-01 03:53:21.571253 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-01 03:53:21.571273 | instance | Tuesday 01 September 2026 03:53:21 +0000 (0:00:00.520) 0:00:21.723 ***** 2026-09-01 03:53:22.195747 | instance | changed: [instance] 2026-09-01 03:53:22.195796 | instance | 2026-09-01 03:53:22.195803 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:53:22.195809 | instance | Tuesday 01 September 2026 03:53:22 +0000 (0:00:00.625) 0:00:22.348 ***** 2026-09-01 03:53:22.443019 | instance | ok: [instance] 2026-09-01 03:53:22.443827 | instance | 2026-09-01 03:53:22.443995 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:53:22.444008 | instance | Tuesday 01 September 2026 03:53:22 +0000 (0:00:00.247) 0:00:22.596 ***** 2026-09-01 03:53:22.514061 | instance | ok: [instance] => { 2026-09-01 03:53:22.514884 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-01 03:53:22.514938 | instance | } 2026-09-01 03:53:22.514946 | instance | 2026-09-01 03:53:22.514952 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:53:22.514959 | instance | Tuesday 01 September 2026 03:53:22 +0000 (0:00:00.071) 0:00:22.667 ***** 2026-09-01 03:53:23.417929 | instance | changed: [instance] 2026-09-01 03:53:23.418746 | instance | 2026-09-01 03:53:23.418817 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:53:23.418829 | instance | Tuesday 01 September 2026 03:53:23 +0000 (0:00:00.903) 0:00:23.570 ***** 2026-09-01 03:53:27.871086 | instance | changed: [instance] 2026-09-01 03:53:27.871218 | instance | 2026-09-01 03:53:27.871584 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-01 03:53:27.871636 | instance | Tuesday 01 September 2026 03:53:27 +0000 (0:00:04.453) 0:00:28.024 ***** 2026-09-01 03:53:29.278246 | instance | ok: [instance] 2026-09-01 03:53:29.279256 | instance | 2026-09-01 03:53:29.279332 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-01 03:53:29.279353 | instance | Tuesday 01 September 2026 03:53:29 +0000 (0:00:01.405) 0:00:29.430 ***** 2026-09-01 03:53:29.716852 | instance | changed: [instance] 2026-09-01 03:53:29.717675 | instance | 2026-09-01 03:53:29.717741 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-01 03:53:29.717753 | instance | Tuesday 01 September 2026 03:53:29 +0000 (0:00:00.438) 0:00:29.869 ***** 2026-09-01 03:53:30.233933 | instance | changed: [instance] 2026-09-01 03:53:30.234051 | instance | 2026-09-01 03:53:30.234357 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-01 03:53:30.234409 | instance | Tuesday 01 September 2026 03:53:30 +0000 (0:00:00.517) 0:00:30.387 ***** 2026-09-01 03:53:30.896243 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-01 03:53:30.896326 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-01 03:53:30.896386 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-01 03:53:30.896746 | instance | 2026-09-01 03:53:30.896814 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-01 03:53:30.896821 | instance | Tuesday 01 September 2026 03:53:30 +0000 (0:00:00.662) 0:00:31.049 ***** 2026-09-01 03:53:31.398093 | instance | changed: [instance] 2026-09-01 03:53:31.398160 | instance | 2026-09-01 03:53:31.398193 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-01 03:53:31.398203 | instance | Tuesday 01 September 2026 03:53:31 +0000 (0:00:00.500) 0:00:31.550 ***** 2026-09-01 03:53:31.938550 | instance | changed: [instance] 2026-09-01 03:53:31.939225 | instance | 2026-09-01 03:53:31.939295 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-01 03:53:31.939326 | instance | Tuesday 01 September 2026 03:53:31 +0000 (0:00:00.513) 0:00:32.063 ***** 2026-09-01 03:53:31.939343 | instance | 2026-09-01 03:53:31.939722 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 03:53:31.939797 | instance | Tuesday 01 September 2026 03:53:31 +0000 (0:00:00.028) 0:00:32.091 ***** 2026-09-01 03:53:32.679390 | instance | ok: [instance] 2026-09-01 03:53:32.679463 | instance | 2026-09-01 03:53:32.679469 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-01 03:53:32.679482 | instance | Tuesday 01 September 2026 03:53:32 +0000 (0:00:00.740) 0:00:32.832 ***** 2026-09-01 03:53:33.759149 | instance | changed: [instance] 2026-09-01 03:53:33.759955 | instance | 2026-09-01 03:53:33.760021 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-01 03:53:33.760034 | instance | Tuesday 01 September 2026 03:53:33 +0000 (0:00:01.079) 0:00:33.912 ***** 2026-09-01 03:53:34.458404 | instance | changed: [instance] 2026-09-01 03:53:34.458479 | instance | 2026-09-01 03:53:34.458818 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-01 03:53:34.458865 | instance | Tuesday 01 September 2026 03:53:34 +0000 (0:00:00.699) 0:00:34.611 ***** 2026-09-01 03:53:34.552220 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-01 03:53:34.552276 | instance | 2026-09-01 03:53:34.552287 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-01 03:53:34.552297 | instance | Tuesday 01 September 2026 03:53:34 +0000 (0:00:00.092) 0:00:34.704 ***** 2026-09-01 03:53:40.739752 | instance | changed: [instance] 2026-09-01 03:53:40.740553 | instance | 2026-09-01 03:53:40.740623 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-01 03:53:40.740635 | instance | Tuesday 01 September 2026 03:53:40 +0000 (0:00:06.188) 0:00:40.892 ***** 2026-09-01 03:53:41.448524 | instance | ok: [instance] => (item=chronyd) 2026-09-01 03:53:41.448582 | instance | ok: [instance] => (item=sshd) 2026-09-01 03:53:41.448594 | instance | 2026-09-01 03:53:41.448603 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-01 03:53:41.448614 | instance | Tuesday 01 September 2026 03:53:41 +0000 (0:00:00.707) 0:00:41.600 ***** 2026-09-01 03:53:41.808168 | instance | changed: [instance] 2026-09-01 03:53:41.808313 | instance | 2026-09-01 03:53:41.808856 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-01 03:53:41.808922 | instance | Tuesday 01 September 2026 03:53:41 +0000 (0:00:00.360) 0:00:41.961 ***** 2026-09-01 03:53:42.050529 | instance | ok: [instance] 2026-09-01 03:53:42.050616 | instance | 2026-09-01 03:53:42.051062 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-01 03:53:42.051365 | instance | Tuesday 01 September 2026 03:53:42 +0000 (0:00:00.242) 0:00:42.203 ***** 2026-09-01 03:53:42.585503 | instance | changed: [instance] 2026-09-01 03:53:42.586262 | instance | 2026-09-01 03:53:42.586326 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-01 03:53:42.586338 | instance | Tuesday 01 September 2026 03:53:42 +0000 (0:00:00.534) 0:00:42.738 ***** 2026-09-01 03:53:43.011582 | instance | changed: [instance] 2026-09-01 03:53:43.011701 | instance | 2026-09-01 03:53:43.012050 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-01 03:53:43.012105 | instance | Tuesday 01 September 2026 03:53:43 +0000 (0:00:00.426) 0:00:43.165 ***** 2026-09-01 03:53:43.264354 | instance | ok: [instance] 2026-09-01 03:53:43.264457 | instance | 2026-09-01 03:53:43.264471 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-01 03:53:43.264546 | instance | Tuesday 01 September 2026 03:53:43 +0000 (0:00:00.252) 0:00:43.417 ***** 2026-09-01 03:53:43.523792 | instance | ok: [instance] => (item=instance) 2026-09-01 03:53:43.523842 | instance | 2026-09-01 03:53:43.523847 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-01 03:53:43.523852 | instance | Tuesday 01 September 2026 03:53:43 +0000 (0:00:00.259) 0:00:43.676 ***** 2026-09-01 03:53:43.605557 | instance | ok: [instance] 2026-09-01 03:53:43.605778 | instance | 2026-09-01 03:53:43.606370 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-01 03:53:43.606447 | instance | Tuesday 01 September 2026 03:53:43 +0000 (0:00:00.080) 0:00:43.757 ***** 2026-09-01 03:53:43.701025 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-01 03:53:43.701137 | instance | 2026-09-01 03:53:43.701152 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-01 03:53:43.701285 | instance | Tuesday 01 September 2026 03:53:43 +0000 (0:00:00.096) 0:00:43.854 ***** 2026-09-01 03:53:44.011823 | instance | changed: [instance] 2026-09-01 03:53:44.011947 | instance | 2026-09-01 03:53:44.012256 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-01 03:53:44.012324 | instance | Tuesday 01 September 2026 03:53:44 +0000 (0:00:00.310) 0:00:44.164 ***** 2026-09-01 03:53:44.792346 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-01 03:53:44.792450 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-01 03:53:44.793036 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-01 03:53:44.793090 | instance | 2026-09-01 03:53:44.793097 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-01 03:53:44.793104 | instance | Tuesday 01 September 2026 03:53:44 +0000 (0:00:00.780) 0:00:44.945 ***** 2026-09-01 03:55:20.876161 | instance | ok: [instance] 2026-09-01 03:55:20.876715 | instance | 2026-09-01 03:55:20.876722 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-01 03:55:20.876729 | instance | Tuesday 01 September 2026 03:55:20 +0000 (0:01:36.082) 0:02:21.028 ***** 2026-09-01 03:55:21.108628 | instance | changed: [instance] 2026-09-01 03:55:21.108695 | instance | 2026-09-01 03:55:21.108709 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-01 03:55:21.108719 | instance | Tuesday 01 September 2026 03:55:21 +0000 (0:00:00.232) 0:02:21.260 ***** 2026-09-01 03:55:21.150145 | instance | ok: [instance] 2026-09-01 03:55:21.150190 | instance | 2026-09-01 03:55:21.150198 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-01 03:55:21.150205 | instance | Tuesday 01 September 2026 03:55:21 +0000 (0:00:00.042) 0:02:21.303 ***** 2026-09-01 03:55:21.229792 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-01 03:55:21.229843 | instance | 2026-09-01 03:55:21.229848 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-01 03:55:21.229853 | instance | Tuesday 01 September 2026 03:55:21 +0000 (0:00:00.079) 0:02:21.383 ***** 2026-09-01 03:55:23.484422 | instance | ok: [instance] 2026-09-01 03:55:23.484490 | instance | 2026-09-01 03:55:23.484515 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-01 03:55:23.484526 | instance | Tuesday 01 September 2026 03:55:23 +0000 (0:00:02.253) 0:02:23.636 ***** 2026-09-01 03:55:23.560804 | instance | ok: [instance] => (item=instance) 2026-09-01 03:55:23.560889 | instance | 2026-09-01 03:55:23.560905 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-01 03:55:23.560916 | instance | Tuesday 01 September 2026 03:55:23 +0000 (0:00:00.076) 0:02:23.712 ***** 2026-09-01 03:55:23.917591 | instance | ok: [instance] 2026-09-01 03:55:23.918395 | instance | 2026-09-01 03:55:23.919239 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-01 03:55:23.919357 | instance | Tuesday 01 September 2026 03:55:23 +0000 (0:00:00.357) 0:02:24.070 ***** 2026-09-01 03:55:26.200283 | instance | ok: [instance] 2026-09-01 03:55:26.200501 | instance | 2026-09-01 03:55:26.200846 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-01 03:55:26.201016 | instance | Tuesday 01 September 2026 03:55:26 +0000 (0:00:02.282) 0:02:26.353 ***** 2026-09-01 03:56:17.318894 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-01 03:56:17.319584 | 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_i7fc31z1/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_i7fc31z1/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_i7fc31z1/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_i7fc31z1/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-01T03:56:17.109+0000 7fda7695d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgB9TJZq1LcYGiAAW22nNjTrbQVkq+ekHJOtFgqbkac75L87hmeyE+i9L64=: Malformed input [buffer:3]\n2026-09-01T03:56:17.109+0000 7fda7695d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T03:56:17.109+0000 7fda7695d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgB9TJZq1LcYGiAAW22nNjTrbQVkq+ekHJOtFgqbkac75L87hmeyE+i9L64=: Malformed input [buffer:3]\n2026-09-01T03:56:17.109+0000 7fda7695d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T03:56:17.109+0000 7fda7695d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgB9TJZq1LcYGiAAW22nNjTrbQVkq+ekHJOtFgqbkac75L87hmeyE+i9L64=: Malformed input [buffer:3]\n2026-09-01T03:56:17.109+0000 7fda7695d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T03:56:17.109+0000 7fda7695d640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-01 03:56:17.319908 | instance | 2026-09-01 03:56:17.320201 | instance | PLAY RECAP ********************************************************************* 2026-09-01 03:56:17.320584 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-01 03:56:17.320862 | instance | 2026-09-01 03:56:17.321150 | instance | Tuesday 01 September 2026 03:56:17 +0000 (0:00:51.119) 0:03:17.472 ***** 2026-09-01 03:56:17.321453 | instance | =============================================================================== 2026-09-01 03:56:17.321823 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 96.08s 2026-09-01 03:56:17.322049 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.12s 2026-09-01 03:56:17.322357 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.75s 2026-09-01 03:56:17.322635 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.19s 2026-09-01 03:56:17.322915 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.45s 2026-09-01 03:56:17.323250 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.06s 2026-09-01 03:56:17.323570 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.28s 2026-09-01 03:56:17.323856 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 2.25s 2026-09-01 03:56:17.324131 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.78s 2026-09-01 03:56:17.324441 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.46s 2026-09-01 03:56:17.324766 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.41s 2026-09-01 03:56:17.325035 | instance | Gathering Facts --------------------------------------------------------- 1.10s 2026-09-01 03:56:17.325358 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.08s 2026-09-01 03:56:17.325661 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.03s 2026-09-01 03:56:17.325977 | instance | Gathering Facts --------------------------------------------------------- 1.00s 2026-09-01 03:56:17.326280 | instance | Gathering Facts --------------------------------------------------------- 0.93s 2026-09-01 03:56:17.326601 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.91s 2026-09-01 03:56:17.326913 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.90s 2026-09-01 03:56:17.327239 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.78s 2026-09-01 03:56:17.327562 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.76s 2026-09-01 03:56:17.425347 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 03:56:17.425500 | instance | ERROR [csi > converge] Executed: Failed 2026-09-01 03:56:17.425632 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 03:56:17.712223 | instance | ERROR 2026-09-01 03:56:17.712542 | instance | { 2026-09-01 03:56:17.712604 | instance | "delta": "0:04:11.323620", 2026-09-01 03:56:17.712693 | instance | "end": "2026-09-01 03:56:17.512411", 2026-09-01 03:56:17.712741 | instance | "msg": "non-zero return code", 2026-09-01 03:56:17.712786 | instance | "rc": 2, 2026-09-01 03:56:17.712826 | instance | "start": "2026-09-01 03:52:06.188791" 2026-09-01 03:56:17.712869 | instance | } failure 2026-09-01 03:56:17.720728 | 2026-09-01 03:56:17.720809 | PLAY RECAP 2026-09-01 03:56:17.720875 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:56:17.720907 | 2026-09-01 03:56:17.860277 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 03:56:17.863348 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 03:56:18.493444 | 2026-09-01 03:56:18.493603 | PLAY [all] 2026-09-01 03:56:18.508253 | 2026-09-01 03:56:18.508334 | TASK [gather-host-logs : creating directory for system status] 2026-09-01 03:56:18.886852 | instance | changed 2026-09-01 03:56:18.893557 | 2026-09-01 03:56:18.893642 | TASK [gather-host-logs : Get logs for each host] 2026-09-01 03:56:19.271996 | instance | + systemd-cgls --full --all --no-pager 2026-09-01 03:56:19.289768 | instance | + ip addr 2026-09-01 03:56:19.293058 | instance | + ip route 2026-09-01 03:56:19.295821 | instance | + lsblk 2026-09-01 03:56:19.301508 | instance | + mount 2026-09-01 03:56:19.304634 | instance | + docker images 2026-09-01 03:56:19.329031 | instance | + brctl show 2026-09-01 03:56:19.329601 | instance | /bin/bash: line 8: brctl: command not found 2026-09-01 03:56:19.329937 | instance | + ps aux --sort=-%mem 2026-09-01 03:56:19.358470 | instance | + dpkg -l 2026-09-01 03:56:19.376038 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-01 03:56:19.376662 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-01 03:56:19.397891 | instance | + '[' '!' -z '' ']' 2026-09-01 03:56:19.460941 | instance | ok: Runtime: 0:00:00.132065 2026-09-01 03:56:19.470130 | 2026-09-01 03:56:19.470205 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-01 03:56:20.114590 | instance | changed: 2026-09-01 03:56:20.114835 | instance | created directory /var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/work/logs/instance 2026-09-01 03:56:20.114874 | instance | cd+++++++++ system/ 2026-09-01 03:56:20.114905 | instance | >f+++++++++ system/brctl-show.txt 2026-09-01 03:56:20.114935 | instance | >f+++++++++ system/docker-images.txt 2026-09-01 03:56:20.114963 | instance | >f+++++++++ system/ip-addr.txt 2026-09-01 03:56:20.114993 | instance | >f+++++++++ system/ip-route.txt 2026-09-01 03:56:20.115022 | instance | >f+++++++++ system/lsblk.txt 2026-09-01 03:56:20.115051 | instance | >f+++++++++ system/mount.txt 2026-09-01 03:56:20.115079 | instance | >f+++++++++ system/packages.txt 2026-09-01 03:56:20.115133 | instance | >f+++++++++ system/ps.txt 2026-09-01 03:56:20.115171 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-01 03:56:20.128480 | 2026-09-01 03:56:20.128573 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-01 03:56:20.344280 | instance | changed: "values" 2026-09-01 03:56:20.530535 | instance | changed: "releases" 2026-09-01 03:56:20.548814 | 2026-09-01 03:56:20.549017 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-01 03:56:20.785869 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-01 03:56:21.091894 | instance | ok: Runtime: 0:00:00.006539 2026-09-01 03:56:21.097025 | 2026-09-01 03:56:21.097101 | TASK [helm-release-status : Downloads logs to executor] 2026-09-01 03:56:21.569885 | instance | changed: 2026-09-01 03:56:21.570148 | instance | cd+++++++++ helm/ 2026-09-01 03:56:21.570188 | instance | cd+++++++++ helm/releases/ 2026-09-01 03:56:21.570218 | instance | cd+++++++++ helm/values/ 2026-09-01 03:56:21.580456 | 2026-09-01 03:56:21.580525 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-01 03:56:21.816907 | instance | changed 2026-09-01 03:56:21.824498 | 2026-09-01 03:56:21.824572 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-01 03:56:22.101553 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:56:22.101689 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:56:22.107410 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:56:22.108769 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:56:22.110428 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:56:22.112379 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:56:22.113923 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:56:22.115217 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:56:22.116824 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:56:22.118584 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:56:22.120157 | instance | environment: line 1: kubectl: command not found 2026-09-01 03:56:22.121700 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 03:56:22.360764 | instance | ok: Runtime: 0:00:00.027041 2026-09-01 03:56:22.367703 | 2026-09-01 03:56:22.367795 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-01 03:56:22.600515 | instance | changed 2026-09-01 03:56:22.605651 | 2026-09-01 03:56:22.605729 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-01 03:56:22.870771 | instance | environment: line 5: kubectl: command not found 2026-09-01 03:56:22.871540 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:56:22.872745 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 03:56:22.872772 | instance | 2026-09-01 03:56:23.142268 | instance | ok: Runtime: 0:00:00.008351 2026-09-01 03:56:23.149145 | 2026-09-01 03:56:23.149218 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-01 03:56:23.651727 | instance | changed: 2026-09-01 03:56:23.652028 | instance | cd+++++++++ objects/ 2026-09-01 03:56:23.652099 | instance | cd+++++++++ objects/cluster/ 2026-09-01 03:56:23.652157 | instance | cd+++++++++ objects/namespaced/ 2026-09-01 03:56:23.661192 | 2026-09-01 03:56:23.661257 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-01 03:56:23.887782 | instance | changed 2026-09-01 03:56:23.893030 | 2026-09-01 03:56:23.893095 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-01 03:56:24.132721 | instance | changed 2026-09-01 03:56:24.138127 | 2026-09-01 03:56:24.138213 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-01 03:56:24.373408 | instance | environment: line 3: kubectl: command not found 2026-09-01 03:56:24.678194 | instance | ok: Runtime: 0:00:00.009851 2026-09-01 03:56:24.703351 | 2026-09-01 03:56:24.703527 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-01 03:56:25.195517 | instance | changed: 2026-09-01 03:56:25.195829 | instance | cd+++++++++ pod-logs/ 2026-09-01 03:56:25.195894 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-01 03:56:25.206479 | 2026-09-01 03:56:25.206544 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-01 03:56:25.431311 | instance | changed 2026-09-01 03:56:25.438590 | 2026-09-01 03:56:25.438679 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-01 03:56:25.685922 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 03:56:25.987510 | instance | ok: Runtime: 0:00:00.053704 2026-09-01 03:56:25.993677 | 2026-09-01 03:56:25.993754 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-01 03:56:26.225747 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 03:56:26.272740 | instance | ceph-mgr endpoints: 2026-09-01 03:56:26.531025 | instance | ok: Runtime: 0:00:00.053116 2026-09-01 03:56:26.539230 | 2026-09-01 03:56:26.539295 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-01 03:56:26.765386 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-01 03:56:27.071383 | instance | ok: Runtime: 0:00:00.053750 2026-09-01 03:56:27.076590 | 2026-09-01 03:56:27.076658 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-01 03:56:27.576775 | instance | changed: cd+++++++++ prometheus/ 2026-09-01 03:56:27.586486 | 2026-09-01 03:56:27.586549 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-01 03:56:27.824378 | instance | changed 2026-09-01 03:56:27.829103 | 2026-09-01 03:56:27.829166 | TASK [gather-selenium-data : Get selenium data] 2026-09-01 03:56:28.102454 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-01 03:56:28.104033 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-01 03:56:28.366897 | instance | ERROR 2026-09-01 03:56:28.367131 | instance | { 2026-09-01 03:56:28.367163 | instance | "delta": "0:00:00.007042", 2026-09-01 03:56:28.367184 | instance | "end": "2026-09-01 03:56:28.104463", 2026-09-01 03:56:28.367206 | instance | "msg": "non-zero return code", 2026-09-01 03:56:28.367225 | instance | "rc": 1, 2026-09-01 03:56:28.367244 | instance | "start": "2026-09-01 03:56:28.097421" 2026-09-01 03:56:28.367262 | instance | } 2026-09-01 03:56:28.367285 | instance | ERROR: Ignoring Errors 2026-09-01 03:56:28.372115 | 2026-09-01 03:56:28.372181 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-01 03:56:28.953366 | instance | changed: cd+++++++++ selenium/ 2026-09-01 03:56:28.962338 | 2026-09-01 03:56:28.962409 | PLAY RECAP 2026-09-01 03:56:28.962460 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-01 03:56:28.962487 | 2026-09-01 03:56:29.107145 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 03:56:29.112390 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 03:56:29.722964 | 2026-09-01 03:56:29.723101 | PLAY [all] 2026-09-01 03:56:29.734779 | 2026-09-01 03:56:29.734861 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-01 03:56:29.780249 | instance | skipping: Conditional result was False 2026-09-01 03:56:29.791062 | 2026-09-01 03:56:29.791204 | TASK [fetch-output : Set log path for single node] 2026-09-01 03:56:29.838640 | instance | ok 2026-09-01 03:56:29.844467 | 2026-09-01 03:56:29.844534 | LOOP [fetch-output : Ensure local output dirs] 2026-09-01 03:56:30.214251 | instance -> localhost | ok: "/var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/work/logs" 2026-09-01 03:56:30.431490 | instance -> localhost | changed: "/var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/work/artifacts" 2026-09-01 03:56:30.661082 | instance -> localhost | changed: "/var/lib/zuul/builds/41182a2deb084b9a9d9c8a9704956692/work/docs" 2026-09-01 03:56:30.679914 | 2026-09-01 03:56:30.680081 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-01 03:56:31.359422 | instance | changed: .d..t...... ./ 2026-09-01 03:56:31.359709 | instance | changed: All items complete 2026-09-01 03:56:31.359738 | 2026-09-01 03:56:32.612131 | instance | changed: .d..t...... ./ 2026-09-01 03:56:33.069386 | instance | changed: .d..t...... ./ 2026-09-01 03:56:33.089019 | 2026-09-01 03:56:33.089212 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-01 03:56:33.496052 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008428 2026-09-01 03:56:33.719740 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008112 2026-09-01 03:56:33.739833 | 2026-09-01 03:56:33.740006 | PLAY [all] 2026-09-01 03:56:33.746368 | 2026-09-01 03:56:33.746435 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-01 03:56:34.214811 | instance | changed 2026-09-01 03:56:34.221671 | 2026-09-01 03:56:34.221722 | PLAY RECAP 2026-09-01 03:56:34.221765 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-01 03:56:34.221786 | 2026-09-01 03:56:34.362545 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 03:56:34.366880 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-01 03:56:34.943483 | 2026-09-01 03:56:34.943653 | PLAY [localhost] 2026-09-01 03:56:34.953567 | 2026-09-01 03:56:34.953637 | TASK [Generate Zuul manifest] 2026-09-01 03:56:34.975221 | localhost | ok 2026-09-01 03:56:34.992681 | 2026-09-01 03:56:34.992781 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-01 03:56:35.356728 | localhost | changed 2026-09-01 03:56:35.369011 | 2026-09-01 03:56:35.369090 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-01 03:56:35.399547 | localhost | ok 2026-09-01 03:56:35.407581 | 2026-09-01 03:56:35.407645 | TASK [Upload logs] 2026-09-01 03:56:35.431956 | localhost | ok 2026-09-01 03:56:35.487897 | 2026-09-01 03:56:35.488046 | TASK [Set zuul-log-path fact] 2026-09-01 03:56:35.511404 | localhost | ok 2026-09-01 03:56:35.523624 | 2026-09-01 03:56:35.523694 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 03:56:35.557704 | localhost | ok 2026-09-01 03:56:35.566371 | 2026-09-01 03:56:35.566438 | TASK [upload-logs : Create log directories] 2026-09-01 03:56:35.981919 | localhost | changed 2026-09-01 03:56:35.986876 | 2026-09-01 03:56:35.986946 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-01 03:56:36.363882 | localhost -> localhost | ok: Runtime: 0:00:00.005249 2026-09-01 03:56:36.371104 | 2026-09-01 03:56:36.371177 | TASK [upload-logs : Upload logs to log server] 2026-09-01 03:56:36.831908 | localhost | Output suppressed because no_log was given 2026-09-01 03:56:36.879161 | 2026-09-01 03:56:36.879328 | LOOP [upload-logs : Compress console log and json output] 2026-09-01 03:56:36.925108 | localhost | skipping: Conditional result was False 2026-09-01 03:56:36.931821 | localhost | skipping: Conditional result was False 2026-09-01 03:56:36.944695 | 2026-09-01 03:56:36.944840 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-01 03:56:36.987530 | localhost | skipping: Conditional result was False 2026-09-01 03:56:36.987904 | 2026-09-01 03:56:36.991139 | localhost | skipping: Conditional result was False 2026-09-01 03:56:37.001323 | 2026-09-01 03:56:37.001507 | LOOP [upload-logs : Upload console log and json output]