2026-09-25 14:51:43.397968 | Job console starting
2026-09-25 14:51:43.412099 | Updating git repos
2026-09-25 14:51:48.264011 | Cloning repos into workspace
2026-09-25 14:52:05.859201 | Restoring repo states
2026-09-25 14:52:24.285664 | Merging changes
2026-09-25 14:52:25.367314 | Checking out repos
2026-09-25 14:52:27.239051 | Preparing playbooks
2026-09-25 14:52:30.701678 | Running Ansible setup
2026-09-25 14:52:34.409082 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-25 14:52:34.941660 | 
2026-09-25 14:52:34.941777 | PLAY [localhost]
2026-09-25 14:52:34.948427 | 
2026-09-25 14:52:34.948517 | TASK [Gathering Facts]
2026-09-25 14:52:35.824264 | localhost | ok
2026-09-25 14:52:35.835932 | 
2026-09-25 14:52:35.836067 | TASK [Setup log path fact]
2026-09-25 14:52:35.858267 | localhost | ok
2026-09-25 14:52:35.878568 | 
2026-09-25 14:52:35.878725 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-25 14:52:35.908307 | localhost | ok
2026-09-25 14:52:35.919582 | 
2026-09-25 14:52:35.919742 | TASK [emit-job-header : Print job information]
2026-09-25 14:52:35.962051 | # Job Information
2026-09-25 14:52:35.962254 | Ansible Version: 2.16.18
2026-09-25 14:52:35.962321 | Job: atmosphere-molecule-csi-rbd
2026-09-25 14:52:35.962365 | Pipeline: check
2026-09-25 14:52:35.962406 | Executor: 2d72f0692154
2026-09-25 14:52:35.962445 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-09-25 14:52:35.962490 | Event ID: db1447f0-b8ef-11f1-95ab-c46cadb8b739
2026-09-25 14:52:35.968063 | 
2026-09-25 14:52:35.968189 | LOOP [emit-job-header : Print node information]
2026-09-25 14:52:36.051549 | localhost | ok:
2026-09-25 14:52:36.051717 | localhost | # Node Information
2026-09-25 14:52:36.051743 | localhost | Inventory Hostname: instance
2026-09-25 14:52:36.051762 | localhost | Hostname: np0000193969
2026-09-25 14:52:36.051780 | localhost | Username: zuul
2026-09-25 14:52:36.051799 | localhost | Distro: Ubuntu 22.04
2026-09-25 14:52:36.051817 | localhost | Provider: yul1
2026-09-25 14:52:36.051833 | localhost | Region: ca-ymq-1
2026-09-25 14:52:36.051849 | localhost | Label: ubuntu-jammy
2026-09-25 14:52:36.051865 | localhost | Product Name: OpenStack Nova
2026-09-25 14:52:36.051882 | localhost | Interface IP: 162.253.55.166
2026-09-25 14:52:36.065211 | 
2026-09-25 14:52:36.065361 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-25 14:52:36.454585 | localhost -> localhost | changed
2026-09-25 14:52:36.463496 | 
2026-09-25 14:52:36.463642 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-25 14:52:37.321580 | localhost -> localhost | changed
2026-09-25 14:52:37.347985 | 
2026-09-25 14:52:37.348108 | PLAY [all]
2026-09-25 14:52:37.361990 | 
2026-09-25 14:52:37.362321 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-25 14:52:37.611310 | instance -> localhost | ok
2026-09-25 14:52:37.623677 | 
2026-09-25 14:52:37.623803 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-25 14:52:37.669279 | instance | ok
2026-09-25 14:52:37.692847 | instance | included: /var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-25 14:52:37.702058 | 
2026-09-25 14:52:37.702167 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-25 14:52:39.472301 | instance -> localhost | Generating public/private rsa key pair.
2026-09-25 14:52:39.474228 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/work/bb864b7642fb4fe6b2f14842673ab79b_id_rsa
2026-09-25 14:52:39.474310 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/work/bb864b7642fb4fe6b2f14842673ab79b_id_rsa.pub
2026-09-25 14:52:39.474356 | instance -> localhost | The key fingerprint is:
2026-09-25 14:52:39.474399 | instance -> localhost | SHA256:6tJzj24GKPH81RG+MbrBaPLAXQ8Fj9hxf8Nf4b8mdjE zuul-build-sshkey
2026-09-25 14:52:39.474465 | instance -> localhost | The key's randomart image is:
2026-09-25 14:52:39.474505 | instance -> localhost | +---[RSA 3072]----+
2026-09-25 14:52:39.474555 | instance -> localhost | |        o..    . |
2026-09-25 14:52:39.474617 | instance -> localhost | |       o =o. .. .|
2026-09-25 14:52:39.474659 | instance -> localhost | |      . oo... +..|
2026-09-25 14:52:39.474698 | instance -> localhost | |  .     o =  . oo|
2026-09-25 14:52:39.474735 | instance -> localhost | |   = o +S= =   Eo|
2026-09-25 14:52:39.474772 | instance -> localhost | |  . B =.= +     +|
2026-09-25 14:52:39.474808 | instance -> localhost | |   . B.o o   o + |
2026-09-25 14:52:39.474845 | instance -> localhost | |    ..= =.  . +  |
2026-09-25 14:52:39.474887 | instance -> localhost | |     ..*o..      |
2026-09-25 14:52:39.474924 | instance -> localhost | +----[SHA256]-----+
2026-09-25 14:52:39.475020 | instance -> localhost | ok: Runtime: 0:00:01.281862
2026-09-25 14:52:39.487186 | 
2026-09-25 14:52:39.487278 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-25 14:52:39.521896 | instance | ok
2026-09-25 14:52:39.537835 | instance | included: /var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-25 14:52:39.547412 | 
2026-09-25 14:52:39.547496 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-25 14:52:39.572482 | instance | skipping: Conditional result was False
2026-09-25 14:52:39.583320 | 
2026-09-25 14:52:39.583445 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-25 14:52:40.015550 | instance | changed
2026-09-25 14:52:40.027005 | 
2026-09-25 14:52:40.027132 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-25 14:52:40.208143 | instance | ok
2026-09-25 14:52:40.212537 | 
2026-09-25 14:52:40.212614 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-25 14:52:40.718393 | instance | changed
2026-09-25 14:52:40.722860 | 
2026-09-25 14:52:40.722922 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-25 14:52:41.196363 | instance | changed
2026-09-25 14:52:41.204068 | 
2026-09-25 14:52:41.204156 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-25 14:52:41.228365 | instance | skipping: Conditional result was False
2026-09-25 14:52:41.235924 | 
2026-09-25 14:52:41.236015 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-25 14:52:41.628171 | instance -> localhost | changed
2026-09-25 14:52:41.647851 | 
2026-09-25 14:52:41.648034 | TASK [add-build-sshkey : Add back temp key]
2026-09-25 14:52:41.972996 | instance -> localhost | Identity added: /var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/work/bb864b7642fb4fe6b2f14842673ab79b_id_rsa (zuul-build-sshkey)
2026-09-25 14:52:41.973470 | instance -> localhost | ok: Runtime: 0:00:00.013506
2026-09-25 14:52:41.982900 | 
2026-09-25 14:52:41.983020 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-25 14:52:42.321804 | instance | ok
2026-09-25 14:52:42.327853 | 
2026-09-25 14:52:42.327977 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-25 14:52:42.364268 | instance | skipping: Conditional result was False
2026-09-25 14:52:42.374421 | 
2026-09-25 14:52:42.374483 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-25 14:52:42.745392 | instance | ok
2026-09-25 14:52:42.752025 | 
2026-09-25 14:52:42.752127 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-25 14:52:46.269511 | instance | Output suppressed because no_log was given
2026-09-25 14:52:46.277310 | 
2026-09-25 14:52:46.277368 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-25 14:52:46.478354 | instance | ok: "logs"
2026-09-25 14:52:46.478637 | instance | ok: All items complete
2026-09-25 14:52:46.478664 | 
2026-09-25 14:52:46.661036 | instance | ok: "artifacts"
2026-09-25 14:52:46.835320 | instance | ok: "docs"
2026-09-25 14:52:46.854243 | 
2026-09-25 14:52:46.854450 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-25 14:52:47.071506 | instance | changed: "logs"
2026-09-25 14:52:47.241212 | instance | changed: "artifacts"
2026-09-25 14:52:47.395770 | instance | changed: "docs"
2026-09-25 14:52:47.424951 | 
2026-09-25 14:52:47.425083 | PLAY RECAP
2026-09-25 14:52:47.425148 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-25 14:52:47.425186 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 14:52:47.425214 | 
2026-09-25 14:52:47.568896 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-25 14:52:47.572387 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-25 14:52:48.152452 | 
2026-09-25 14:52:48.152658 | PLAY [all]
2026-09-25 14:52:48.162060 | 
2026-09-25 14:52:48.162130 | TASK [setup-uv : Extract archive]
2026-09-25 14:52:50.810360 | instance | changed
2026-09-25 14:52:50.821162 | 
2026-09-25 14:52:50.821317 | TASK [setup-uv : Print version]
2026-09-25 14:52:51.242557 | instance | uv 0.8.13
2026-09-25 14:52:51.368916 | instance | ok: Runtime: 0:00:00.013200
2026-09-25 14:52:51.384943 | 
2026-09-25 14:52:51.385071 | TASK [Install Ansible collection siblings]
2026-09-25 14:53:00.577536 | instance | changed:
2026-09-25 14:53:00.577790 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-25 14:53:00.577847 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-25 14:53:00.577889 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-25 14:53:00.577926 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-25 14:53:00.577963 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-25 14:53:00.578006 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-25 14:53:00.578045 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-25 14:53:00.578087 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-25 14:53:00.578125 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-25 14:53:00.578161 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-25 14:53:00.578197 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-25 14:53:00.578232 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-25 14:53:00.578266 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-25 14:53:00.578301 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-25 14:53:00.578336 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0
2026-09-25 14:53:00.578657 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-25 14:53:00.578708 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-25 14:53:00.578747 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-25 14:53:00.578783 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-25 14:53:00.578819 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-25 14:53:00.578853 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-25 14:53:00.578886 | instance | Resolved atmosphere.common to v0.7.1 for >=0.7.0
2026-09-25 14:53:00.578925 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-25 14:53:00.578978 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-25 14:53:00.579015 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0,>=6.0.0
2026-09-25 14:53:00.579050 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-25 14:53:00.579083 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-25 14:53:00.579116 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-25 14:53:00.579149 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-25 14:53:00.579181 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-25 14:53:00.579215 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-25 14:53:00.579250 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-25 14:53:00.579285 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-25 14:53:00.579458 | 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-25 14:53:00.579560 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.579631 | instance | Process install dependency map
2026-09-25 14:53:00.579670 | instance | Starting collection install process
2026-09-25 14:53:00.579705 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-25 14:53:00.579740 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-25 14:53:00.579775 | instance | ansible.netcommon:* was installed successfully
2026-09-25 14:53:00.579811 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-25 14:53:00.579845 | instance | Creating virtual environment at: .venv
2026-09-25 14:53:00.579879 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-25 14:53:00.579913 | instance | Downloading kubernetes (1.9MiB)
2026-09-25 14:53:00.579948 | instance | Downloading rjsonnet (1.2MiB)
2026-09-25 14:53:00.579987 | instance | Downloading pygments (1.2MiB)
2026-09-25 14:53:00.580023 | instance | Downloading setuptools (1.1MiB)
2026-09-25 14:53:00.580057 | instance | Downloading ansible-core (2.1MiB)
2026-09-25 14:53:00.580092 | instance | Downloading netaddr (2.2MiB)
2026-09-25 14:53:00.580126 | instance | Downloading openstacksdk (1.7MiB)
2026-09-25 14:53:00.580161 | instance | Downloading pydantic-core (2.0MiB)
2026-09-25 14:53:00.580196 | instance | Downloading cryptography (4.2MiB)
2026-09-25 14:53:00.580253 | instance |  Downloading rjsonnet
2026-09-25 14:53:00.580291 | instance |    Building pyperclip==1.9.0
2026-09-25 14:53:00.580326 | instance |  Downloading pydantic-core
2026-09-25 14:53:00.580361 | instance |  Downloading netaddr
2026-09-25 14:53:00.580397 | instance |  Downloading pygments
2026-09-25 14:53:00.580431 | instance |  Downloading cryptography
2026-09-25 14:53:00.580465 | instance |  Downloading setuptools
2026-09-25 14:53:00.580531 | instance |  Downloading kubernetes
2026-09-25 14:53:00.580688 | instance |  Downloading ansible-core
2026-09-25 14:53:00.580736 | instance |  Downloading openstacksdk
2026-09-25 14:53:00.580773 | instance |       Built pyperclip==1.9.0
2026-09-25 14:53:00.580808 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-25 14:53:00.580842 | instance | Installed 83 packages in 68ms
2026-09-25 14:53:00.580876 | instance | Checked out ansible.posix to 1.6.0
2026-09-25 14:53:00.580910 | 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-25 14:53:00.580944 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.581004 | instance | Process install dependency map
2026-09-25 14:53:00.581052 | instance | Starting collection install process
2026-09-25 14:53:00.581090 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-25 14:53:00.581124 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-25 14:53:00.581157 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-25 14:53:00.581191 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.581226 | instance | 2.17.12
2026-09-25 14:53:00.581260 | instance | Checked out ansible.utils to v6.1.1
2026-09-25 14:53:00.581294 | 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-25 14:53:00.581328 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.581360 | instance | Process install dependency map
2026-09-25 14:53:00.581393 | instance | Starting collection install process
2026-09-25 14:53:00.581427 | instance | Installing 'ansible.utils:6.1.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-25 14:53:00.581461 | instance | Created collection for ansible.utils:6.1.1 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-25 14:53:00.581495 | instance | ansible.utils:6.1.1 was installed successfully
2026-09-25 14:53:00.581529 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.581562 | instance | 2.17.12
2026-09-25 14:53:00.581617 | instance | Checked out atmosphere.common to v0.7.1
2026-09-25 14:53:00.581652 | 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-25 14:53:00.581685 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.581718 | instance | Process install dependency map
2026-09-25 14:53:00.581751 | instance | Starting collection install process
2026-09-25 14:53:00.581784 | instance | Installing 'atmosphere.common:0.7.1' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-25 14:53:00.581817 | instance | Created collection for atmosphere.common:0.7.1 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-25 14:53:00.581850 | instance | atmosphere.common:0.7.1 was installed successfully
2026-09-25 14:53:00.581883 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.581916 | instance | 2.17.12
2026-09-25 14:53:00.581950 | instance | Checked out community.crypto to 2.2.3
2026-09-25 14:53:00.581983 | 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-25 14:53:00.582016 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.582048 | instance | Process install dependency map
2026-09-25 14:53:00.582081 | instance | Starting collection install process
2026-09-25 14:53:00.582113 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-25 14:53:00.582147 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-25 14:53:00.582180 | instance | community.crypto:2.2.3 was installed successfully
2026-09-25 14:53:00.582214 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.582247 | instance | 2.17.12
2026-09-25 14:53:00.582279 | instance | Checked out community.general to 7.3.0
2026-09-25 14:53:00.582312 | 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-25 14:53:00.582355 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.582389 | instance | Process install dependency map
2026-09-25 14:53:00.582422 | instance | Starting collection install process
2026-09-25 14:53:00.582455 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-25 14:53:00.582488 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-25 14:53:00.582522 | instance | community.general:7.3.0 was installed successfully
2026-09-25 14:53:00.582556 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.582607 | instance | 2.17.12
2026-09-25 14:53:00.582644 | instance | Checked out community.mysql to 3.6.0
2026-09-25 14:53:00.582682 | 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-25 14:53:00.582718 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.582752 | instance | Process install dependency map
2026-09-25 14:53:00.582787 | instance | Starting collection install process
2026-09-25 14:53:00.582820 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-25 14:53:00.582854 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-25 14:53:00.582887 | instance | community.mysql:3.6.0 was installed successfully
2026-09-25 14:53:00.582920 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.582953 | instance | 2.17.12
2026-09-25 14:53:00.582987 | instance | Checked out kubernetes.core to 2.4.0
2026-09-25 14:53:00.583021 | 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-25 14:53:00.583056 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.583090 | instance | Process install dependency map
2026-09-25 14:53:00.583124 | instance | Starting collection install process
2026-09-25 14:53:00.583157 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-25 14:53:00.583190 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-25 14:53:00.583223 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-25 14:53:00.583256 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.583289 | instance | 2.17.12
2026-09-25 14:53:00.583322 | instance | Checked out openstack.cloud to 2.6.0
2026-09-25 14:53:00.583355 | 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-25 14:53:00.583388 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.583421 | instance | Process install dependency map
2026-09-25 14:53:00.583453 | instance | Starting collection install process
2026-09-25 14:53:00.583486 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-25 14:53:00.583518 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-25 14:53:00.583551 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-25 14:53:00.583598 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.583633 | instance | 2.17.12
2026-09-25 14:53:00.583675 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-25 14:53:00.583708 | 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-25 14:53:00.583742 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.583775 | instance | Process install dependency map
2026-09-25 14:53:00.583807 | instance | Starting collection install process
2026-09-25 14:53:00.583839 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-25 14:53:00.583871 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-25 14:53:00.583903 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-25 14:53:00.583936 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.583968 | instance | 2.17.12
2026-09-25 14:53:00.584002 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-25 14:53:00.584034 | 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-25 14:53:00.584068 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.584100 | instance | Process install dependency map
2026-09-25 14:53:00.584132 | instance | Starting collection install process
2026-09-25 14:53:00.584165 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-25 14:53:00.584198 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-25 14:53:00.584230 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-25 14:53:00.584262 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.584295 | instance | 2.17.12
2026-09-25 14:53:00.584328 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-25 14:53:00.584362 | 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-25 14:53:00.584395 | instance | Starting galaxy collection install process
2026-09-25 14:53:00.584428 | instance | Process install dependency map
2026-09-25 14:53:00.584461 | instance | Starting collection install process
2026-09-25 14:53:00.584519 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-25 14:53:00.584557 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-25 14:53:00.584608 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-25 14:53:00.584644 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-25 14:53:00.584677 | instance | 2.17.12
2026-09-25 14:53:00.592580 | 
2026-09-25 14:53:00.592658 | PLAY RECAP
2026-09-25 14:53:00.592710 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 14:53:00.592737 | 
2026-09-25 14:53:00.707770 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-25 14:53:00.709601 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-25 14:53:01.262916 | 
2026-09-25 14:53:01.263031 | PLAY [all]
2026-09-25 14:53:01.273126 | 
2026-09-25 14:53:01.273197 | TASK [Install "jq" for log collection]
2026-09-25 14:53:11.610363 | instance | changed
2026-09-25 14:53:11.613722 | 
2026-09-25 14:53:11.613837 | PLAY RECAP
2026-09-25 14:53:11.613957 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 14:53:11.614082 | 
2026-09-25 14:53:11.789570 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-25 14:53:11.793337 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-25 14:53:12.373682 | 
2026-09-25 14:53:12.373881 | PLAY [all]
2026-09-25 14:53:12.384611 | 
2026-09-25 14:53:12.384708 | LOOP [Check inventory directories]
2026-09-25 14:53:12.760957 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-25 14:53:12.924382 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-25 14:53:13.095381 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-25 14:53:13.106242 | 
2026-09-25 14:53:13.106442 | LOOP [Copy inventory file for Zuul]
2026-09-25 14:53:13.973197 | instance | changed:
2026-09-25 14:53:13.973485 | instance | {
2026-09-25 14:53:13.973543 | instance |   "ansible_loop_var": "item",
2026-09-25 14:53:13.973611 | instance |   "changed": false,
2026-09-25 14:53:13.973654 | instance |   "failed": false,
2026-09-25 14:53:13.973699 | instance |   "invocation": {
2026-09-25 14:53:13.973737 | instance |     "module_args": {
2026-09-25 14:53:13.973774 | instance |       "checksum_algorithm": "sha1",
2026-09-25 14:53:13.973810 | instance |       "follow": true,
2026-09-25 14:53:13.973847 | instance |       "get_attributes": true,
2026-09-25 14:53:13.973884 | instance |       "get_checksum": true,
2026-09-25 14:53:13.973921 | instance |       "get_mime": true,
2026-09-25 14:53:13.973958 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-25 14:53:13.973995 | instance |     }
2026-09-25 14:53:13.974029 | instance |   },
2026-09-25 14:53:13.974065 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-25 14:53:13.974101 | instance |   "stat": {
2026-09-25 14:53:13.974136 | instance |     "atime": 1790347973.3866293,
2026-09-25 14:53:13.974172 | instance |     "attr_flags": "e",
2026-09-25 14:53:13.974208 | instance |     "attributes": [
2026-09-25 14:53:13.974243 | instance |       "extents"
2026-09-25 14:53:13.974278 | instance |     ],
2026-09-25 14:53:13.974313 | instance |     "block_size": 4096,
2026-09-25 14:53:13.974349 | instance |     "blocks": 8,
2026-09-25 14:53:13.974384 | instance |     "charset": "unknown",
2026-09-25 14:53:13.974422 | instance |     "ctime": 1790347972.3105686,
2026-09-25 14:53:13.974459 | instance |     "dev": 64513,
2026-09-25 14:53:13.974494 | instance |     "device_type": 0,
2026-09-25 14:53:13.974529 | instance |     "executable": true,
2026-09-25 14:53:13.974565 | instance |     "exists": true,
2026-09-25 14:53:13.974621 | instance |     "gid": 1000,
2026-09-25 14:53:13.974658 | instance |     "gr_name": "zuul",
2026-09-25 14:53:13.974693 | instance |     "inode": 1049346,
2026-09-25 14:53:13.974729 | instance |     "isblk": false,
2026-09-25 14:53:13.974764 | instance |     "ischr": false,
2026-09-25 14:53:13.974798 | instance |     "isdir": true,
2026-09-25 14:53:13.974833 | instance |     "isfifo": false,
2026-09-25 14:53:13.974868 | instance |     "isgid": false,
2026-09-25 14:53:13.974903 | instance |     "islnk": false,
2026-09-25 14:53:13.974937 | instance |     "isreg": false,
2026-09-25 14:53:13.974972 | instance |     "issock": false,
2026-09-25 14:53:13.975006 | instance |     "isuid": false,
2026-09-25 14:53:13.975040 | instance |     "mimetype": "unknown",
2026-09-25 14:53:13.975074 | instance |     "mode": "0755",
2026-09-25 14:53:13.975108 | instance |     "mtime": 1790347972.3105686,
2026-09-25 14:53:13.975143 | instance |     "nlink": 23,
2026-09-25 14:53:13.975177 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-25 14:53:13.975212 | instance |     "pw_name": "zuul",
2026-09-25 14:53:13.975247 | instance |     "readable": true,
2026-09-25 14:53:13.975282 | instance |     "rgrp": true,
2026-09-25 14:53:13.975317 | instance |     "roth": true,
2026-09-25 14:53:13.975370 | instance |     "rusr": true,
2026-09-25 14:53:13.975407 | instance |     "size": 4096,
2026-09-25 14:53:13.975441 | instance |     "uid": 1000,
2026-09-25 14:53:13.975476 | instance |     "version": "2787023408",
2026-09-25 14:53:13.975511 | instance |     "wgrp": false,
2026-09-25 14:53:13.975548 | instance |     "woth": false,
2026-09-25 14:53:13.975603 | instance |     "writeable": true,
2026-09-25 14:53:13.975639 | instance |     "wusr": true,
2026-09-25 14:53:13.975673 | instance |     "xgrp": true,
2026-09-25 14:53:13.975708 | instance |     "xoth": true,
2026-09-25 14:53:13.975741 | instance |     "xusr": true
2026-09-25 14:53:13.975775 | instance |   }
2026-09-25 14:53:13.975810 | instance | }
2026-09-25 14:53:13.975876 | instance | ok: All items complete
2026-09-25 14:53:13.975918 | 
2026-09-25 14:53:13.978350 | instance | skipping: Conditional result was False
2026-09-25 14:53:13.979555 | instance | skipping: Conditional result was False
2026-09-25 14:53:14.003182 | 
2026-09-25 14:53:14.003359 | LOOP [Switch "ansible_host" to private IP]
2026-09-25 14:53:14.390737 | instance | changed: 1 replacements made
2026-09-25 14:53:14.392953 | instance | skipping: Conditional result was False
2026-09-25 14:53:14.399067 | instance | skipping: Conditional result was False
2026-09-25 14:53:14.421434 | 
2026-09-25 14:53:14.421638 | TASK [Run Molecule scenario]
2026-09-25 14:53:15.336439 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-25 14:53:15.792202 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-25 14:53:15.792284 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-25 14:53:27.154173 | instance | INFO     [csi > dependency] Executing
2026-09-25 14:53:27.154434 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-25 14:53:27.154729 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-25 14:53:27.154898 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-25 14:53:27.164660 | instance | INFO     [csi > cleanup] Executing
2026-09-25 14:53:27.165079 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-25 14:53:27.174869 | instance | INFO     [csi > destroy] Executing
2026-09-25 14:53:27.174966 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-25 14:53:27.175077 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-25 14:53:27.183504 | instance | INFO     [csi > syntax] Executing
2026-09-25 14:53:28.733213 | instance |
2026-09-25 14:53:28.733511 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-25 14:53:28.842358 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-25 14:53:28.858634 | instance | INFO     [csi > create] Executing
2026-09-25 14:53:28.862173 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-25 14:53:28.877039 | instance | INFO     [csi > prepare] Executing
2026-09-25 14:53:29.704746 | instance |
2026-09-25 14:53:29.704906 | instance | PLAY [Prepare] *****************************************************************
2026-09-25 14:53:29.705141 | instance |
2026-09-25 14:53:29.705400 | instance | TASK [Gathering Facts] *********************************************************
2026-09-25 14:53:29.705634 | instance | Friday 25 September 2026  14:53:29 +0000 (0:00:00.024)       0:00:00.024 ******
2026-09-25 14:53:30.941150 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-25 14:53:30.941372 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-25 14:53:30.941612 | instance | interpreter could change the meaning of that path. See
2026-09-25 14:53:30.941869 | instance | https://docs.ansible.com/ansible-
2026-09-25 14:53:30.942087 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-25 14:53:30.951792 | instance | ok: [instance]
2026-09-25 14:53:30.951991 | instance |
2026-09-25 14:53:30.952240 | instance | TASK [Configure short hostname] ************************************************
2026-09-25 14:53:30.952474 | instance | Friday 25 September 2026  14:53:30 +0000 (0:00:01.247)       0:00:01.272 ******
2026-09-25 14:53:31.711940 | instance | changed: [instance]
2026-09-25 14:53:31.712128 | instance |
2026-09-25 14:53:31.712404 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-25 14:53:31.712648 | instance | Friday 25 September 2026  14:53:31 +0000 (0:00:00.759)       0:00:02.031 ******
2026-09-25 14:53:32.013963 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-25 14:53:32.014047 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-25 14:53:32.014139 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-25 14:53:32.019384 | instance | changed: [instance]
2026-09-25 14:53:32.019455 | instance |
2026-09-25 14:53:32.019619 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-25 14:53:32.019718 | instance | Friday 25 September 2026  14:53:32 +0000 (0:00:00.308)       0:00:02.340 ******
2026-09-25 14:53:32.821896 | instance | ok: [instance]
2026-09-25 14:53:32.822114 | instance |
2026-09-25 14:53:32.822381 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-25 14:53:32.822605 | instance |
2026-09-25 14:53:32.822840 | instance | TASK [Gathering Facts] *********************************************************
2026-09-25 14:53:32.823080 | instance | Friday 25 September 2026  14:53:32 +0000 (0:00:00.802)       0:00:03.142 ******
2026-09-25 14:53:33.553267 | instance | ok: [instance]
2026-09-25 14:53:33.553344 | instance |
2026-09-25 14:53:33.553417 | instance | TASK [Install depedencies] *****************************************************
2026-09-25 14:53:33.553522 | instance | Friday 25 September 2026  14:53:33 +0000 (0:00:00.730)       0:00:03.873 ******
2026-09-25 14:53:56.381441 | instance | changed: [instance]
2026-09-25 14:53:56.381813 | instance |
2026-09-25 14:53:56.382197 | instance | TASK [Start up service] ********************************************************
2026-09-25 14:53:56.382539 | instance | Friday 25 September 2026  14:53:56 +0000 (0:00:22.828)       0:00:26.701 ******
2026-09-25 14:53:56.970011 | instance | ok: [instance]
2026-09-25 14:53:56.970192 | instance |
2026-09-25 14:53:56.970334 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-25 14:53:56.970472 | instance | Friday 25 September 2026  14:53:56 +0000 (0:00:00.587)       0:00:27.289 ******
2026-09-25 14:53:57.266984 | instance | ok: [instance]
2026-09-25 14:53:57.267141 | instance |
2026-09-25 14:53:57.267395 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-25 14:53:57.267646 | instance | Friday 25 September 2026  14:53:57 +0000 (0:00:00.298)       0:00:27.587 ******
2026-09-25 14:53:57.972521 | instance | changed: [instance]
2026-09-25 14:53:57.972722 | instance |
2026-09-25 14:53:57.973067 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-25 14:53:57.973407 | instance | Friday 25 September 2026  14:53:57 +0000 (0:00:00.705)       0:00:28.292 ******
2026-09-25 14:53:58.144116 | instance | ok: [instance]
2026-09-25 14:53:58.144400 | instance |
2026-09-25 14:53:58.144556 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-25 14:53:58.144719 | instance | Friday 25 September 2026  14:53:58 +0000 (0:00:00.170)       0:00:28.463 ******
2026-09-25 14:53:58.172046 | instance | skipping: [instance]
2026-09-25 14:53:58.172241 | instance |
2026-09-25 14:53:58.172385 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-25 14:53:58.172524 | instance | Friday 25 September 2026  14:53:58 +0000 (0:00:00.027)       0:00:28.490 ******
2026-09-25 14:53:58.712406 | instance | changed: [instance] => (item=osd0)
2026-09-25 14:53:58.712766 | instance | changed: [instance] => (item=osd1)
2026-09-25 14:53:58.713068 | instance | changed: [instance] => (item=osd2)
2026-09-25 14:53:58.713377 | instance |
2026-09-25 14:53:58.713670 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-25 14:53:58.713960 | instance | Friday 25 September 2026  14:53:58 +0000 (0:00:00.540)       0:00:29.031 ******
2026-09-25 14:53:59.300600 | instance | changed: [instance] => (item=osd0)
2026-09-25 14:53:59.301001 | instance | changed: [instance] => (item=osd1)
2026-09-25 14:53:59.301340 | instance | changed: [instance] => (item=osd2)
2026-09-25 14:53:59.301625 | instance |
2026-09-25 14:53:59.301918 | instance | TASK [Start loop devices] ******************************************************
2026-09-25 14:53:59.302244 | instance | Friday 25 September 2026  14:53:59 +0000 (0:00:00.588)       0:00:29.619 ******
2026-09-25 14:54:00.022588 | instance | changed: [instance] => (item=osd0)
2026-09-25 14:54:00.022666 | instance | changed: [instance] => (item=osd1)
2026-09-25 14:54:00.023098 | instance | changed: [instance] => (item=osd2)
2026-09-25 14:54:00.023139 | instance |
2026-09-25 14:54:00.023146 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-25 14:54:00.023151 | instance | Friday 25 September 2026  14:54:00 +0000 (0:00:00.722)       0:00:30.342 ******
2026-09-25 14:54:03.135690 | instance | changed: [instance] => (item=osd0)
2026-09-25 14:54:03.136900 | instance | changed: [instance] => (item=osd1)
2026-09-25 14:54:03.136965 | instance | changed: [instance] => (item=osd2)
2026-09-25 14:54:03.136977 | instance |
2026-09-25 14:54:03.136987 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-25 14:54:03.136998 | instance | Friday 25 September 2026  14:54:03 +0000 (0:00:03.113)       0:00:33.455 ******
2026-09-25 14:54:05.135355 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-25 14:54:05.135531 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-25 14:54:05.136469 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-25 14:54:05.136500 | instance |
2026-09-25 14:54:05.136511 | instance | PLAY RECAP *********************************************************************
2026-09-25 14:54:05.136522 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-25 14:54:05.137247 | instance |
2026-09-25 14:54:05.137336 | instance | Friday 25 September 2026  14:54:05 +0000 (0:00:02.000)       0:00:35.456 ******
2026-09-25 14:54:05.137354 | instance | ===============================================================================
2026-09-25 14:54:05.137378 | instance | Install depedencies ---------------------------------------------------- 22.83s
2026-09-25 14:54:05.139743 | instance | Create a volume group for each loop device ------------------------------ 3.11s
2026-09-25 14:54:05.139829 | instance | Create a logical volume for each loop device ---------------------------- 2.00s
2026-09-25 14:54:05.139839 | instance | Gathering Facts --------------------------------------------------------- 1.25s
2026-09-25 14:54:05.139846 | instance | Purge "snapd" package --------------------------------------------------- 0.80s
2026-09-25 14:54:05.139853 | instance | Configure short hostname ------------------------------------------------ 0.76s
2026-09-25 14:54:05.139859 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-09-25 14:54:05.139864 | instance | Start loop devices ------------------------------------------------------ 0.72s
2026-09-25 14:54:05.139870 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.71s
2026-09-25 14:54:05.139875 | instance | Set permissions on loopback devices ------------------------------------- 0.59s
2026-09-25 14:54:05.139881 | instance | Start up service -------------------------------------------------------- 0.59s
2026-09-25 14:54:05.139895 | instance | Create devices for Ceph ------------------------------------------------- 0.54s
2026-09-25 14:54:05.140051 | instance | Ensure hostname inside hosts file --------------------------------------- 0.31s
2026-09-25 14:54:05.140375 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-09-25 14:54:05.140657 | instance | Get list of all loopback devices ---------------------------------------- 0.17s
2026-09-25 14:54:05.140938 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-25 14:54:05.257355 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-25 14:54:05.265842 | instance | INFO     [csi > converge] Executing
2026-09-25 14:54:06.642560 | instance |
2026-09-25 14:54:06.642976 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-25 14:54:06.643316 | instance |
2026-09-25 14:54:06.643713 | instance | TASK [Gathering Facts] *********************************************************
2026-09-25 14:54:06.644093 | instance | Friday 25 September 2026  14:54:06 +0000 (0:00:00.007)       0:00:00.007 ******
2026-09-25 14:54:07.697174 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-25 14:54:07.697557 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-25 14:54:07.697866 | instance | interpreter could change the meaning of that path. See
2026-09-25 14:54:07.698191 | instance | https://docs.ansible.com/ansible-
2026-09-25 14:54:07.698492 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-25 14:54:07.706736 | instance | ok: [instance]
2026-09-25 14:54:07.707060 | instance |
2026-09-25 14:54:07.707363 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-25 14:54:07.707689 | instance | Friday 25 September 2026  14:54:07 +0000 (0:00:01.064)       0:00:01.071 ******
2026-09-25 14:54:07.777323 | instance | ok: [instance] => {
2026-09-25 14:54:07.777721 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-25 14:54:07.778060 | instance | }
2026-09-25 14:54:07.778444 | instance |
2026-09-25 14:54:07.778868 | instance | PLAY [all] *********************************************************************
2026-09-25 14:54:07.779310 | instance |
2026-09-25 14:54:07.779817 | instance | TASK [Gathering Facts] *********************************************************
2026-09-25 14:54:07.780361 | instance | Friday 25 September 2026  14:54:07 +0000 (0:00:00.069)       0:00:01.141 ******
2026-09-25 14:54:08.763470 | instance | ok: [instance]
2026-09-25 14:54:08.763744 | instance |
2026-09-25 14:54:08.763903 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-25 14:54:08.764111 | instance | Friday 25 September 2026  14:54:08 +0000 (0:00:00.986)       0:00:02.128 ******
2026-09-25 14:54:08.812445 | instance | skipping: [instance]
2026-09-25 14:54:08.812598 | instance |
2026-09-25 14:54:08.812844 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-25 14:54:08.813199 | instance | Friday 25 September 2026  14:54:08 +0000 (0:00:00.047)       0:00:02.175 ******
2026-09-25 14:54:09.016584 | instance | ok: [instance]
2026-09-25 14:54:09.016864 | instance |
2026-09-25 14:54:09.017062 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-25 14:54:09.017220 | instance |
2026-09-25 14:54:09.017385 | instance | TASK [Gathering Facts] *********************************************************
2026-09-25 14:54:09.017577 | instance | Friday 25 September 2026  14:54:09 +0000 (0:00:00.204)       0:00:02.380 ******
2026-09-25 14:54:10.020525 | instance | ok: [instance]
2026-09-25 14:54:10.020727 | instance |
2026-09-25 14:54:10.022496 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-25 14:54:10.022515 | instance | Friday 25 September 2026  14:54:10 +0000 (0:00:01.001)       0:00:03.381 ******
2026-09-25 14:54:10.316394 | instance | ok: [instance]
2026-09-25 14:54:10.316511 | instance |
2026-09-25 14:54:10.316789 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-25 14:54:10.317157 | instance | Friday 25 September 2026  14:54:10 +0000 (0:00:00.297)       0:00:03.678 ******
2026-09-25 14:54:10.376242 | instance | skipping: [instance]
2026-09-25 14:54:10.376385 | instance |
2026-09-25 14:54:10.376659 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-25 14:54:10.376914 | instance | Friday 25 September 2026  14:54:10 +0000 (0:00:00.062)       0:00:03.740 ******
2026-09-25 14:54:10.766903 | instance | changed: [instance]
2026-09-25 14:54:10.767190 | instance |
2026-09-25 14:54:10.767455 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-25 14:54:10.767768 | instance | Friday 25 September 2026  14:54:10 +0000 (0:00:00.389)       0:00:04.130 ******
2026-09-25 14:54:10.868133 | instance | ok: [instance] => {
2026-09-25 14:54:10.868637 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-25 14:54:10.869038 | instance | }
2026-09-25 14:54:10.869399 | instance |
2026-09-25 14:54:10.869765 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-25 14:54:10.870128 | instance | Friday 25 September 2026  14:54:10 +0000 (0:00:00.098)       0:00:04.229 ******
2026-09-25 14:54:11.576224 | instance | changed: [instance]
2026-09-25 14:54:11.576559 | instance |
2026-09-25 14:54:11.576773 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-25 14:54:11.576991 | instance | Friday 25 September 2026  14:54:11 +0000 (0:00:00.710)       0:00:04.940 ******
2026-09-25 14:54:11.636287 | instance | skipping: [instance]
2026-09-25 14:54:11.636431 | instance |
2026-09-25 14:54:11.636674 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-25 14:54:11.636870 | instance | Friday 25 September 2026  14:54:11 +0000 (0:00:00.059)       0:00:04.999 ******
2026-09-25 14:54:11.700293 | instance | skipping: [instance]
2026-09-25 14:54:11.700382 | instance |
2026-09-25 14:54:11.700489 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-25 14:54:11.700719 | instance | Friday 25 September 2026  14:54:11 +0000 (0:00:00.065)       0:00:05.064 ******
2026-09-25 14:54:11.937778 | instance | ok: [instance]
2026-09-25 14:54:11.937820 | instance |
2026-09-25 14:54:11.937826 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-25 14:54:11.937831 | instance | Friday 25 September 2026  14:54:11 +0000 (0:00:00.237)       0:00:05.302 ******
2026-09-25 14:54:13.300232 | instance | ok: [instance]
2026-09-25 14:54:13.300294 | instance |
2026-09-25 14:54:13.300303 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-25 14:54:13.300791 | instance | Friday 25 September 2026  14:54:13 +0000 (0:00:01.358)       0:00:06.661 ******
2026-09-25 14:54:13.384501 | instance | ok: [instance] => {
2026-09-25 14:54:13.385691 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-25 14:54:13.385867 | instance | }
2026-09-25 14:54:13.385881 | instance |
2026-09-25 14:54:13.385891 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-25 14:54:13.385900 | instance | Friday 25 September 2026  14:54:13 +0000 (0:00:00.087)       0:00:06.748 ******
2026-09-25 14:54:14.104022 | instance | changed: [instance]
2026-09-25 14:54:14.104328 | instance |
2026-09-25 14:54:14.104624 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-25 14:54:14.104900 | instance | Friday 25 September 2026  14:54:14 +0000 (0:00:00.719)       0:00:07.468 ******
2026-09-25 14:54:16.965592 | instance | changed: [instance]
2026-09-25 14:54:16.965730 | instance |
2026-09-25 14:54:16.965819 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-25 14:54:16.965982 | instance | Friday 25 September 2026  14:54:16 +0000 (0:00:02.861)       0:00:10.329 ******
2026-09-25 14:54:17.003836 | instance | skipping: [instance]
2026-09-25 14:54:17.004276 | instance |
2026-09-25 14:54:17.004417 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-25 14:54:17.004563 | instance | Friday 25 September 2026  14:54:16 +0000 (0:00:00.038)       0:00:10.368 ******
2026-09-25 14:54:17.046098 | instance | skipping: [instance]
2026-09-25 14:54:17.046230 | instance |
2026-09-25 14:54:17.046385 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-25 14:54:17.046536 | instance | Friday 25 September 2026  14:54:17 +0000 (0:00:00.042)       0:00:10.410 ******
2026-09-25 14:54:17.084847 | instance | skipping: [instance]
2026-09-25 14:54:17.084974 | instance |
2026-09-25 14:54:17.085103 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-25 14:54:17.085233 | instance | Friday 25 September 2026  14:54:17 +0000 (0:00:00.039)       0:00:10.449 ******
2026-09-25 14:54:23.055145 | instance | changed: [instance]
2026-09-25 14:54:23.055364 | instance |
2026-09-25 14:54:23.055385 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-25 14:54:23.055401 | instance | Friday 25 September 2026  14:54:23 +0000 (0:00:05.968)       0:00:16.418 ******
2026-09-25 14:54:23.742401 | instance | changed: [instance]
2026-09-25 14:54:23.742861 | instance |
2026-09-25 14:54:23.742934 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-25 14:54:23.742941 | instance | Friday 25 September 2026  14:54:23 +0000 (0:00:00.688)       0:00:17.106 ******
2026-09-25 14:54:24.668370 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-25 14:54:24.668484 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-25 14:54:24.669538 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-25 14:54:24.669605 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-25 14:54:24.669614 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-25 14:54:24.669620 | instance |
2026-09-25 14:54:24.669627 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-25 14:54:24.669632 | instance | Friday 25 September 2026  14:54:24 +0000 (0:00:00.926)       0:00:18.033 ******
2026-09-25 14:54:25.277736 | instance | changed: [instance]
2026-09-25 14:54:25.278712 | instance |
2026-09-25 14:54:25.278763 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-25 14:54:25.278772 | instance | Friday 25 September 2026  14:54:25 +0000 (0:00:00.581)       0:00:18.615 ******
2026-09-25 14:54:25.278779 | instance |
2026-09-25 14:54:25.278785 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-25 14:54:25.278791 | instance | Friday 25 September 2026  14:54:25 +0000 (0:00:00.027)       0:00:18.642 ******
2026-09-25 14:54:26.216075 | instance | ok: [instance]
2026-09-25 14:54:26.216149 | instance |
2026-09-25 14:54:26.216360 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-25 14:54:26.216375 | instance | Friday 25 September 2026  14:54:26 +0000 (0:00:00.938)       0:00:19.581 ******
2026-09-25 14:54:26.704207 | instance | changed: [instance]
2026-09-25 14:54:26.704701 | instance |
2026-09-25 14:54:26.704723 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-25 14:54:26.704731 | instance | Friday 25 September 2026  14:54:26 +0000 (0:00:00.487)       0:00:20.069 ******
2026-09-25 14:54:27.247165 | instance | changed: [instance]
2026-09-25 14:54:27.247678 | instance |
2026-09-25 14:54:27.247723 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-25 14:54:27.247948 | instance | Friday 25 September 2026  14:54:27 +0000 (0:00:00.542)       0:00:20.611 ******
2026-09-25 14:54:27.487538 | instance | ok: [instance]
2026-09-25 14:54:27.488378 | instance |
2026-09-25 14:54:27.488410 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-25 14:54:27.488422 | instance | Friday 25 September 2026  14:54:27 +0000 (0:00:00.240)       0:00:20.852 ******
2026-09-25 14:54:27.535437 | instance | ok: [instance] => {
2026-09-25 14:54:27.535652 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-25 14:54:27.535702 | instance | }
2026-09-25 14:54:27.536137 | instance |
2026-09-25 14:54:27.536183 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-25 14:54:27.536191 | instance | Friday 25 September 2026  14:54:27 +0000 (0:00:00.048)       0:00:20.900 ******
2026-09-25 14:54:28.360894 | instance | changed: [instance]
2026-09-25 14:54:28.361565 | instance |
2026-09-25 14:54:28.361622 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-25 14:54:28.361634 | instance | Friday 25 September 2026  14:54:28 +0000 (0:00:00.824)       0:00:21.725 ******
2026-09-25 14:54:32.690062 | instance | changed: [instance]
2026-09-25 14:54:32.690137 | instance |
2026-09-25 14:54:32.690497 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-25 14:54:32.690547 | instance | Friday 25 September 2026  14:54:32 +0000 (0:00:04.329)       0:00:26.054 ******
2026-09-25 14:54:33.845803 | instance | ok: [instance]
2026-09-25 14:54:33.845914 | instance |
2026-09-25 14:54:33.846248 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-25 14:54:33.846295 | instance | Friday 25 September 2026  14:54:33 +0000 (0:00:01.155)       0:00:27.210 ******
2026-09-25 14:54:34.216080 | instance | changed: [instance]
2026-09-25 14:54:34.216555 | instance |
2026-09-25 14:54:34.216577 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-25 14:54:34.216585 | instance | Friday 25 September 2026  14:54:34 +0000 (0:00:00.370)       0:00:27.581 ******
2026-09-25 14:54:34.710166 | instance | changed: [instance]
2026-09-25 14:54:34.710235 | instance |
2026-09-25 14:54:34.710467 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-25 14:54:34.710616 | instance | Friday 25 September 2026  14:54:34 +0000 (0:00:00.494)       0:00:28.075 ******
2026-09-25 14:54:35.338450 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-25 14:54:35.339655 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-25 14:54:35.339725 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-25 14:54:35.339733 | instance |
2026-09-25 14:54:35.339740 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-25 14:54:35.339752 | instance | Friday 25 September 2026  14:54:35 +0000 (0:00:00.627)       0:00:28.702 ******
2026-09-25 14:54:35.747041 | instance | changed: [instance]
2026-09-25 14:54:35.747098 | instance |
2026-09-25 14:54:35.747360 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-25 14:54:35.747394 | instance | Friday 25 September 2026  14:54:35 +0000 (0:00:00.409)       0:00:29.112 ******
2026-09-25 14:54:36.214204 | instance | changed: [instance]
2026-09-25 14:54:36.214278 | instance |
2026-09-25 14:54:36.215088 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-25 14:54:36.215147 | instance | Friday 25 September 2026  14:54:36 +0000 (0:00:00.439)       0:00:29.552 ******
2026-09-25 14:54:36.215155 | instance |
2026-09-25 14:54:36.215162 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-25 14:54:36.215173 | instance | Friday 25 September 2026  14:54:36 +0000 (0:00:00.026)       0:00:29.579 ******
2026-09-25 14:54:36.956522 | instance | ok: [instance]
2026-09-25 14:54:36.957174 | instance |
2026-09-25 14:54:36.957226 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-25 14:54:36.957236 | instance | Friday 25 September 2026  14:54:36 +0000 (0:00:00.741)       0:00:30.320 ******
2026-09-25 14:54:37.921271 | instance | changed: [instance]
2026-09-25 14:54:37.921330 | instance |
2026-09-25 14:54:37.921341 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-25 14:54:37.921352 | instance | Friday 25 September 2026  14:54:37 +0000 (0:00:00.964)       0:00:31.285 ******
2026-09-25 14:54:38.533405 | instance | changed: [instance]
2026-09-25 14:54:38.533493 | instance |
2026-09-25 14:54:38.533833 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-25 14:54:38.533847 | instance | Friday 25 September 2026  14:54:38 +0000 (0:00:00.613)       0:00:31.898 ******
2026-09-25 14:54:38.600200 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-25 14:54:38.600313 | instance |
2026-09-25 14:54:38.600631 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-25 14:54:38.600853 | instance | Friday 25 September 2026  14:54:38 +0000 (0:00:00.066)       0:00:31.965 ******
2026-09-25 14:54:44.195023 | instance | changed: [instance]
2026-09-25 14:54:44.195727 | instance |
2026-09-25 14:54:44.195953 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-25 14:54:44.195975 | instance | Friday 25 September 2026  14:54:44 +0000 (0:00:05.593)       0:00:37.559 ******
2026-09-25 14:54:44.909107 | instance | ok: [instance] => (item=chronyd)
2026-09-25 14:54:44.909349 | instance | ok: [instance] => (item=sshd)
2026-09-25 14:54:44.909593 | instance |
2026-09-25 14:54:44.909894 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-25 14:54:44.910139 | instance | Friday 25 September 2026  14:54:44 +0000 (0:00:00.713)       0:00:38.273 ******
2026-09-25 14:54:45.236005 | instance | changed: [instance]
2026-09-25 14:54:45.236067 | instance |
2026-09-25 14:54:45.236187 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-25 14:54:45.236299 | instance | Friday 25 September 2026  14:54:45 +0000 (0:00:00.327)       0:00:38.600 ******
2026-09-25 14:54:45.471307 | instance | ok: [instance]
2026-09-25 14:54:45.471640 | instance |
2026-09-25 14:54:45.472010 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-25 14:54:45.472370 | instance | Friday 25 September 2026  14:54:45 +0000 (0:00:00.234)       0:00:38.835 ******
2026-09-25 14:54:45.931224 | instance | changed: [instance]
2026-09-25 14:54:45.931300 | instance |
2026-09-25 14:54:45.931439 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-25 14:54:45.931560 | instance | Friday 25 September 2026  14:54:45 +0000 (0:00:00.460)       0:00:39.295 ******
2026-09-25 14:54:46.316476 | instance | changed: [instance]
2026-09-25 14:54:46.316558 | instance |
2026-09-25 14:54:46.316567 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-25 14:54:46.316574 | instance | Friday 25 September 2026  14:54:46 +0000 (0:00:00.384)       0:00:39.680 ******
2026-09-25 14:54:46.440218 | instance | ok: [instance]
2026-09-25 14:54:46.440327 | instance |
2026-09-25 14:54:46.440564 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-25 14:54:46.440843 | instance | Friday 25 September 2026  14:54:46 +0000 (0:00:00.123)       0:00:39.804 ******
2026-09-25 14:54:46.677534 | instance | ok: [instance] => (item=instance)
2026-09-25 14:54:46.677833 | instance |
2026-09-25 14:54:46.678083 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-25 14:54:46.678323 | instance | Friday 25 September 2026  14:54:46 +0000 (0:00:00.237)       0:00:40.041 ******
2026-09-25 14:54:46.728261 | instance | ok: [instance]
2026-09-25 14:54:46.728370 | instance |
2026-09-25 14:54:46.728660 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-25 14:54:46.728925 | instance | Friday 25 September 2026  14:54:46 +0000 (0:00:00.051)       0:00:40.092 ******
2026-09-25 14:54:46.826532 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-25 14:54:46.826704 | instance |
2026-09-25 14:54:46.826965 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-25 14:54:46.827203 | instance | Friday 25 September 2026  14:54:46 +0000 (0:00:00.097)       0:00:40.190 ******
2026-09-25 14:54:47.192726 | instance | changed: [instance]
2026-09-25 14:54:47.192953 | instance |
2026-09-25 14:54:47.193158 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-25 14:54:47.193390 | instance | Friday 25 September 2026  14:54:47 +0000 (0:00:00.366)       0:00:40.557 ******
2026-09-25 14:54:47.929693 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-25 14:54:47.929946 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-25 14:54:47.930199 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-25 14:54:47.930454 | instance |
2026-09-25 14:54:47.930775 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-25 14:54:47.931023 | instance | Friday 25 September 2026  14:54:47 +0000 (0:00:00.736)       0:00:41.294 ******
2026-09-25 14:55:04.271992 | instance | fatal: [instance]: FAILED! => {"changed": false, "cmd": ["cephadm", "bootstrap", "--fsid", "4837cbf8-4f90-4300-b3f6-726c9b9f89b4", "--mon-ip", "162.253.55.166", "--cluster-network", "0.0.0.0/0", "--ssh-user", "cephadm", "--config", "/tmp/ceph_gj6t7qeu.conf", "--skip-monitoring-stack"], "delta": "0:00:16.017530", "end": "2026-09-25 14:55:04.242302", "msg": "non-zero return code", "rc": 1, "start": "2026-09-25 14:54:48.224772", "stderr": "Specifying an fsid for your cluster offers no advantages and may increase the likelihood of fsid conflicts.\nThe cluster CIDR network 0.0.0.0/0 is not configured locally.\nError: Failed command: /usr/bin/docker pull quay.io/ceph/ceph:v18\nERROR: Failed command: /usr/bin/docker pull quay.io/ceph/ceph:v18", "stderr_lines": ["Specifying an fsid for your cluster offers no advantages and may increase the likelihood of fsid conflicts.", "The cluster CIDR network 0.0.0.0/0 is not configured locally.", "Error: Failed command: /usr/bin/docker pull quay.io/ceph/ceph:v18", "ERROR: Failed command: /usr/bin/docker pull quay.io/ceph/ceph:v18"], "stdout": "Creating directory /etc/ceph for ceph.conf\nVerifying ssh connectivity using standard pubkey authentication ...\nAdding key to cephadm@localhost authorized_keys...\nVerifying podman|docker is present...\nVerifying lvm2 is present...\nVerifying time synchronization is in place...\nUnit chrony.service is enabled and running\nRepeating the final host check...\ndocker (/usr/bin/docker) is present\nsystemctl is present\nlvcreate is present\nUnit chrony.service is enabled and running\nHost looks OK\nCluster fsid: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4\nVerifying IP 162.253.55.166 port 3300 ...\nVerifying IP 162.253.55.166 port 6789 ...\nMon IP `162.253.55.166` is in CIDR network `162.253.52.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.52.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.53.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.53.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.54.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.54.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.55.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.55.0/24`\nMon IP `162.253.55.166` is in CIDR network `162.253.55.1/32`\nMon IP `162.253.55.166` is in CIDR network `162.253.55.1/32`\nMon IP `162.253.55.166` is in CIDR network `162.253.55.103/32`\nMon IP `162.253.55.166` is in CIDR network `162.253.55.103/32`\nMon IP `162.253.55.166` is in CIDR network `199.19.212.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.19.212.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.19.213.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.19.213.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.19.214.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.19.214.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.19.215.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.19.215.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.204.45.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.204.45.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.204.46.0/24`\nMon IP `162.253.55.166` is in CIDR network `199.204.46.0/24`\nPulling container image quay.io/ceph/ceph:v18...\nNon-zero exit code 1 from /usr/bin/docker pull quay.io/ceph/ceph:v18\n/usr/bin/docker: stderr Error response from daemon: Head \"https://quay.io/v2/ceph/ceph/manifests/v18\": Get \"https://quay.io/v2/auth?scope=repository%3Aceph%2Fceph%3Apull&service=quay.io\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)\n\n\n\t***************\n\tCephadm hit an issue during cluster installation. Current cluster files will NOT BE DELETED automatically to change\n\tthis behaviour you can pass the --cleanup-on-failure. To remove this broken cluster manually please run:\n\n\t   > cephadm rm-cluster --force --fsid 4837cbf8-4f90-4300-b3f6-726c9b9f89b4\n\n\tin case of any previous broken installation user must use the rm-cluster command to delete the broken cluster:\n\n\t   > cephadm rm-cluster --force --zap-osds --fsid <fsid>\n\n\tfor more information please refer to https://docs.ceph.com/en/latest/cephadm/operations/#purging-a-cluster\n\t***************", "stdout_lines": ["Creating directory /etc/ceph for ceph.conf", "Verifying ssh connectivity using standard pubkey authentication ...", "Adding key to cephadm@localhost authorized_keys...", "Verifying podman|docker is present...", "Verifying lvm2 is present...", "Verifying time synchronization is in place...", "Unit chrony.service is enabled and running", "Repeating the final host check...", "docker (/usr/bin/docker) is present", "systemctl is present", "lvcreate is present", "Unit chrony.service is enabled and running", "Host looks OK", "Cluster fsid: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4", "Verifying IP 162.253.55.166 port 3300 ...", "Verifying IP 162.253.55.166 port 6789 ...", "Mon IP `162.253.55.166` is in CIDR network `162.253.52.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.52.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.53.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.53.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.54.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.54.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.55.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.55.0/24`", "Mon IP `162.253.55.166` is in CIDR network `162.253.55.1/32`", "Mon IP `162.253.55.166` is in CIDR network `162.253.55.1/32`", "Mon IP `162.253.55.166` is in CIDR network `162.253.55.103/32`", "Mon IP `162.253.55.166` is in CIDR network `162.253.55.103/32`", "Mon IP `162.253.55.166` is in CIDR network `199.19.212.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.19.212.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.19.213.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.19.213.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.19.214.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.19.214.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.19.215.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.19.215.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.204.45.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.204.45.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.204.46.0/24`", "Mon IP `162.253.55.166` is in CIDR network `199.204.46.0/24`", "Pulling container image quay.io/ceph/ceph:v18...", "Non-zero exit code 1 from /usr/bin/docker pull quay.io/ceph/ceph:v18", "/usr/bin/docker: stderr Error response from daemon: Head \"https://quay.io/v2/ceph/ceph/manifests/v18\": Get \"https://quay.io/v2/auth?scope=repository%3Aceph%2Fceph%3Apull&service=quay.io\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)", "", "", "\t***************", "\tCephadm hit an issue during cluster installation. Current cluster files will NOT BE DELETED automatically to change", "\tthis behaviour you can pass the --cleanup-on-failure. To remove this broken cluster manually please run:", "", "\t   > cephadm rm-cluster --force --fsid 4837cbf8-4f90-4300-b3f6-726c9b9f89b4", "", "\tin case of any previous broken installation user must use the rm-cluster command to delete the broken cluster:", "", "\t   > cephadm rm-cluster --force --zap-osds --fsid <fsid>", "", "\tfor more information please refer to https://docs.ceph.com/en/latest/cephadm/operations/#purging-a-cluster", "\t***************"]}
2026-09-25 14:55:04.282426 | instance |
2026-09-25 14:55:04.282548 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-25 14:55:04.282647 | instance | Friday 25 September 2026  14:55:04 +0000 (0:00:16.353)       0:00:57.647 ******
2026-09-25 14:55:04.519648 | instance | changed: [instance]
2026-09-25 14:55:04.519686 | instance |
2026-09-25 14:55:04.519692 | instance | PLAY RECAP *********************************************************************
2026-09-25 14:55:04.519697 | instance | instance                   : ok=48   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-25 14:55:04.519702 | instance |
2026-09-25 14:55:04.519706 | instance | Friday 25 September 2026  14:55:04 +0000 (0:00:00.230)       0:00:57.878 ******
2026-09-25 14:55:04.519711 | instance | ===============================================================================
2026-09-25 14:55:04.519715 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 16.35s
2026-09-25 14:55:04.519718 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.97s
2026-09-25 14:55:04.519722 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.59s
2026-09-25 14:55:04.519726 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.33s
2026-09-25 14:55:04.519730 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.86s
2026-09-25 14:55:04.519734 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.68s
2026-09-25 14:55:04.519738 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.36s
2026-09-25 14:55:04.519742 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.16s
2026-09-25 14:55:04.519746 | instance | Gathering Facts --------------------------------------------------------- 1.06s
2026-09-25 14:55:04.519750 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-09-25 14:55:04.519754 | instance | Gathering Facts --------------------------------------------------------- 0.99s
2026-09-25 14:55:04.519757 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.96s
2026-09-25 14:55:04.519761 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.93s
2026-09-25 14:55:04.519765 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.82s
2026-09-25 14:55:04.519769 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.74s
2026-09-25 14:55:04.519773 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.72s
2026-09-25 14:55:04.519776 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.71s
2026-09-25 14:55:04.519780 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.71s
2026-09-25 14:55:04.519784 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.69s
2026-09-25 14:55:04.519792 | instance | vexxhost.containers.docker : Create folders for configuration ----------- 0.63s
2026-09-25 14:55:04.626653 | 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-25 14:55:04.626950 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-25 14:55:04.627142 | 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-25 14:55:05.131638 | instance | ERROR
2026-09-25 14:55:05.132083 | instance | {
2026-09-25 14:55:05.132123 | instance |   "delta": "0:01:49.915476",
2026-09-25 14:55:05.132147 | instance |   "end": "2026-09-25 14:55:04.702973",
2026-09-25 14:55:05.132169 | instance |   "msg": "non-zero return code",
2026-09-25 14:55:05.132190 | instance |   "rc": 2,
2026-09-25 14:55:05.132211 | instance |   "start": "2026-09-25 14:53:14.787497"
2026-09-25 14:55:05.132233 | instance | }
failure
2026-09-25 14:55:05.137511 | 
2026-09-25 14:55:05.137591 | PLAY RECAP
2026-09-25 14:55:05.137644 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-25 14:55:05.137669 | 
2026-09-25 14:55:05.252333 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-25 14:55:05.261584 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-25 14:55:05.830808 | 
2026-09-25 14:55:05.830934 | PLAY [all]
2026-09-25 14:55:05.843673 | 
2026-09-25 14:55:05.843779 | TASK [gather-host-logs : creating directory for system status]
2026-09-25 14:55:06.219482 | instance | changed
2026-09-25 14:55:06.229151 | 
2026-09-25 14:55:06.229323 | TASK [gather-host-logs : Get logs for each host]
2026-09-25 14:55:06.594984 | instance | + systemd-cgls --full --all --no-pager
2026-09-25 14:55:06.607529 | instance | + ip addr
2026-09-25 14:55:06.610250 | instance | + ip route
2026-09-25 14:55:06.613180 | instance | + lsblk
2026-09-25 14:55:06.618672 | instance | + mount
2026-09-25 14:55:06.621338 | instance | + docker images
2026-09-25 14:55:06.639366 | instance | + brctl show
2026-09-25 14:55:06.639951 | instance | /bin/bash: line 8: brctl: command not found
2026-09-25 14:55:06.640210 | instance | + ip neigh
2026-09-25 14:55:06.643431 | instance | + bridge fdb show
2026-09-25 14:55:06.646836 | instance | + ps aux --sort=-%mem
2026-09-25 14:55:06.659063 | instance | + dpkg -l
2026-09-25 14:55:06.670279 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-25 14:55:06.670768 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-25 14:55:06.692829 | instance | + '[' '!' -z '' ']'
2026-09-25 14:55:06.775552 | instance | ok: Runtime: 0:00:00.102686
2026-09-25 14:55:06.780611 | 
2026-09-25 14:55:06.780672 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-25 14:55:07.490175 | instance | changed:
2026-09-25 14:55:07.491117 | instance | created directory /var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/work/logs/instance
2026-09-25 14:55:07.491189 | instance | cd+++++++++ system/
2026-09-25 14:55:07.491236 | instance | >f+++++++++ system/brctl-show.txt
2026-09-25 14:55:07.491280 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-25 14:55:07.491321 | instance | >f+++++++++ system/docker-images.txt
2026-09-25 14:55:07.491370 | instance | >f+++++++++ system/ip-addr.txt
2026-09-25 14:55:07.491413 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-25 14:55:07.491454 | instance | >f+++++++++ system/ip-route.txt
2026-09-25 14:55:07.491498 | instance | >f+++++++++ system/lsblk.txt
2026-09-25 14:55:07.491563 | instance | >f+++++++++ system/mount.txt
2026-09-25 14:55:07.491610 | instance | >f+++++++++ system/packages.txt
2026-09-25 14:55:07.491651 | instance | >f+++++++++ system/ps.txt
2026-09-25 14:55:07.491689 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-25 14:55:07.510088 | 
2026-09-25 14:55:07.510265 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-25 14:55:07.740613 | instance | changed: "values"
2026-09-25 14:55:07.906075 | instance | changed: "releases"
2026-09-25 14:55:07.923423 | 
2026-09-25 14:55:07.923614 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-25 14:55:08.183054 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-25 14:55:08.474486 | instance | ok: Runtime: 0:00:00.003478
2026-09-25 14:55:08.483829 | 
2026-09-25 14:55:08.483946 | TASK [helm-release-status : Downloads logs to executor]
2026-09-25 14:55:09.006513 | instance | changed:
2026-09-25 14:55:09.006777 | instance | cd+++++++++ helm/
2026-09-25 14:55:09.006834 | instance | cd+++++++++ helm/releases/
2026-09-25 14:55:09.006876 | instance | cd+++++++++ helm/values/
2026-09-25 14:55:09.028079 | 
2026-09-25 14:55:09.028172 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-25 14:55:09.254211 | instance | changed
2026-09-25 14:55:09.267354 | 
2026-09-25 14:55:09.267553 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-25 14:55:09.558146 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 14:55:09.560090 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 14:55:09.566087 | instance | environment: line 1: kubectl: command not found
2026-09-25 14:55:09.567185 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 14:55:09.569154 | instance | environment: line 1: kubectl: command not found
2026-09-25 14:55:09.571354 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 14:55:09.572135 | instance | environment: line 1: kubectl: command not found
2026-09-25 14:55:09.573933 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 14:55:09.575633 | instance | environment: line 1: kubectl: command not found
2026-09-25 14:55:09.577495 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 14:55:09.577619 | instance | environment: line 1: kubectl: command not found
2026-09-25 14:55:09.579388 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 14:55:09.814480 | instance | ok: Runtime: 0:00:00.030477
2026-09-25 14:55:09.824756 | 
2026-09-25 14:55:09.824884 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-25 14:55:10.053014 | instance | changed
2026-09-25 14:55:10.062431 | 
2026-09-25 14:55:10.062650 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-25 14:55:10.336753 | instance | environment: line 5: kubectl: command not found
2026-09-25 14:55:10.338327 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 14:55:10.338365 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 14:55:10.338678 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 14:55:10.607657 | instance | ok: Runtime: 0:00:00.009038
2026-09-25 14:55:10.613070 | 
2026-09-25 14:55:10.613137 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-25 14:55:11.133150 | instance | changed:
2026-09-25 14:55:11.133499 | instance | cd+++++++++ objects/
2026-09-25 14:55:11.133624 | instance | cd+++++++++ objects/cluster/
2026-09-25 14:55:11.133672 | instance | cd+++++++++ objects/namespaced/
2026-09-25 14:55:11.149928 | 
2026-09-25 14:55:11.150133 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-25 14:55:11.365270 | instance | changed
2026-09-25 14:55:11.374635 | 
2026-09-25 14:55:11.374762 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-25 14:55:11.592029 | instance | changed
2026-09-25 14:55:11.601043 | 
2026-09-25 14:55:11.601224 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-25 14:55:11.860218 | instance | environment: line 3: kubectl: command not found
2026-09-25 14:55:12.146936 | instance | ok: Runtime: 0:00:00.007962
2026-09-25 14:55:12.156284 | 
2026-09-25 14:55:12.156412 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-25 14:55:12.672952 | instance | changed:
2026-09-25 14:55:12.673438 | instance | cd+++++++++ pod-logs/
2026-09-25 14:55:12.673481 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-25 14:55:12.683334 | 
2026-09-25 14:55:12.683391 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-25 14:55:12.896962 | instance | changed
2026-09-25 14:55:12.906089 | 
2026-09-25 14:55:12.906214 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-25 14:55:13.161987 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-25 14:55:13.453398 | instance | ok: Runtime: 0:00:00.049690
2026-09-25 14:55:13.462028 | 
2026-09-25 14:55:13.462150 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-25 14:55:13.704430 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-25 14:55:13.753873 | instance | ceph-mgr endpoints:
2026-09-25 14:55:14.007046 | instance | ok: Runtime: 0:00:00.056739
2026-09-25 14:55:14.013129 | 
2026-09-25 14:55:14.013213 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-25 14:55:14.235670 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-25 14:55:14.550210 | instance | ok: Runtime: 0:00:00.035060
2026-09-25 14:55:14.559227 | 
2026-09-25 14:55:14.559362 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-25 14:55:15.012111 | instance | changed: cd+++++++++ prometheus/
2026-09-25 14:55:15.019875 | 
2026-09-25 14:55:15.019935 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-25 14:55:15.235891 | instance | changed
2026-09-25 14:55:15.244466 | 
2026-09-25 14:55:15.244632 | TASK [gather-selenium-data : Get selenium data]
2026-09-25 14:55:15.525893 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-25 14:55:15.527344 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-25 14:55:15.790910 | instance | ERROR
2026-09-25 14:55:15.791196 | instance | {
2026-09-25 14:55:15.791256 | instance |   "delta": "0:00:00.006022",
2026-09-25 14:55:15.791297 | instance |   "end": "2026-09-25 14:55:15.527707",
2026-09-25 14:55:15.791333 | instance |   "msg": "non-zero return code",
2026-09-25 14:55:15.791370 | instance |   "rc": 1,
2026-09-25 14:55:15.791405 | instance |   "start": "2026-09-25 14:55:15.521685"
2026-09-25 14:55:15.791441 | instance | }
2026-09-25 14:55:15.791490 | instance | ERROR: Ignoring Errors
2026-09-25 14:55:15.800050 | 
2026-09-25 14:55:15.800169 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-25 14:55:16.349565 | instance | changed: cd+++++++++ selenium/
2026-09-25 14:55:16.359876 | 
2026-09-25 14:55:16.359974 | PLAY RECAP
2026-09-25 14:55:16.360066 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-25 14:55:16.360109 | 
2026-09-25 14:55:16.482721 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-25 14:55:16.486692 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-25 14:55:17.018767 | 
2026-09-25 14:55:17.018876 | PLAY [all]
2026-09-25 14:55:17.029300 | 
2026-09-25 14:55:17.029370 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-25 14:55:17.083155 | instance | skipping: Conditional result was False
2026-09-25 14:55:17.089488 | 
2026-09-25 14:55:17.089629 | TASK [fetch-output : Set log path for single node]
2026-09-25 14:55:17.141623 | instance | ok
2026-09-25 14:55:17.150141 | 
2026-09-25 14:55:17.150277 | LOOP [fetch-output : Ensure local output dirs]
2026-09-25 14:55:17.541011 | instance -> localhost | ok: "/var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/work/logs"
2026-09-25 14:55:17.758261 | instance -> localhost | changed: "/var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/work/artifacts"
2026-09-25 14:55:18.005414 | instance -> localhost | changed: "/var/lib/zuul/builds/bb864b7642fb4fe6b2f14842673ab79b/work/docs"
2026-09-25 14:55:18.020403 | 
2026-09-25 14:55:18.020539 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-25 14:55:18.702018 | instance | changed: .d..t...... ./
2026-09-25 14:55:18.702306 | instance | changed: All items complete
2026-09-25 14:55:18.702358 | 
2026-09-25 14:55:19.168396 | instance | changed: .d..t...... ./
2026-09-25 14:55:19.615819 | instance | changed: .d..t...... ./
2026-09-25 14:55:19.636972 | 
2026-09-25 14:55:19.637152 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-25 14:55:20.041588 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006596
2026-09-25 14:55:20.290702 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006859
2026-09-25 14:55:20.303749 | 
2026-09-25 14:55:20.303879 | PLAY [all]
2026-09-25 14:55:20.310695 | 
2026-09-25 14:55:20.310767 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-25 14:55:20.726634 | instance | changed
2026-09-25 14:55:20.733027 | 
2026-09-25 14:55:20.733084 | PLAY RECAP
2026-09-25 14:55:20.733135 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-25 14:55:20.733159 | 
2026-09-25 14:55:20.910787 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-25 14:55:20.928082 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-25 14:55:21.473453 | 
2026-09-25 14:55:21.473590 | PLAY [localhost]
2026-09-25 14:55:21.483358 | 
2026-09-25 14:55:21.483433 | TASK [Generate Zuul manifest]
2026-09-25 14:55:21.500369 | localhost | ok
2026-09-25 14:55:21.515736 | 
2026-09-25 14:55:21.515821 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-25 14:55:21.933147 | localhost | changed
2026-09-25 14:55:21.953407 | 
2026-09-25 14:55:21.953652 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-25 14:55:21.985398 | localhost | ok
2026-09-25 14:55:21.997852 | 
2026-09-25 14:55:21.997980 | TASK [Upload logs]
2026-09-25 14:55:22.019951 | localhost | ok
2026-09-25 14:55:22.088094 | 
2026-09-25 14:55:22.088216 | TASK [Set zuul-log-path fact]
2026-09-25 14:55:22.105546 | localhost | ok
2026-09-25 14:55:22.118229 | 
2026-09-25 14:55:22.118303 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-25 14:55:22.145481 | localhost | ok
2026-09-25 14:55:22.153237 | 
2026-09-25 14:55:22.153322 | TASK [upload-logs : Create log directories]
2026-09-25 14:55:22.564850 | localhost | changed
2026-09-25 14:55:22.569312 | 
2026-09-25 14:55:22.569377 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-25 14:55:22.994559 | localhost -> localhost | ok: Runtime: 0:00:00.005884
2026-09-25 14:55:23.004063 | 
2026-09-25 14:55:23.004196 | TASK [upload-logs : Upload logs to log server]
2026-09-25 14:55:23.558581 | localhost | Output suppressed because no_log was given
2026-09-25 14:55:23.591384 | 
2026-09-25 14:55:23.591514 | LOOP [upload-logs : Compress console log and json output]
2026-09-25 14:55:23.655351 | localhost | skipping: Conditional result was False
2026-09-25 14:55:23.664208 | localhost | skipping: Conditional result was False
2026-09-25 14:55:23.674751 | 
2026-09-25 14:55:23.674937 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-25 14:55:23.718568 | localhost | skipping: Conditional result was False
2026-09-25 14:55:23.719050 | 
2026-09-25 14:55:23.722368 | localhost | skipping: Conditional result was False
2026-09-25 14:55:23.738131 | 
2026-09-25 14:55:23.738317 | LOOP [upload-logs : Upload console log and json output]