2026-09-12 06:23:10.160728 | Job console starting
2026-09-12 06:23:10.173696 | Updating git repos
2026-09-12 06:23:14.959814 | Cloning repos into workspace
2026-09-12 06:23:31.843825 | Restoring repo states
2026-09-12 06:23:35.660577 | Merging changes
2026-09-12 06:23:37.136635 | Checking out repos
2026-09-12 06:23:39.481847 | Preparing playbooks
2026-09-12 06:23:41.650895 | Running Ansible setup
2026-09-12 06:23:45.119392 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 06:23:45.729326 | 
2026-09-12 06:23:45.729443 | PLAY [localhost]
2026-09-12 06:23:45.736293 | 
2026-09-12 06:23:45.736358 | TASK [Gathering Facts]
2026-09-12 06:23:47.769243 | localhost | ok
2026-09-12 06:23:47.786841 | 
2026-09-12 06:23:47.788721 | TASK [Setup log path fact]
2026-09-12 06:23:47.811857 | localhost | ok
2026-09-12 06:23:47.831069 | 
2026-09-12 06:23:47.831210 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 06:23:47.859029 | localhost | ok
2026-09-12 06:23:47.868842 | 
2026-09-12 06:23:47.868919 | TASK [emit-job-header : Print job information]
2026-09-12 06:23:47.914919 | # Job Information
2026-09-12 06:23:47.915050 | Ansible Version: 2.16.18
2026-09-12 06:23:47.915079 | Job: atmosphere-molecule-csi-rbd
2026-09-12 06:23:47.915099 | Pipeline: check
2026-09-12 06:23:47.915118 | Executor: 2d72f0692154
2026-09-12 06:23:47.915135 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-09-12 06:23:47.915156 | Event ID: d09a8100-ae71-11f1-8ed5-e649570f9d0a
2026-09-12 06:23:47.917555 | 
2026-09-12 06:23:47.917615 | LOOP [emit-job-header : Print node information]
2026-09-12 06:23:48.011096 | localhost | ok:
2026-09-12 06:23:48.011410 | localhost | # Node Information
2026-09-12 06:23:48.011483 | localhost | Inventory Hostname: instance
2026-09-12 06:23:48.011515 | localhost | Hostname: np0000192714
2026-09-12 06:23:48.011537 | localhost | Username: zuul
2026-09-12 06:23:48.011582 | localhost | Distro: Ubuntu 22.04
2026-09-12 06:23:48.011604 | localhost | Provider: yul1
2026-09-12 06:23:48.011640 | localhost | Region: ca-ymq-1
2026-09-12 06:23:48.011665 | localhost | Label: ubuntu-jammy
2026-09-12 06:23:48.011684 | localhost | Product Name: OpenStack Nova
2026-09-12 06:23:48.011720 | localhost | Interface IP: 162.253.55.59
2026-09-12 06:23:48.026914 | 
2026-09-12 06:23:48.057009 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-12 06:23:48.576760 | localhost -> localhost | changed
2026-09-12 06:23:48.581813 | 
2026-09-12 06:23:48.581877 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-12 06:23:49.785284 | localhost -> localhost | changed
2026-09-12 06:23:49.793746 | 
2026-09-12 06:23:49.793822 | PLAY [all]
2026-09-12 06:23:49.812572 | 
2026-09-12 06:23:49.812991 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-12 06:23:50.077212 | instance -> localhost | ok
2026-09-12 06:23:50.082592 | 
2026-09-12 06:23:50.082659 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-12 06:23:50.109930 | instance | ok
2026-09-12 06:23:50.121795 | instance | included: /var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-12 06:23:50.127036 | 
2026-09-12 06:23:50.127107 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-12 06:23:51.105567 | instance -> localhost | Generating public/private rsa key pair.
2026-09-12 06:23:51.105943 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/work/f71ced5fab4b4c66a8b97a216443afcd_id_rsa
2026-09-12 06:23:51.105998 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/work/f71ced5fab4b4c66a8b97a216443afcd_id_rsa.pub
2026-09-12 06:23:51.106033 | instance -> localhost | The key fingerprint is:
2026-09-12 06:23:51.106064 | instance -> localhost | SHA256:ATlL8n7EnWfx7qGcErvbe0cxmdc8gNBrgawAnh5lm5Y zuul-build-sshkey
2026-09-12 06:23:51.106117 | instance -> localhost | The key's randomart image is:
2026-09-12 06:23:51.106149 | instance -> localhost | +---[RSA 3072]----+
2026-09-12 06:23:51.106189 | instance -> localhost | |   ..o....+ .    |
2026-09-12 06:23:51.106224 | instance -> localhost | |  . =.B. o +..   |
2026-09-12 06:23:51.106371 | instance -> localhost | |   + E.+o. .oo..+|
2026-09-12 06:23:51.106410 | instance -> localhost | |  . o o.o.ooo .*+|
2026-09-12 06:23:51.106441 | instance -> localhost | |   . . .S .o .  =|
2026-09-12 06:23:51.106487 | instance -> localhost | |      . . .   o .|
2026-09-12 06:23:51.106518 | instance -> localhost | |       .   + + o |
2026-09-12 06:23:51.106547 | instance -> localhost | |          o.+ o .|
2026-09-12 06:23:51.106580 | instance -> localhost | |          o+oo . |
2026-09-12 06:23:51.106609 | instance -> localhost | +----[SHA256]-----+
2026-09-12 06:23:51.106702 | instance -> localhost | ok: Runtime: 0:00:00.563538
2026-09-12 06:23:51.113578 | 
2026-09-12 06:23:51.113698 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-12 06:23:51.135254 | instance | ok
2026-09-12 06:23:51.143653 | instance | included: /var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-12 06:23:51.153319 | 
2026-09-12 06:23:51.153509 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-12 06:23:51.178399 | instance | skipping: Conditional result was False
2026-09-12 06:23:51.184727 | 
2026-09-12 06:23:51.184898 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-12 06:23:51.652145 | instance | changed
2026-09-12 06:23:51.660238 | 
2026-09-12 06:23:51.660346 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-12 06:23:51.845145 | instance | ok
2026-09-12 06:23:51.850914 | 
2026-09-12 06:23:51.850991 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-12 06:23:52.369510 | instance | changed
2026-09-12 06:23:52.381814 | 
2026-09-12 06:23:52.381971 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-12 06:23:52.868302 | instance | changed
2026-09-12 06:23:52.891762 | 
2026-09-12 06:23:52.891921 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-12 06:23:52.916265 | instance | skipping: Conditional result was False
2026-09-12 06:23:52.941201 | 
2026-09-12 06:23:52.941384 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-12 06:23:53.383000 | instance -> localhost | changed
2026-09-12 06:23:53.396389 | 
2026-09-12 06:23:53.661166 | TASK [add-build-sshkey : Add back temp key]
2026-09-12 06:23:54.033581 | instance -> localhost | Identity added: /var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/work/f71ced5fab4b4c66a8b97a216443afcd_id_rsa (zuul-build-sshkey)
2026-09-12 06:23:54.033765 | instance -> localhost | ok: Runtime: 0:00:00.013015
2026-09-12 06:23:54.038182 | 
2026-09-12 06:23:54.038246 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-12 06:23:54.345313 | instance | ok
2026-09-12 06:23:54.475061 | 
2026-09-12 06:23:54.475244 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-12 06:23:54.499435 | instance | skipping: Conditional result was False
2026-09-12 06:23:54.519027 | 
2026-09-12 06:23:54.519198 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-12 06:23:54.926944 | instance | ok
2026-09-12 06:23:54.932334 | 
2026-09-12 06:23:54.932412 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-12 06:23:58.737494 | instance | Output suppressed because no_log was given
2026-09-12 06:23:58.750046 | 
2026-09-12 06:23:58.750202 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-12 06:23:58.968405 | instance | ok: "logs"
2026-09-12 06:23:58.968819 | instance | ok: All items complete
2026-09-12 06:23:58.968875 | 
2026-09-12 06:23:59.134977 | instance | ok: "artifacts"
2026-09-12 06:23:59.294786 | instance | ok: "docs"
2026-09-12 06:23:59.303531 | 
2026-09-12 06:23:59.303678 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-12 06:23:59.508182 | instance | changed: "logs"
2026-09-12 06:23:59.678825 | instance | changed: "artifacts"
2026-09-12 06:23:59.853251 | instance | changed: "docs"
2026-09-12 06:23:59.872440 | 
2026-09-12 06:23:59.872581 | PLAY RECAP
2026-09-12 06:23:59.872639 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-12 06:23:59.872678 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 06:23:59.872707 | 
2026-09-12 06:23:59.999475 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 06:24:00.002070 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-12 06:24:00.585640 | 
2026-09-12 06:24:00.586292 | PLAY [all]
2026-09-12 06:24:00.597828 | 
2026-09-12 06:24:00.597941 | TASK [setup-uv : Extract archive]
2026-09-12 06:24:02.914964 | instance | changed
2026-09-12 06:24:02.983728 | 
2026-09-12 06:24:02.983931 | TASK [setup-uv : Print version]
2026-09-12 06:24:03.383942 | instance | uv 0.8.13
2026-09-12 06:24:03.525325 | instance | ok: Runtime: 0:00:00.013764
2026-09-12 06:24:03.540117 | 
2026-09-12 06:24:03.540315 | TASK [Install Ansible collection siblings]
2026-09-12 06:24:14.669592 | instance | changed:
2026-09-12 06:24:14.669917 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-12 06:24:14.669998 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-12 06:24:14.670077 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-12 06:24:14.670118 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-12 06:24:14.670188 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-12 06:24:14.670258 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-12 06:24:14.670326 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-12 06:24:14.670372 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-12 06:24:14.670435 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-12 06:24:14.670485 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-12 06:24:14.670511 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-12 06:24:14.670536 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-12 06:24:14.670558 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-12 06:24:14.670580 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-12 06:24:14.670602 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-12 06:24:14.670624 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-12 06:24:14.670647 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-12 06:24:14.670669 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-12 06:24:14.670690 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-12 06:24:14.670711 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-12 06:24:14.670733 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-12 06:24:14.670753 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-12 06:24:14.670777 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-12 06:24:14.670811 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-12 06:24:14.670834 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-12 06:24:14.670856 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-12 06:24:14.670877 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-12 06:24:14.670893 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-12 06:24:14.670909 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-12 06:24:14.670924 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-12 06:24:14.670939 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-12 06:24:14.670955 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-12 06:24:14.670971 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-12 06:24:14.670987 | 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-12 06:24:14.671010 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.671026 | instance | Process install dependency map
2026-09-12 06:24:14.671041 | instance | Starting collection install process
2026-09-12 06:24:14.671057 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-12 06:24:14.671072 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-12 06:24:14.671087 | instance | ansible.netcommon:* was installed successfully
2026-09-12 06:24:14.671102 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-12 06:24:14.671118 | instance | Creating virtual environment at: .venv
2026-09-12 06:24:14.671133 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-12 06:24:14.671149 | instance | Downloading setuptools (1.1MiB)
2026-09-12 06:24:14.671164 | instance | Downloading openstacksdk (1.7MiB)
2026-09-12 06:24:14.671185 | instance | Downloading ansible-core (2.1MiB)
2026-09-12 06:24:14.671202 | instance | Downloading rjsonnet (1.2MiB)
2026-09-12 06:24:14.671217 | instance | Downloading cryptography (4.2MiB)
2026-09-12 06:24:14.671233 | instance | Downloading pydantic-core (2.0MiB)
2026-09-12 06:24:14.671248 | instance | Downloading netaddr (2.2MiB)
2026-09-12 06:24:14.671264 | instance | Downloading pygments (1.2MiB)
2026-09-12 06:24:14.671279 | instance | Downloading kubernetes (1.9MiB)
2026-09-12 06:24:14.671295 | instance |  Downloading rjsonnet
2026-09-12 06:24:14.671312 | instance |    Building pyperclip==1.9.0
2026-09-12 06:24:14.671328 | instance |  Downloading pydantic-core
2026-09-12 06:24:14.671343 | instance |  Downloading netaddr
2026-09-12 06:24:14.671359 | instance |  Downloading pygments
2026-09-12 06:24:14.671375 | instance |  Downloading cryptography
2026-09-12 06:24:14.671390 | instance |  Downloading setuptools
2026-09-12 06:24:14.671406 | instance |  Downloading kubernetes
2026-09-12 06:24:14.671421 | instance |  Downloading ansible-core
2026-09-12 06:24:14.671437 | instance |  Downloading openstacksdk
2026-09-12 06:24:14.671476 | instance |       Built pyperclip==1.9.0
2026-09-12 06:24:14.671501 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-12 06:24:14.671523 | instance | Installed 83 packages in 49ms
2026-09-12 06:24:14.671544 | instance | Checked out ansible.posix to 1.6.0
2026-09-12 06:24:14.671565 | 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-12 06:24:14.671586 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.671606 | instance | Process install dependency map
2026-09-12 06:24:14.671632 | instance | Starting collection install process
2026-09-12 06:24:14.671653 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-12 06:24:14.671674 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-12 06:24:14.671695 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-12 06:24:14.671716 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.671737 | instance | 2.17.12
2026-09-12 06:24:14.671758 | instance | Checked out ansible.utils to v6.1.0
2026-09-12 06:24:14.671779 | 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-12 06:24:14.671800 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.671821 | instance | Process install dependency map
2026-09-12 06:24:14.671842 | instance | Starting collection install process
2026-09-12 06:24:14.671863 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-12 06:24:14.671882 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-12 06:24:14.671899 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-12 06:24:14.671915 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.671931 | instance | 2.17.12
2026-09-12 06:24:14.671947 | instance | Checked out atmosphere.common to v0.7.0
2026-09-12 06:24:14.671962 | 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-12 06:24:14.671978 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.671993 | instance | Process install dependency map
2026-09-12 06:24:14.672009 | instance | Starting collection install process
2026-09-12 06:24:14.672024 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-12 06:24:14.672040 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-12 06:24:14.672056 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-12 06:24:14.672071 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.672087 | instance | 2.17.12
2026-09-12 06:24:14.672102 | instance | Checked out community.crypto to 2.2.3
2026-09-12 06:24:14.672118 | 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-12 06:24:14.672133 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.672148 | instance | Process install dependency map
2026-09-12 06:24:14.672164 | instance | Starting collection install process
2026-09-12 06:24:14.672179 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-12 06:24:14.672195 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-12 06:24:14.672210 | instance | community.crypto:2.2.3 was installed successfully
2026-09-12 06:24:14.672225 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.672240 | instance | 2.17.12
2026-09-12 06:24:14.672255 | instance | Checked out community.general to 7.3.0
2026-09-12 06:24:14.672270 | 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-12 06:24:14.672292 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.672309 | instance | Process install dependency map
2026-09-12 06:24:14.672324 | instance | Starting collection install process
2026-09-12 06:24:14.672339 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-12 06:24:14.672354 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-12 06:24:14.672369 | instance | community.general:7.3.0 was installed successfully
2026-09-12 06:24:14.672385 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.672400 | instance | 2.17.12
2026-09-12 06:24:14.672416 | instance | Checked out community.mysql to 3.6.0
2026-09-12 06:24:14.672434 | 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-12 06:24:14.672466 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.672490 | instance | Process install dependency map
2026-09-12 06:24:14.672529 | instance | Starting collection install process
2026-09-12 06:24:14.672551 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-12 06:24:14.672573 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-12 06:24:14.672594 | instance | community.mysql:3.6.0 was installed successfully
2026-09-12 06:24:14.672615 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.672637 | instance | 2.17.12
2026-09-12 06:24:14.672658 | instance | Checked out kubernetes.core to 2.4.0
2026-09-12 06:24:14.672679 | 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-12 06:24:14.672700 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.672721 | instance | Process install dependency map
2026-09-12 06:24:14.672742 | instance | Starting collection install process
2026-09-12 06:24:14.672763 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-12 06:24:14.672784 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-12 06:24:14.672805 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-12 06:24:14.672822 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.672838 | instance | 2.17.12
2026-09-12 06:24:14.672853 | instance | Checked out openstack.cloud to 2.6.0
2026-09-12 06:24:14.672869 | 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-12 06:24:14.672885 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.672900 | instance | Process install dependency map
2026-09-12 06:24:14.672915 | instance | Starting collection install process
2026-09-12 06:24:14.672930 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-12 06:24:14.672945 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-12 06:24:14.672960 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-12 06:24:14.672975 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.672989 | instance | 2.17.12
2026-09-12 06:24:14.673064 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-12 06:24:14.673103 | 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-12 06:24:14.673123 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.673142 | instance | Process install dependency map
2026-09-12 06:24:14.673158 | instance | Starting collection install process
2026-09-12 06:24:14.673173 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-12 06:24:14.673189 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-12 06:24:14.673204 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-12 06:24:14.673220 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.673235 | instance | 2.17.12
2026-09-12 06:24:14.673251 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-12 06:24:14.673267 | 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-12 06:24:14.673282 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.673298 | instance | Process install dependency map
2026-09-12 06:24:14.673313 | instance | Starting collection install process
2026-09-12 06:24:14.673329 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-12 06:24:14.673344 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-12 06:24:14.673359 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-12 06:24:14.673375 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.673391 | instance | 2.17.12
2026-09-12 06:24:14.673407 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-12 06:24:14.673422 | 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-12 06:24:14.673438 | instance | Starting galaxy collection install process
2026-09-12 06:24:14.673474 | instance | Process install dependency map
2026-09-12 06:24:14.673497 | instance | Starting collection install process
2026-09-12 06:24:14.673518 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-12 06:24:14.673539 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-12 06:24:14.673559 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-12 06:24:14.673579 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-12 06:24:14.673600 | instance | 2.17.12
2026-09-12 06:24:14.679199 | 
2026-09-12 06:24:14.679258 | PLAY RECAP
2026-09-12 06:24:14.679313 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 06:24:14.679341 | 
2026-09-12 06:24:14.854851 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-12 06:24:14.858975 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-12 06:24:15.497036 | 
2026-09-12 06:24:15.497218 | PLAY [all]
2026-09-12 06:24:15.509655 | 
2026-09-12 06:24:15.509814 | TASK [Install "jq" for log collection]
2026-09-12 06:24:25.508708 | instance | changed
2026-09-12 06:24:25.510308 | 
2026-09-12 06:24:25.510361 | PLAY RECAP
2026-09-12 06:24:25.510405 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 06:24:25.510487 | 
2026-09-12 06:24:25.608656 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-12 06:24:25.611029 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-12 06:24:26.261545 | 
2026-09-12 06:24:26.262014 | PLAY [all]
2026-09-12 06:24:26.275443 | 
2026-09-12 06:24:26.275593 | LOOP [Check inventory directories]
2026-09-12 06:24:26.620255 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-12 06:24:26.791987 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-12 06:24:26.973627 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-12 06:24:26.995308 | 
2026-09-12 06:24:26.995581 | LOOP [Copy inventory file for Zuul]
2026-09-12 06:24:27.887273 | instance | changed:
2026-09-12 06:24:27.887575 | instance | {
2026-09-12 06:24:27.887633 | instance |   "ansible_loop_var": "item",
2026-09-12 06:24:27.887679 | instance |   "changed": false,
2026-09-12 06:24:27.887719 | instance |   "failed": false,
2026-09-12 06:24:27.887764 | instance |   "invocation": {
2026-09-12 06:24:27.887801 | instance |     "module_args": {
2026-09-12 06:24:27.887838 | instance |       "checksum_algorithm": "sha1",
2026-09-12 06:24:27.887874 | instance |       "follow": true,
2026-09-12 06:24:27.887910 | instance |       "get_attributes": true,
2026-09-12 06:24:27.887948 | instance |       "get_checksum": true,
2026-09-12 06:24:27.887984 | instance |       "get_mime": true,
2026-09-12 06:24:27.888018 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-12 06:24:27.888054 | instance |     }
2026-09-12 06:24:27.888087 | instance |   },
2026-09-12 06:24:27.888121 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-12 06:24:27.888154 | instance |   "stat": {
2026-09-12 06:24:27.888188 | instance |     "atime": 1789194247.2136343,
2026-09-12 06:24:27.888223 | instance |     "attr_flags": "e",
2026-09-12 06:24:27.888257 | instance |     "attributes": [
2026-09-12 06:24:27.888291 | instance |       "extents"
2026-09-12 06:24:27.888324 | instance |     ],
2026-09-12 06:24:27.888358 | instance |     "block_size": 4096,
2026-09-12 06:24:27.888392 | instance |     "blocks": 8,
2026-09-12 06:24:27.888425 | instance |     "charset": "unknown",
2026-09-12 06:24:27.888484 | instance |     "ctime": 1789194244.3015814,
2026-09-12 06:24:27.888571 | instance |     "dev": 64513,
2026-09-12 06:24:27.888608 | instance |     "device_type": 0,
2026-09-12 06:24:27.888643 | instance |     "executable": true,
2026-09-12 06:24:27.888677 | instance |     "exists": true,
2026-09-12 06:24:27.888711 | instance |     "gid": 1000,
2026-09-12 06:24:27.888745 | instance |     "gr_name": "zuul",
2026-09-12 06:24:27.888778 | instance |     "inode": 1048623,
2026-09-12 06:24:27.888812 | instance |     "isblk": false,
2026-09-12 06:24:27.888847 | instance |     "ischr": false,
2026-09-12 06:24:27.888880 | instance |     "isdir": true,
2026-09-12 06:24:27.888913 | instance |     "isfifo": false,
2026-09-12 06:24:27.888946 | instance |     "isgid": false,
2026-09-12 06:24:27.888979 | instance |     "islnk": false,
2026-09-12 06:24:27.889012 | instance |     "isreg": false,
2026-09-12 06:24:27.889045 | instance |     "issock": false,
2026-09-12 06:24:27.889078 | instance |     "isuid": false,
2026-09-12 06:24:27.889111 | instance |     "mimetype": "unknown",
2026-09-12 06:24:27.889144 | instance |     "mode": "0755",
2026-09-12 06:24:27.889177 | instance |     "mtime": 1789194244.3015814,
2026-09-12 06:24:27.889210 | instance |     "nlink": 23,
2026-09-12 06:24:27.889243 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-12 06:24:27.889276 | instance |     "pw_name": "zuul",
2026-09-12 06:24:27.889310 | instance |     "readable": true,
2026-09-12 06:24:27.889344 | instance |     "rgrp": true,
2026-09-12 06:24:27.889379 | instance |     "roth": true,
2026-09-12 06:24:27.889427 | instance |     "rusr": true,
2026-09-12 06:24:27.889490 | instance |     "size": 4096,
2026-09-12 06:24:27.889526 | instance |     "uid": 1000,
2026-09-12 06:24:27.889560 | instance |     "version": "2626125985",
2026-09-12 06:24:27.889595 | instance |     "wgrp": false,
2026-09-12 06:24:27.889632 | instance |     "woth": false,
2026-09-12 06:24:27.889667 | instance |     "writeable": true,
2026-09-12 06:24:27.889701 | instance |     "wusr": true,
2026-09-12 06:24:27.889735 | instance |     "xgrp": true,
2026-09-12 06:24:27.889768 | instance |     "xoth": true,
2026-09-12 06:24:27.889801 | instance |     "xusr": true
2026-09-12 06:24:27.889835 | instance |   }
2026-09-12 06:24:27.889869 | instance | }
2026-09-12 06:24:27.889938 | instance | ok: All items complete
2026-09-12 06:24:27.889980 | 
2026-09-12 06:24:27.892310 | instance | skipping: Conditional result was False
2026-09-12 06:24:27.893748 | instance | skipping: Conditional result was False
2026-09-12 06:24:27.910260 | 
2026-09-12 06:24:27.910513 | LOOP [Switch "ansible_host" to private IP]
2026-09-12 06:24:28.274508 | instance | changed: 1 replacements made
2026-09-12 06:24:28.278477 | instance | skipping: Conditional result was False
2026-09-12 06:24:28.286841 | instance | skipping: Conditional result was False
2026-09-12 06:24:28.302752 | 
2026-09-12 06:24:28.302909 | TASK [Run Molecule scenario]
2026-09-12 06:24:29.287471 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-12 06:24:29.724490 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-12 06:24:29.724598 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-12 06:24:41.364840 | instance | INFO     [csi > dependency] Executing
2026-09-12 06:24:41.364957 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-12 06:24:41.365163 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-12 06:24:41.365332 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-12 06:24:41.372671 | instance | INFO     [csi > cleanup] Executing
2026-09-12 06:24:41.372930 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-12 06:24:41.380218 | instance | INFO     [csi > destroy] Executing
2026-09-12 06:24:41.380298 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-12 06:24:41.380313 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-12 06:24:41.387027 | instance | INFO     [csi > syntax] Executing
2026-09-12 06:24:43.041978 | instance |
2026-09-12 06:24:43.042062 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-12 06:24:43.120405 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-12 06:24:43.139947 | instance | INFO     [csi > create] Executing
2026-09-12 06:24:43.143781 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-12 06:24:43.158976 | instance | INFO     [csi > prepare] Executing
2026-09-12 06:24:43.977292 | instance |
2026-09-12 06:24:43.977566 | instance | PLAY [Prepare] *****************************************************************
2026-09-12 06:24:43.977795 | instance |
2026-09-12 06:24:43.978034 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 06:24:43.978277 | instance | Saturday 12 September 2026  06:24:43 +0000 (0:00:00.029)       0:00:00.029 ****
2026-09-12 06:24:45.287754 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-12 06:24:45.287959 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-12 06:24:45.288209 | instance | interpreter could change the meaning of that path. See
2026-09-12 06:24:45.288471 | instance | https://docs.ansible.com/ansible-
2026-09-12 06:24:45.288713 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-12 06:24:45.295915 | instance | ok: [instance]
2026-09-12 06:24:45.296246 | instance |
2026-09-12 06:24:45.296434 | instance | TASK [Configure short hostname] ************************************************
2026-09-12 06:24:45.296665 | instance | Saturday 12 September 2026  06:24:45 +0000 (0:00:01.319)       0:00:01.349 ****
2026-09-12 06:24:46.091731 | instance | changed: [instance]
2026-09-12 06:24:46.091935 | instance |
2026-09-12 06:24:46.092177 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-12 06:24:46.092455 | instance | Saturday 12 September 2026  06:24:46 +0000 (0:00:00.795)       0:00:02.144 ****
2026-09-12 06:24:46.384698 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-12 06:24:46.384792 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-12 06:24:46.384901 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-12 06:24:46.395723 | instance | changed: [instance]
2026-09-12 06:24:46.395958 | instance |
2026-09-12 06:24:46.396078 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-12 06:24:46.396234 | instance | Saturday 12 September 2026  06:24:46 +0000 (0:00:00.303)       0:00:02.447 ****
2026-09-12 06:24:47.268864 | instance | ok: [instance]
2026-09-12 06:24:47.268943 | instance |
2026-09-12 06:24:47.269064 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-12 06:24:47.269250 | instance |
2026-09-12 06:24:47.269330 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 06:24:47.269464 | instance | Saturday 12 September 2026  06:24:47 +0000 (0:00:00.873)       0:00:03.321 ****
2026-09-12 06:24:48.010679 | instance | ok: [instance]
2026-09-12 06:24:48.011648 | instance |
2026-09-12 06:24:48.012148 | instance | TASK [Install depedencies] *****************************************************
2026-09-12 06:24:48.012272 | instance | Saturday 12 September 2026  06:24:48 +0000 (0:00:00.741)       0:00:04.063 ****
2026-09-12 06:25:11.164701 | instance | changed: [instance]
2026-09-12 06:25:11.164945 | instance |
2026-09-12 06:25:11.165216 | instance | TASK [Start up service] ********************************************************
2026-09-12 06:25:11.165533 | instance | Saturday 12 September 2026  06:25:11 +0000 (0:00:23.153)       0:00:27.217 ****
2026-09-12 06:25:11.773401 | instance | ok: [instance]
2026-09-12 06:25:11.773636 | instance |
2026-09-12 06:25:11.773935 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-12 06:25:11.774213 | instance | Saturday 12 September 2026  06:25:11 +0000 (0:00:00.608)       0:00:27.825 ****
2026-09-12 06:25:12.131741 | instance | ok: [instance]
2026-09-12 06:25:12.131892 | instance |
2026-09-12 06:25:12.132069 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-12 06:25:12.132244 | instance | Saturday 12 September 2026  06:25:12 +0000 (0:00:00.358)       0:00:28.184 ****
2026-09-12 06:25:12.892763 | instance | changed: [instance]
2026-09-12 06:25:12.892818 | instance |
2026-09-12 06:25:12.892826 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-12 06:25:12.892833 | instance | Saturday 12 September 2026  06:25:12 +0000 (0:00:00.760)       0:00:28.944 ****
2026-09-12 06:25:13.119465 | instance | ok: [instance]
2026-09-12 06:25:13.120231 | instance |
2026-09-12 06:25:13.120303 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-12 06:25:13.120316 | instance | Saturday 12 September 2026  06:25:13 +0000 (0:00:00.226)       0:00:29.171 ****
2026-09-12 06:25:13.143203 | instance | skipping: [instance]
2026-09-12 06:25:13.144423 | instance |
2026-09-12 06:25:13.144492 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-12 06:25:13.144504 | instance | Saturday 12 September 2026  06:25:13 +0000 (0:00:00.023)       0:00:29.195 ****
2026-09-12 06:25:13.734823 | instance | changed: [instance] => (item=osd0)
2026-09-12 06:25:13.735087 | instance | changed: [instance] => (item=osd1)
2026-09-12 06:25:13.735760 | instance | changed: [instance] => (item=osd2)
2026-09-12 06:25:13.736140 | instance |
2026-09-12 06:25:13.736532 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-12 06:25:13.736917 | instance | Saturday 12 September 2026  06:25:13 +0000 (0:00:00.591)       0:00:29.787 ****
2026-09-12 06:25:14.475528 | instance | changed: [instance] => (item=osd0)
2026-09-12 06:25:14.475810 | instance | changed: [instance] => (item=osd1)
2026-09-12 06:25:14.476191 | instance | changed: [instance] => (item=osd2)
2026-09-12 06:25:14.476526 | instance |
2026-09-12 06:25:14.476847 | instance | TASK [Start loop devices] ******************************************************
2026-09-12 06:25:14.477167 | instance | Saturday 12 September 2026  06:25:14 +0000 (0:00:00.740)       0:00:30.527 ****
2026-09-12 06:25:15.216122 | instance | changed: [instance] => (item=osd0)
2026-09-12 06:25:15.216543 | instance | changed: [instance] => (item=osd1)
2026-09-12 06:25:15.216870 | instance | changed: [instance] => (item=osd2)
2026-09-12 06:25:15.217147 | instance |
2026-09-12 06:25:15.217471 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-12 06:25:15.217717 | instance | Saturday 12 September 2026  06:25:15 +0000 (0:00:00.741)       0:00:31.268 ****
2026-09-12 06:25:18.616867 | instance | changed: [instance] => (item=osd0)
2026-09-12 06:25:18.616940 | instance | changed: [instance] => (item=osd1)
2026-09-12 06:25:18.616952 | instance | changed: [instance] => (item=osd2)
2026-09-12 06:25:18.616962 | instance |
2026-09-12 06:25:18.616972 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-12 06:25:18.616981 | instance | Saturday 12 September 2026  06:25:18 +0000 (0:00:03.398)       0:00:34.667 ****
2026-09-12 06:25:20.571517 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-12 06:25:20.571610 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-12 06:25:20.571650 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-12 06:25:20.571663 | instance |
2026-09-12 06:25:20.571676 | instance | PLAY RECAP *********************************************************************
2026-09-12 06:25:20.571690 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-12 06:25:20.571703 | instance |
2026-09-12 06:25:20.571716 | instance | Saturday 12 September 2026  06:25:20 +0000 (0:00:01.953)       0:00:36.621 ****
2026-09-12 06:25:20.571730 | instance | ===============================================================================
2026-09-12 06:25:20.571742 | instance | Install depedencies ---------------------------------------------------- 23.15s
2026-09-12 06:25:20.571752 | instance | Create a volume group for each loop device ------------------------------ 3.40s
2026-09-12 06:25:20.571761 | instance | Create a logical volume for each loop device ---------------------------- 1.95s
2026-09-12 06:25:20.571770 | instance | Gathering Facts --------------------------------------------------------- 1.32s
2026-09-12 06:25:20.571779 | instance | Purge "snapd" package --------------------------------------------------- 0.87s
2026-09-12 06:25:20.571796 | instance | Configure short hostname ------------------------------------------------ 0.80s
2026-09-12 06:25:20.572078 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.76s
2026-09-12 06:25:20.572684 | instance | Gathering Facts --------------------------------------------------------- 0.74s
2026-09-12 06:25:20.573042 | instance | Start loop devices ------------------------------------------------------ 0.74s
2026-09-12 06:25:20.573355 | instance | Set permissions on loopback devices ------------------------------------- 0.74s
2026-09-12 06:25:20.573738 | instance | Start up service -------------------------------------------------------- 0.61s
2026-09-12 06:25:20.574119 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-09-12 06:25:20.574540 | instance | Generate lvm.conf ------------------------------------------------------- 0.36s
2026-09-12 06:25:20.574937 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-09-12 06:25:20.575266 | instance | Get list of all loopback devices ---------------------------------------- 0.23s
2026-09-12 06:25:20.575881 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-12 06:25:20.700312 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-12 06:25:20.717206 | instance | INFO     [csi > converge] Executing
2026-09-12 06:25:22.309599 | instance |
2026-09-12 06:25:22.309993 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-12 06:25:22.310284 | instance |
2026-09-12 06:25:22.310607 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 06:25:22.310895 | instance | Saturday 12 September 2026  06:25:22 +0000 (0:00:00.009)       0:00:00.009 ****
2026-09-12 06:25:23.316679 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-12 06:25:23.317061 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-12 06:25:23.317443 | instance | interpreter could change the meaning of that path. See
2026-09-12 06:25:23.317742 | instance | https://docs.ansible.com/ansible-
2026-09-12 06:25:23.318033 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-12 06:25:23.332282 | instance | ok: [instance]
2026-09-12 06:25:23.332609 | instance |
2026-09-12 06:25:23.332909 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-12 06:25:23.333214 | instance | Saturday 12 September 2026  06:25:23 +0000 (0:00:01.022)       0:00:01.031 ****
2026-09-12 06:25:23.388964 | instance | ok: [instance] => {
2026-09-12 06:25:23.389365 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-12 06:25:23.389709 | instance | }
2026-09-12 06:25:23.390006 | instance |
2026-09-12 06:25:23.390314 | instance | PLAY [all] *********************************************************************
2026-09-12 06:25:23.390681 | instance |
2026-09-12 06:25:23.391002 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 06:25:23.391453 | instance | Saturday 12 September 2026  06:25:23 +0000 (0:00:00.056)       0:00:01.088 ****
2026-09-12 06:25:24.370989 | instance | ok: [instance]
2026-09-12 06:25:24.371417 | instance |
2026-09-12 06:25:24.371739 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-12 06:25:24.372042 | instance | Saturday 12 September 2026  06:25:24 +0000 (0:00:00.981)       0:00:02.070 ****
2026-09-12 06:25:24.424055 | instance | skipping: [instance]
2026-09-12 06:25:24.424452 | instance |
2026-09-12 06:25:24.424774 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-12 06:25:24.425079 | instance | Saturday 12 September 2026  06:25:24 +0000 (0:00:00.052)       0:00:02.123 ****
2026-09-12 06:25:24.661449 | instance | ok: [instance]
2026-09-12 06:25:24.661861 | instance |
2026-09-12 06:25:24.662244 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-12 06:25:24.662637 | instance |
2026-09-12 06:25:24.662983 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 06:25:24.663356 | instance | Saturday 12 September 2026  06:25:24 +0000 (0:00:00.238)       0:00:02.361 ****
2026-09-12 06:25:25.674449 | instance | ok: [instance]
2026-09-12 06:25:25.674821 | instance |
2026-09-12 06:25:25.675140 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-12 06:25:25.675513 | instance | Saturday 12 September 2026  06:25:25 +0000 (0:00:01.012)       0:00:03.373 ****
2026-09-12 06:25:26.002853 | instance | ok: [instance]
2026-09-12 06:25:26.003217 | instance |
2026-09-12 06:25:26.003604 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-12 06:25:26.003913 | instance | Saturday 12 September 2026  06:25:25 +0000 (0:00:00.328)       0:00:03.702 ****
2026-09-12 06:25:26.056513 | instance | skipping: [instance]
2026-09-12 06:25:26.056898 | instance |
2026-09-12 06:25:26.057317 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-12 06:25:26.057747 | instance | Saturday 12 September 2026  06:25:26 +0000 (0:00:00.053)       0:00:03.756 ****
2026-09-12 06:25:26.447199 | instance | changed: [instance]
2026-09-12 06:25:26.447668 | instance |
2026-09-12 06:25:26.448004 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-12 06:25:26.448335 | instance | Saturday 12 September 2026  06:25:26 +0000 (0:00:00.390)       0:00:04.146 ****
2026-09-12 06:25:26.526106 | instance | ok: [instance] => {
2026-09-12 06:25:26.526328 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-12 06:25:26.526497 | instance | }
2026-09-12 06:25:26.526631 | instance |
2026-09-12 06:25:26.526789 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-12 06:25:26.526929 | instance | Saturday 12 September 2026  06:25:26 +0000 (0:00:00.079)       0:00:04.225 ****
2026-09-12 06:25:27.282788 | instance | changed: [instance]
2026-09-12 06:25:27.283151 | instance |
2026-09-12 06:25:27.283535 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-12 06:25:27.283876 | instance | Saturday 12 September 2026  06:25:27 +0000 (0:00:00.756)       0:00:04.982 ****
2026-09-12 06:25:27.331130 | instance | skipping: [instance]
2026-09-12 06:25:27.331536 | instance |
2026-09-12 06:25:27.331841 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-12 06:25:27.332132 | instance | Saturday 12 September 2026  06:25:27 +0000 (0:00:00.048)       0:00:05.031 ****
2026-09-12 06:25:27.382069 | instance | skipping: [instance]
2026-09-12 06:25:27.382493 | instance |
2026-09-12 06:25:27.382990 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-12 06:25:27.383249 | instance | Saturday 12 September 2026  06:25:27 +0000 (0:00:00.050)       0:00:05.081 ****
2026-09-12 06:25:27.634837 | instance | ok: [instance]
2026-09-12 06:25:27.635107 | instance |
2026-09-12 06:25:27.635348 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-12 06:25:27.635589 | instance | Saturday 12 September 2026  06:25:27 +0000 (0:00:00.252)       0:00:05.334 ****
2026-09-12 06:25:29.265447 | instance | ok: [instance]
2026-09-12 06:25:29.265857 | instance |
2026-09-12 06:25:29.266179 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-12 06:25:29.266583 | instance | Saturday 12 September 2026  06:25:29 +0000 (0:00:01.630)       0:00:06.964 ****
2026-09-12 06:25:29.344036 | instance | ok: [instance] => {
2026-09-12 06:25:29.344330 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-12 06:25:29.344546 | instance | }
2026-09-12 06:25:29.344729 | instance |
2026-09-12 06:25:29.344935 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-12 06:25:29.345140 | instance | Saturday 12 September 2026  06:25:29 +0000 (0:00:00.078)       0:00:07.043 ****
2026-09-12 06:25:30.111533 | instance | changed: [instance]
2026-09-12 06:25:30.111839 | instance |
2026-09-12 06:25:30.111989 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-12 06:25:30.112134 | instance | Saturday 12 September 2026  06:25:30 +0000 (0:00:00.768)       0:00:07.811 ****
2026-09-12 06:25:33.019469 | instance | changed: [instance]
2026-09-12 06:25:33.019549 | instance |
2026-09-12 06:25:33.019569 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-12 06:25:33.019582 | instance | Saturday 12 September 2026  06:25:33 +0000 (0:00:02.907)       0:00:10.718 ****
2026-09-12 06:25:33.055870 | instance | skipping: [instance]
2026-09-12 06:25:33.056559 | instance |
2026-09-12 06:25:33.056613 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-12 06:25:33.056619 | instance | Saturday 12 September 2026  06:25:33 +0000 (0:00:00.036)       0:00:10.755 ****
2026-09-12 06:25:33.085952 | instance | skipping: [instance]
2026-09-12 06:25:33.086023 | instance |
2026-09-12 06:25:33.086342 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-12 06:25:33.086362 | instance | Saturday 12 September 2026  06:25:33 +0000 (0:00:00.030)       0:00:10.785 ****
2026-09-12 06:25:33.129816 | instance | skipping: [instance]
2026-09-12 06:25:33.129906 | instance |
2026-09-12 06:25:33.130252 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-12 06:25:33.130317 | instance | Saturday 12 September 2026  06:25:33 +0000 (0:00:00.043)       0:00:10.829 ****
2026-09-12 06:25:39.857738 | instance | changed: [instance]
2026-09-12 06:25:39.857989 | instance |
2026-09-12 06:25:39.858307 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-12 06:25:39.858351 | instance | Saturday 12 September 2026  06:25:39 +0000 (0:00:06.728)       0:00:17.557 ****
2026-09-12 06:25:40.582209 | instance | changed: [instance]
2026-09-12 06:25:40.582370 | instance |
2026-09-12 06:25:40.582956 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-12 06:25:40.583024 | instance | Saturday 12 September 2026  06:25:40 +0000 (0:00:00.724)       0:00:18.281 ****
2026-09-12 06:25:41.578015 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-12 06:25:41.579483 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-12 06:25:41.579586 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-12 06:25:41.579616 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-12 06:25:41.579626 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-12 06:25:41.579636 | instance |
2026-09-12 06:25:41.579644 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-12 06:25:41.579653 | instance | Saturday 12 September 2026  06:25:41 +0000 (0:00:00.996)       0:00:19.277 ****
2026-09-12 06:25:42.273073 | instance | changed: [instance]
2026-09-12 06:25:42.273184 | instance |
2026-09-12 06:25:42.274080 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-12 06:25:42.274149 | instance | Saturday 12 September 2026  06:25:42 +0000 (0:00:00.669)       0:00:19.947 ****
2026-09-12 06:25:42.274157 | instance |
2026-09-12 06:25:42.274164 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-12 06:25:42.274170 | instance | Saturday 12 September 2026  06:25:42 +0000 (0:00:00.025)       0:00:19.972 ****
2026-09-12 06:25:43.269958 | instance | ok: [instance]
2026-09-12 06:25:43.270133 | instance |
2026-09-12 06:25:43.270661 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-12 06:25:43.270747 | instance | Saturday 12 September 2026  06:25:43 +0000 (0:00:00.996)       0:00:20.969 ****
2026-09-12 06:25:43.766502 | instance | changed: [instance]
2026-09-12 06:25:43.766590 | instance |
2026-09-12 06:25:43.766800 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-12 06:25:43.766818 | instance | Saturday 12 September 2026  06:25:43 +0000 (0:00:00.497)       0:00:21.466 ****
2026-09-12 06:25:44.374534 | instance | changed: [instance]
2026-09-12 06:25:44.374643 | instance |
2026-09-12 06:25:44.375044 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-12 06:25:44.375110 | instance | Saturday 12 September 2026  06:25:44 +0000 (0:00:00.607)       0:00:22.074 ****
2026-09-12 06:25:44.620087 | instance | ok: [instance]
2026-09-12 06:25:44.620194 | instance |
2026-09-12 06:25:44.620594 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-12 06:25:44.620667 | instance | Saturday 12 September 2026  06:25:44 +0000 (0:00:00.245)       0:00:22.319 ****
2026-09-12 06:25:44.697998 | instance | ok: [instance] => {
2026-09-12 06:25:44.698979 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-12 06:25:44.699074 | instance | }
2026-09-12 06:25:44.699130 | instance |
2026-09-12 06:25:44.699157 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-12 06:25:44.699556 | instance | Saturday 12 September 2026  06:25:44 +0000 (0:00:00.077)       0:00:22.397 ****
2026-09-12 06:25:45.678002 | instance | changed: [instance]
2026-09-12 06:25:45.678102 | instance |
2026-09-12 06:25:45.678657 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-12 06:25:45.678728 | instance | Saturday 12 September 2026  06:25:45 +0000 (0:00:00.980)       0:00:23.377 ****
2026-09-12 06:25:50.135535 | instance | changed: [instance]
2026-09-12 06:25:50.135660 | instance |
2026-09-12 06:25:50.135939 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-12 06:25:50.136000 | instance | Saturday 12 September 2026  06:25:50 +0000 (0:00:04.457)       0:00:27.835 ****
2026-09-12 06:25:51.532858 | instance | ok: [instance]
2026-09-12 06:25:51.532992 | instance |
2026-09-12 06:25:51.533573 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-12 06:25:51.533657 | instance | Saturday 12 September 2026  06:25:51 +0000 (0:00:01.396)       0:00:29.232 ****
2026-09-12 06:25:51.913031 | instance | changed: [instance]
2026-09-12 06:25:51.913106 | instance |
2026-09-12 06:25:51.913391 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-12 06:25:51.913431 | instance | Saturday 12 September 2026  06:25:51 +0000 (0:00:00.381)       0:00:29.613 ****
2026-09-12 06:25:52.438667 | instance | changed: [instance]
2026-09-12 06:25:52.438751 | instance |
2026-09-12 06:25:52.439099 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-12 06:25:52.439163 | instance | Saturday 12 September 2026  06:25:52 +0000 (0:00:00.525)       0:00:30.138 ****
2026-09-12 06:25:53.066288 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-12 06:25:53.067473 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-12 06:25:53.067831 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-12 06:25:53.067850 | instance |
2026-09-12 06:25:53.067870 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-12 06:25:53.068048 | instance | Saturday 12 September 2026  06:25:53 +0000 (0:00:00.626)       0:00:30.765 ****
2026-09-12 06:25:53.568757 | instance | changed: [instance]
2026-09-12 06:25:53.568850 | instance |
2026-09-12 06:25:53.569065 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-12 06:25:53.569133 | instance | Saturday 12 September 2026  06:25:53 +0000 (0:00:00.503)       0:00:31.268 ****
2026-09-12 06:25:54.106452 | instance | changed: [instance]
2026-09-12 06:25:54.106547 | instance |
2026-09-12 06:25:54.107368 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-12 06:25:54.107444 | instance | Saturday 12 September 2026  06:25:54 +0000 (0:00:00.513)       0:00:31.782 ****
2026-09-12 06:25:54.107454 | instance |
2026-09-12 06:25:54.107461 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-12 06:25:54.107473 | instance | Saturday 12 September 2026  06:25:54 +0000 (0:00:00.024)       0:00:31.806 ****
2026-09-12 06:25:54.871720 | instance | ok: [instance]
2026-09-12 06:25:54.871813 | instance |
2026-09-12 06:25:54.872032 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-12 06:25:54.872108 | instance | Saturday 12 September 2026  06:25:54 +0000 (0:00:00.765)       0:00:32.571 ****
2026-09-12 06:25:55.834366 | instance | changed: [instance]
2026-09-12 06:25:55.834444 | instance |
2026-09-12 06:25:55.834724 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-12 06:25:55.834743 | instance | Saturday 12 September 2026  06:25:55 +0000 (0:00:00.962)       0:00:33.534 ****
2026-09-12 06:25:56.424164 | instance | changed: [instance]
2026-09-12 06:25:56.424212 | instance |
2026-09-12 06:25:56.424220 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-12 06:25:56.424227 | instance | Saturday 12 September 2026  06:25:56 +0000 (0:00:00.589)       0:00:34.123 ****
2026-09-12 06:25:56.479264 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-12 06:25:56.479381 | instance |
2026-09-12 06:25:56.479622 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-12 06:25:56.479683 | instance | Saturday 12 September 2026  06:25:56 +0000 (0:00:00.055)       0:00:34.179 ****
2026-09-12 06:26:02.146473 | instance | changed: [instance]
2026-09-12 06:26:02.147267 | instance |
2026-09-12 06:26:02.147368 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-12 06:26:02.147386 | instance | Saturday 12 September 2026  06:26:02 +0000 (0:00:05.666)       0:00:39.845 ****
2026-09-12 06:26:02.864782 | instance | ok: [instance] => (item=chronyd)
2026-09-12 06:26:02.864862 | instance | ok: [instance] => (item=sshd)
2026-09-12 06:26:02.865230 | instance |
2026-09-12 06:26:02.865299 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-12 06:26:02.865305 | instance | Saturday 12 September 2026  06:26:02 +0000 (0:00:00.718)       0:00:40.564 ****
2026-09-12 06:26:16.420571 | instance | changed: [instance]
2026-09-12 06:26:16.421333 | instance |
2026-09-12 06:26:16.421406 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-12 06:26:16.421418 | instance | Saturday 12 September 2026  06:26:16 +0000 (0:00:13.555)       0:00:54.119 ****
2026-09-12 06:26:16.644953 | instance | ok: [instance]
2026-09-12 06:26:16.645096 | instance |
2026-09-12 06:26:16.645104 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-12 06:26:16.645217 | instance | Saturday 12 September 2026  06:26:16 +0000 (0:00:00.224)       0:00:54.344 ****
2026-09-12 06:26:17.183894 | instance | changed: [instance]
2026-09-12 06:26:17.184656 | instance |
2026-09-12 06:26:17.184737 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-12 06:26:17.184754 | instance | Saturday 12 September 2026  06:26:17 +0000 (0:00:00.538)       0:00:54.883 ****
2026-09-12 06:26:17.569168 | instance | changed: [instance]
2026-09-12 06:26:17.569282 | instance |
2026-09-12 06:26:17.569648 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-12 06:26:17.569715 | instance | Saturday 12 September 2026  06:26:17 +0000 (0:00:00.385)       0:00:55.268 ****
2026-09-12 06:26:17.688394 | instance | ok: [instance]
2026-09-12 06:26:17.688463 | instance |
2026-09-12 06:26:17.688477 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-12 06:26:17.688488 | instance | Saturday 12 September 2026  06:26:17 +0000 (0:00:00.117)       0:00:55.386 ****
2026-09-12 06:26:17.949681 | instance | ok: [instance] => (item=instance)
2026-09-12 06:26:17.949787 | instance |
2026-09-12 06:26:17.949799 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-12 06:26:17.950093 | instance | Saturday 12 September 2026  06:26:17 +0000 (0:00:00.262)       0:00:55.649 ****
2026-09-12 06:26:18.018770 | instance | ok: [instance]
2026-09-12 06:26:18.018862 | instance |
2026-09-12 06:26:18.019282 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-12 06:26:18.019502 | instance | Saturday 12 September 2026  06:26:18 +0000 (0:00:00.068)       0:00:55.718 ****
2026-09-12 06:26:18.100379 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-12 06:26:18.100479 | instance |
2026-09-12 06:26:18.100493 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-12 06:26:18.100653 | instance | Saturday 12 September 2026  06:26:18 +0000 (0:00:00.082)       0:00:55.800 ****
2026-09-12 06:26:18.477015 | instance | changed: [instance]
2026-09-12 06:26:18.477116 | instance |
2026-09-12 06:26:18.477464 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-12 06:26:18.477530 | instance | Saturday 12 September 2026  06:26:18 +0000 (0:00:00.376)       0:00:56.176 ****
2026-09-12 06:26:19.332976 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-12 06:26:19.334140 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-12 06:26:19.334209 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-12 06:26:19.334222 | instance |
2026-09-12 06:26:19.334232 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-12 06:26:19.334259 | instance | Saturday 12 September 2026  06:26:19 +0000 (0:00:00.855)       0:00:57.032 ****
2026-09-12 06:27:47.433441 | instance | ok: [instance]
2026-09-12 06:27:47.433637 | instance |
2026-09-12 06:27:47.433949 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-12 06:27:47.434249 | instance | Saturday 12 September 2026  06:27:47 +0000 (0:01:28.098)       0:02:25.131 ****
2026-09-12 06:27:47.682845 | instance | changed: [instance]
2026-09-12 06:27:47.683273 | instance |
2026-09-12 06:27:47.683645 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-12 06:27:47.683892 | instance | Saturday 12 September 2026  06:27:47 +0000 (0:00:00.251)       0:02:25.382 ****
2026-09-12 06:27:47.733313 | instance | ok: [instance]
2026-09-12 06:27:47.733532 | instance |
2026-09-12 06:27:47.733788 | instance | TASK [Install Ceph host] *******************************************************
2026-09-12 06:27:47.734036 | instance | Saturday 12 September 2026  06:27:47 +0000 (0:00:00.050)       0:02:25.432 ****
2026-09-12 06:27:47.846716 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-12 06:27:47.846770 | instance |
2026-09-12 06:27:47.846883 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-12 06:27:47.846991 | instance | Saturday 12 September 2026  06:27:47 +0000 (0:00:00.114)       0:02:25.547 ****
2026-09-12 06:27:49.707638 | instance | ok: [instance]
2026-09-12 06:27:49.707854 | instance |
2026-09-12 06:27:49.708160 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-12 06:27:49.708429 | instance | Saturday 12 September 2026  06:27:49 +0000 (0:00:01.860)       0:02:27.407 ****
2026-09-12 06:27:49.773477 | instance | ok: [instance] => (item=instance)
2026-09-12 06:27:49.773628 | instance |
2026-09-12 06:27:49.773877 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-12 06:27:49.774142 | instance | Saturday 12 September 2026  06:27:49 +0000 (0:00:00.065)       0:02:27.472 ****
2026-09-12 06:27:50.124625 | instance | ok: [instance]
2026-09-12 06:27:50.124697 | instance |
2026-09-12 06:27:50.124848 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-12 06:27:50.124989 | instance | Saturday 12 September 2026  06:27:50 +0000 (0:00:00.351)       0:02:27.824 ****
2026-09-12 06:27:52.347000 | instance | ok: [instance]
2026-09-12 06:27:52.347108 | instance |
2026-09-12 06:27:52.347269 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-12 06:27:52.347473 | instance | Saturday 12 September 2026  06:27:52 +0000 (0:00:02.222)       0:02:30.047 ****
2026-09-12 06:28:36.911714 | 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-12 06:28:36.912215 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n  File \"<stdin>\", line 107, in <module>\n  File \"<stdin>\", line 99, in _ansiballz_main\n  File \"<stdin>\", 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_q55it8cf/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in <module>\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_q55it8cf/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_q55it8cf/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_q55it8cf/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-12T06:28:36.691+0000 7fef88407640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC68KRqM5pOLSAAwBVB0FwNRVmy16+jJkJ+mkMSp4pbKp+2JEt+WgVMOkk=: Malformed input [buffer:3]\n2026-09-12T06:28:36.691+0000 7fef88407640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T06:28:36.691+0000 7fef88407640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC68KRqM5pOLSAAwBVB0FwNRVmy16+jJkJ+mkMSp4pbKp+2JEt+WgVMOkk=: Malformed input [buffer:3]\n2026-09-12T06:28:36.691+0000 7fef88407640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T06:28:36.691+0000 7fef88407640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC68KRqM5pOLSAAwBVB0FwNRVmy16+jJkJ+mkMSp4pbKp+2JEt+WgVMOkk=: Malformed input [buffer:3]\n2026-09-12T06:28:36.691+0000 7fef88407640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T06:28:36.691+0000 7fef88407640 -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-12 06:28:36.913602 | instance |
2026-09-12 06:28:36.913813 | instance | PLAY RECAP *********************************************************************
2026-09-12 06:28:36.914171 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-12 06:28:36.914360 | instance |
2026-09-12 06:28:36.914610 | instance | Saturday 12 September 2026  06:28:36 +0000 (0:00:44.567)       0:03:14.614 ****
2026-09-12 06:28:36.914842 | instance | ===============================================================================
2026-09-12 06:28:36.915156 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 88.10s
2026-09-12 06:28:36.915449 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 44.57s
2026-09-12 06:28:36.915719 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 13.56s
2026-09-12 06:28:36.915973 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.73s
2026-09-12 06:28:36.916293 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.67s
2026-09-12 06:28:36.916562 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.46s
2026-09-12 06:28:36.916836 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.91s
2026-09-12 06:28:36.917120 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.22s
2026-09-12 06:28:36.917375 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.86s
2026-09-12 06:28:36.917615 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.76s
2026-09-12 06:28:36.917841 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.63s
2026-09-12 06:28:36.918062 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.40s
2026-09-12 06:28:36.918326 | instance | Gathering Facts --------------------------------------------------------- 1.02s
2026-09-12 06:28:36.918546 | instance | Gathering Facts --------------------------------------------------------- 1.01s
2026-09-12 06:28:36.918770 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.00s
2026-09-12 06:28:36.918986 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-12 06:28:36.919251 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.98s
2026-09-12 06:28:36.919800 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.96s
2026-09-12 06:28:36.920041 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.86s
2026-09-12 06:28:36.920298 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s
2026-09-12 06:28:37.035522 | 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-12 06:28:37.035691 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-12 06:28:37.035837 | 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-12 06:28:37.216026 | instance | ERROR
2026-09-12 06:28:37.216275 | instance | {
2026-09-12 06:28:37.216335 | instance |   "delta": "0:04:08.470684",
2026-09-12 06:28:37.216377 | instance |   "end": "2026-09-12 06:28:37.145152",
2026-09-12 06:28:37.216415 | instance |   "msg": "non-zero return code",
2026-09-12 06:28:37.216480 | instance |   "rc": 2,
2026-09-12 06:28:37.216570 | instance |   "start": "2026-09-12 06:24:28.674468"
2026-09-12 06:28:37.216612 | instance | }
failure
2026-09-12 06:28:37.227841 | 
2026-09-12 06:28:37.227979 | PLAY RECAP
2026-09-12 06:28:37.228065 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-12 06:28:37.228107 | 
2026-09-12 06:28:37.402896 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-12 06:28:37.405605 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-12 06:28:38.029668 | 
2026-09-12 06:28:38.029795 | PLAY [all]
2026-09-12 06:28:38.043485 | 
2026-09-12 06:28:38.043600 | TASK [gather-host-logs : creating directory for system status]
2026-09-12 06:28:38.408429 | instance | changed
2026-09-12 06:28:38.416170 | 
2026-09-12 06:28:38.416307 | TASK [gather-host-logs : Get logs for each host]
2026-09-12 06:28:38.798278 | instance | + systemd-cgls --full --all --no-pager
2026-09-12 06:28:38.831855 | instance | + ip addr
2026-09-12 06:28:38.834970 | instance | + ip route
2026-09-12 06:28:38.838036 | instance | + lsblk
2026-09-12 06:28:38.843444 | instance | + mount
2026-09-12 06:28:38.846239 | instance | + docker images
2026-09-12 06:28:38.872732 | instance | + brctl show
2026-09-12 06:28:38.873567 | instance | /bin/bash: line 8: brctl: command not found
2026-09-12 06:28:38.873899 | instance | + ip neigh
2026-09-12 06:28:38.876873 | instance | + bridge fdb show
2026-09-12 06:28:38.904294 | instance | + ps aux --sort=-%mem
2026-09-12 06:28:38.932272 | instance | + dpkg -l
2026-09-12 06:28:38.940694 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-12 06:28:38.941173 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-12 06:28:38.955748 | instance | + '[' '!' -z '' ']'
2026-09-12 06:28:39.454913 | instance | ok: Runtime: 0:00:00.162433
2026-09-12 06:28:39.466118 | 
2026-09-12 06:28:39.466310 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-12 06:28:40.151479 | instance | changed:
2026-09-12 06:28:40.151737 | instance | created directory /var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/work/logs/instance
2026-09-12 06:28:40.151796 | instance | cd+++++++++ system/
2026-09-12 06:28:40.151840 | instance | >f+++++++++ system/brctl-show.txt
2026-09-12 06:28:40.151879 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-12 06:28:40.151916 | instance | >f+++++++++ system/docker-images.txt
2026-09-12 06:28:40.151958 | instance | >f+++++++++ system/ip-addr.txt
2026-09-12 06:28:40.151997 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-12 06:28:40.152035 | instance | >f+++++++++ system/ip-route.txt
2026-09-12 06:28:40.152078 | instance | >f+++++++++ system/lsblk.txt
2026-09-12 06:28:40.152115 | instance | >f+++++++++ system/mount.txt
2026-09-12 06:28:40.152151 | instance | >f+++++++++ system/packages.txt
2026-09-12 06:28:40.152191 | instance | >f+++++++++ system/ps.txt
2026-09-12 06:28:40.152232 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-12 06:28:40.169946 | 
2026-09-12 06:28:40.170083 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-12 06:28:40.385692 | instance | changed: "values"
2026-09-12 06:28:40.560467 | instance | changed: "releases"
2026-09-12 06:28:40.578588 | 
2026-09-12 06:28:40.578802 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-12 06:28:40.829038 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-12 06:28:41.132536 | instance | ok: Runtime: 0:00:00.005793
2026-09-12 06:28:41.143905 | 
2026-09-12 06:28:41.144069 | TASK [helm-release-status : Downloads logs to executor]
2026-09-12 06:28:41.679787 | instance | changed:
2026-09-12 06:28:41.680083 | instance | cd+++++++++ helm/
2026-09-12 06:28:41.680145 | instance | cd+++++++++ helm/releases/
2026-09-12 06:28:41.680192 | instance | cd+++++++++ helm/values/
2026-09-12 06:28:41.700931 | 
2026-09-12 06:28:41.701153 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-12 06:28:41.899138 | instance | changed
2026-09-12 06:28:41.912250 | 
2026-09-12 06:28:41.912556 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-12 06:28:42.211570 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 06:28:42.211942 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 06:28:42.218312 | instance | environment: line 1: kubectl: command not found
2026-09-12 06:28:42.219609 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 06:28:42.220614 | instance | environment: line 1: kubectl: command not found
2026-09-12 06:28:42.222041 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 06:28:42.224394 | instance | environment: line 1: kubectl: command not found
2026-09-12 06:28:42.225759 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 06:28:42.228075 | instance | environment: line 1: kubectl: command not found
2026-09-12 06:28:42.229603 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 06:28:42.229981 | instance | environment: line 1: kubectl: command not found
2026-09-12 06:28:42.231648 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 06:28:42.463661 | instance | ok: Runtime: 0:00:00.027552
2026-09-12 06:28:42.473190 | 
2026-09-12 06:28:42.473315 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-12 06:28:42.688285 | instance | changed
2026-09-12 06:28:42.692963 | 
2026-09-12 06:28:42.693040 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-12 06:28:42.922690 | instance | environment: line 5: kubectl: command not found
2026-09-12 06:28:42.922819 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 06:28:42.923618 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 06:28:42.924003 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 06:28:43.229281 | instance | ok: Runtime: 0:00:00.009503
2026-09-12 06:28:43.241380 | 
2026-09-12 06:28:43.241665 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-12 06:28:43.747983 | instance | changed:
2026-09-12 06:28:43.748187 | instance | cd+++++++++ objects/
2026-09-12 06:28:43.748213 | instance | cd+++++++++ objects/cluster/
2026-09-12 06:28:43.748233 | instance | cd+++++++++ objects/namespaced/
2026-09-12 06:28:43.756595 | 
2026-09-12 06:28:43.756685 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-12 06:28:43.983655 | instance | changed
2026-09-12 06:28:43.993582 | 
2026-09-12 06:28:43.993705 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-12 06:28:44.244771 | instance | changed
2026-09-12 06:28:44.256625 | 
2026-09-12 06:28:44.256853 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-12 06:28:44.524706 | instance | environment: line 3: kubectl: command not found
2026-09-12 06:28:44.805784 | instance | ok: Runtime: 0:00:00.010013
2026-09-12 06:28:44.812373 | 
2026-09-12 06:28:44.812466 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-12 06:28:45.348342 | instance | changed:
2026-09-12 06:28:45.348569 | instance | cd+++++++++ pod-logs/
2026-09-12 06:28:45.348606 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-12 06:28:45.361938 | 
2026-09-12 06:28:45.362073 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-12 06:28:45.597067 | instance | changed
2026-09-12 06:28:45.606621 | 
2026-09-12 06:28:45.606764 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-12 06:28:45.856301 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-12 06:28:46.155979 | instance | ok: Runtime: 0:00:00.038151
2026-09-12 06:28:46.165213 | 
2026-09-12 06:28:46.165373 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-12 06:28:46.388651 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-12 06:28:46.410705 | instance | ceph-mgr endpoints:
2026-09-12 06:28:46.718051 | instance | ok: Runtime: 0:00:00.029147
2026-09-12 06:28:46.728371 | 
2026-09-12 06:28:46.728556 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-12 06:28:46.989718 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-12 06:28:47.268732 | instance | ok: Runtime: 0:00:00.027593
2026-09-12 06:28:47.274692 | 
2026-09-12 06:28:47.274770 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-12 06:28:47.780263 | instance | changed: cd+++++++++ prometheus/
2026-09-12 06:28:47.791833 | 
2026-09-12 06:28:47.791981 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-12 06:28:48.027826 | instance | changed
2026-09-12 06:28:48.034415 | 
2026-09-12 06:28:48.034592 | TASK [gather-selenium-data : Get selenium data]
2026-09-12 06:28:48.318235 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-12 06:28:48.319179 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-12 06:28:48.570242 | instance | ERROR
2026-09-12 06:28:48.570416 | instance | {
2026-09-12 06:28:48.570460 | instance |   "delta": "0:00:00.004786",
2026-09-12 06:28:48.570481 | instance |   "end": "2026-09-12 06:28:48.319454",
2026-09-12 06:28:48.570499 | instance |   "msg": "non-zero return code",
2026-09-12 06:28:48.570515 | instance |   "rc": 1,
2026-09-12 06:28:48.570532 | instance |   "start": "2026-09-12 06:28:48.314668"
2026-09-12 06:28:48.570549 | instance | }
2026-09-12 06:28:48.570571 | instance | ERROR: Ignoring Errors
2026-09-12 06:28:48.575726 | 
2026-09-12 06:28:48.575805 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-12 06:28:49.104709 | instance | changed: cd+++++++++ selenium/
2026-09-12 06:28:49.115915 | 
2026-09-12 06:28:49.116017 | PLAY RECAP
2026-09-12 06:28:49.116108 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-12 06:28:49.116152 | 
2026-09-12 06:28:49.288304 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-12 06:28:49.292814 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 06:28:49.891478 | 
2026-09-12 06:28:49.891606 | PLAY [all]
2026-09-12 06:28:49.902986 | 
2026-09-12 06:28:49.903114 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-12 06:28:49.967147 | instance | skipping: Conditional result was False
2026-09-12 06:28:49.973574 | 
2026-09-12 06:28:49.973683 | TASK [fetch-output : Set log path for single node]
2026-09-12 06:28:50.016073 | instance | ok
2026-09-12 06:28:50.025014 | 
2026-09-12 06:28:50.025164 | LOOP [fetch-output : Ensure local output dirs]
2026-09-12 06:28:50.404266 | instance -> localhost | ok: "/var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/work/logs"
2026-09-12 06:28:50.632735 | instance -> localhost | changed: "/var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/work/artifacts"
2026-09-12 06:28:50.856678 | instance -> localhost | changed: "/var/lib/zuul/builds/f71ced5fab4b4c66a8b97a216443afcd/work/docs"
2026-09-12 06:28:50.870784 | 
2026-09-12 06:28:50.870967 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-12 06:28:51.542190 | instance | changed: .d..t...... ./
2026-09-12 06:28:51.542577 | instance | changed: All items complete
2026-09-12 06:28:51.542643 | 
2026-09-12 06:28:52.089457 | instance | changed: .d..t...... ./
2026-09-12 06:28:52.595056 | instance | changed: .d..t...... ./
2026-09-12 06:28:52.631221 | 
2026-09-12 06:28:52.631489 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-12 06:28:53.061809 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.010180
2026-09-12 06:28:53.320331 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007931
2026-09-12 06:28:53.339965 | 
2026-09-12 06:28:53.340100 | PLAY [all]
2026-09-12 06:28:53.347679 | 
2026-09-12 06:28:53.347768 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-12 06:28:53.799542 | instance | changed
2026-09-12 06:28:53.810003 | 
2026-09-12 06:28:53.810107 | PLAY RECAP
2026-09-12 06:28:53.810190 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-12 06:28:53.810235 | 
2026-09-12 06:28:53.942733 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 06:28:53.945416 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-12 06:28:54.501253 | 
2026-09-12 06:28:54.501462 | PLAY [localhost]
2026-09-12 06:28:54.512895 | 
2026-09-12 06:28:54.513013 | TASK [Generate Zuul manifest]
2026-09-12 06:28:54.529985 | localhost | ok
2026-09-12 06:28:54.546604 | 
2026-09-12 06:28:54.546729 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-12 06:28:54.910330 | localhost | changed
2026-09-12 06:28:54.935217 | 
2026-09-12 06:28:54.935580 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-12 06:28:54.966489 | localhost | ok
2026-09-12 06:28:54.979096 | 
2026-09-12 06:28:54.979255 | TASK [Upload logs]
2026-09-12 06:28:55.001104 | localhost | ok
2026-09-12 06:28:55.082089 | 
2026-09-12 06:28:55.082211 | TASK [Set zuul-log-path fact]
2026-09-12 06:28:55.098381 | localhost | ok
2026-09-12 06:28:55.109250 | 
2026-09-12 06:28:55.109322 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 06:28:55.137299 | localhost | ok
2026-09-12 06:28:55.143420 | 
2026-09-12 06:28:55.143520 | TASK [upload-logs : Create log directories]
2026-09-12 06:28:55.548896 | localhost | changed
2026-09-12 06:28:55.557814 | 
2026-09-12 06:28:55.557952 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-12 06:28:55.954333 | localhost -> localhost | ok: Runtime: 0:00:00.006490
2026-09-12 06:28:55.963460 | 
2026-09-12 06:28:55.963596 | TASK [upload-logs : Upload logs to log server]
2026-09-12 06:28:56.469098 | localhost | Output suppressed because no_log was given
2026-09-12 06:28:56.506100 | 
2026-09-12 06:28:56.506231 | LOOP [upload-logs : Compress console log and json output]
2026-09-12 06:28:56.570135 | localhost | skipping: Conditional result was False
2026-09-12 06:28:56.582871 | localhost | skipping: Conditional result was False
2026-09-12 06:28:56.597952 | 
2026-09-12 06:28:56.598099 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-12 06:28:56.642830 | localhost | skipping: Conditional result was False
2026-09-12 06:28:56.643061 | 
2026-09-12 06:28:56.647669 | localhost | skipping: Conditional result was False
2026-09-12 06:28:56.660606 | 
2026-09-12 06:28:56.660836 | LOOP [upload-logs : Upload console log and json output]