2026-08-24 15:09:44.254745 | Job console starting
2026-08-24 15:09:44.268459 | Updating git repos
2026-08-24 15:09:49.352024 | Cloning repos into workspace
2026-08-24 15:10:08.089209 | Restoring repo states
2026-08-24 15:10:20.475953 | Merging changes
2026-08-24 15:10:23.655190 | Checking out repos
2026-08-24 15:10:31.683925 | Preparing playbooks
2026-08-24 15:10:39.113199 | Running Ansible setup
2026-08-24 15:10:44.053607 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-24 15:10:44.711016 | 
2026-08-24 15:10:44.711213 | PLAY [localhost]
2026-08-24 15:10:44.721179 | 
2026-08-24 15:10:44.721299 | TASK [Gathering Facts]
2026-08-24 15:10:45.603961 | localhost | ok
2026-08-24 15:10:45.611322 | 
2026-08-24 15:10:45.611411 | TASK [Setup log path fact]
2026-08-24 15:10:45.631937 | localhost | ok
2026-08-24 15:10:45.644342 | 
2026-08-24 15:10:45.644411 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-24 15:10:45.674638 | localhost | ok
2026-08-24 15:10:45.683493 | 
2026-08-24 15:10:45.683585 | TASK [emit-job-header : Print job information]
2026-08-24 15:10:45.724590 | # Job Information
2026-08-24 15:10:45.724770 | Ansible Version: 2.16.18
2026-08-24 15:10:45.724815 | Job: atmosphere-molecule-csi-rbd
2026-08-24 15:10:45.724846 | Pipeline: check
2026-08-24 15:10:45.724874 | Executor: 2d72f0692154
2026-08-24 15:10:45.724902 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3845
2026-08-24 15:10:45.724934 | Event ID: 9dbd9c30-9fcc-11f1-94c7-6a0e2c390c46
2026-08-24 15:10:45.728777 | 
2026-08-24 15:10:45.728865 | LOOP [emit-job-header : Print node information]
2026-08-24 15:10:45.819014 | localhost | ok:
2026-08-24 15:10:45.819208 | localhost | # Node Information
2026-08-24 15:10:45.819248 | localhost | Inventory Hostname: instance
2026-08-24 15:10:45.819280 | localhost | Hostname: np0000190802
2026-08-24 15:10:45.819308 | localhost | Username: zuul
2026-08-24 15:10:45.819338 | localhost | Distro: Ubuntu 22.04
2026-08-24 15:10:45.819365 | localhost | Provider: yul1
2026-08-24 15:10:45.819391 | localhost | Region: ca-ymq-1
2026-08-24 15:10:45.819417 | localhost | Label: ubuntu-jammy
2026-08-24 15:10:45.819442 | localhost | Product Name: OpenStack Nova
2026-08-24 15:10:45.819468 | localhost | Interface IP: 199.19.213.75
2026-08-24 15:10:45.842703 | 
2026-08-24 15:10:45.842851 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-24 15:10:46.223728 | localhost -> localhost | changed
2026-08-24 15:10:46.233637 | 
2026-08-24 15:10:46.233709 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-24 15:10:47.505936 | localhost -> localhost | changed
2026-08-24 15:10:47.516975 | 
2026-08-24 15:10:47.517029 | PLAY [all]
2026-08-24 15:10:47.525105 | 
2026-08-24 15:10:47.525170 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-24 15:10:47.734897 | instance -> localhost | ok
2026-08-24 15:10:47.743424 | 
2026-08-24 15:10:47.743522 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-24 15:10:47.774021 | instance | ok
2026-08-24 15:10:47.791220 | instance | included: /var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-24 15:10:47.799058 | 
2026-08-24 15:10:47.799131 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-24 15:10:49.008152 | instance -> localhost | Generating public/private rsa key pair.
2026-08-24 15:10:49.008352 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/work/56ffa0e0f651463a9ae2c7c66fe3e72b_id_rsa
2026-08-24 15:10:49.008393 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/work/56ffa0e0f651463a9ae2c7c66fe3e72b_id_rsa.pub
2026-08-24 15:10:49.008424 | instance -> localhost | The key fingerprint is:
2026-08-24 15:10:49.008452 | instance -> localhost | SHA256:0LEQ3kB+b3o9cbgDoM5AHg65PsBoEYGmNDaIgPFraV8 zuul-build-sshkey
2026-08-24 15:10:49.008494 | instance -> localhost | The key's randomart image is:
2026-08-24 15:10:49.008523 | instance -> localhost | +---[RSA 3072]----+
2026-08-24 15:10:49.008554 | instance -> localhost | |O+.  .=..        |
2026-08-24 15:10:49.008583 | instance -> localhost | |=B.. o = o       |
2026-08-24 15:10:49.008609 | instance -> localhost | |=.* o + *        |
2026-08-24 15:10:49.008636 | instance -> localhost | |+ .O . + o   .   |
2026-08-24 15:10:49.008686 | instance -> localhost | |oo* + E S + o .  |
2026-08-24 15:10:49.008714 | instance -> localhost | |.= . =   o o +   |
2026-08-24 15:10:49.008740 | instance -> localhost | |  o . o . . =    |
2026-08-24 15:10:49.008766 | instance -> localhost | |   .     .   o   |
2026-08-24 15:10:49.008796 | instance -> localhost | |                 |
2026-08-24 15:10:49.008823 | instance -> localhost | +----[SHA256]-----+
2026-08-24 15:10:49.008885 | instance -> localhost | ok: Runtime: 0:00:00.661667
2026-08-24 15:10:49.017029 | 
2026-08-24 15:10:49.017116 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-24 15:10:49.049006 | instance | ok
2026-08-24 15:10:49.059578 | instance | included: /var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-24 15:10:49.066872 | 
2026-08-24 15:10:49.066936 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-24 15:10:49.090969 | instance | skipping: Conditional result was False
2026-08-24 15:10:49.101012 | 
2026-08-24 15:10:49.101103 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-24 15:10:49.880615 | instance | changed
2026-08-24 15:10:49.889036 | 
2026-08-24 15:10:49.889127 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-24 15:10:50.077289 | instance | ok
2026-08-24 15:10:50.082762 | 
2026-08-24 15:10:50.082839 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-24 15:10:50.658251 | instance | changed
2026-08-24 15:10:50.664723 | 
2026-08-24 15:10:50.664809 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-24 15:10:51.132054 | instance | changed
2026-08-24 15:10:51.143675 | 
2026-08-24 15:10:51.143801 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-24 15:10:51.169084 | instance | skipping: Conditional result was False
2026-08-24 15:10:51.180681 | 
2026-08-24 15:10:51.180817 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-24 15:10:51.691284 | instance -> localhost | changed
2026-08-24 15:10:51.705048 | 
2026-08-24 15:10:51.705146 | TASK [add-build-sshkey : Add back temp key]
2026-08-24 15:10:52.077345 | instance -> localhost | Identity added: /var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/work/56ffa0e0f651463a9ae2c7c66fe3e72b_id_rsa (zuul-build-sshkey)
2026-08-24 15:10:52.077521 | instance -> localhost | ok: Runtime: 0:00:00.013034
2026-08-24 15:10:52.082605 | 
2026-08-24 15:10:52.082669 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-24 15:10:52.426077 | instance | ok
2026-08-24 15:10:52.431465 | 
2026-08-24 15:10:52.431539 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-24 15:10:52.458615 | instance | skipping: Conditional result was False
2026-08-24 15:10:52.473685 | 
2026-08-24 15:10:52.473857 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-24 15:10:52.840984 | instance | ok
2026-08-24 15:10:52.849599 | 
2026-08-24 15:10:52.849670 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-24 15:10:58.287495 | instance | Output suppressed because no_log was given
2026-08-24 15:10:58.297289 | 
2026-08-24 15:10:58.297357 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-24 15:10:58.621130 | instance | ok: "logs"
2026-08-24 15:10:58.801656 | instance | ok: All items complete
2026-08-24 15:10:58.801845 | 
2026-08-24 15:10:58.811918 | instance | ok: "artifacts"
2026-08-24 15:10:58.946887 | instance | ok: "docs"
2026-08-24 15:10:58.958593 | 
2026-08-24 15:10:58.958692 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-24 15:10:59.154940 | instance | changed: "logs"
2026-08-24 15:10:59.317449 | instance | changed: "artifacts"
2026-08-24 15:10:59.524731 | instance | changed: "docs"
2026-08-24 15:10:59.535857 | 
2026-08-24 15:10:59.535921 | PLAY RECAP
2026-08-24 15:10:59.535963 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-24 15:10:59.535992 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-24 15:10:59.536015 | 
2026-08-24 15:10:59.671634 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-24 15:10:59.916222 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-24 15:11:00.537177 | 
2026-08-24 15:11:01.947949 | PLAY [all]
2026-08-24 15:11:01.968093 | 
2026-08-24 15:11:01.968218 | TASK [setup-uv : Extract archive]
2026-08-24 15:11:05.737586 | instance | changed
2026-08-24 15:11:05.745454 | 
2026-08-24 15:11:05.745530 | TASK [setup-uv : Print version]
2026-08-24 15:11:06.212517 | instance | uv 0.8.13
2026-08-24 15:11:08.330325 | instance | ok: Runtime: 0:00:00.065185
2026-08-24 15:11:08.346199 | 
2026-08-24 15:11:08.346328 | TASK [Install Ansible collection siblings]
2026-08-24 15:11:30.783232 | instance | changed:
2026-08-24 15:11:30.783396 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-24 15:11:30.783426 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-24 15:11:30.783449 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-24 15:11:30.783470 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-24 15:11:30.783489 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-24 15:11:30.783512 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-24 15:11:30.783532 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-24 15:11:30.783554 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-24 15:11:30.783573 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-24 15:11:30.783593 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-24 15:11:30.783613 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-24 15:11:30.783632 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-24 15:11:30.783651 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-24 15:11:30.783670 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-24 15:11:30.783688 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-24 15:11:30.783707 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-24 15:11:30.783725 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-24 15:11:30.783744 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-24 15:11:30.783763 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-24 15:11:30.783781 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-24 15:11:30.783799 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-24 15:11:30.783816 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-24 15:11:30.783836 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-24 15:11:30.783865 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-24 15:11:30.783884 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-24 15:11:30.783902 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-24 15:11:30.783920 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-24 15:11:30.783938 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-24 15:11:30.783956 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-24 15:11:30.783973 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-24 15:11:30.783990 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-24 15:11:30.784008 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-24 15:11:30.784026 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-24 15:11:30.784045 | 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-24 15:11:30.784069 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.784087 | instance | Process install dependency map
2026-08-24 15:11:30.784105 | instance | Starting collection install process
2026-08-24 15:11:30.784123 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-24 15:11:30.784141 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-24 15:11:30.784159 | instance | ansible.netcommon:* was installed successfully
2026-08-24 15:11:30.784177 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-24 15:11:30.784195 | instance | Creating virtual environment at: .venv
2026-08-24 15:11:30.784212 | instance | Downloading pygments (1.2MiB)
2026-08-24 15:11:30.784230 | instance | Downloading cryptography (4.2MiB)
2026-08-24 15:11:30.784248 | instance | Downloading kubernetes (1.9MiB)
2026-08-24 15:11:30.784269 | instance | Downloading ansible-core (2.1MiB)
2026-08-24 15:11:30.784288 | instance | Downloading setuptools (1.1MiB)
2026-08-24 15:11:30.784306 | instance | Downloading pydantic-core (2.0MiB)
2026-08-24 15:11:30.784328 | instance | Downloading rjsonnet (1.2MiB)
2026-08-24 15:11:30.784348 | instance | Downloading openstacksdk (1.7MiB)
2026-08-24 15:11:30.784367 | instance | Downloading netaddr (2.2MiB)
2026-08-24 15:11:30.784386 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-24 15:11:30.784404 | instance |  Downloading rjsonnet
2026-08-24 15:11:30.784454 | instance |    Building pyperclip==1.9.0
2026-08-24 15:11:30.784480 | instance |  Downloading pydantic-core
2026-08-24 15:11:30.784503 | instance |  Downloading netaddr
2026-08-24 15:11:30.784522 | instance |  Downloading pygments
2026-08-24 15:11:30.784540 | instance |  Downloading cryptography
2026-08-24 15:11:30.784558 | instance |  Downloading setuptools
2026-08-24 15:11:30.784576 | instance |  Downloading kubernetes
2026-08-24 15:11:30.784595 | instance |  Downloading ansible-core
2026-08-24 15:11:30.784613 | instance |  Downloading openstacksdk
2026-08-24 15:11:30.784631 | instance |       Built pyperclip==1.9.0
2026-08-24 15:11:30.784650 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-24 15:11:30.784668 | instance | Installed 83 packages in 3.82s
2026-08-24 15:11:30.784686 | instance | Checked out ansible.posix to 1.6.0
2026-08-24 15:11:30.784704 | 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-24 15:11:30.784723 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.784741 | instance | Process install dependency map
2026-08-24 15:11:30.784764 | instance | Starting collection install process
2026-08-24 15:11:30.784783 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-24 15:11:30.784802 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-24 15:11:30.784820 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-24 15:11:30.784839 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.784858 | instance | 2.17.12
2026-08-24 15:11:30.784877 | instance | Checked out ansible.utils to v6.1.0
2026-08-24 15:11:30.784895 | 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-24 15:11:30.784914 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.784932 | instance | Process install dependency map
2026-08-24 15:11:30.784950 | instance | Starting collection install process
2026-08-24 15:11:30.784969 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-24 15:11:30.784988 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-24 15:11:30.785008 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-24 15:11:30.785027 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.785045 | instance | 2.17.12
2026-08-24 15:11:30.786032 | instance | Checked out atmosphere.common to v0.7.0
2026-08-24 15:11:30.786062 | 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-24 15:11:30.786084 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.786105 | instance | Process install dependency map
2026-08-24 15:11:30.786125 | instance | Starting collection install process
2026-08-24 15:11:30.786145 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-24 15:11:30.786165 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-24 15:11:30.786184 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-24 15:11:30.786203 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.786221 | instance | 2.17.12
2026-08-24 15:11:30.786240 | instance | Checked out community.crypto to 2.2.3
2026-08-24 15:11:30.786258 | 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-24 15:11:30.786277 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.786297 | instance | Process install dependency map
2026-08-24 15:11:30.786315 | instance | Starting collection install process
2026-08-24 15:11:30.786334 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-24 15:11:30.786358 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-24 15:11:30.786387 | instance | community.crypto:2.2.3 was installed successfully
2026-08-24 15:11:30.786410 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.786430 | instance | 2.17.12
2026-08-24 15:11:30.786451 | instance | Checked out community.general to 7.3.0
2026-08-24 15:11:30.786478 | 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-24 15:11:30.786511 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.786533 | instance | Process install dependency map
2026-08-24 15:11:30.786553 | instance | Starting collection install process
2026-08-24 15:11:30.786581 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-24 15:11:30.786609 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-24 15:11:30.786630 | instance | community.general:7.3.0 was installed successfully
2026-08-24 15:11:30.786653 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.786681 | instance | 2.17.12
2026-08-24 15:11:30.786707 | instance | Checked out community.mysql to 3.6.0
2026-08-24 15:11:30.786733 | 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-24 15:11:30.786759 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.786786 | instance | Process install dependency map
2026-08-24 15:11:30.786810 | instance | Starting collection install process
2026-08-24 15:11:30.786837 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-24 15:11:30.786931 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-24 15:11:30.786972 | instance | community.mysql:3.6.0 was installed successfully
2026-08-24 15:11:30.786998 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.787025 | instance | 2.17.12
2026-08-24 15:11:30.787051 | instance | Checked out kubernetes.core to 2.4.0
2026-08-24 15:11:30.787076 | 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-24 15:11:30.787100 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.787128 | instance | Process install dependency map
2026-08-24 15:11:30.787152 | instance | Starting collection install process
2026-08-24 15:11:30.787176 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-24 15:11:30.787202 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-24 15:11:30.787227 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-24 15:11:30.787250 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.787273 | instance | 2.17.12
2026-08-24 15:11:30.787296 | instance | Checked out openstack.cloud to 2.6.0
2026-08-24 15:11:30.787318 | 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-24 15:11:30.787342 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.787364 | instance | Process install dependency map
2026-08-24 15:11:30.787385 | instance | Starting collection install process
2026-08-24 15:11:30.787410 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-24 15:11:30.787436 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-24 15:11:30.787461 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-24 15:11:30.787485 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.787511 | instance | 2.17.12
2026-08-24 15:11:30.787542 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-24 15:11:30.787565 | 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-24 15:11:30.787588 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.787611 | instance | Process install dependency map
2026-08-24 15:11:30.787634 | instance | Starting collection install process
2026-08-24 15:11:30.787657 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-24 15:11:30.787680 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-24 15:11:30.787703 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-24 15:11:30.787724 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.787743 | instance | 2.17.12
2026-08-24 15:11:30.787761 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-24 15:11:30.787780 | 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-24 15:11:30.787799 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.787817 | instance | Process install dependency map
2026-08-24 15:11:30.787835 | instance | Starting collection install process
2026-08-24 15:11:30.787854 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-24 15:11:30.787873 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-24 15:11:30.787891 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-24 15:11:30.787911 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.787937 | instance | 2.17.12
2026-08-24 15:11:30.787962 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-24 15:11:30.787981 | 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-24 15:11:30.788000 | instance | Starting galaxy collection install process
2026-08-24 15:11:30.788023 | instance | Process install dependency map
2026-08-24 15:11:30.788049 | instance | Starting collection install process
2026-08-24 15:11:30.788070 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-24 15:11:30.788090 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-24 15:11:30.788108 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-24 15:11:30.788127 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-24 15:11:30.788144 | instance | 2.17.12
2026-08-24 15:11:30.793897 | 
2026-08-24 15:11:30.793943 | PLAY RECAP
2026-08-24 15:11:30.793986 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-24 15:11:30.794007 | 
2026-08-24 15:11:30.906366 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-24 15:11:30.910522 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-24 15:11:31.574489 | 
2026-08-24 15:11:31.574636 | PLAY [all]
2026-08-24 15:11:31.585757 | 
2026-08-24 15:11:31.585839 | TASK [Install "jq" for log collection]
2026-08-24 15:11:42.315077 | instance | changed
2026-08-24 15:11:42.316896 | 
2026-08-24 15:11:42.316953 | PLAY RECAP
2026-08-24 15:11:42.317019 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-24 15:11:42.317084 | 
2026-08-24 15:11:42.432002 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-24 15:11:42.436873 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-24 15:11:43.110385 | 
2026-08-24 15:11:43.110508 | PLAY [all]
2026-08-24 15:11:43.122372 | 
2026-08-24 15:11:43.122464 | LOOP [Check inventory directories]
2026-08-24 15:11:43.456334 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-24 15:11:43.612024 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-24 15:11:43.764785 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-24 15:11:43.786802 | 
2026-08-24 15:11:43.786973 | LOOP [Copy inventory file for Zuul]
2026-08-24 15:11:44.580268 | instance | changed:
2026-08-24 15:11:44.580492 | instance | {
2026-08-24 15:11:44.580532 | instance |   "ansible_loop_var": "item",
2026-08-24 15:11:44.580563 | instance |   "changed": false,
2026-08-24 15:11:44.580590 | instance |   "failed": false,
2026-08-24 15:11:44.580621 | instance |   "invocation": {
2026-08-24 15:11:44.580648 | instance |     "module_args": {
2026-08-24 15:11:44.580709 | instance |       "checksum_algorithm": "sha1",
2026-08-24 15:11:44.580750 | instance |       "follow": true,
2026-08-24 15:11:44.580778 | instance |       "get_attributes": true,
2026-08-24 15:11:44.580806 | instance |       "get_checksum": true,
2026-08-24 15:11:44.580831 | instance |       "get_mime": true,
2026-08-24 15:11:44.580856 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-24 15:11:44.580881 | instance |     }
2026-08-24 15:11:44.580905 | instance |   },
2026-08-24 15:11:44.580930 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-24 15:11:44.580953 | instance |   "stat": {
2026-08-24 15:11:44.581003 | instance |     "atime": 1787584273.9338987,
2026-08-24 15:11:44.581031 | instance |     "attr_flags": "e",
2026-08-24 15:11:44.581056 | instance |     "attributes": [
2026-08-24 15:11:44.581079 | instance |       "extents"
2026-08-24 15:11:44.581104 | instance |     ],
2026-08-24 15:11:44.581127 | instance |     "block_size": 4096,
2026-08-24 15:11:44.581150 | instance |     "blocks": 8,
2026-08-24 15:11:44.581173 | instance |     "charset": "unknown",
2026-08-24 15:11:44.581198 | instance |     "ctime": 1787584271.1018705,
2026-08-24 15:11:44.581226 | instance |     "dev": 64513,
2026-08-24 15:11:44.581250 | instance |     "device_type": 0,
2026-08-24 15:11:44.581274 | instance |     "executable": true,
2026-08-24 15:11:44.581297 | instance |     "exists": true,
2026-08-24 15:11:44.581320 | instance |     "gid": 1000,
2026-08-24 15:11:44.581343 | instance |     "gr_name": "zuul",
2026-08-24 15:11:44.581366 | instance |     "inode": 1047658,
2026-08-24 15:11:44.581390 | instance |     "isblk": false,
2026-08-24 15:11:44.581414 | instance |     "ischr": false,
2026-08-24 15:11:44.581438 | instance |     "isdir": true,
2026-08-24 15:11:44.581461 | instance |     "isfifo": false,
2026-08-24 15:11:44.581485 | instance |     "isgid": false,
2026-08-24 15:11:44.581507 | instance |     "islnk": false,
2026-08-24 15:11:44.581529 | instance |     "isreg": false,
2026-08-24 15:11:44.581551 | instance |     "issock": false,
2026-08-24 15:11:44.581573 | instance |     "isuid": false,
2026-08-24 15:11:44.581596 | instance |     "mimetype": "unknown",
2026-08-24 15:11:44.581619 | instance |     "mode": "0755",
2026-08-24 15:11:44.581642 | instance |     "mtime": 1787584271.1018705,
2026-08-24 15:11:44.581664 | instance |     "nlink": 23,
2026-08-24 15:11:44.581693 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-24 15:11:44.581729 | instance |     "pw_name": "zuul",
2026-08-24 15:11:44.581769 | instance |     "readable": true,
2026-08-24 15:11:44.581795 | instance |     "rgrp": true,
2026-08-24 15:11:44.581819 | instance |     "roth": true,
2026-08-24 15:11:44.581857 | instance |     "rusr": true,
2026-08-24 15:11:44.581882 | instance |     "size": 4096,
2026-08-24 15:11:44.581907 | instance |     "uid": 1000,
2026-08-24 15:11:44.581931 | instance |     "version": "4109786228",
2026-08-24 15:11:44.581955 | instance |     "wgrp": false,
2026-08-24 15:11:44.581980 | instance |     "woth": false,
2026-08-24 15:11:44.582005 | instance |     "writeable": true,
2026-08-24 15:11:44.582028 | instance |     "wusr": true,
2026-08-24 15:11:44.582051 | instance |     "xgrp": true,
2026-08-24 15:11:44.582074 | instance |     "xoth": true,
2026-08-24 15:11:44.582097 | instance |     "xusr": true
2026-08-24 15:11:44.582120 | instance |   }
2026-08-24 15:11:44.582144 | instance | }
2026-08-24 15:11:44.582192 | instance | ok: All items complete
2026-08-24 15:11:44.582224 | 
2026-08-24 15:11:44.584112 | instance | skipping: Conditional result was False
2026-08-24 15:11:44.584768 | instance | skipping: Conditional result was False
2026-08-24 15:11:44.596528 | 
2026-08-24 15:11:44.596595 | LOOP [Switch "ansible_host" to private IP]
2026-08-24 15:11:44.935017 | instance | changed: 1 replacements made
2026-08-24 15:11:44.936221 | instance | skipping: Conditional result was False
2026-08-24 15:11:44.937720 | instance | skipping: Conditional result was False
2026-08-24 15:11:44.960164 | 
2026-08-24 15:11:44.960259 | TASK [Run Molecule scenario]
2026-08-24 15:11:45.945217 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-24 15:11:46.352196 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-24 15:11:46.352254 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-24 15:11:58.023673 | instance | INFO     [csi > dependency] Executing
2026-08-24 15:11:58.023829 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-24 15:11:58.024037 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-24 15:11:58.024165 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-24 15:11:58.031728 | instance | INFO     [csi > cleanup] Executing
2026-08-24 15:11:58.032056 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-24 15:11:58.039825 | instance | INFO     [csi > destroy] Executing
2026-08-24 15:11:58.039868 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-24 15:11:58.039952 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-24 15:11:58.047473 | instance | INFO     [csi > syntax] Executing
2026-08-24 15:11:59.756665 | instance |
2026-08-24 15:11:59.756847 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-24 15:11:59.913658 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-24 15:11:59.926678 | instance | INFO     [csi > create] Executing
2026-08-24 15:11:59.929194 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-24 15:11:59.936736 | instance | INFO     [csi > prepare] Executing
2026-08-24 15:12:00.787542 | instance |
2026-08-24 15:12:00.787784 | instance | PLAY [Prepare] *****************************************************************
2026-08-24 15:12:00.788048 | instance |
2026-08-24 15:12:00.788359 | instance | TASK [Gathering Facts] *********************************************************
2026-08-24 15:12:00.788641 | instance | Monday 24 August 2026  15:12:00 +0000 (0:00:00.026)       0:00:00.026 *********
2026-08-24 15:12:01.963788 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-24 15:12:01.964063 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-24 15:12:01.964270 | instance | interpreter could change the meaning of that path. See
2026-08-24 15:12:01.964535 | instance | https://docs.ansible.com/ansible-
2026-08-24 15:12:01.964803 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-24 15:12:01.972688 | instance | ok: [instance]
2026-08-24 15:12:01.972935 | instance |
2026-08-24 15:12:01.973287 | instance | TASK [Configure short hostname] ************************************************
2026-08-24 15:12:01.973609 | instance | Monday 24 August 2026  15:12:01 +0000 (0:00:01.186)       0:00:01.212 *********
2026-08-24 15:12:02.650744 | instance | changed: [instance]
2026-08-24 15:12:02.650864 | instance |
2026-08-24 15:12:02.651094 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-24 15:12:02.651331 | instance | Monday 24 August 2026  15:12:02 +0000 (0:00:00.677)       0:00:01.890 *********
2026-08-24 15:12:02.925537 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-24 15:12:02.925812 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-24 15:12:02.926242 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-24 15:12:02.934958 | instance | changed: [instance]
2026-08-24 15:12:02.935213 | instance |
2026-08-24 15:12:02.935562 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-24 15:12:02.935832 | instance | Monday 24 August 2026  15:12:02 +0000 (0:00:00.284)       0:00:02.175 *********
2026-08-24 15:12:03.713856 | instance | ok: [instance]
2026-08-24 15:12:03.714155 | instance |
2026-08-24 15:12:03.714475 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-24 15:12:03.714739 | instance |
2026-08-24 15:12:03.715186 | instance | TASK [Gathering Facts] *********************************************************
2026-08-24 15:12:03.715647 | instance | Monday 24 August 2026  15:12:03 +0000 (0:00:00.778)       0:00:02.953 *********
2026-08-24 15:12:04.401962 | instance | ok: [instance]
2026-08-24 15:12:04.402150 | instance |
2026-08-24 15:12:04.402432 | instance | TASK [Install depedencies] *****************************************************
2026-08-24 15:12:04.402725 | instance | Monday 24 August 2026  15:12:04 +0000 (0:00:00.687)       0:00:03.641 *********
2026-08-24 15:12:30.941069 | instance | changed: [instance]
2026-08-24 15:12:30.941173 | instance |
2026-08-24 15:12:30.941352 | instance | TASK [Start up service] ********************************************************
2026-08-24 15:12:30.941529 | instance | Monday 24 August 2026  15:12:30 +0000 (0:00:26.539)       0:00:30.181 *********
2026-08-24 15:12:31.501326 | instance | ok: [instance]
2026-08-24 15:12:31.501576 | instance |
2026-08-24 15:12:31.502021 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-24 15:12:31.502334 | instance | Monday 24 August 2026  15:12:31 +0000 (0:00:00.559)       0:00:30.741 *********
2026-08-24 15:12:31.799416 | instance | ok: [instance]
2026-08-24 15:12:31.799730 | instance |
2026-08-24 15:12:31.800121 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-24 15:12:31.800517 | instance | Monday 24 August 2026  15:12:31 +0000 (0:00:00.298)       0:00:31.039 *********
2026-08-24 15:12:32.491347 | instance | changed: [instance]
2026-08-24 15:12:32.491410 | instance |
2026-08-24 15:12:32.491424 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-24 15:12:32.491761 | instance | Monday 24 August 2026  15:12:32 +0000 (0:00:00.691)       0:00:31.730 *********
2026-08-24 15:12:32.698666 | instance | ok: [instance]
2026-08-24 15:12:32.698794 | instance |
2026-08-24 15:12:32.699208 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-24 15:12:32.699307 | instance | Monday 24 August 2026  15:12:32 +0000 (0:00:00.207)       0:00:31.938 *********
2026-08-24 15:12:32.726888 | instance | skipping: [instance]
2026-08-24 15:12:32.727226 | instance |
2026-08-24 15:12:32.727837 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-24 15:12:32.728142 | instance | Monday 24 August 2026  15:12:32 +0000 (0:00:00.027)       0:00:31.966 *********
2026-08-24 15:12:33.280395 | instance | changed: [instance] => (item=osd0)
2026-08-24 15:12:33.281327 | instance | changed: [instance] => (item=osd1)
2026-08-24 15:12:33.281398 | instance | changed: [instance] => (item=osd2)
2026-08-24 15:12:33.281406 | instance |
2026-08-24 15:12:33.281413 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-24 15:12:33.281419 | instance | Monday 24 August 2026  15:12:33 +0000 (0:00:00.553)       0:00:32.520 *********
2026-08-24 15:12:33.912392 | instance | changed: [instance] => (item=osd0)
2026-08-24 15:12:33.912543 | instance | changed: [instance] => (item=osd1)
2026-08-24 15:12:33.913320 | instance | changed: [instance] => (item=osd2)
2026-08-24 15:12:33.913387 | instance |
2026-08-24 15:12:33.913396 | instance | TASK [Start loop devices] ******************************************************
2026-08-24 15:12:33.913403 | instance | Monday 24 August 2026  15:12:33 +0000 (0:00:00.632)       0:00:33.152 *********
2026-08-24 15:12:34.638520 | instance | changed: [instance] => (item=osd0)
2026-08-24 15:12:34.638580 | instance | changed: [instance] => (item=osd1)
2026-08-24 15:12:34.638585 | instance | changed: [instance] => (item=osd2)
2026-08-24 15:12:34.638590 | instance |
2026-08-24 15:12:34.638595 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-24 15:12:34.638599 | instance | Monday 24 August 2026  15:12:34 +0000 (0:00:00.724)       0:00:33.877 *********
2026-08-24 15:12:38.340511 | instance | changed: [instance] => (item=osd0)
2026-08-24 15:12:38.340672 | instance | changed: [instance] => (item=osd1)
2026-08-24 15:12:38.341399 | instance | changed: [instance] => (item=osd2)
2026-08-24 15:12:38.341466 | instance |
2026-08-24 15:12:38.341473 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-24 15:12:38.341481 | instance | Monday 24 August 2026  15:12:38 +0000 (0:00:03.702)       0:00:37.580 *********
2026-08-24 15:12:40.429219 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-24 15:12:40.429969 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-24 15:12:40.430014 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-24 15:12:40.430025 | instance |
2026-08-24 15:12:40.430033 | instance | PLAY RECAP *********************************************************************
2026-08-24 15:12:40.431376 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-24 15:12:40.431472 | instance |
2026-08-24 15:12:40.431485 | instance | Monday 24 August 2026  15:12:40 +0000 (0:00:02.089)       0:00:39.669 *********
2026-08-24 15:12:40.431495 | instance | ===============================================================================
2026-08-24 15:12:40.431504 | instance | Install depedencies ---------------------------------------------------- 26.54s
2026-08-24 15:12:40.431513 | instance | Create a volume group for each loop device ------------------------------ 3.70s
2026-08-24 15:12:40.431521 | instance | Create a logical volume for each loop device ---------------------------- 2.09s
2026-08-24 15:12:40.431542 | instance | Gathering Facts --------------------------------------------------------- 1.19s
2026-08-24 15:12:40.431551 | instance | Purge "snapd" package --------------------------------------------------- 0.78s
2026-08-24 15:12:40.431845 | instance | Start loop devices ------------------------------------------------------ 0.72s
2026-08-24 15:12:40.431930 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.69s
2026-08-24 15:12:40.433489 | instance | Gathering Facts --------------------------------------------------------- 0.69s
2026-08-24 15:12:40.433545 | instance | Configure short hostname ------------------------------------------------ 0.68s
2026-08-24 15:12:40.433553 | instance | Set permissions on loopback devices ------------------------------------- 0.63s
2026-08-24 15:12:40.433559 | instance | Start up service -------------------------------------------------------- 0.56s
2026-08-24 15:12:40.433565 | instance | Create devices for Ceph ------------------------------------------------- 0.55s
2026-08-24 15:12:40.433571 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-08-24 15:12:40.433578 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-08-24 15:12:40.433584 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-08-24 15:12:40.433590 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-08-24 15:12:40.537268 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-24 15:12:40.547861 | instance | INFO     [csi > converge] Executing
2026-08-24 15:12:42.131549 | instance |
2026-08-24 15:12:42.131949 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-24 15:12:42.132261 | instance |
2026-08-24 15:12:42.132581 | instance | TASK [Gathering Facts] *********************************************************
2026-08-24 15:12:42.132901 | instance | Monday 24 August 2026  15:12:42 +0000 (0:00:00.008)       0:00:00.008 *********
2026-08-24 15:12:43.178124 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-24 15:12:43.178582 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-24 15:12:43.178964 | instance | interpreter could change the meaning of that path. See
2026-08-24 15:12:43.179389 | instance | https://docs.ansible.com/ansible-
2026-08-24 15:12:43.179769 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-24 15:12:43.188571 | instance | ok: [instance]
2026-08-24 15:12:43.188783 | instance |
2026-08-24 15:12:43.189005 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-24 15:12:43.189215 | instance | Monday 24 August 2026  15:12:43 +0000 (0:00:01.057)       0:00:01.066 *********
2026-08-24 15:12:43.242396 | instance | ok: [instance] => {
2026-08-24 15:12:43.242594 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-24 15:12:43.242753 | instance | }
2026-08-24 15:12:43.242905 | instance |
2026-08-24 15:12:43.243063 | instance | PLAY [all] *********************************************************************
2026-08-24 15:12:43.243212 | instance |
2026-08-24 15:12:43.243406 | instance | TASK [Gathering Facts] *********************************************************
2026-08-24 15:12:43.243589 | instance | Monday 24 August 2026  15:12:43 +0000 (0:00:00.053)       0:00:01.119 *********
2026-08-24 15:12:44.162846 | instance | ok: [instance]
2026-08-24 15:12:44.163303 | instance |
2026-08-24 15:12:44.163689 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-08-24 15:12:44.164077 | instance | Monday 24 August 2026  15:12:44 +0000 (0:00:00.919)       0:00:02.039 *********
2026-08-24 15:12:44.206289 | instance | skipping: [instance]
2026-08-24 15:12:44.206707 | instance |
2026-08-24 15:12:44.207042 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-24 15:12:44.207407 | instance | Monday 24 August 2026  15:12:44 +0000 (0:00:00.043)       0:00:02.083 *********
2026-08-24 15:12:44.415148 | instance | ok: [instance]
2026-08-24 15:12:44.415580 | instance |
2026-08-24 15:12:44.415937 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-24 15:12:44.416256 | instance |
2026-08-24 15:12:44.416592 | instance | TASK [Gathering Facts] *********************************************************
2026-08-24 15:12:44.416974 | instance | Monday 24 August 2026  15:12:44 +0000 (0:00:00.208)       0:00:02.292 *********
2026-08-24 15:12:45.341823 | instance | ok: [instance]
2026-08-24 15:12:45.341882 | instance |
2026-08-24 15:12:45.341890 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-24 15:12:45.341897 | instance | Monday 24 August 2026  15:12:45 +0000 (0:00:00.926)       0:00:03.218 *********
2026-08-24 15:12:45.700933 | instance | ok: [instance]
2026-08-24 15:12:45.700974 | instance |
2026-08-24 15:12:45.700980 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-24 15:12:45.700985 | instance | Monday 24 August 2026  15:12:45 +0000 (0:00:00.359)       0:00:03.577 *********
2026-08-24 15:12:45.743301 | instance | skipping: [instance]
2026-08-24 15:12:45.743322 | instance |
2026-08-24 15:12:45.743328 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-24 15:12:45.743333 | instance | Monday 24 August 2026  15:12:45 +0000 (0:00:00.042)       0:00:03.620 *********
2026-08-24 15:12:46.087810 | instance | changed: [instance]
2026-08-24 15:12:46.087912 | instance |
2026-08-24 15:12:46.088211 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-24 15:12:46.088264 | instance | Monday 24 August 2026  15:12:46 +0000 (0:00:00.344)       0:00:03.964 *********
2026-08-24 15:12:46.161408 | instance | ok: [instance] => {
2026-08-24 15:12:46.162019 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-24 15:12:46.162062 | instance | }
2026-08-24 15:12:46.162069 | instance |
2026-08-24 15:12:46.162073 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-24 15:12:46.162079 | instance | Monday 24 August 2026  15:12:46 +0000 (0:00:00.073)       0:00:04.038 *********
2026-08-24 15:12:46.845515 | instance | changed: [instance]
2026-08-24 15:12:46.845595 | instance |
2026-08-24 15:12:46.845890 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-24 15:12:46.845943 | instance | Monday 24 August 2026  15:12:46 +0000 (0:00:00.684)       0:00:04.722 *********
2026-08-24 15:12:46.900165 | instance | skipping: [instance]
2026-08-24 15:12:46.900248 | instance |
2026-08-24 15:12:46.900387 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-24 15:12:46.900565 | instance | Monday 24 August 2026  15:12:46 +0000 (0:00:00.054)       0:00:04.777 *********
2026-08-24 15:12:46.952316 | instance | skipping: [instance]
2026-08-24 15:12:46.952381 | instance |
2026-08-24 15:12:46.952503 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-24 15:12:46.952690 | instance | Monday 24 August 2026  15:12:46 +0000 (0:00:00.052)       0:00:04.829 *********
2026-08-24 15:12:47.167068 | instance | ok: [instance]
2026-08-24 15:12:47.167209 | instance |
2026-08-24 15:12:47.167223 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-24 15:12:47.167385 | instance | Monday 24 August 2026  15:12:47 +0000 (0:00:00.214)       0:00:05.044 *********
2026-08-24 15:12:48.655520 | instance | ok: [instance]
2026-08-24 15:12:48.655591 | instance |
2026-08-24 15:12:48.655709 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-24 15:12:48.655918 | instance | Monday 24 August 2026  15:12:48 +0000 (0:00:01.488)       0:00:06.532 *********
2026-08-24 15:12:48.728445 | instance | ok: [instance] => {
2026-08-24 15:12:48.729077 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-24 15:12:48.729441 | instance | }
2026-08-24 15:12:48.729784 | instance |
2026-08-24 15:12:48.730140 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-24 15:12:48.730490 | instance | Monday 24 August 2026  15:12:48 +0000 (0:00:00.070)       0:00:06.603 *********
2026-08-24 15:12:49.475457 | instance | changed: [instance]
2026-08-24 15:12:49.475657 | instance |
2026-08-24 15:12:49.475821 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-24 15:12:49.475992 | instance | Monday 24 August 2026  15:12:49 +0000 (0:00:00.747)       0:00:07.350 *********
2026-08-24 15:12:52.398439 | instance | changed: [instance]
2026-08-24 15:12:52.398492 | instance |
2026-08-24 15:12:52.398497 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-24 15:12:52.398502 | instance | Monday 24 August 2026  15:12:52 +0000 (0:00:02.924)       0:00:10.275 *********
2026-08-24 15:12:52.434679 | instance | skipping: [instance]
2026-08-24 15:12:52.434709 | instance |
2026-08-24 15:12:52.434714 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-24 15:12:52.434719 | instance | Monday 24 August 2026  15:12:52 +0000 (0:00:00.036)       0:00:10.311 *********
2026-08-24 15:12:52.466051 | instance | skipping: [instance]
2026-08-24 15:12:52.466068 | instance |
2026-08-24 15:12:52.466073 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-24 15:12:52.466078 | instance | Monday 24 August 2026  15:12:52 +0000 (0:00:00.031)       0:00:10.343 *********
2026-08-24 15:12:52.498743 | instance | skipping: [instance]
2026-08-24 15:12:52.499106 | instance |
2026-08-24 15:12:52.499536 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-24 15:12:52.499890 | instance | Monday 24 August 2026  15:12:52 +0000 (0:00:00.032)       0:00:10.375 *********
2026-08-24 15:12:58.546414 | instance | changed: [instance]
2026-08-24 15:12:58.546774 | instance |
2026-08-24 15:12:58.546792 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-24 15:12:58.546806 | instance | Monday 24 August 2026  15:12:58 +0000 (0:00:06.046)       0:00:16.422 *********
2026-08-24 15:12:59.146279 | instance | changed: [instance]
2026-08-24 15:12:59.146351 | instance |
2026-08-24 15:12:59.146628 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-24 15:12:59.146670 | instance | Monday 24 August 2026  15:12:59 +0000 (0:00:00.601)       0:00:17.023 *********
2026-08-24 15:13:00.090834 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-24 15:13:00.090878 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-24 15:13:00.090884 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-24 15:13:00.090894 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-24 15:13:00.090900 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-24 15:13:00.090904 | instance |
2026-08-24 15:13:00.090909 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-24 15:13:00.090913 | instance | Monday 24 August 2026  15:13:00 +0000 (0:00:00.943)       0:00:17.967 *********
2026-08-24 15:13:00.686638 | instance | changed: [instance]
2026-08-24 15:13:00.687625 | instance |
2026-08-24 15:13:00.687723 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-24 15:13:00.689108 | instance | Monday 24 August 2026  15:13:00 +0000 (0:00:00.579)       0:00:18.546 *********
2026-08-24 15:13:00.689212 | instance |
2026-08-24 15:13:00.689225 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-24 15:13:00.689236 | instance | Monday 24 August 2026  15:13:00 +0000 (0:00:00.016)       0:00:18.563 *********
2026-08-24 15:13:01.761496 | instance | ok: [instance]
2026-08-24 15:13:01.761641 | instance |
2026-08-24 15:13:01.761653 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-24 15:13:01.761830 | instance | Monday 24 August 2026  15:13:01 +0000 (0:00:01.075)       0:00:19.638 *********
2026-08-24 15:13:02.294314 | instance | changed: [instance]
2026-08-24 15:13:02.294403 | instance |
2026-08-24 15:13:02.294533 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-24 15:13:02.294707 | instance | Monday 24 August 2026  15:13:02 +0000 (0:00:00.532)       0:00:20.171 *********
2026-08-24 15:13:02.877506 | instance | changed: [instance]
2026-08-24 15:13:02.877626 | instance |
2026-08-24 15:13:02.877641 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-24 15:13:02.877805 | instance | Monday 24 August 2026  15:13:02 +0000 (0:00:00.583)       0:00:20.754 *********
2026-08-24 15:13:03.100632 | instance | ok: [instance]
2026-08-24 15:13:03.100707 | instance |
2026-08-24 15:13:03.101035 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-24 15:13:03.101093 | instance | Monday 24 August 2026  15:13:03 +0000 (0:00:00.223)       0:00:20.977 *********
2026-08-24 15:13:03.160572 | instance | ok: [instance] => {
2026-08-24 15:13:03.160618 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-24 15:13:03.160625 | instance | }
2026-08-24 15:13:03.160630 | instance |
2026-08-24 15:13:03.160634 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-24 15:13:03.160641 | instance | Monday 24 August 2026  15:13:03 +0000 (0:00:00.058)       0:00:21.036 *********
2026-08-24 15:13:04.237140 | instance | changed: [instance]
2026-08-24 15:13:04.237213 | instance |
2026-08-24 15:13:04.237504 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-24 15:13:04.237605 | instance | Monday 24 August 2026  15:13:04 +0000 (0:00:01.077)       0:00:22.114 *********
2026-08-24 15:13:08.781100 | instance | changed: [instance]
2026-08-24 15:13:08.781204 | instance |
2026-08-24 15:13:08.781449 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-24 15:13:08.781471 | instance | Monday 24 August 2026  15:13:08 +0000 (0:00:04.543)       0:00:26.658 *********
2026-08-24 15:13:10.329690 | instance | ok: [instance]
2026-08-24 15:13:10.329798 | instance |
2026-08-24 15:13:10.329869 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-24 15:13:10.330017 | instance | Monday 24 August 2026  15:13:10 +0000 (0:00:01.548)       0:00:28.206 *********
2026-08-24 15:13:10.734382 | instance | changed: [instance]
2026-08-24 15:13:10.734475 | instance |
2026-08-24 15:13:10.734754 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-24 15:13:10.734819 | instance | Monday 24 August 2026  15:13:10 +0000 (0:00:00.404)       0:00:28.611 *********
2026-08-24 15:13:11.238865 | instance | changed: [instance]
2026-08-24 15:13:11.238969 | instance |
2026-08-24 15:13:11.239038 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-24 15:13:11.239202 | instance | Monday 24 August 2026  15:13:11 +0000 (0:00:00.504)       0:00:29.115 *********
2026-08-24 15:13:11.884010 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-24 15:13:11.884115 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-24 15:13:11.884187 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-24 15:13:11.884589 | instance |
2026-08-24 15:13:11.884652 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-24 15:13:11.884658 | instance | Monday 24 August 2026  15:13:11 +0000 (0:00:00.644)       0:00:29.760 *********
2026-08-24 15:13:12.376590 | instance | changed: [instance]
2026-08-24 15:13:12.376678 | instance |
2026-08-24 15:13:12.376933 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-24 15:13:12.376991 | instance | Monday 24 August 2026  15:13:12 +0000 (0:00:00.492)       0:00:30.253 *********
2026-08-24 15:13:12.880122 | instance | changed: [instance]
2026-08-24 15:13:12.880222 | instance |
2026-08-24 15:13:12.880854 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-24 15:13:12.880935 | instance | Monday 24 August 2026  15:13:12 +0000 (0:00:00.484)       0:00:30.738 *********
2026-08-24 15:13:12.880940 | instance |
2026-08-24 15:13:12.880945 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-24 15:13:12.880956 | instance | Monday 24 August 2026  15:13:12 +0000 (0:00:00.018)       0:00:30.757 *********
2026-08-24 15:13:13.592916 | instance | ok: [instance]
2026-08-24 15:13:13.593025 | instance |
2026-08-24 15:13:13.593268 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-24 15:13:13.593329 | instance | Monday 24 August 2026  15:13:13 +0000 (0:00:00.712)       0:00:31.469 *********
2026-08-24 15:13:16.032463 | instance | changed: [instance]
2026-08-24 15:13:16.032562 | instance |
2026-08-24 15:13:16.032837 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-24 15:13:16.032903 | instance | Monday 24 August 2026  15:13:16 +0000 (0:00:02.439)       0:00:33.909 *********
2026-08-24 15:13:16.599762 | instance | changed: [instance]
2026-08-24 15:13:16.600286 | instance |
2026-08-24 15:13:16.600307 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-24 15:13:16.600315 | instance | Monday 24 August 2026  15:13:16 +0000 (0:00:00.567)       0:00:34.476 *********
2026-08-24 15:13:16.661536 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-24 15:13:16.661629 | instance |
2026-08-24 15:13:16.661994 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-24 15:13:16.662017 | instance | Monday 24 August 2026  15:13:16 +0000 (0:00:00.061)       0:00:34.538 *********
2026-08-24 15:13:22.253486 | instance | changed: [instance]
2026-08-24 15:13:22.253768 | instance |
2026-08-24 15:13:22.253785 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-24 15:13:22.253796 | instance | Monday 24 August 2026  15:13:22 +0000 (0:00:05.592)       0:00:40.130 *********
2026-08-24 15:13:22.922031 | instance | ok: [instance] => (item=chronyd)
2026-08-24 15:13:22.922122 | instance | ok: [instance] => (item=sshd)
2026-08-24 15:13:22.922537 | instance |
2026-08-24 15:13:22.922591 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-24 15:13:22.922597 | instance | Monday 24 August 2026  15:13:22 +0000 (0:00:00.668)       0:00:40.799 *********
2026-08-24 15:13:23.881045 | instance | changed: [instance]
2026-08-24 15:13:23.881136 | instance |
2026-08-24 15:13:23.881373 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-24 15:13:23.881433 | instance | Monday 24 August 2026  15:13:23 +0000 (0:00:00.958)       0:00:41.757 *********
2026-08-24 15:13:24.106881 | instance | ok: [instance]
2026-08-24 15:13:24.106966 | instance |
2026-08-24 15:13:24.107225 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-24 15:13:24.107280 | instance | Monday 24 August 2026  15:13:24 +0000 (0:00:00.226)       0:00:41.984 *********
2026-08-24 15:13:25.274174 | instance | changed: [instance]
2026-08-24 15:13:25.274285 | instance |
2026-08-24 15:13:25.274643 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-24 15:13:25.274707 | instance | Monday 24 August 2026  15:13:25 +0000 (0:00:01.166)       0:00:43.151 *********
2026-08-24 15:13:25.730751 | instance | changed: [instance]
2026-08-24 15:13:25.731038 | instance |
2026-08-24 15:13:25.731084 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-24 15:13:25.731297 | instance | Monday 24 August 2026  15:13:25 +0000 (0:00:00.456)       0:00:43.607 *********
2026-08-24 15:13:25.962029 | instance | ok: [instance]
2026-08-24 15:13:25.962137 | instance |
2026-08-24 15:13:25.962210 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-24 15:13:25.962377 | instance | Monday 24 August 2026  15:13:25 +0000 (0:00:00.231)       0:00:43.838 *********
2026-08-24 15:13:26.213091 | instance | ok: [instance] => (item=instance)
2026-08-24 15:13:26.213240 | instance |
2026-08-24 15:13:26.213475 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-24 15:13:26.213689 | instance | Monday 24 August 2026  15:13:26 +0000 (0:00:00.251)       0:00:44.090 *********
2026-08-24 15:13:26.269804 | instance | ok: [instance]
2026-08-24 15:13:26.269875 | instance |
2026-08-24 15:13:26.270154 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-24 15:13:26.270200 | instance | Monday 24 August 2026  15:13:26 +0000 (0:00:00.056)       0:00:44.147 *********
2026-08-24 15:13:26.350195 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-24 15:13:26.350585 | instance |
2026-08-24 15:13:26.350637 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-24 15:13:26.350644 | instance | Monday 24 August 2026  15:13:26 +0000 (0:00:00.080)       0:00:44.227 *********
2026-08-24 15:13:26.627535 | instance | changed: [instance]
2026-08-24 15:13:26.627648 | instance |
2026-08-24 15:13:26.627663 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-24 15:13:26.627841 | instance | Monday 24 August 2026  15:13:26 +0000 (0:00:00.277)       0:00:44.504 *********
2026-08-24 15:13:27.341111 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-24 15:13:27.341195 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-24 15:13:27.341746 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-24 15:13:27.341795 | instance |
2026-08-24 15:13:27.341800 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-24 15:13:27.341805 | instance | Monday 24 August 2026  15:13:27 +0000 (0:00:00.713)       0:00:45.218 *********
2026-08-24 15:15:02.821876 | instance | ok: [instance]
2026-08-24 15:15:02.822150 | instance |
2026-08-24 15:15:02.822459 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-24 15:15:02.823301 | instance | Monday 24 August 2026  15:15:02 +0000 (0:01:35.479)       0:02:20.697 *********
2026-08-24 15:15:03.042018 | instance | changed: [instance]
2026-08-24 15:15:03.042144 | instance |
2026-08-24 15:15:03.042304 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-24 15:15:03.042466 | instance | Monday 24 August 2026  15:15:03 +0000 (0:00:00.221)       0:02:20.919 *********
2026-08-24 15:15:03.084892 | instance | ok: [instance]
2026-08-24 15:15:03.084988 | instance |
2026-08-24 15:15:03.085110 | instance | TASK [Install Ceph host] *******************************************************
2026-08-24 15:15:03.085224 | instance | Monday 24 August 2026  15:15:03 +0000 (0:00:00.043)       0:02:20.962 *********
2026-08-24 15:15:03.166661 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-24 15:15:03.166730 | instance |
2026-08-24 15:15:03.166866 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-24 15:15:03.166986 | instance | Monday 24 August 2026  15:15:03 +0000 (0:00:00.081)       0:02:21.044 *********
2026-08-24 15:15:05.387753 | instance | ok: [instance]
2026-08-24 15:15:05.387835 | instance |
2026-08-24 15:15:05.387981 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-24 15:15:05.388092 | instance | Monday 24 August 2026  15:15:05 +0000 (0:00:02.221)       0:02:23.265 *********
2026-08-24 15:15:05.442042 | instance | ok: [instance] => (item=instance)
2026-08-24 15:15:05.442117 | instance |
2026-08-24 15:15:05.442264 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-24 15:15:05.442412 | instance | Monday 24 August 2026  15:15:05 +0000 (0:00:00.053)       0:02:23.319 *********
2026-08-24 15:15:05.845056 | instance | ok: [instance]
2026-08-24 15:15:05.845308 | instance |
2026-08-24 15:15:05.845490 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-24 15:15:05.845645 | instance | Monday 24 August 2026  15:15:05 +0000 (0:00:00.402)       0:02:23.722 *********
2026-08-24 15:15:08.183802 | instance | ok: [instance]
2026-08-24 15:15:08.183906 | instance |
2026-08-24 15:15:08.184121 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-24 15:15:08.184167 | instance | Monday 24 August 2026  15:15:08 +0000 (0:00:02.338)       0:02:26.061 *********
2026-08-24 15:16:07.172156 | 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-24 15:16:07.172784 | 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_y39ntum1/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_y39ntum1/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_y39ntum1/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_y39ntum1/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-24T15:16:06.986+0000 7fb6eb77c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDKX4xqLeC3NyAAlDArBpl72EpbwPkBGPMoctSJZgLyB+cgRgSxJGhj1gs=: Malformed input [buffer:3]\n2026-08-24T15:16:06.986+0000 7fb6eb77c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-24T15:16:06.990+0000 7fb6eb77c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDKX4xqLeC3NyAAlDArBpl72EpbwPkBGPMoctSJZgLyB+cgRgSxJGhj1gs=: Malformed input [buffer:3]\n2026-08-24T15:16:06.990+0000 7fb6eb77c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-24T15:16:06.990+0000 7fb6eb77c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDKX4xqLeC3NyAAlDArBpl72EpbwPkBGPMoctSJZgLyB+cgRgSxJGhj1gs=: Malformed input [buffer:3]\n2026-08-24T15:16:06.990+0000 7fb6eb77c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-24T15:16:06.990+0000 7fb6eb77c640 -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-24 15:16:07.174877 | instance |
2026-08-24 15:16:07.175056 | instance | PLAY RECAP *********************************************************************
2026-08-24 15:16:07.175337 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-08-24 15:16:07.177871 | instance |
2026-08-24 15:16:07.177880 | instance | Monday 24 August 2026  15:16:07 +0000 (0:00:58.991)       0:03:25.052 *********
2026-08-24 15:16:07.177885 | instance | ===============================================================================
2026-08-24 15:16:07.177889 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 95.48s
2026-08-24 15:16:07.177894 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 58.99s
2026-08-24 15:16:07.177898 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.05s
2026-08-24 15:16:07.177902 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.59s
2026-08-24 15:16:07.177906 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.54s
2026-08-24 15:16:07.177910 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.92s
2026-08-24 15:16:07.177925 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 2.44s
2026-08-24 15:16:07.177929 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.34s
2026-08-24 15:16:07.177933 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 2.22s
2026-08-24 15:16:07.177937 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.79s
2026-08-24 15:16:07.177941 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.55s
2026-08-24 15:16:07.177945 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.49s
2026-08-24 15:16:07.179317 | instance | vexxhost.ceph.cephadm : Ensure "cephadm" user is present ---------------- 1.17s
2026-08-24 15:16:07.179370 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.08s
2026-08-24 15:16:07.179375 | instance | Gathering Facts --------------------------------------------------------- 1.06s
2026-08-24 15:16:07.179379 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 0.96s
2026-08-24 15:16:07.179383 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.94s
2026-08-24 15:16:07.179387 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-08-24 15:16:07.179391 | instance | Gathering Facts --------------------------------------------------------- 0.92s
2026-08-24 15:16:07.179399 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.75s
2026-08-24 15:16:07.282020 | 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-24 15:16:07.282182 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-24 15:16:07.282339 | 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-24 15:16:07.490673 | instance | ERROR
2026-08-24 15:16:07.490922 | instance | {
2026-08-24 15:16:07.490963 | instance |   "delta": "0:04:22.002148",
2026-08-24 15:16:07.490992 | instance |   "end": "2026-08-24 15:16:07.338833",
2026-08-24 15:16:07.491018 | instance |   "msg": "non-zero return code",
2026-08-24 15:16:07.491044 | instance |   "rc": 2,
2026-08-24 15:16:07.491068 | instance |   "start": "2026-08-24 15:11:45.336685"
2026-08-24 15:16:07.491095 | instance | }
failure
2026-08-24 15:16:07.497567 | 
2026-08-24 15:16:07.497633 | PLAY RECAP
2026-08-24 15:16:07.497678 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-24 15:16:07.497701 | 
2026-08-24 15:16:07.703460 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-24 15:16:07.706898 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-24 15:16:09.028024 | 
2026-08-24 15:16:09.028163 | PLAY [all]
2026-08-24 15:16:09.053777 | 
2026-08-24 15:16:09.053908 | TASK [gather-host-logs : creating directory for system status]
2026-08-24 15:16:09.455671 | instance | changed
2026-08-24 15:16:09.473430 | 
2026-08-24 15:16:09.473662 | TASK [gather-host-logs : Get logs for each host]
2026-08-24 15:16:09.845088 | instance | + systemd-cgls --full --all --no-pager
2026-08-24 15:16:09.863644 | instance | + ip addr
2026-08-24 15:16:09.866682 | instance | + ip route
2026-08-24 15:16:09.869651 | instance | + lsblk
2026-08-24 15:16:09.873441 | instance | + mount
2026-08-24 15:16:09.875527 | instance | + docker images
2026-08-24 15:16:09.901016 | instance | + brctl show
2026-08-24 15:16:09.901729 | instance | /bin/bash: line 8: brctl: command not found
2026-08-24 15:16:09.902074 | instance | + ps aux --sort=-%mem
2026-08-24 15:16:09.917426 | instance | + dpkg -l
2026-08-24 15:16:09.932089 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-24 15:16:09.932624 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-24 15:16:09.947936 | instance | + '[' '!' -z '' ']'
2026-08-24 15:16:10.013596 | instance | ok: Runtime: 0:00:00.108020
2026-08-24 15:16:10.020071 | 
2026-08-24 15:16:10.020161 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-24 15:16:10.674470 | instance | changed:
2026-08-24 15:16:10.674634 | instance | created directory /var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/work/logs/instance
2026-08-24 15:16:10.674674 | instance | cd+++++++++ system/
2026-08-24 15:16:10.674718 | instance | >f+++++++++ system/brctl-show.txt
2026-08-24 15:16:10.674779 | instance | >f+++++++++ system/docker-images.txt
2026-08-24 15:16:10.674836 | instance | >f+++++++++ system/ip-addr.txt
2026-08-24 15:16:10.674894 | instance | >f+++++++++ system/ip-route.txt
2026-08-24 15:16:10.674934 | instance | >f+++++++++ system/lsblk.txt
2026-08-24 15:16:10.674965 | instance | >f+++++++++ system/mount.txt
2026-08-24 15:16:10.674994 | instance | >f+++++++++ system/packages.txt
2026-08-24 15:16:10.675023 | instance | >f+++++++++ system/ps.txt
2026-08-24 15:16:10.675054 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-24 15:16:10.684051 | 
2026-08-24 15:16:10.684124 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-24 15:16:10.879378 | instance | changed: "values"
2026-08-24 15:16:11.047333 | instance | changed: "releases"
2026-08-24 15:16:11.063859 | 
2026-08-24 15:16:11.064446 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-24 15:16:11.280546 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-24 15:16:11.615317 | instance | ok: Runtime: 0:00:00.006081
2026-08-24 15:16:11.623940 | 
2026-08-24 15:16:11.624033 | TASK [helm-release-status : Downloads logs to executor]
2026-08-24 15:16:12.135484 | instance | changed:
2026-08-24 15:16:12.135736 | instance | cd+++++++++ helm/
2026-08-24 15:16:12.135864 | instance | cd+++++++++ helm/releases/
2026-08-24 15:16:12.135913 | instance | cd+++++++++ helm/values/
2026-08-24 15:16:12.148594 | 
2026-08-24 15:16:12.148708 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-24 15:16:12.354967 | instance | changed
2026-08-24 15:16:12.363845 | 
2026-08-24 15:16:12.363985 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-24 15:16:12.609681 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-24 15:16:12.609901 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-24 15:16:12.614692 | instance | environment: line 1: kubectl: command not found
2026-08-24 15:16:12.616048 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-24 15:16:12.617945 | instance | environment: line 1: kubectl: command not found
2026-08-24 15:16:12.619176 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-24 15:16:12.625592 | instance | environment: line 1: kubectl: command not found
2026-08-24 15:16:12.627072 | instance | xargs: environment: line 1: kubectl: command not found
2026-08-24 15:16:12.627085 | instance | warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-24 15:16:12.628394 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-24 15:16:12.629532 | instance | environment: line 1: kubectl: command not found
2026-08-24 15:16:12.630443 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-24 15:16:12.898527 | instance | ok: Runtime: 0:00:00.029476
2026-08-24 15:16:12.903673 | 
2026-08-24 15:16:12.903741 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-24 15:16:13.128753 | instance | changed
2026-08-24 15:16:13.137137 | 
2026-08-24 15:16:13.137299 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-24 15:16:13.365847 | instance | environment: line 5: kubectl: command not found
2026-08-24 15:16:13.366930 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-24 15:16:13.366946 | instance |
2026-08-24 15:16:13.367222 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-24 15:16:13.685533 | instance | ok: Runtime: 0:00:00.008871
2026-08-24 15:16:13.696654 | 
2026-08-24 15:16:13.696788 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-24 15:16:14.261777 | instance | changed:
2026-08-24 15:16:14.261932 | instance | cd+++++++++ objects/
2026-08-24 15:16:14.261960 | instance | cd+++++++++ objects/cluster/
2026-08-24 15:16:14.261981 | instance | cd+++++++++ objects/namespaced/
2026-08-24 15:16:14.271325 | 
2026-08-24 15:16:14.271426 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-24 15:16:14.476035 | instance | changed
2026-08-24 15:16:14.482501 | 
2026-08-24 15:16:14.482633 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-24 15:16:14.731109 | instance | changed
2026-08-24 15:16:14.736632 | 
2026-08-24 15:16:14.737034 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-24 15:16:14.952504 | instance | environment: line 3: kubectl: command not found
2026-08-24 15:16:15.284404 | instance | ok: Runtime: 0:00:00.008545
2026-08-24 15:16:15.292625 | 
2026-08-24 15:16:15.292693 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-24 15:16:15.825279 | instance | changed:
2026-08-24 15:16:15.825471 | instance | cd+++++++++ pod-logs/
2026-08-24 15:16:15.825513 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-24 15:16:15.836101 | 
2026-08-24 15:16:15.836169 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-24 15:16:16.026540 | instance | changed
2026-08-24 15:16:16.031866 | 
2026-08-24 15:16:16.031961 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-24 15:16:16.243221 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-24 15:16:16.577062 | instance | ok: Runtime: 0:00:00.035817
2026-08-24 15:16:16.584298 | 
2026-08-24 15:16:16.584397 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-24 15:16:16.814581 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-24 15:16:16.842894 | instance | ceph-mgr endpoints:
2026-08-24 15:16:17.122040 | instance | ok: Runtime: 0:00:00.034956
2026-08-24 15:16:17.130459 | 
2026-08-24 15:16:17.130584 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-24 15:16:17.331771 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-24 15:16:17.663393 | instance | ok: Runtime: 0:00:00.032486
2026-08-24 15:16:17.670114 | 
2026-08-24 15:16:17.670186 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-24 15:16:18.197331 | instance | changed: cd+++++++++ prometheus/
2026-08-24 15:16:18.208917 | 
2026-08-24 15:16:18.209111 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-24 15:16:18.403831 | instance | changed
2026-08-24 15:16:18.410250 | 
2026-08-24 15:16:18.410404 | TASK [gather-selenium-data : Get selenium data]
2026-08-24 15:16:18.653095 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-24 15:16:18.654111 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-24 15:16:18.944361 | instance | ERROR
2026-08-24 15:16:18.944518 | instance | {
2026-08-24 15:16:18.944553 | instance |   "delta": "0:00:00.006060",
2026-08-24 15:16:18.944576 | instance |   "end": "2026-08-24 15:16:18.654362",
2026-08-24 15:16:18.944601 | instance |   "msg": "non-zero return code",
2026-08-24 15:16:18.944623 | instance |   "rc": 1,
2026-08-24 15:16:18.944643 | instance |   "start": "2026-08-24 15:16:18.648302"
2026-08-24 15:16:18.944663 | instance | }
2026-08-24 15:16:18.944690 | instance | ERROR: Ignoring Errors
2026-08-24 15:16:18.950026 | 
2026-08-24 15:16:18.950118 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-24 15:16:19.532558 | instance | changed: cd+++++++++ selenium/
2026-08-24 15:16:19.541018 | 
2026-08-24 15:16:19.541129 | PLAY RECAP
2026-08-24 15:16:19.541196 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-24 15:16:19.541230 | 
2026-08-24 15:16:19.742287 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-24 15:16:19.778730 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-24 15:16:20.639894 | 
2026-08-24 15:16:20.640029 | PLAY [all]
2026-08-24 15:16:20.652859 | 
2026-08-24 15:16:20.653011 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-24 15:16:20.718152 | instance | skipping: Conditional result was False
2026-08-24 15:16:20.726296 | 
2026-08-24 15:16:20.726421 | TASK [fetch-output : Set log path for single node]
2026-08-24 15:16:20.770789 | instance | ok
2026-08-24 15:16:20.778586 | 
2026-08-24 15:16:20.778708 | LOOP [fetch-output : Ensure local output dirs]
2026-08-24 15:16:21.203459 | instance -> localhost | ok: "/var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/work/logs"
2026-08-24 15:16:21.418247 | instance -> localhost | changed: "/var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/work/artifacts"
2026-08-24 15:16:21.664777 | instance -> localhost | changed: "/var/lib/zuul/builds/56ffa0e0f651463a9ae2c7c66fe3e72b/work/docs"
2026-08-24 15:16:21.677873 | 
2026-08-24 15:16:21.677992 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-24 15:16:22.430721 | instance | changed: .d..t...... ./
2026-08-24 15:16:22.431511 | instance | changed: All items complete
2026-08-24 15:16:22.431676 | 
2026-08-24 15:16:22.947372 | instance | changed: .d..t...... ./
2026-08-24 15:16:23.456547 | instance | changed: .d..t...... ./
2026-08-24 15:16:23.477611 | 
2026-08-24 15:16:23.477734 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-24 15:16:24.110084 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.166542
2026-08-24 15:16:24.394369 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007385
2026-08-24 15:16:24.409709 | 
2026-08-24 15:16:24.409879 | PLAY [all]
2026-08-24 15:16:24.416284 | 
2026-08-24 15:16:24.416352 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-24 15:16:24.926129 | instance | changed
2026-08-24 15:16:24.934205 | 
2026-08-24 15:16:24.934265 | PLAY RECAP
2026-08-24 15:16:24.934316 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-24 15:16:24.934338 | 
2026-08-24 15:16:25.124364 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-24 15:16:25.129292 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-24 15:16:25.834271 | 
2026-08-24 15:16:25.974839 | PLAY [localhost]
2026-08-24 15:16:25.993490 | 
2026-08-24 15:16:25.993665 | TASK [Generate Zuul manifest]
2026-08-24 15:16:26.019046 | localhost | ok
2026-08-24 15:16:26.241876 | 
2026-08-24 15:16:26.242069 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-24 15:16:26.714885 | localhost | changed
2026-08-24 15:16:26.908365 | 
2026-08-24 15:16:26.908571 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-24 15:16:27.095399 | localhost | ok
2026-08-24 15:16:27.103093 | 
2026-08-24 15:16:27.103226 | TASK [Upload logs]
2026-08-24 15:16:27.131381 | localhost | ok
2026-08-24 15:16:27.389688 | 
2026-08-24 15:16:27.389862 | TASK [Set zuul-log-path fact]
2026-08-24 15:16:27.414388 | localhost | ok
2026-08-24 15:16:27.460767 | 
2026-08-24 15:16:27.460947 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-24 15:16:27.492728 | localhost | ok
2026-08-24 15:16:27.501187 | 
2026-08-24 15:16:27.501310 | TASK [upload-logs : Create log directories]
2026-08-24 15:16:27.921226 | localhost | changed
2026-08-24 15:16:27.927927 | 
2026-08-24 15:16:27.928050 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-24 15:16:28.377807 | localhost -> localhost | ok: Runtime: 0:00:00.013960
2026-08-24 15:16:28.383339 | 
2026-08-24 15:16:28.383408 | TASK [upload-logs : Upload logs to log server]
2026-08-24 15:16:29.007012 | localhost | Output suppressed because no_log was given
2026-08-24 15:16:29.064833 | 
2026-08-24 15:16:29.065051 | LOOP [upload-logs : Compress console log and json output]
2026-08-24 15:16:29.143367 | localhost | skipping: Conditional result was False
2026-08-24 15:16:29.151806 | localhost | skipping: Conditional result was False
2026-08-24 15:16:29.171610 | 
2026-08-24 15:16:29.171802 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-24 15:16:29.221325 | localhost | skipping: Conditional result was False
2026-08-24 15:16:29.221750 | 
2026-08-24 15:16:29.226063 | localhost | skipping: Conditional result was False
2026-08-24 15:16:29.237379 | 
2026-08-24 15:16:29.237561 | LOOP [upload-logs : Upload console log and json output]