2026-08-22 18:35:43.627000 | Job console starting
2026-08-22 18:35:43.637780 | Updating git repos
2026-08-22 18:35:46.437640 | Cloning repos into workspace
2026-08-22 18:36:01.286405 | Restoring repo states
2026-08-22 18:36:11.191227 | Merging changes
2026-08-22 18:36:12.161550 | Checking out repos
2026-08-22 18:36:13.646831 | Preparing playbooks
2026-08-22 18:36:15.223600 | Running Ansible setup
2026-08-22 18:36:19.450857 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 18:36:20.093153 | 
2026-08-22 18:36:20.131052 | PLAY [localhost]
2026-08-22 18:36:20.145033 | 
2026-08-22 18:36:20.145200 | TASK [Gathering Facts]
2026-08-22 18:36:21.056370 | localhost | ok
2026-08-22 18:36:21.067620 | 
2026-08-22 18:36:21.067727 | TASK [Setup log path fact]
2026-08-22 18:36:21.084864 | localhost | ok
2026-08-22 18:36:21.099010 | 
2026-08-22 18:36:21.099108 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 18:36:21.126707 | localhost | ok
2026-08-22 18:36:21.135655 | 
2026-08-22 18:36:21.135750 | TASK [emit-job-header : Print job information]
2026-08-22 18:36:21.176643 | # Job Information
2026-08-22 18:36:21.176835 | Ansible Version: 2.16.18
2026-08-22 18:36:21.176886 | Job: atmosphere-molecule-csi-rbd
2026-08-22 18:36:21.176919 | Pipeline: check
2026-08-22 18:36:21.176987 | Executor: 2d72f0692154
2026-08-22 18:36:21.177034 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-08-22 18:36:21.177077 | Event ID: c7f26150-9e57-11f1-9823-fb8b53a5ae6a
2026-08-22 18:36:21.181680 | 
2026-08-22 18:36:21.181828 | LOOP [emit-job-header : Print node information]
2026-08-22 18:36:21.282153 | localhost | ok:
2026-08-22 18:36:21.282366 | localhost | # Node Information
2026-08-22 18:36:21.282410 | localhost | Inventory Hostname: instance
2026-08-22 18:36:21.282442 | localhost | Hostname: np0000190701
2026-08-22 18:36:21.282471 | localhost | Username: zuul
2026-08-22 18:36:21.282506 | localhost | Distro: Ubuntu 22.04
2026-08-22 18:36:21.282544 | localhost | Provider: yul1
2026-08-22 18:36:21.282598 | localhost | Region: ca-ymq-1
2026-08-22 18:36:21.282637 | localhost | Label: ubuntu-jammy
2026-08-22 18:36:21.282668 | localhost | Product Name: OpenStack Nova
2026-08-22 18:36:21.282698 | localhost | Interface IP: 199.204.45.74
2026-08-22 18:36:21.303928 | 
2026-08-22 18:36:21.304068 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 18:36:21.681080 | localhost -> localhost | changed
2026-08-22 18:36:21.685693 | 
2026-08-22 18:36:21.685764 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 18:36:22.897398 | localhost -> localhost | changed
2026-08-22 18:36:22.910719 | 
2026-08-22 18:36:22.910833 | PLAY [all]
2026-08-22 18:36:22.923059 | 
2026-08-22 18:36:22.923182 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 18:36:23.391595 | instance -> localhost | ok
2026-08-22 18:36:23.397777 | 
2026-08-22 18:36:23.397842 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 18:36:23.429706 | instance | ok
2026-08-22 18:36:23.445454 | instance | included: /var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 18:36:23.450767 | 
2026-08-22 18:36:23.450825 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 18:36:24.621519 | instance -> localhost | Generating public/private rsa key pair.
2026-08-22 18:36:24.621660 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/work/654eb65c39624984bce6ff52f1d6e7a0_id_rsa
2026-08-22 18:36:24.621690 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/work/654eb65c39624984bce6ff52f1d6e7a0_id_rsa.pub
2026-08-22 18:36:24.621713 | instance -> localhost | The key fingerprint is:
2026-08-22 18:36:24.621734 | instance -> localhost | SHA256:GW/FPQxc7UTPVUB9BjXpYNNt61gPiB3vWynti1T3pdk zuul-build-sshkey
2026-08-22 18:36:24.621763 | instance -> localhost | The key's randomart image is:
2026-08-22 18:36:24.621785 | instance -> localhost | +---[RSA 3072]----+
2026-08-22 18:36:24.621810 | instance -> localhost | |           ..o*OO|
2026-08-22 18:36:24.621832 | instance -> localhost | |           .oB +%|
2026-08-22 18:36:24.621853 | instance -> localhost | |        .  o++B==|
2026-08-22 18:36:24.621873 | instance -> localhost | |         +..o o=.|
2026-08-22 18:36:24.621893 | instance -> localhost | |        S o  .=o=|
2026-08-22 18:36:24.621913 | instance -> localhost | |         .   ooB*|
2026-08-22 18:36:24.621932 | instance -> localhost | |             .=oE|
2026-08-22 18:36:24.621952 | instance -> localhost | |            . o. |
2026-08-22 18:36:24.621974 | instance -> localhost | |             . ..|
2026-08-22 18:36:24.621994 | instance -> localhost | +----[SHA256]-----+
2026-08-22 18:36:24.622090 | instance -> localhost | ok: Runtime: 0:00:00.755495
2026-08-22 18:36:24.631496 | 
2026-08-22 18:36:24.631567 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 18:36:24.664466 | instance | ok
2026-08-22 18:36:24.672724 | instance | included: /var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 18:36:24.679971 | 
2026-08-22 18:36:24.680044 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 18:36:24.704193 | instance | skipping: Conditional result was False
2026-08-22 18:36:24.714066 | 
2026-08-22 18:36:24.714171 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 18:36:25.157928 | instance | changed
2026-08-22 18:36:25.163848 | 
2026-08-22 18:36:25.163910 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 18:36:25.337905 | instance | ok
2026-08-22 18:36:25.342655 | 
2026-08-22 18:36:25.342716 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 18:36:25.790465 | instance | changed
2026-08-22 18:36:25.805350 | 
2026-08-22 18:36:25.805696 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 18:36:26.254104 | instance | changed
2026-08-22 18:36:26.281277 | 
2026-08-22 18:36:26.281352 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 18:36:26.313234 | instance | skipping: Conditional result was False
2026-08-22 18:36:26.323207 | 
2026-08-22 18:36:26.323315 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 18:36:26.724393 | instance -> localhost | changed
2026-08-22 18:36:26.739128 | 
2026-08-22 18:36:26.739203 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 18:36:27.036187 | instance -> localhost | Identity added: /var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/work/654eb65c39624984bce6ff52f1d6e7a0_id_rsa (zuul-build-sshkey)
2026-08-22 18:36:27.036397 | instance -> localhost | ok: Runtime: 0:00:00.013522
2026-08-22 18:36:27.041431 | 
2026-08-22 18:36:27.041493 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 18:36:27.348533 | instance | ok
2026-08-22 18:36:27.352909 | 
2026-08-22 18:36:27.353018 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 18:36:27.376584 | instance | skipping: Conditional result was False
2026-08-22 18:36:27.392274 | 
2026-08-22 18:36:27.392483 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 18:36:27.701288 | instance | ok
2026-08-22 18:36:27.706587 | 
2026-08-22 18:36:27.706645 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 18:36:30.987047 | instance | Output suppressed because no_log was given
2026-08-22 18:36:30.995334 | 
2026-08-22 18:36:30.995422 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 18:36:31.178655 | instance | ok: "logs"
2026-08-22 18:36:31.178976 | instance | ok: All items complete
2026-08-22 18:36:31.179028 | 
2026-08-22 18:36:31.338481 | instance | ok: "artifacts"
2026-08-22 18:36:31.519511 | instance | ok: "docs"
2026-08-22 18:36:31.537332 | 
2026-08-22 18:36:31.537602 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 18:36:31.760229 | instance | changed: "logs"
2026-08-22 18:36:31.911205 | instance | changed: "artifacts"
2026-08-22 18:36:32.074251 | instance | changed: "docs"
2026-08-22 18:36:32.093189 | 
2026-08-22 18:36:32.093277 | PLAY RECAP
2026-08-22 18:36:32.093335 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 18:36:32.093378 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:36:32.093411 | 
2026-08-22 18:36:32.199074 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 18:36:32.203595 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 18:36:32.771623 | 
2026-08-22 18:36:32.771751 | PLAY [all]
2026-08-22 18:36:32.782499 | 
2026-08-22 18:36:32.782631 | TASK [setup-uv : Extract archive]
2026-08-22 18:36:34.993897 | instance | changed
2026-08-22 18:36:35.002010 | 
2026-08-22 18:36:35.002131 | TASK [setup-uv : Print version]
2026-08-22 18:36:35.344403 | instance | uv 0.8.13
2026-08-22 18:36:35.614444 | instance | ok: Runtime: 0:00:00.012296
2026-08-22 18:36:35.626343 | 
2026-08-22 18:36:35.626426 | TASK [Install Ansible collection siblings]
2026-08-22 18:36:44.694688 | instance | changed:
2026-08-22 18:36:44.694970 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-22 18:36:44.695002 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-22 18:36:44.695024 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-22 18:36:44.695044 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-22 18:36:44.695064 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-22 18:36:44.695085 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-22 18:36:44.695107 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-22 18:36:44.695129 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-22 18:36:44.695148 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-22 18:36:44.695172 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-22 18:36:44.695192 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-22 18:36:44.695211 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-22 18:36:44.695229 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-08-22 18:36:44.695334 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-22 18:36:44.695443 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-08-22 18:36:44.695471 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-22 18:36:44.695491 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-22 18:36:44.695511 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-22 18:36:44.695530 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-22 18:36:44.695549 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-08-22 18:36:44.695568 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-08-22 18:36:44.695587 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-08-22 18:36:44.695607 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 18:36:44.695639 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-22 18:36:44.695658 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 18:36:44.695677 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 18:36:44.695695 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-22 18:36:44.695714 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-22 18:36:44.695733 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 18:36:44.695752 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-22 18:36:44.695770 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-22 18:36:44.695790 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 18:36:44.695809 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 18:36:44.695828 | instance | Checked out ansible.netcommon to v8.6.2
2026-08-22 18:36:44.695847 | 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-08-22 18:36:44.695872 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.695891 | instance | Process install dependency map
2026-08-22 18:36:44.695909 | instance | Starting collection install process
2026-08-22 18:36:44.695928 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-22 18:36:44.695947 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-22 18:36:44.695966 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-08-22 18:36:44.695985 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-22 18:36:44.696003 | instance | Creating virtual environment at: .venv
2026-08-22 18:36:44.696021 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 18:36:44.696040 | instance | Downloading setuptools (1.1MiB)
2026-08-22 18:36:44.696060 | instance | Downloading pygments (1.2MiB)
2026-08-22 18:36:44.696079 | instance | Downloading netaddr (2.2MiB)
2026-08-22 18:36:44.696098 | instance | Downloading cryptography (4.2MiB)
2026-08-22 18:36:44.696117 | instance | Downloading ansible-core (2.1MiB)
2026-08-22 18:36:44.696136 | instance | Downloading kubernetes (1.9MiB)
2026-08-22 18:36:44.696155 | instance | Downloading openstacksdk (1.7MiB)
2026-08-22 18:36:44.696173 | instance | Downloading rjsonnet (1.2MiB)
2026-08-22 18:36:44.696194 | instance |    Building pyperclip==1.9.0
2026-08-22 18:36:44.696216 | instance |  Downloading rjsonnet
2026-08-22 18:36:44.696237 | instance |  Downloading netaddr
2026-08-22 18:36:44.696262 | instance |  Downloading pygments
2026-08-22 18:36:44.696300 | instance |  Downloading cryptography
2026-08-22 18:36:44.696327 | instance |  Downloading setuptools
2026-08-22 18:36:44.696351 | instance |  Downloading kubernetes
2026-08-22 18:36:44.696371 | instance |  Downloading ansible-core
2026-08-22 18:36:44.696392 | instance |  Downloading openstacksdk
2026-08-22 18:36:44.696413 | instance |       Built pyperclip==1.9.0
2026-08-22 18:36:44.696434 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 18:36:44.696454 | instance | Installed 79 packages in 73ms
2026-08-22 18:36:44.696475 | instance | Checked out ansible.posix to 1.6.0
2026-08-22 18:36:44.696496 | 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-08-22 18:36:44.696517 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.696537 | instance | Process install dependency map
2026-08-22 18:36:44.696563 | instance | Starting collection install process
2026-08-22 18:36:44.696584 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-22 18:36:44.696604 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-22 18:36:44.696625 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-22 18:36:44.696646 | instance | Checked out ansible.utils to v6.1.0
2026-08-22 18:36:44.696666 | 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-08-22 18:36:44.696686 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.696707 | instance | Process install dependency map
2026-08-22 18:36:44.696727 | instance | Starting collection install process
2026-08-22 18:36:44.696747 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-22 18:36:44.696768 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-22 18:36:44.696791 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-22 18:36:44.696814 | instance | Checked out atmosphere.common to v0.6.0
2026-08-22 18:36:44.696835 | 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-08-22 18:36:44.696856 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.696877 | instance | Process install dependency map
2026-08-22 18:36:44.696898 | instance | Starting collection install process
2026-08-22 18:36:44.696918 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-22 18:36:44.696938 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-22 18:36:44.696958 | instance | atmosphere.common:0.6.0 was installed successfully
2026-08-22 18:36:44.696982 | instance | Checked out community.crypto to 2.2.3
2026-08-22 18:36:44.697025 | 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-08-22 18:36:44.697049 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.697069 | instance | Process install dependency map
2026-08-22 18:36:44.697090 | instance | Starting collection install process
2026-08-22 18:36:44.697110 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-22 18:36:44.697130 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-22 18:36:44.697151 | instance | community.crypto:2.2.3 was installed successfully
2026-08-22 18:36:44.697171 | instance | Checked out community.general to 7.3.0
2026-08-22 18:36:44.697192 | 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-08-22 18:36:44.697212 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.697232 | instance | Process install dependency map
2026-08-22 18:36:44.697252 | instance | Starting collection install process
2026-08-22 18:36:44.697273 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-22 18:36:44.697292 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-22 18:36:44.697312 | instance | community.general:7.3.0 was installed successfully
2026-08-22 18:36:44.697337 | instance | Checked out community.mysql to 3.6.0
2026-08-22 18:36:44.697359 | 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-08-22 18:36:44.697379 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.697399 | instance | Process install dependency map
2026-08-22 18:36:44.697419 | instance | Starting collection install process
2026-08-22 18:36:44.697440 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-22 18:36:44.697460 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-22 18:36:44.697480 | instance | community.mysql:3.6.0 was installed successfully
2026-08-22 18:36:44.697501 | instance | Checked out kubernetes.core to 2.4.0
2026-08-22 18:36:44.697521 | 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-08-22 18:36:44.697544 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.697564 | instance | Process install dependency map
2026-08-22 18:36:44.697587 | instance | Starting collection install process
2026-08-22 18:36:44.697608 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-22 18:36:44.697628 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-22 18:36:44.697648 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-22 18:36:44.697668 | instance | Checked out openstack.cloud to 1.7.0
2026-08-22 18:36:44.697687 | 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-08-22 18:36:44.697708 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.697727 | instance | Process install dependency map
2026-08-22 18:36:44.697748 | instance | Starting collection install process
2026-08-22 18:36:44.697768 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-22 18:36:44.697788 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-22 18:36:44.697806 | instance | openstack.cloud:1.7.0 was installed successfully
2026-08-22 18:36:44.697827 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-22 18:36:44.697847 | 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-08-22 18:36:44.697867 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.697887 | instance | Process install dependency map
2026-08-22 18:36:44.697906 | instance | Starting collection install process
2026-08-22 18:36:44.697926 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-22 18:36:44.697946 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-22 18:36:44.697965 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-22 18:36:44.697985 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-22 18:36:44.698004 | 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-08-22 18:36:44.698024 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.698047 | instance | Process install dependency map
2026-08-22 18:36:44.698067 | instance | Starting collection install process
2026-08-22 18:36:44.698086 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-22 18:36:44.698106 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-22 18:36:44.698126 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-22 18:36:44.698146 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-22 18:36:44.698166 | 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-08-22 18:36:44.698185 | instance | Starting galaxy collection install process
2026-08-22 18:36:44.698206 | instance | Process install dependency map
2026-08-22 18:36:44.698225 | instance | Starting collection install process
2026-08-22 18:36:44.698245 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-22 18:36:44.698264 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-22 18:36:44.698285 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-22 18:36:44.703650 | 
2026-08-22 18:36:44.703692 | PLAY RECAP
2026-08-22 18:36:44.703733 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:36:44.703754 | 
2026-08-22 18:36:44.809718 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 18:36:44.813393 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 18:36:45.405267 | 
2026-08-22 18:36:45.422463 | PLAY [all]
2026-08-22 18:36:45.438974 | 
2026-08-22 18:36:45.439090 | TASK [Install "jq" for log collection]
2026-08-22 18:36:55.169943 | instance | changed
2026-08-22 18:36:55.299308 | 
2026-08-22 18:36:55.299418 | PLAY RECAP
2026-08-22 18:36:55.299489 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:36:55.299575 | 
2026-08-22 18:36:55.394224 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 18:36:55.396529 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 18:36:56.002519 | 
2026-08-22 18:36:56.002887 | PLAY [all]
2026-08-22 18:36:56.013638 | 
2026-08-22 18:36:56.013733 | LOOP [Check inventory directories]
2026-08-22 18:36:56.345598 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-22 18:36:56.490344 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-22 18:36:56.658331 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-22 18:36:56.668605 | 
2026-08-22 18:36:56.668873 | LOOP [Copy inventory file for Zuul]
2026-08-22 18:36:57.462794 | instance | changed:
2026-08-22 18:36:57.462952 | instance | {
2026-08-22 18:36:57.462982 | instance |   "ansible_loop_var": "item",
2026-08-22 18:36:57.463006 | instance |   "changed": false,
2026-08-22 18:36:57.463028 | instance |   "failed": false,
2026-08-22 18:36:57.463050 | instance |   "invocation": {
2026-08-22 18:36:57.463070 | instance |     "module_args": {
2026-08-22 18:36:57.463090 | instance |       "checksum_algorithm": "sha1",
2026-08-22 18:36:57.463109 | instance |       "follow": true,
2026-08-22 18:36:57.463128 | instance |       "get_attributes": true,
2026-08-22 18:36:57.463148 | instance |       "get_checksum": true,
2026-08-22 18:36:57.463167 | instance |       "get_mime": true,
2026-08-22 18:36:57.463188 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-22 18:36:57.463209 | instance |     }
2026-08-22 18:36:57.463237 | instance |   },
2026-08-22 18:36:57.463256 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-22 18:36:57.463275 | instance |   "stat": {
2026-08-22 18:36:57.463293 | instance |     "atime": 1787423797.4045591,
2026-08-22 18:36:57.463312 | instance |     "attr_flags": "e",
2026-08-22 18:36:57.463331 | instance |     "attributes": [
2026-08-22 18:36:57.463350 | instance |       "extents"
2026-08-22 18:36:57.463368 | instance |     ],
2026-08-22 18:36:57.463387 | instance |     "block_size": 4096,
2026-08-22 18:36:57.463405 | instance |     "blocks": 8,
2026-08-22 18:36:57.463424 | instance |     "charset": "unknown",
2026-08-22 18:36:57.463443 | instance |     "ctime": 1787423796.416615,
2026-08-22 18:36:57.463462 | instance |     "dev": 64513,
2026-08-22 18:36:57.463480 | instance |     "device_type": 0,
2026-08-22 18:36:57.463498 | instance |     "executable": true,
2026-08-22 18:36:57.463516 | instance |     "exists": true,
2026-08-22 18:36:57.463534 | instance |     "gid": 1000,
2026-08-22 18:36:57.463552 | instance |     "gr_name": "zuul",
2026-08-22 18:36:57.463569 | instance |     "inode": 1048355,
2026-08-22 18:36:57.463587 | instance |     "isblk": false,
2026-08-22 18:36:57.463605 | instance |     "ischr": false,
2026-08-22 18:36:57.463623 | instance |     "isdir": true,
2026-08-22 18:36:57.463641 | instance |     "isfifo": false,
2026-08-22 18:36:57.463658 | instance |     "isgid": false,
2026-08-22 18:36:57.463676 | instance |     "islnk": false,
2026-08-22 18:36:57.463693 | instance |     "isreg": false,
2026-08-22 18:36:57.463711 | instance |     "issock": false,
2026-08-22 18:36:57.463729 | instance |     "isuid": false,
2026-08-22 18:36:57.463747 | instance |     "mimetype": "unknown",
2026-08-22 18:36:57.463764 | instance |     "mode": "0755",
2026-08-22 18:36:57.463782 | instance |     "mtime": 1787423796.416615,
2026-08-22 18:36:57.463799 | instance |     "nlink": 22,
2026-08-22 18:36:57.463817 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-22 18:36:57.463834 | instance |     "pw_name": "zuul",
2026-08-22 18:36:57.463853 | instance |     "readable": true,
2026-08-22 18:36:57.463872 | instance |     "rgrp": true,
2026-08-22 18:36:57.463890 | instance |     "roth": true,
2026-08-22 18:36:57.463908 | instance |     "rusr": true,
2026-08-22 18:36:57.463930 | instance |     "size": 4096,
2026-08-22 18:36:57.463980 | instance |     "uid": 1000,
2026-08-22 18:36:57.464000 | instance |     "version": "4204535743",
2026-08-22 18:36:57.464018 | instance |     "wgrp": false,
2026-08-22 18:36:57.464038 | instance |     "woth": false,
2026-08-22 18:36:57.464056 | instance |     "writeable": true,
2026-08-22 18:36:57.464074 | instance |     "wusr": true,
2026-08-22 18:36:57.464091 | instance |     "xgrp": true,
2026-08-22 18:36:57.464109 | instance |     "xoth": true,
2026-08-22 18:36:57.464126 | instance |     "xusr": true
2026-08-22 18:36:57.464144 | instance |   }
2026-08-22 18:36:57.464162 | instance | }
2026-08-22 18:36:57.464198 | instance | ok: All items complete
2026-08-22 18:36:57.464220 | 
2026-08-22 18:36:57.465503 | instance | skipping: Conditional result was False
2026-08-22 18:36:57.468453 | instance | skipping: Conditional result was False
2026-08-22 18:36:57.485821 | 
2026-08-22 18:36:57.485969 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 18:36:57.818495 | instance | changed: 1 replacements made
2026-08-22 18:36:57.820147 | instance | skipping: Conditional result was False
2026-08-22 18:36:57.824941 | instance | skipping: Conditional result was False
2026-08-22 18:36:57.839135 | 
2026-08-22 18:36:57.839264 | TASK [Run Molecule scenario]
2026-08-22 18:36:58.737791 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-22 18:36:59.113075 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 18:36:59.113141 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-22 18:37:10.387807 | instance | INFO     [csi > dependency] Executing
2026-08-22 18:37:10.387988 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-22 18:37:10.388323 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-22 18:37:10.388448 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 18:37:10.397540 | instance | INFO     [csi > cleanup] Executing
2026-08-22 18:37:10.397876 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 18:37:10.405135 | instance | INFO     [csi > destroy] Executing
2026-08-22 18:37:10.405174 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-22 18:37:10.405289 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-22 18:37:10.412718 | instance | INFO     [csi > syntax] Executing
2026-08-22 18:37:12.064182 | instance |
2026-08-22 18:37:12.064449 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 18:37:12.149198 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-22 18:37:12.162817 | instance | INFO     [csi > create] Executing
2026-08-22 18:37:12.164616 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 18:37:12.173002 | instance | INFO     [csi > prepare] Executing
2026-08-22 18:37:12.962446 | instance |
2026-08-22 18:37:12.962754 | instance | PLAY [Prepare] *****************************************************************
2026-08-22 18:37:12.963003 | instance |
2026-08-22 18:37:12.963278 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:37:12.963553 | instance | Saturday 22 August 2026  18:37:12 +0000 (0:00:00.024)       0:00:00.024 *******
2026-08-22 18:37:14.081440 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 18:37:14.081534 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 18:37:14.081628 | instance | interpreter could change the meaning of that path. See
2026-08-22 18:37:14.081753 | instance | https://docs.ansible.com/ansible-
2026-08-22 18:37:14.081872 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 18:37:14.091153 | instance | ok: [instance]
2026-08-22 18:37:14.091271 | instance |
2026-08-22 18:37:14.091399 | instance | TASK [Configure short hostname] ************************************************
2026-08-22 18:37:14.091520 | instance | Saturday 22 August 2026  18:37:14 +0000 (0:00:01.129)       0:00:01.154 *******
2026-08-22 18:37:14.751850 | instance | changed: [instance]
2026-08-22 18:37:14.752078 | instance |
2026-08-22 18:37:14.752361 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 18:37:14.752647 | instance | Saturday 22 August 2026  18:37:14 +0000 (0:00:00.659)       0:00:01.813 *******
2026-08-22 18:37:15.022508 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 18:37:15.022748 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 18:37:15.023103 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 18:37:15.033065 | instance | changed: [instance]
2026-08-22 18:37:15.033229 | instance |
2026-08-22 18:37:15.033542 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-22 18:37:15.033866 | instance | Saturday 22 August 2026  18:37:15 +0000 (0:00:00.281)       0:00:02.095 *******
2026-08-22 18:37:15.920547 | instance | ok: [instance]
2026-08-22 18:37:15.920802 | instance |
2026-08-22 18:37:15.921092 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-22 18:37:15.921362 | instance |
2026-08-22 18:37:15.921641 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:37:15.921929 | instance | Saturday 22 August 2026  18:37:15 +0000 (0:00:00.887)       0:00:02.983 *******
2026-08-22 18:37:16.591341 | instance | ok: [instance]
2026-08-22 18:37:16.591552 | instance |
2026-08-22 18:37:16.591907 | instance | TASK [Install depedencies] *****************************************************
2026-08-22 18:37:16.592284 | instance | Saturday 22 August 2026  18:37:16 +0000 (0:00:00.670)       0:00:03.653 *******
2026-08-22 18:37:36.484225 | instance | changed: [instance]
2026-08-22 18:37:36.484434 | instance |
2026-08-22 18:37:36.484709 | instance | TASK [Start up service] ********************************************************
2026-08-22 18:37:36.484986 | instance | Saturday 22 August 2026  18:37:36 +0000 (0:00:19.892)       0:00:23.546 *******
2026-08-22 18:37:37.019569 | instance | ok: [instance]
2026-08-22 18:37:37.019863 | instance |
2026-08-22 18:37:37.020131 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-22 18:37:37.020408 | instance | Saturday 22 August 2026  18:37:37 +0000 (0:00:00.535)       0:00:24.082 *******
2026-08-22 18:37:37.311560 | instance | ok: [instance]
2026-08-22 18:37:37.311836 | instance |
2026-08-22 18:37:37.312130 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 18:37:37.312401 | instance | Saturday 22 August 2026  18:37:37 +0000 (0:00:00.292)       0:00:24.374 *******
2026-08-22 18:37:37.937609 | instance | changed: [instance]
2026-08-22 18:37:37.937836 | instance |
2026-08-22 18:37:37.938069 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-22 18:37:37.938335 | instance | Saturday 22 August 2026  18:37:37 +0000 (0:00:00.625)       0:00:25.000 *******
2026-08-22 18:37:38.123717 | instance | ok: [instance]
2026-08-22 18:37:38.123963 | instance |
2026-08-22 18:37:38.124234 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 18:37:38.124602 | instance | Saturday 22 August 2026  18:37:38 +0000 (0:00:00.186)       0:00:25.186 *******
2026-08-22 18:37:38.139092 | instance | skipping: [instance]
2026-08-22 18:37:38.139316 | instance |
2026-08-22 18:37:38.139573 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-22 18:37:38.139872 | instance | Saturday 22 August 2026  18:37:38 +0000 (0:00:00.015)       0:00:25.202 *******
2026-08-22 18:37:38.647715 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:37:38.647950 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:37:38.648205 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:37:38.648453 | instance |
2026-08-22 18:37:38.648841 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-22 18:37:38.649065 | instance | Saturday 22 August 2026  18:37:38 +0000 (0:00:00.507)       0:00:25.709 *******
2026-08-22 18:37:39.245765 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:37:39.245834 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:37:39.245845 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:37:39.245855 | instance |
2026-08-22 18:37:39.245865 | instance | TASK [Start loop devices] ******************************************************
2026-08-22 18:37:39.245875 | instance | Saturday 22 August 2026  18:37:39 +0000 (0:00:00.596)       0:00:26.306 *******
2026-08-22 18:37:39.940145 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:37:39.940213 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:37:39.940755 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:37:39.940806 | instance |
2026-08-22 18:37:39.940812 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-22 18:37:39.940818 | instance | Saturday 22 August 2026  18:37:39 +0000 (0:00:00.696)       0:00:27.002 *******
2026-08-22 18:37:43.077696 | instance | changed: [instance] => (item=osd0)
2026-08-22 18:37:43.077780 | instance | changed: [instance] => (item=osd1)
2026-08-22 18:37:43.078294 | instance | changed: [instance] => (item=osd2)
2026-08-22 18:37:43.078347 | instance |
2026-08-22 18:37:43.078353 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 18:37:43.078358 | instance | Saturday 22 August 2026  18:37:43 +0000 (0:00:03.137)       0:00:30.140 *******
2026-08-22 18:37:44.911093 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-22 18:37:44.911722 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-22 18:37:44.911812 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-22 18:37:44.911935 | instance |
2026-08-22 18:37:44.915550 | instance | PLAY RECAP *********************************************************************
2026-08-22 18:37:44.915631 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 18:37:44.915641 | instance |
2026-08-22 18:37:44.915648 | instance | Saturday 22 August 2026  18:37:44 +0000 (0:00:01.833)       0:00:31.974 *******
2026-08-22 18:37:44.915655 | instance | ===============================================================================
2026-08-22 18:37:44.915661 | instance | Install depedencies ---------------------------------------------------- 19.89s
2026-08-22 18:37:44.915667 | instance | Create a volume group for each loop device ------------------------------ 3.14s
2026-08-22 18:37:44.915673 | instance | Create a logical volume for each loop device ---------------------------- 1.83s
2026-08-22 18:37:44.915680 | instance | Gathering Facts --------------------------------------------------------- 1.13s
2026-08-22 18:37:44.915687 | instance | Purge "snapd" package --------------------------------------------------- 0.89s
2026-08-22 18:37:44.915693 | instance | Start loop devices ------------------------------------------------------ 0.70s
2026-08-22 18:37:44.915698 | instance | Gathering Facts --------------------------------------------------------- 0.67s
2026-08-22 18:37:44.915704 | instance | Configure short hostname ------------------------------------------------ 0.66s
2026-08-22 18:37:44.915710 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.63s
2026-08-22 18:37:44.915716 | instance | Set permissions on loopback devices ------------------------------------- 0.60s
2026-08-22 18:37:44.915722 | instance | Start up service -------------------------------------------------------- 0.54s
2026-08-22 18:37:44.915728 | instance | Create devices for Ceph ------------------------------------------------- 0.51s
2026-08-22 18:37:44.915733 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s
2026-08-22 18:37:44.915739 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-08-22 18:37:44.915745 | instance | Get list of all loopback devices ---------------------------------------- 0.19s
2026-08-22 18:37:44.915751 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-22 18:37:44.982414 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-22 18:37:44.994210 | instance | INFO     [csi > converge] Executing
2026-08-22 18:37:46.398435 | instance |
2026-08-22 18:37:46.398842 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-22 18:37:46.399162 | instance |
2026-08-22 18:37:46.399522 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:37:46.399883 | instance | Saturday 22 August 2026  18:37:46 +0000 (0:00:00.007)       0:00:00.007 *******
2026-08-22 18:37:47.375423 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 18:37:47.375876 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 18:37:47.376224 | instance | interpreter could change the meaning of that path. See
2026-08-22 18:37:47.376557 | instance | https://docs.ansible.com/ansible-
2026-08-22 18:37:47.376891 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 18:37:47.386703 | instance | ok: [instance]
2026-08-22 18:37:47.387057 | instance |
2026-08-22 18:37:47.387395 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-22 18:37:47.387760 | instance | Saturday 22 August 2026  18:37:47 +0000 (0:00:00.988)       0:00:00.995 *******
2026-08-22 18:37:47.437127 | instance | ok: [instance] => {
2026-08-22 18:37:47.437519 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-22 18:37:47.437851 | instance | }
2026-08-22 18:37:47.438166 | instance |
2026-08-22 18:37:47.438512 | instance | PLAY [all] *********************************************************************
2026-08-22 18:37:47.438821 | instance |
2026-08-22 18:37:47.439147 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:37:47.439504 | instance | Saturday 22 August 2026  18:37:47 +0000 (0:00:00.050)       0:00:01.046 *******
2026-08-22 18:37:48.286849 | instance | ok: [instance]
2026-08-22 18:37:48.287228 | instance |
2026-08-22 18:37:48.287647 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-22 18:37:48.288009 | instance | Saturday 22 August 2026  18:37:48 +0000 (0:00:00.849)       0:00:01.895 *******
2026-08-22 18:37:48.467631 | instance | ok: [instance]
2026-08-22 18:37:48.467999 | instance |
2026-08-22 18:37:48.468336 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 18:37:48.468645 | instance |
2026-08-22 18:37:48.468972 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 18:37:48.469299 | instance | Saturday 22 August 2026  18:37:48 +0000 (0:00:00.181)       0:00:02.077 *******
2026-08-22 18:37:49.336178 | instance | ok: [instance]
2026-08-22 18:37:49.336255 | instance |
2026-08-22 18:37:49.336365 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 18:37:49.336539 | instance | Saturday 22 August 2026  18:37:49 +0000 (0:00:00.866)       0:00:02.943 *******
2026-08-22 18:37:49.620473 | instance | ok: [instance]
2026-08-22 18:37:49.620865 | instance |
2026-08-22 18:37:49.621223 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 18:37:49.621569 | instance | Saturday 22 August 2026  18:37:49 +0000 (0:00:00.285)       0:00:03.228 *******
2026-08-22 18:37:49.662983 | instance | skipping: [instance]
2026-08-22 18:37:49.663175 | instance |
2026-08-22 18:37:49.663330 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 18:37:49.663496 | instance | Saturday 22 August 2026  18:37:49 +0000 (0:00:00.041)       0:00:03.269 *******
2026-08-22 18:37:49.989587 | instance | changed: [instance]
2026-08-22 18:37:49.989979 | instance |
2026-08-22 18:37:49.990335 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 18:37:49.990682 | instance | Saturday 22 August 2026  18:37:49 +0000 (0:00:00.328)       0:00:03.598 *******
2026-08-22 18:37:50.057288 | instance | ok: [instance] => {
2026-08-22 18:37:50.057666 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 18:37:50.057993 | instance | }
2026-08-22 18:37:50.058310 | instance |
2026-08-22 18:37:50.058646 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 18:37:50.059010 | instance | Saturday 22 August 2026  18:37:50 +0000 (0:00:00.068)       0:00:03.666 *******
2026-08-22 18:37:50.696559 | instance | changed: [instance]
2026-08-22 18:37:50.696907 | instance |
2026-08-22 18:37:50.697245 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 18:37:50.697580 | instance | Saturday 22 August 2026  18:37:50 +0000 (0:00:00.638)       0:00:04.305 *******
2026-08-22 18:37:50.750816 | instance | skipping: [instance]
2026-08-22 18:37:50.751164 | instance |
2026-08-22 18:37:50.751560 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 18:37:50.751931 | instance | Saturday 22 August 2026  18:37:50 +0000 (0:00:00.054)       0:00:04.360 *******
2026-08-22 18:37:50.802123 | instance | skipping: [instance]
2026-08-22 18:37:50.802469 | instance |
2026-08-22 18:37:50.802811 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 18:37:50.803148 | instance | Saturday 22 August 2026  18:37:50 +0000 (0:00:00.051)       0:00:04.411 *******
2026-08-22 18:37:51.015168 | instance | ok: [instance]
2026-08-22 18:37:51.015637 | instance |
2026-08-22 18:37:51.016010 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 18:37:51.016368 | instance | Saturday 22 August 2026  18:37:51 +0000 (0:00:00.212)       0:00:04.624 *******
2026-08-22 18:37:52.174228 | instance | ok: [instance]
2026-08-22 18:37:52.174415 | instance |
2026-08-22 18:37:52.174572 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 18:37:52.174726 | instance | Saturday 22 August 2026  18:37:52 +0000 (0:00:01.159)       0:00:05.783 *******
2026-08-22 18:37:52.239008 | instance | ok: [instance] => {
2026-08-22 18:37:52.239225 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 18:37:52.239391 | instance | }
2026-08-22 18:37:52.239547 | instance |
2026-08-22 18:37:52.239722 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 18:37:52.239876 | instance | Saturday 22 August 2026  18:37:52 +0000 (0:00:00.062)       0:00:05.845 *******
2026-08-22 18:37:52.910880 | instance | changed: [instance]
2026-08-22 18:37:52.911125 | instance |
2026-08-22 18:37:52.911340 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 18:37:52.911551 | instance | Saturday 22 August 2026  18:37:52 +0000 (0:00:00.674)       0:00:06.520 *******
2026-08-22 18:37:55.685819 | instance | changed: [instance]
2026-08-22 18:37:55.686260 | instance |
2026-08-22 18:37:55.686629 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 18:37:55.686985 | instance | Saturday 22 August 2026  18:37:55 +0000 (0:00:02.774)       0:00:09.294 *******
2026-08-22 18:37:55.718264 | instance | skipping: [instance]
2026-08-22 18:37:55.718631 | instance |
2026-08-22 18:37:55.719004 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 18:37:55.719401 | instance | Saturday 22 August 2026  18:37:55 +0000 (0:00:00.032)       0:00:09.327 *******
2026-08-22 18:37:55.752692 | instance | skipping: [instance]
2026-08-22 18:37:55.753028 | instance |
2026-08-22 18:37:55.753691 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 18:37:55.754047 | instance | Saturday 22 August 2026  18:37:55 +0000 (0:00:00.034)       0:00:09.362 *******
2026-08-22 18:37:55.781623 | instance | skipping: [instance]
2026-08-22 18:37:55.781965 | instance |
2026-08-22 18:37:55.782340 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 18:37:55.782677 | instance | Saturday 22 August 2026  18:37:55 +0000 (0:00:00.028)       0:00:09.391 *******
2026-08-22 18:38:00.934561 | instance | changed: [instance]
2026-08-22 18:38:00.934785 | instance |
2026-08-22 18:38:00.934951 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 18:38:00.935108 | instance | Saturday 22 August 2026  18:38:00 +0000 (0:00:05.152)       0:00:14.543 *******
2026-08-22 18:38:01.507413 | instance | changed: [instance]
2026-08-22 18:38:01.507475 | instance |
2026-08-22 18:38:01.507794 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 18:38:01.507831 | instance | Saturday 22 August 2026  18:38:01 +0000 (0:00:00.573)       0:00:15.116 *******
2026-08-22 18:38:02.382195 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-22 18:38:02.382268 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 18:38:02.383029 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 18:38:02.383076 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 18:38:02.383082 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 18:38:02.383088 | instance |
2026-08-22 18:38:02.383098 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 18:38:02.383102 | instance | Saturday 22 August 2026  18:38:02 +0000 (0:00:00.874)       0:00:15.991 *******
2026-08-22 18:38:02.958887 | instance | changed: [instance]
2026-08-22 18:38:02.958961 | instance |
2026-08-22 18:38:02.959733 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 18:38:02.959789 | instance | Saturday 22 August 2026  18:38:02 +0000 (0:00:00.560)       0:00:16.551 *******
2026-08-22 18:38:02.959801 | instance |
2026-08-22 18:38:02.959811 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 18:38:02.959825 | instance | Saturday 22 August 2026  18:38:02 +0000 (0:00:00.016)       0:00:16.568 *******
2026-08-22 18:38:03.879703 | instance | ok: [instance]
2026-08-22 18:38:03.879823 | instance |
2026-08-22 18:38:03.879887 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 18:38:03.880046 | instance | Saturday 22 August 2026  18:38:03 +0000 (0:00:00.920)       0:00:17.489 *******
2026-08-22 18:38:04.310174 | instance | changed: [instance]
2026-08-22 18:38:04.310294 | instance |
2026-08-22 18:38:04.310548 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 18:38:04.310734 | instance | Saturday 22 August 2026  18:38:04 +0000 (0:00:00.430)       0:00:17.919 *******
2026-08-22 18:38:04.832300 | instance | changed: [instance]
2026-08-22 18:38:04.832359 | instance |
2026-08-22 18:38:04.832365 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 18:38:04.832370 | instance | Saturday 22 August 2026  18:38:04 +0000 (0:00:00.521)       0:00:18.440 *******
2026-08-22 18:38:05.044271 | instance | ok: [instance]
2026-08-22 18:38:05.044324 | instance |
2026-08-22 18:38:05.044331 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 18:38:05.044337 | instance | Saturday 22 August 2026  18:38:05 +0000 (0:00:00.211)       0:00:18.652 *******
2026-08-22 18:38:05.110092 | instance | ok: [instance] => {
2026-08-22 18:38:05.110154 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 18:38:05.110703 | instance | }
2026-08-22 18:38:05.110763 | instance |
2026-08-22 18:38:05.110769 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 18:38:05.110775 | instance | Saturday 22 August 2026  18:38:05 +0000 (0:00:00.066)       0:00:18.719 *******
2026-08-22 18:38:05.912013 | instance | changed: [instance]
2026-08-22 18:38:05.912106 | instance |
2026-08-22 18:38:05.912376 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 18:38:05.912394 | instance | Saturday 22 August 2026  18:38:05 +0000 (0:00:00.802)       0:00:19.521 *******
2026-08-22 18:38:10.176343 | instance | changed: [instance]
2026-08-22 18:38:10.176409 | instance |
2026-08-22 18:38:10.176422 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 18:38:10.176431 | instance | Saturday 22 August 2026  18:38:10 +0000 (0:00:04.263)       0:00:23.785 *******
2026-08-22 18:38:11.239811 | instance | ok: [instance]
2026-08-22 18:38:11.239893 | instance |
2026-08-22 18:38:11.240214 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 18:38:11.240257 | instance | Saturday 22 August 2026  18:38:11 +0000 (0:00:01.063)       0:00:24.849 *******
2026-08-22 18:38:11.570965 | instance | changed: [instance]
2026-08-22 18:38:11.571017 | instance |
2026-08-22 18:38:11.571023 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 18:38:11.571028 | instance | Saturday 22 August 2026  18:38:11 +0000 (0:00:00.330)       0:00:25.180 *******
2026-08-22 18:38:12.020580 | instance | changed: [instance]
2026-08-22 18:38:12.020655 | instance |
2026-08-22 18:38:12.020942 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 18:38:12.020977 | instance | Saturday 22 August 2026  18:38:12 +0000 (0:00:00.449)       0:00:25.630 *******
2026-08-22 18:38:12.570962 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-22 18:38:12.571034 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 18:38:12.571541 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 18:38:12.571614 | instance |
2026-08-22 18:38:12.571621 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 18:38:12.571629 | instance | Saturday 22 August 2026  18:38:12 +0000 (0:00:00.550)       0:00:26.180 *******
2026-08-22 18:38:13.019097 | instance | changed: [instance]
2026-08-22 18:38:13.019131 | instance |
2026-08-22 18:38:13.019136 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 18:38:13.019141 | instance | Saturday 22 August 2026  18:38:13 +0000 (0:00:00.447)       0:00:26.628 *******
2026-08-22 18:38:13.499423 | instance | changed: [instance]
2026-08-22 18:38:13.499495 | instance |
2026-08-22 18:38:13.499674 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 18:38:13.499893 | instance | Saturday 22 August 2026  18:38:13 +0000 (0:00:00.463)       0:00:27.091 *******
2026-08-22 18:38:13.499907 | instance |
2026-08-22 18:38:13.500181 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 18:38:13.500232 | instance | Saturday 22 August 2026  18:38:13 +0000 (0:00:00.016)       0:00:27.108 *******
2026-08-22 18:38:14.161765 | instance | ok: [instance]
2026-08-22 18:38:14.161871 | instance |
2026-08-22 18:38:14.162138 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 18:38:14.162185 | instance | Saturday 22 August 2026  18:38:14 +0000 (0:00:00.662)       0:00:27.771 *******
2026-08-22 18:38:14.961181 | instance | changed: [instance]
2026-08-22 18:38:14.961255 | instance |
2026-08-22 18:38:14.961532 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 18:38:14.961574 | instance | Saturday 22 August 2026  18:38:14 +0000 (0:00:00.799)       0:00:28.570 *******
2026-08-22 18:38:15.505505 | instance | changed: [instance]
2026-08-22 18:38:15.505536 | instance |
2026-08-22 18:38:15.505541 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 18:38:15.505546 | instance | Saturday 22 August 2026  18:38:15 +0000 (0:00:00.543)       0:00:29.114 *******
2026-08-22 18:38:15.556142 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-22 18:38:15.556531 | instance |
2026-08-22 18:38:15.556568 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 18:38:15.556574 | instance | Saturday 22 August 2026  18:38:15 +0000 (0:00:00.050)       0:00:29.165 *******
2026-08-22 18:38:20.203858 | instance | changed: [instance]
2026-08-22 18:38:20.203992 | instance |
2026-08-22 18:38:20.204065 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 18:38:20.204192 | instance | Saturday 22 August 2026  18:38:20 +0000 (0:00:04.647)       0:00:33.813 *******
2026-08-22 18:38:20.814948 | instance | ok: [instance] => (item=chronyd)
2026-08-22 18:38:20.815016 | instance | ok: [instance] => (item=sshd)
2026-08-22 18:38:20.815411 | instance |
2026-08-22 18:38:20.815450 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 18:38:20.815456 | instance | Saturday 22 August 2026  18:38:20 +0000 (0:00:00.611)       0:00:34.424 *******
2026-08-22 18:38:22.399674 | instance | changed: [instance]
2026-08-22 18:38:22.399747 | instance |
2026-08-22 18:38:22.399978 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 18:38:22.400019 | instance | Saturday 22 August 2026  18:38:22 +0000 (0:00:01.584)       0:00:36.009 *******
2026-08-22 18:38:22.620286 | instance | ok: [instance]
2026-08-22 18:38:22.620369 | instance |
2026-08-22 18:38:22.620753 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 18:38:22.620801 | instance | Saturday 22 August 2026  18:38:22 +0000 (0:00:00.220)       0:00:36.229 *******
2026-08-22 18:38:23.039716 | instance | changed: [instance]
2026-08-22 18:38:23.039751 | instance |
2026-08-22 18:38:23.039757 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 18:38:23.039761 | instance | Saturday 22 August 2026  18:38:23 +0000 (0:00:00.419)       0:00:36.648 *******
2026-08-22 18:38:23.360137 | instance | changed: [instance]
2026-08-22 18:38:23.360203 | instance |
2026-08-22 18:38:23.360477 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 18:38:23.360517 | instance | Saturday 22 August 2026  18:38:23 +0000 (0:00:00.320)       0:00:36.969 *******
2026-08-22 18:38:23.472938 | instance | ok: [instance]
2026-08-22 18:38:23.473021 | instance |
2026-08-22 18:38:23.473285 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 18:38:23.473317 | instance | Saturday 22 August 2026  18:38:23 +0000 (0:00:00.112)       0:00:37.082 *******
2026-08-22 18:38:23.688401 | instance | ok: [instance] => (item=instance)
2026-08-22 18:38:23.688766 | instance |
2026-08-22 18:38:23.688924 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 18:38:23.688930 | instance | Saturday 22 August 2026  18:38:23 +0000 (0:00:00.215)       0:00:37.297 *******
2026-08-22 18:38:23.739250 | instance | ok: [instance]
2026-08-22 18:38:23.739310 | instance |
2026-08-22 18:38:23.739589 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 18:38:23.739630 | instance | Saturday 22 August 2026  18:38:23 +0000 (0:00:00.050)       0:00:37.348 *******
2026-08-22 18:38:23.810045 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-22 18:38:23.810431 | instance |
2026-08-22 18:38:23.810469 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 18:38:23.810475 | instance | Saturday 22 August 2026  18:38:23 +0000 (0:00:00.070)       0:00:37.419 *******
2026-08-22 18:38:24.103840 | instance | changed: [instance]
2026-08-22 18:38:24.103963 | instance |
2026-08-22 18:38:24.104332 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 18:38:24.104373 | instance | Saturday 22 August 2026  18:38:24 +0000 (0:00:00.293)       0:00:37.712 *******
2026-08-22 18:38:24.765926 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-22 18:38:24.765998 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 18:38:24.766447 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 18:38:24.766480 | instance |
2026-08-22 18:38:24.766485 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 18:38:24.766490 | instance | Saturday 22 August 2026  18:38:24 +0000 (0:00:00.662)       0:00:38.375 *******
2026-08-22 18:39:47.613333 | instance | ok: [instance]
2026-08-22 18:39:47.613372 | instance |
2026-08-22 18:39:47.613377 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 18:39:47.613382 | instance | Saturday 22 August 2026  18:39:47 +0000 (0:01:22.847)       0:02:01.222 *******
2026-08-22 18:39:47.830997 | instance | changed: [instance]
2026-08-22 18:39:47.831063 | instance |
2026-08-22 18:39:47.831203 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 18:39:47.831325 | instance | Saturday 22 August 2026  18:39:47 +0000 (0:00:00.218)       0:02:01.440 *******
2026-08-22 18:39:47.904622 | instance | ok: [instance]
2026-08-22 18:39:47.905072 | instance |
2026-08-22 18:39:47.905112 | instance | TASK [Install Ceph host] *******************************************************
2026-08-22 18:39:47.905118 | instance | Saturday 22 August 2026  18:39:47 +0000 (0:00:00.073)       0:02:01.514 *******
2026-08-22 18:39:47.986794 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-22 18:39:47.987183 | instance |
2026-08-22 18:39:47.987219 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 18:39:47.987224 | instance | Saturday 22 August 2026  18:39:47 +0000 (0:00:00.082)       0:02:01.596 *******
2026-08-22 18:39:49.528993 | instance | ok: [instance]
2026-08-22 18:39:49.529547 | instance |
2026-08-22 18:39:49.529569 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 18:39:49.529577 | instance | Saturday 22 August 2026  18:39:49 +0000 (0:00:01.542)       0:02:03.138 *******
2026-08-22 18:39:49.588865 | instance | ok: [instance] => (item=instance)
2026-08-22 18:39:49.588930 | instance |
2026-08-22 18:39:49.589055 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 18:39:49.589173 | instance | Saturday 22 August 2026  18:39:49 +0000 (0:00:00.060)       0:02:03.198 *******
2026-08-22 18:39:49.935206 | instance | ok: [instance]
2026-08-22 18:39:49.935276 | instance |
2026-08-22 18:39:49.935407 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 18:39:49.935528 | instance | Saturday 22 August 2026  18:39:49 +0000 (0:00:00.346)       0:02:03.544 *******
2026-08-22 18:39:52.205541 | instance | ok: [instance]
2026-08-22 18:39:52.205612 | instance |
2026-08-22 18:39:52.205888 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 18:39:52.205926 | instance | Saturday 22 August 2026  18:39:52 +0000 (0:00:02.270)       0:02:05.814 *******
2026-08-22 18:40:35.971497 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-22 18:40:35.972139 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n  File \"<stdin>\", line 107, in <module>\n  File \"<stdin>\", line 99, in _ansiballz_main\n  File \"<stdin>\", line 47, in invoke_module\n  File \"/usr/lib/python3.10/runpy.py\", line 224, in run_module\n    return _run_module_code(code, init_globals, run_name, mod_spec)\n  File \"/usr/lib/python3.10/runpy.py\", line 96, in _run_module_code\n    _run_code(code, mod_globals, init_globals,\n  File \"/usr/lib/python3.10/runpy.py\", line 86, in _run_code\n    exec(code, run_globals)\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k2dmpos3/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in <module>\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k2dmpos3/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 198, in main\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k2dmpos3/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 177, in run_module\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k2dmpos3/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-08-22T18:40:35.811+0000 7ff02d088640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDK7IlqaEskFCAA3flJzmlt6MrQ6Q/5uUjDm+HWsyO9g10naP4F8JuFDqU=: Malformed input [buffer:3]\n2026-08-22T18:40:35.811+0000 7ff02d088640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T18:40:35.811+0000 7ff02d088640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDK7IlqaEskFCAA3flJzmlt6MrQ6Q/5uUjDm+HWsyO9g10naP4F8JuFDqU=: Malformed input [buffer:3]\n2026-08-22T18:40:35.811+0000 7ff02d088640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T18:40:35.811+0000 7ff02d088640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDK7IlqaEskFCAA3flJzmlt6MrQ6Q/5uUjDm+HWsyO9g10naP4F8JuFDqU=: Malformed input [buffer:3]\n2026-08-22T18:40:35.811+0000 7ff02d088640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T18:40:35.811+0000 7ff02d088640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-22 18:40:35.974595 | instance |
2026-08-22 18:40:35.974766 | instance | PLAY RECAP *********************************************************************
2026-08-22 18:40:35.975672 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-22 18:40:35.975716 | instance |
2026-08-22 18:40:35.975724 | instance | Saturday 22 August 2026  18:40:35 +0000 (0:00:43.769)       0:02:49.584 *******
2026-08-22 18:40:35.975731 | instance | ===============================================================================
2026-08-22 18:40:35.975736 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 82.85s
2026-08-22 18:40:35.975747 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 43.77s
2026-08-22 18:40:35.977819 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.15s
2026-08-22 18:40:35.977886 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.65s
2026-08-22 18:40:35.977891 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.26s
2026-08-22 18:40:35.977896 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.77s
2026-08-22 18:40:35.977900 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.27s
2026-08-22 18:40:35.977904 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.58s
2026-08-22 18:40:35.977924 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.58s
2026-08-22 18:40:35.977928 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.54s
2026-08-22 18:40:35.977932 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.16s
2026-08-22 18:40:35.977936 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.06s
2026-08-22 18:40:35.977939 | instance | Gathering Facts --------------------------------------------------------- 0.99s
2026-08-22 18:40:35.977943 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.87s
2026-08-22 18:40:35.977947 | instance | Gathering Facts --------------------------------------------------------- 0.87s
2026-08-22 18:40:35.977951 | instance | Gathering Facts --------------------------------------------------------- 0.85s
2026-08-22 18:40:35.977955 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.80s
2026-08-22 18:40:35.977958 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.80s
2026-08-22 18:40:35.977962 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.67s
2026-08-22 18:40:35.977966 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.66s
2026-08-22 18:40:36.096346 | 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-08-22 18:40:36.096748 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-22 18:40:36.096966 | 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-08-22 18:40:36.673397 | instance | ERROR
2026-08-22 18:40:36.673586 | instance | {
2026-08-22 18:40:36.673627 | instance |   "delta": "0:03:37.983686",
2026-08-22 18:40:36.673661 | instance |   "end": "2026-08-22 18:40:36.178264",
2026-08-22 18:40:36.673703 | instance |   "msg": "non-zero return code",
2026-08-22 18:40:36.673743 | instance |   "rc": 2,
2026-08-22 18:40:36.673772 | instance |   "start": "2026-08-22 18:36:58.194578"
2026-08-22 18:40:36.673798 | instance | }
failure
2026-08-22 18:40:36.679256 | 
2026-08-22 18:40:36.679317 | PLAY RECAP
2026-08-22 18:40:36.679368 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 18:40:36.679392 | 
2026-08-22 18:40:36.878959 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 18:40:37.011269 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 18:40:37.754026 | 
2026-08-22 18:40:37.754164 | PLAY [all]
2026-08-22 18:40:37.768781 | 
2026-08-22 18:40:37.768904 | TASK [gather-host-logs : creating directory for system status]
2026-08-22 18:40:38.107480 | instance | changed
2026-08-22 18:40:38.114320 | 
2026-08-22 18:40:38.114401 | TASK [gather-host-logs : Get logs for each host]
2026-08-22 18:40:38.450928 | instance | + systemd-cgls --full --all --no-pager
2026-08-22 18:40:38.463412 | instance | + ip addr
2026-08-22 18:40:38.465704 | instance | + ip route
2026-08-22 18:40:38.466687 | instance | + lsblk
2026-08-22 18:40:38.473290 | instance | + mount
2026-08-22 18:40:38.475923 | instance | + docker images
2026-08-22 18:40:38.495865 | instance | + brctl show
2026-08-22 18:40:38.496426 | instance | /bin/bash: line 8: brctl: command not found
2026-08-22 18:40:38.496769 | instance | + ps aux --sort=-%mem
2026-08-22 18:40:38.512210 | instance | + dpkg -l
2026-08-22 18:40:38.518320 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-22 18:40:38.518618 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-22 18:40:38.533368 | instance | + '[' '!' -z '' ']'
2026-08-22 18:40:38.648675 | instance | ok: Runtime: 0:00:00.087244
2026-08-22 18:40:38.656263 | 
2026-08-22 18:40:38.656351 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-22 18:40:39.318625 | instance | changed:
2026-08-22 18:40:39.318812 | instance | created directory /var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/work/logs/instance
2026-08-22 18:40:39.318851 | instance | cd+++++++++ system/
2026-08-22 18:40:39.318881 | instance | >f+++++++++ system/brctl-show.txt
2026-08-22 18:40:39.318912 | instance | >f+++++++++ system/docker-images.txt
2026-08-22 18:40:39.318940 | instance | >f+++++++++ system/ip-addr.txt
2026-08-22 18:40:39.318968 | instance | >f+++++++++ system/ip-route.txt
2026-08-22 18:40:39.318994 | instance | >f+++++++++ system/lsblk.txt
2026-08-22 18:40:39.319018 | instance | >f+++++++++ system/mount.txt
2026-08-22 18:40:39.319043 | instance | >f+++++++++ system/packages.txt
2026-08-22 18:40:39.319067 | instance | >f+++++++++ system/ps.txt
2026-08-22 18:40:39.319093 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-22 18:40:39.331085 | 
2026-08-22 18:40:39.331181 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 18:40:39.517253 | instance | changed: "values"
2026-08-22 18:40:39.687147 | instance | changed: "releases"
2026-08-22 18:40:39.779656 | 
2026-08-22 18:40:39.779767 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 18:40:39.990326 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-22 18:40:40.317373 | instance | ok: Runtime: 0:00:00.004276
2026-08-22 18:40:40.323846 | 
2026-08-22 18:40:40.323942 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 18:40:40.803164 | instance | changed:
2026-08-22 18:40:40.803666 | instance | cd+++++++++ helm/
2026-08-22 18:40:40.803713 | instance | cd+++++++++ helm/releases/
2026-08-22 18:40:40.803743 | instance | cd+++++++++ helm/values/
2026-08-22 18:40:40.816153 | 
2026-08-22 18:40:40.816232 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 18:40:41.018700 | instance | changed
2026-08-22 18:40:41.027491 | 
2026-08-22 18:40:41.027679 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 18:40:41.314129 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:40:41.314230 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:40:41.319911 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:40:41.321220 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:40:41.322331 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:40:41.324346 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:40:41.325364 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:40:41.326489 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:40:41.326970 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:40:41.327578 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:40:41.329663 | instance | environment: line 1: kubectl: command not found
2026-08-22 18:40:41.330831 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 18:40:41.630973 | instance | ok: Runtime: 0:00:00.026633
2026-08-22 18:40:41.636606 | 
2026-08-22 18:40:41.636683 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 18:40:41.843701 | instance | changed
2026-08-22 18:40:41.848929 | 
2026-08-22 18:40:41.849035 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 18:40:42.079580 | instance | environment: line 5: kubectl: command not found
2026-08-22 18:40:42.080628 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuexargs:
2026-08-22 18:40:42.080658 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:40:42.081657 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 18:40:42.393716 | instance | ok: Runtime: 0:00:00.008784
2026-08-22 18:40:42.398780 | 
2026-08-22 18:40:42.398841 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 18:40:43.147904 | instance | changed:
2026-08-22 18:40:43.148090 | instance | cd+++++++++ objects/
2026-08-22 18:40:43.148130 | instance | cd+++++++++ objects/cluster/
2026-08-22 18:40:43.148163 | instance | cd+++++++++ objects/namespaced/
2026-08-22 18:40:43.157661 | 
2026-08-22 18:40:43.157784 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 18:40:43.385972 | instance | changed
2026-08-22 18:40:43.393134 | 
2026-08-22 18:40:43.393220 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 18:40:43.589232 | instance | changed
2026-08-22 18:40:43.595315 | 
2026-08-22 18:40:43.595382 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 18:40:43.815946 | instance | environment: line 3: kubectl: command not found
2026-08-22 18:40:44.127622 | instance | ok: Runtime: 0:00:00.007931
2026-08-22 18:40:44.136444 | 
2026-08-22 18:40:44.136527 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 18:40:44.643710 | instance | changed:
2026-08-22 18:40:44.643879 | instance | cd+++++++++ pod-logs/
2026-08-22 18:40:44.643916 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-22 18:40:44.656001 | 
2026-08-22 18:40:44.656071 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-22 18:40:44.850307 | instance | changed
2026-08-22 18:40:44.856875 | 
2026-08-22 18:40:44.856986 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-22 18:40:45.063668 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 18:40:45.473430 | instance | ok: Runtime: 0:00:00.029999
2026-08-22 18:40:45.482092 | 
2026-08-22 18:40:45.482158 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-22 18:40:45.701652 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 18:40:45.728977 | instance | ceph-mgr endpoints:
2026-08-22 18:40:46.015257 | instance | ok: Runtime: 0:00:00.033901
2026-08-22 18:40:46.022199 | 
2026-08-22 18:40:46.022338 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-22 18:40:46.229145 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-22 18:40:46.557517 | instance | ok: Runtime: 0:00:00.034965
2026-08-22 18:40:46.562464 | 
2026-08-22 18:40:46.562531 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-22 18:40:47.057762 | instance | changed: cd+++++++++ prometheus/
2026-08-22 18:40:47.067296 | 
2026-08-22 18:40:47.067357 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-22 18:40:47.273666 | instance | changed
2026-08-22 18:40:47.280837 | 
2026-08-22 18:40:47.280925 | TASK [gather-selenium-data : Get selenium data]
2026-08-22 18:40:47.526537 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-22 18:40:47.527556 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-22 18:40:47.816793 | instance | ERROR
2026-08-22 18:40:47.817081 | instance | {
2026-08-22 18:40:47.817152 | instance |   "delta": "0:00:00.006618",
2026-08-22 18:40:47.817198 | instance |   "end": "2026-08-22 18:40:47.527924",
2026-08-22 18:40:47.817244 | instance |   "msg": "non-zero return code",
2026-08-22 18:40:47.817285 | instance |   "rc": 1,
2026-08-22 18:40:47.817325 | instance |   "start": "2026-08-22 18:40:47.521306"
2026-08-22 18:40:47.817363 | instance | }
2026-08-22 18:40:47.817413 | instance | ERROR: Ignoring Errors
2026-08-22 18:40:47.824221 | 
2026-08-22 18:40:47.824304 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-22 18:40:48.395269 | instance | changed: cd+++++++++ selenium/
2026-08-22 18:40:48.401869 | 
2026-08-22 18:40:48.401912 | PLAY RECAP
2026-08-22 18:40:48.401954 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-22 18:40:48.401975 | 
2026-08-22 18:40:48.503009 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 18:40:48.507438 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 18:40:49.118224 | 
2026-08-22 18:40:49.118333 | PLAY [all]
2026-08-22 18:40:49.129863 | 
2026-08-22 18:40:49.129942 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 18:40:49.174467 | instance | skipping: Conditional result was False
2026-08-22 18:40:49.184782 | 
2026-08-22 18:40:49.184865 | TASK [fetch-output : Set log path for single node]
2026-08-22 18:40:49.227935 | instance | ok
2026-08-22 18:40:49.232504 | 
2026-08-22 18:40:49.232570 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 18:40:49.621456 | instance -> localhost | ok: "/var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/work/logs"
2026-08-22 18:40:49.823038 | instance -> localhost | changed: "/var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/work/artifacts"
2026-08-22 18:40:50.027085 | instance -> localhost | changed: "/var/lib/zuul/builds/654eb65c39624984bce6ff52f1d6e7a0/work/docs"
2026-08-22 18:40:50.049759 | 
2026-08-22 18:40:50.050233 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 18:40:50.680381 | instance | changed: .d..t...... ./
2026-08-22 18:40:50.680590 | instance | changed: All items complete
2026-08-22 18:40:50.680618 | 
2026-08-22 18:40:51.141062 | instance | changed: .d..t...... ./
2026-08-22 18:40:51.581819 | instance | changed: .d..t...... ./
2026-08-22 18:40:51.601906 | 
2026-08-22 18:40:51.602033 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 18:40:51.978442 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007483
2026-08-22 18:40:52.196738 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005086
2026-08-22 18:40:52.222740 | 
2026-08-22 18:40:52.222846 | PLAY [all]
2026-08-22 18:40:52.228714 | 
2026-08-22 18:40:52.228798 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 18:40:52.638022 | instance | changed
2026-08-22 18:40:52.643966 | 
2026-08-22 18:40:52.644019 | PLAY RECAP
2026-08-22 18:40:52.644071 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 18:40:52.644094 | 
2026-08-22 18:40:52.876263 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 18:40:52.884797 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 18:40:53.485050 | 
2026-08-22 18:40:53.485159 | PLAY [localhost]
2026-08-22 18:40:53.494776 | 
2026-08-22 18:40:53.494851 | TASK [Generate Zuul manifest]
2026-08-22 18:40:53.515516 | localhost | ok
2026-08-22 18:40:53.533335 | 
2026-08-22 18:40:53.533411 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 18:40:54.447474 | localhost | changed
2026-08-22 18:40:54.460660 | 
2026-08-22 18:40:54.460767 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 18:40:54.654033 | localhost | ok
2026-08-22 18:40:54.668042 | 
2026-08-22 18:40:54.668184 | TASK [Upload logs]
2026-08-22 18:40:54.690148 | localhost | ok
2026-08-22 18:40:54.752571 | 
2026-08-22 18:40:54.752701 | TASK [Set zuul-log-path fact]
2026-08-22 18:40:54.774361 | localhost | ok
2026-08-22 18:40:54.798567 | 
2026-08-22 18:40:54.798712 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 18:40:54.830496 | localhost | ok
2026-08-22 18:40:54.840570 | 
2026-08-22 18:40:54.840677 | TASK [upload-logs : Create log directories]
2026-08-22 18:40:55.440890 | localhost | changed
2026-08-22 18:40:55.447616 | 
2026-08-22 18:40:55.447713 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 18:40:55.832501 | localhost -> localhost | ok: Runtime: 0:00:00.003835
2026-08-22 18:40:55.839345 | 
2026-08-22 18:40:55.839434 | TASK [upload-logs : Upload logs to log server]
2026-08-22 18:40:56.409934 | localhost | Output suppressed because no_log was given
2026-08-22 18:40:56.440263 | 
2026-08-22 18:40:56.440377 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 18:40:56.482000 | localhost | skipping: Conditional result was False
2026-08-22 18:40:56.488702 | localhost | skipping: Conditional result was False
2026-08-22 18:40:56.503388 | 
2026-08-22 18:40:56.503589 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 18:40:56.543077 | localhost | skipping: Conditional result was False
2026-08-22 18:40:56.543411 | 
2026-08-22 18:40:56.547408 | localhost | skipping: Conditional result was False
2026-08-22 18:40:56.557905 | 
2026-08-22 18:40:56.558101 | LOOP [upload-logs : Upload console log and json output]