2026-09-09 06:08:38.415187 | Job console starting
2026-09-09 06:08:38.424459 | Updating git repos
2026-09-09 06:08:45.824396 | Cloning repos into workspace
2026-09-09 06:09:00.903252 | Restoring repo states
2026-09-09 06:09:13.057732 | Merging changes
2026-09-09 06:09:14.590921 | Checking out repos
2026-09-09 06:09:17.679348 | Preparing playbooks
2026-09-09 06:09:20.250549 | Running Ansible setup
2026-09-09 06:09:23.923416 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-09 06:09:24.556841 | 
2026-09-09 06:09:24.751959 | PLAY [localhost]
2026-09-09 06:09:24.767467 | 
2026-09-09 06:09:24.767628 | TASK [Gathering Facts]
2026-09-09 06:09:25.663897 | localhost | ok
2026-09-09 06:09:25.676578 | 
2026-09-09 06:09:25.676735 | TASK [Setup log path fact]
2026-09-09 06:09:25.700333 | localhost | ok
2026-09-09 06:09:25.721058 | 
2026-09-09 06:09:25.721161 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-09 06:09:25.753664 | localhost | ok
2026-09-09 06:09:25.765169 | 
2026-09-09 06:09:25.765301 | TASK [emit-job-header : Print job information]
2026-09-09 06:09:25.817110 | # Job Information
2026-09-09 06:09:25.817360 | Ansible Version: 2.16.18
2026-09-09 06:09:25.817426 | Job: atmosphere-molecule-csi-rbd
2026-09-09 06:09:25.817468 | Pipeline: check
2026-09-09 06:09:25.817506 | Executor: 2d72f0692154
2026-09-09 06:09:25.817544 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4094
2026-09-09 06:09:25.817590 | Event ID: 6bb8c2b0-ac14-11f1-9b5b-d5fc46f65dda
2026-09-09 06:09:25.823018 | 
2026-09-09 06:09:25.823147 | LOOP [emit-job-header : Print node information]
2026-09-09 06:09:25.930118 | localhost | ok:
2026-09-09 06:09:25.930384 | localhost | # Node Information
2026-09-09 06:09:25.930449 | localhost | Inventory Hostname: instance
2026-09-09 06:09:25.930499 | localhost | Hostname: np0000192372
2026-09-09 06:09:25.930545 | localhost | Username: zuul
2026-09-09 06:09:25.930596 | localhost | Distro: Ubuntu 22.04
2026-09-09 06:09:25.930642 | localhost | Provider: yul1
2026-09-09 06:09:25.930685 | localhost | Region: ca-ymq-1
2026-09-09 06:09:25.930727 | localhost | Label: ubuntu-jammy
2026-09-09 06:09:25.930769 | localhost | Product Name: OpenStack Nova
2026-09-09 06:09:25.930810 | localhost | Interface IP: 162.253.55.186
2026-09-09 06:09:25.942960 | 
2026-09-09 06:09:25.943138 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-09 06:09:26.364144 | localhost -> localhost | changed
2026-09-09 06:09:26.370544 | 
2026-09-09 06:09:26.370646 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-09 06:09:27.285862 | localhost -> localhost | changed
2026-09-09 06:09:27.293322 | 
2026-09-09 06:09:27.293427 | PLAY [all]
2026-09-09 06:09:27.304308 | 
2026-09-09 06:09:27.304554 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-09 06:09:27.587663 | instance -> localhost | ok
2026-09-09 06:09:27.599249 | 
2026-09-09 06:09:27.599384 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-09 06:09:27.629854 | instance | ok
2026-09-09 06:09:27.648651 | instance | included: /var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-09 06:09:27.656198 | 
2026-09-09 06:09:27.656259 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-09 06:09:28.346296 | instance -> localhost | Generating public/private rsa key pair.
2026-09-09 06:09:28.346546 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/work/312bed97db5e4eeb8e10e9f475bd26ac_id_rsa
2026-09-09 06:09:28.346609 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/work/312bed97db5e4eeb8e10e9f475bd26ac_id_rsa.pub
2026-09-09 06:09:28.346757 | instance -> localhost | The key fingerprint is:
2026-09-09 06:09:28.346805 | instance -> localhost | SHA256:wbnIfYB3cAEJB2TFjk5l9vsaxwpCBSy7ow/8lSX6kHA zuul-build-sshkey
2026-09-09 06:09:28.346866 | instance -> localhost | The key's randomart image is:
2026-09-09 06:09:28.346910 | instance -> localhost | +---[RSA 3072]----+
2026-09-09 06:09:28.346989 | instance -> localhost | |     o*==oo.     |
2026-09-09 06:09:28.347035 | instance -> localhost | |    ...=*+       |
2026-09-09 06:09:28.347076 | instance -> localhost | |     o.*B..      |
2026-09-09 06:09:28.347118 | instance -> localhost | |    ..o=.=.      |
2026-09-09 06:09:28.347158 | instance -> localhost | |  . E+= S ..     |
2026-09-09 06:09:28.347201 | instance -> localhost | | . oo+.+ ...     |
2026-09-09 06:09:28.347242 | instance -> localhost | |  o.+.+ . ..o    |
2026-09-09 06:09:28.347282 | instance -> localhost | |  .o + . . +.    |
2026-09-09 06:09:28.347329 | instance -> localhost | |   .o .   o.     |
2026-09-09 06:09:28.347371 | instance -> localhost | +----[SHA256]-----+
2026-09-09 06:09:28.347463 | instance -> localhost | ok: Runtime: 0:00:00.284496
2026-09-09 06:09:28.359503 | 
2026-09-09 06:09:28.359637 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-09 06:09:28.391957 | instance | ok
2026-09-09 06:09:28.408623 | instance | included: /var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-09 06:09:28.419887 | 
2026-09-09 06:09:28.419988 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-09 06:09:28.443463 | instance | skipping: Conditional result was False
2026-09-09 06:09:28.453462 | 
2026-09-09 06:09:28.453617 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-09 06:09:28.944225 | instance | changed
2026-09-09 06:09:28.954778 | 
2026-09-09 06:09:28.955049 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-09 06:09:29.149123 | instance | ok
2026-09-09 06:09:29.158671 | 
2026-09-09 06:09:29.158790 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-09 06:09:29.688336 | instance | changed
2026-09-09 06:09:29.693205 | 
2026-09-09 06:09:29.693267 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-09 06:09:30.230004 | instance | changed
2026-09-09 06:09:30.238834 | 
2026-09-09 06:09:30.239082 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-09 06:09:30.279225 | instance | skipping: Conditional result was False
2026-09-09 06:09:30.291331 | 
2026-09-09 06:09:30.291424 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-09 06:09:30.749697 | instance -> localhost | changed
2026-09-09 06:09:30.761597 | 
2026-09-09 06:09:30.761720 | TASK [add-build-sshkey : Add back temp key]
2026-09-09 06:09:31.066909 | instance -> localhost | Identity added: /var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/work/312bed97db5e4eeb8e10e9f475bd26ac_id_rsa (zuul-build-sshkey)
2026-09-09 06:09:31.067196 | instance -> localhost | ok: Runtime: 0:00:00.012190
2026-09-09 06:09:31.075765 | 
2026-09-09 06:09:31.075844 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-09 06:09:31.430729 | instance | ok
2026-09-09 06:09:31.435055 | 
2026-09-09 06:09:31.435117 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-09 06:09:31.479250 | instance | skipping: Conditional result was False
2026-09-09 06:09:31.498429 | 
2026-09-09 06:09:31.498609 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-09 06:09:31.876234 | instance | ok
2026-09-09 06:09:31.882814 | 
2026-09-09 06:09:31.882956 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-09 06:09:36.228103 | instance | Output suppressed because no_log was given
2026-09-09 06:09:36.237178 | 
2026-09-09 06:09:36.237245 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-09 06:09:36.458568 | instance | ok: "logs"
2026-09-09 06:09:36.458994 | instance | ok: All items complete
2026-09-09 06:09:36.459054 | 
2026-09-09 06:09:36.611973 | instance | ok: "artifacts"
2026-09-09 06:09:36.794133 | instance | ok: "docs"
2026-09-09 06:09:36.816372 | 
2026-09-09 06:09:36.816624 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-09 06:09:37.027180 | instance | changed: "logs"
2026-09-09 06:09:37.216019 | instance | changed: "artifacts"
2026-09-09 06:09:37.376748 | instance | changed: "docs"
2026-09-09 06:09:37.392892 | 
2026-09-09 06:09:37.393103 | PLAY RECAP
2026-09-09 06:09:37.393205 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-09 06:09:37.393279 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-09 06:09:37.393321 | 
2026-09-09 06:09:37.552915 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-09 06:09:37.556252 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-09 06:09:38.197919 | 
2026-09-09 06:09:38.198092 | PLAY [all]
2026-09-09 06:09:38.208764 | 
2026-09-09 06:09:38.208874 | TASK [setup-uv : Extract archive]
2026-09-09 06:09:40.484658 | instance | changed
2026-09-09 06:09:40.493832 | 
2026-09-09 06:09:40.494031 | TASK [setup-uv : Print version]
2026-09-09 06:09:40.886650 | instance | uv 0.8.13
2026-09-09 06:09:41.032836 | instance | ok: Runtime: 0:00:00.011993
2026-09-09 06:09:41.046353 | 
2026-09-09 06:09:41.046547 | TASK [Install Ansible collection siblings]
2026-09-09 06:09:50.866716 | instance | changed:
2026-09-09 06:09:50.867599 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-09 06:09:50.868073 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-09 06:09:50.868324 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-09 06:09:50.868367 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-09 06:09:50.868396 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-09 06:09:50.868429 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-09 06:09:50.869025 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-09 06:09:50.869102 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-09 06:09:50.869135 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-09 06:09:50.869450 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-09 06:09:50.869828 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-09 06:09:50.870184 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-09 06:09:50.870254 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-09 06:09:50.870283 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-09 06:09:50.870555 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-09 06:09:50.870599 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-09 06:09:50.870627 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-09 06:09:50.871376 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-09 06:09:50.871433 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-09 06:09:50.871626 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-09 06:09:50.871660 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-09 06:09:50.871687 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-09 06:09:50.871728 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-09 06:09:50.872477 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-09 06:09:50.874562 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-09 06:09:50.874988 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-09 06:09:50.875024 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-09 06:09:50.875051 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-09 06:09:50.875075 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-09 06:09:50.875100 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-09 06:09:50.875125 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-09 06:09:50.875150 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-09 06:09:50.875176 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-09 06:09:50.875201 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-09 06:09:50.875236 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.875261 | instance | Process install dependency map
2026-09-09 06:09:50.875287 | instance | Starting collection install process
2026-09-09 06:09:50.875311 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-09 06:09:50.875335 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-09 06:09:50.875359 | instance | ansible.netcommon:* was installed successfully
2026-09-09 06:09:50.875384 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-09 06:09:50.875408 | instance | Creating virtual environment at: .venv
2026-09-09 06:09:50.875433 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-09 06:09:50.875457 | instance | Downloading kubernetes (1.9MiB)
2026-09-09 06:09:50.875481 | instance | Downloading setuptools (1.1MiB)
2026-09-09 06:09:50.875581 | instance | Downloading ansible-core (2.1MiB)
2026-09-09 06:09:50.875642 | instance | Downloading openstacksdk (1.7MiB)
2026-09-09 06:09:50.875676 | instance | Downloading pygments (1.2MiB)
2026-09-09 06:09:50.875703 | instance | Downloading netaddr (2.2MiB)
2026-09-09 06:09:50.875729 | instance | Downloading cryptography (4.2MiB)
2026-09-09 06:09:50.875754 | instance | Downloading pydantic-core (2.0MiB)
2026-09-09 06:09:50.875778 | instance | Downloading rjsonnet (1.2MiB)
2026-09-09 06:09:50.875804 | instance |    Building pyperclip==1.9.0
2026-09-09 06:09:50.875844 | instance |  Downloading rjsonnet
2026-09-09 06:09:50.875874 | instance |  Downloading pydantic-core
2026-09-09 06:09:50.875898 | instance |  Downloading netaddr
2026-09-09 06:09:50.875943 | instance |  Downloading pygments
2026-09-09 06:09:50.875974 | instance |  Downloading cryptography
2026-09-09 06:09:50.875999 | instance |  Downloading setuptools
2026-09-09 06:09:50.876023 | instance |  Downloading kubernetes
2026-09-09 06:09:50.876048 | instance |  Downloading ansible-core
2026-09-09 06:09:50.876072 | instance |  Downloading openstacksdk
2026-09-09 06:09:50.876097 | instance |       Built pyperclip==1.9.0
2026-09-09 06:09:50.876121 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-09 06:09:50.876145 | instance | Installed 83 packages in 67ms
2026-09-09 06:09:50.876169 | instance | Checked out ansible.posix to 1.6.0
2026-09-09 06:09:50.876193 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-09 06:09:50.876216 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.876240 | instance | Process install dependency map
2026-09-09 06:09:50.876277 | instance | Starting collection install process
2026-09-09 06:09:50.876302 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-09 06:09:50.876327 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-09 06:09:50.876351 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-09 06:09:50.876375 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.876399 | instance | 2.17.12
2026-09-09 06:09:50.876423 | instance | Checked out ansible.utils to v6.1.0
2026-09-09 06:09:50.876448 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-09 06:09:50.876472 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.876516 | instance | Process install dependency map
2026-09-09 06:09:50.876543 | instance | Starting collection install process
2026-09-09 06:09:50.876569 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-09 06:09:50.876593 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-09 06:09:50.876617 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-09 06:09:50.876641 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.876696 | instance | 2.17.12
2026-09-09 06:09:50.876940 | instance | Checked out atmosphere.common to v0.7.0
2026-09-09 06:09:50.876992 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-09 06:09:50.877035 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.877070 | instance | Process install dependency map
2026-09-09 06:09:50.877097 | instance | Starting collection install process
2026-09-09 06:09:50.877122 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-09 06:09:50.877148 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-09 06:09:50.877184 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-09 06:09:50.877225 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.877263 | instance | 2.17.12
2026-09-09 06:09:50.877294 | instance | Checked out community.crypto to 2.2.3
2026-09-09 06:09:50.877320 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-09 06:09:50.877345 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.877369 | instance | Process install dependency map
2026-09-09 06:09:50.877473 | instance | Starting collection install process
2026-09-09 06:09:50.877539 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-09 06:09:50.877581 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-09 06:09:50.877616 | instance | community.crypto:2.2.3 was installed successfully
2026-09-09 06:09:50.877652 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.877688 | instance | 2.17.12
2026-09-09 06:09:50.877721 | instance | Checked out community.general to 7.3.0
2026-09-09 06:09:50.877747 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-09 06:09:50.877783 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.877809 | instance | Process install dependency map
2026-09-09 06:09:50.877833 | instance | Starting collection install process
2026-09-09 06:09:50.877858 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-09 06:09:50.877882 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-09 06:09:50.877909 | instance | community.general:7.3.0 was installed successfully
2026-09-09 06:09:50.877966 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.878004 | instance | 2.17.12
2026-09-09 06:09:50.878039 | instance | Checked out community.mysql to 3.6.0
2026-09-09 06:09:50.878071 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-09 06:09:50.878097 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.878121 | instance | Process install dependency map
2026-09-09 06:09:50.878148 | instance | Starting collection install process
2026-09-09 06:09:50.878172 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-09 06:09:50.878196 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-09 06:09:50.878220 | instance | community.mysql:3.6.0 was installed successfully
2026-09-09 06:09:50.878246 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.878270 | instance | 2.17.12
2026-09-09 06:09:50.878294 | instance | Checked out kubernetes.core to 2.4.0
2026-09-09 06:09:50.878319 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-09 06:09:50.878344 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.878369 | instance | Process install dependency map
2026-09-09 06:09:50.878393 | instance | Starting collection install process
2026-09-09 06:09:50.878418 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-09 06:09:50.878442 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-09 06:09:50.878466 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-09 06:09:50.878490 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.878514 | instance | 2.17.12
2026-09-09 06:09:50.878538 | instance | Checked out openstack.cloud to 2.6.0
2026-09-09 06:09:50.878562 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-09 06:09:50.878587 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.878611 | instance | Process install dependency map
2026-09-09 06:09:50.878635 | instance | Starting collection install process
2026-09-09 06:09:50.878830 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-09 06:09:50.878874 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-09 06:09:50.878901 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-09 06:09:50.878945 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.878981 | instance | 2.17.12
2026-09-09 06:09:50.879039 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-09 06:09:50.879067 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-09 06:09:50.879091 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.879116 | instance | Process install dependency map
2026-09-09 06:09:50.879140 | instance | Starting collection install process
2026-09-09 06:09:50.879166 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-09 06:09:50.879192 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-09 06:09:50.879217 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-09 06:09:50.879329 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.879395 | instance | 2.17.12
2026-09-09 06:09:50.879425 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-09 06:09:50.879450 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-09 06:09:50.879477 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.879502 | instance | Process install dependency map
2026-09-09 06:09:50.879526 | instance | Starting collection install process
2026-09-09 06:09:50.879550 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-09 06:09:50.879574 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-09 06:09:50.879598 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-09 06:09:50.879622 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.879646 | instance | 2.17.12
2026-09-09 06:09:50.879670 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-09 06:09:50.879695 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-09 06:09:50.879719 | instance | Starting galaxy collection install process
2026-09-09 06:09:50.879743 | instance | Process install dependency map
2026-09-09 06:09:50.879767 | instance | Starting collection install process
2026-09-09 06:09:50.879791 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-09 06:09:50.879814 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-09 06:09:50.879837 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-09 06:09:50.879861 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-09 06:09:50.879884 | instance | 2.17.12
2026-09-09 06:09:50.889530 | 
2026-09-09 06:09:50.889615 | PLAY RECAP
2026-09-09 06:09:50.889766 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-09 06:09:50.889805 | 
2026-09-09 06:09:51.080548 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-09 06:09:51.087119 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-09 06:09:51.625354 | 
2026-09-09 06:09:51.625472 | PLAY [all]
2026-09-09 06:09:51.635432 | 
2026-09-09 06:09:51.635500 | TASK [Install "jq" for log collection]
2026-09-09 06:10:01.413037 | instance | changed
2026-09-09 06:10:01.416075 | 
2026-09-09 06:10:01.416189 | PLAY RECAP
2026-09-09 06:10:01.416286 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-09 06:10:01.416396 | 
2026-09-09 06:10:01.548308 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-09 06:10:01.552390 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-09 06:10:02.182389 | 
2026-09-09 06:10:02.182756 | PLAY [all]
2026-09-09 06:10:02.193575 | 
2026-09-09 06:10:02.193683 | LOOP [Check inventory directories]
2026-09-09 06:10:02.568464 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-09 06:10:02.704694 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-09 06:10:02.838979 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-09 06:10:02.851513 | 
2026-09-09 06:10:02.851766 | LOOP [Copy inventory file for Zuul]
2026-09-09 06:10:03.677724 | instance | changed:
2026-09-09 06:10:03.678026 | instance | {
2026-09-09 06:10:03.678089 | instance |   "ansible_loop_var": "item",
2026-09-09 06:10:03.678137 | instance |   "changed": false,
2026-09-09 06:10:03.678176 | instance |   "failed": false,
2026-09-09 06:10:03.678225 | instance |   "invocation": {
2026-09-09 06:10:03.678264 | instance |     "module_args": {
2026-09-09 06:10:03.678300 | instance |       "checksum_algorithm": "sha1",
2026-09-09 06:10:03.678335 | instance |       "follow": true,
2026-09-09 06:10:03.678370 | instance |       "get_attributes": true,
2026-09-09 06:10:03.678406 | instance |       "get_checksum": true,
2026-09-09 06:10:03.678441 | instance |       "get_mime": true,
2026-09-09 06:10:03.678476 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-09 06:10:03.678511 | instance |     }
2026-09-09 06:10:03.678545 | instance |   },
2026-09-09 06:10:03.678579 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-09 06:10:03.678614 | instance |   "stat": {
2026-09-09 06:10:03.678648 | instance |     "atime": 1788934183.6426864,
2026-09-09 06:10:03.678683 | instance |     "attr_flags": "e",
2026-09-09 06:10:03.678718 | instance |     "attributes": [
2026-09-09 06:10:03.678751 | instance |       "extents"
2026-09-09 06:10:03.678784 | instance |     ],
2026-09-09 06:10:03.678818 | instance |     "block_size": 4096,
2026-09-09 06:10:03.678850 | instance |     "blocks": 8,
2026-09-09 06:10:03.678884 | instance |     "charset": "unknown",
2026-09-09 06:10:03.678936 | instance |     "ctime": 1788934181.9866478,
2026-09-09 06:10:03.678972 | instance |     "dev": 64513,
2026-09-09 06:10:03.679006 | instance |     "device_type": 0,
2026-09-09 06:10:03.679039 | instance |     "executable": true,
2026-09-09 06:10:03.679072 | instance |     "exists": true,
2026-09-09 06:10:03.679104 | instance |     "gid": 1000,
2026-09-09 06:10:03.679137 | instance |     "gr_name": "zuul",
2026-09-09 06:10:03.679170 | instance |     "inode": 1047152,
2026-09-09 06:10:03.679203 | instance |     "isblk": false,
2026-09-09 06:10:03.679236 | instance |     "ischr": false,
2026-09-09 06:10:03.679269 | instance |     "isdir": true,
2026-09-09 06:10:03.679303 | instance |     "isfifo": false,
2026-09-09 06:10:03.679336 | instance |     "isgid": false,
2026-09-09 06:10:03.679369 | instance |     "islnk": false,
2026-09-09 06:10:03.679403 | instance |     "isreg": false,
2026-09-09 06:10:03.679435 | instance |     "issock": false,
2026-09-09 06:10:03.679468 | instance |     "isuid": false,
2026-09-09 06:10:03.679500 | instance |     "mimetype": "unknown",
2026-09-09 06:10:03.679533 | instance |     "mode": "0755",
2026-09-09 06:10:03.679566 | instance |     "mtime": 1788934181.9866478,
2026-09-09 06:10:03.679599 | instance |     "nlink": 22,
2026-09-09 06:10:03.679632 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-09 06:10:03.679665 | instance |     "pw_name": "zuul",
2026-09-09 06:10:03.679699 | instance |     "readable": true,
2026-09-09 06:10:03.679733 | instance |     "rgrp": true,
2026-09-09 06:10:03.679767 | instance |     "roth": true,
2026-09-09 06:10:03.679816 | instance |     "rusr": true,
2026-09-09 06:10:03.679851 | instance |     "size": 4096,
2026-09-09 06:10:03.679885 | instance |     "uid": 1000,
2026-09-09 06:10:03.679936 | instance |     "version": "418694307",
2026-09-09 06:10:03.679973 | instance |     "wgrp": false,
2026-09-09 06:10:03.680009 | instance |     "woth": false,
2026-09-09 06:10:03.680044 | instance |     "writeable": true,
2026-09-09 06:10:03.680078 | instance |     "wusr": true,
2026-09-09 06:10:03.680110 | instance |     "xgrp": true,
2026-09-09 06:10:03.680143 | instance |     "xoth": true,
2026-09-09 06:10:03.680175 | instance |     "xusr": true
2026-09-09 06:10:03.680209 | instance |   }
2026-09-09 06:10:03.680243 | instance | }
2026-09-09 06:10:03.680312 | instance | ok: All items complete
2026-09-09 06:10:03.680354 | 
2026-09-09 06:10:03.682719 | instance | skipping: Conditional result was False
2026-09-09 06:10:03.687625 | instance | skipping: Conditional result was False
2026-09-09 06:10:03.709637 | 
2026-09-09 06:10:03.709890 | LOOP [Switch "ansible_host" to private IP]
2026-09-09 06:10:04.100308 | instance | changed: 1 replacements made
2026-09-09 06:10:04.164128 | instance | skipping: Conditional result was False
2026-09-09 06:10:04.165378 | instance | skipping: Conditional result was False
2026-09-09 06:10:04.185853 | 
2026-09-09 06:10:04.186124 | TASK [Run Molecule scenario]
2026-09-09 06:10:05.121191 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-09 06:10:05.552628 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-09 06:10:05.552740 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-09 06:10:16.956225 | instance | INFO     [csi > dependency] Executing
2026-09-09 06:10:16.956689 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-09 06:10:16.957154 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-09 06:10:16.957410 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-09 06:10:16.973074 | instance | INFO     [csi > cleanup] Executing
2026-09-09 06:10:16.973767 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-09 06:10:16.988960 | instance | INFO     [csi > destroy] Executing
2026-09-09 06:10:16.989061 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-09 06:10:16.989155 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-09 06:10:16.998037 | instance | INFO     [csi > syntax] Executing
2026-09-09 06:10:18.530472 | instance |
2026-09-09 06:10:18.530798 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-09 06:10:18.635734 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-09 06:10:18.653608 | instance | INFO     [csi > create] Executing
2026-09-09 06:10:18.657643 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-09 06:10:18.673560 | instance | INFO     [csi > prepare] Executing
2026-09-09 06:10:19.520288 | instance |
2026-09-09 06:10:19.520596 | instance | PLAY [Prepare] *****************************************************************
2026-09-09 06:10:19.520857 | instance |
2026-09-09 06:10:19.521193 | instance | TASK [Gathering Facts] *********************************************************
2026-09-09 06:10:19.521618 | instance | Wednesday 09 September 2026  06:10:19 +0000 (0:00:00.027)       0:00:00.027 ***
2026-09-09 06:10:20.814152 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-09 06:10:20.814336 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-09 06:10:20.814600 | instance | interpreter could change the meaning of that path. See
2026-09-09 06:10:20.814833 | instance | https://docs.ansible.com/ansible-
2026-09-09 06:10:20.815070 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-09 06:10:20.824935 | instance | ok: [instance]
2026-09-09 06:10:20.825174 | instance |
2026-09-09 06:10:20.825508 | instance | TASK [Configure short hostname] ************************************************
2026-09-09 06:10:20.825853 | instance | Wednesday 09 September 2026  06:10:20 +0000 (0:00:01.305)       0:00:01.332 ***
2026-09-09 06:10:21.594757 | instance | changed: [instance]
2026-09-09 06:10:21.594961 | instance |
2026-09-09 06:10:21.595256 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-09 06:10:21.595546 | instance | Wednesday 09 September 2026  06:10:21 +0000 (0:00:00.769)       0:00:02.102 ***
2026-09-09 06:10:21.872999 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-09 06:10:21.873231 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-09 06:10:21.873526 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-09 06:10:21.877961 | instance | changed: [instance]
2026-09-09 06:10:21.878179 | instance |
2026-09-09 06:10:21.878455 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-09 06:10:21.878699 | instance | Wednesday 09 September 2026  06:10:21 +0000 (0:00:00.284)       0:00:02.386 ***
2026-09-09 06:10:22.642440 | instance | ok: [instance]
2026-09-09 06:10:22.642534 | instance |
2026-09-09 06:10:22.642694 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-09 06:10:22.642861 | instance |
2026-09-09 06:10:22.642980 | instance | TASK [Gathering Facts] *********************************************************
2026-09-09 06:10:22.643107 | instance | Wednesday 09 September 2026  06:10:22 +0000 (0:00:00.763)       0:00:03.150 ***
2026-09-09 06:10:23.435342 | instance | ok: [instance]
2026-09-09 06:10:23.435517 | instance |
2026-09-09 06:10:23.435774 | instance | TASK [Install depedencies] *****************************************************
2026-09-09 06:10:23.436159 | instance | Wednesday 09 September 2026  06:10:23 +0000 (0:00:00.792)       0:00:03.942 ***
2026-09-09 06:10:48.214160 | instance | changed: [instance]
2026-09-09 06:10:48.214277 | instance |
2026-09-09 06:10:48.214402 | instance | TASK [Start up service] ********************************************************
2026-09-09 06:10:48.214554 | instance | Wednesday 09 September 2026  06:10:48 +0000 (0:00:24.778)       0:00:28.721 ***
2026-09-09 06:10:48.790533 | instance | ok: [instance]
2026-09-09 06:10:48.790703 | instance |
2026-09-09 06:10:48.790992 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-09 06:10:48.791242 | instance | Wednesday 09 September 2026  06:10:48 +0000 (0:00:00.576)       0:00:29.297 ***
2026-09-09 06:10:49.101457 | instance | ok: [instance]
2026-09-09 06:10:49.101672 | instance |
2026-09-09 06:10:49.101960 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-09 06:10:49.102223 | instance | Wednesday 09 September 2026  06:10:49 +0000 (0:00:00.310)       0:00:29.608 ***
2026-09-09 06:10:49.840703 | instance | changed: [instance]
2026-09-09 06:10:49.841130 | instance |
2026-09-09 06:10:49.841441 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-09 06:10:49.841740 | instance | Wednesday 09 September 2026  06:10:49 +0000 (0:00:00.739)       0:00:30.348 ***
2026-09-09 06:10:50.057151 | instance | ok: [instance]
2026-09-09 06:10:50.057499 | instance |
2026-09-09 06:10:50.057833 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-09 06:10:50.058184 | instance | Wednesday 09 September 2026  06:10:50 +0000 (0:00:00.216)       0:00:30.564 ***
2026-09-09 06:10:50.085500 | instance | skipping: [instance]
2026-09-09 06:10:50.085874 | instance |
2026-09-09 06:10:50.086230 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-09 06:10:50.086539 | instance | Wednesday 09 September 2026  06:10:50 +0000 (0:00:00.028)       0:00:30.593 ***
2026-09-09 06:10:50.717150 | instance | changed: [instance] => (item=osd0)
2026-09-09 06:10:50.717746 | instance | changed: [instance] => (item=osd1)
2026-09-09 06:10:50.718122 | instance | changed: [instance] => (item=osd2)
2026-09-09 06:10:50.718414 | instance |
2026-09-09 06:10:50.718708 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-09 06:10:50.719026 | instance | Wednesday 09 September 2026  06:10:50 +0000 (0:00:00.629)       0:00:31.222 ***
2026-09-09 06:10:51.389522 | instance | changed: [instance] => (item=osd0)
2026-09-09 06:10:51.389623 | instance | changed: [instance] => (item=osd1)
2026-09-09 06:10:51.389693 | instance | changed: [instance] => (item=osd2)
2026-09-09 06:10:51.389812 | instance |
2026-09-09 06:10:51.389927 | instance | TASK [Start loop devices] ******************************************************
2026-09-09 06:10:51.390040 | instance | Wednesday 09 September 2026  06:10:51 +0000 (0:00:00.675)       0:00:31.897 ***
2026-09-09 06:10:52.199105 | instance | changed: [instance] => (item=osd0)
2026-09-09 06:10:52.199608 | instance | changed: [instance] => (item=osd1)
2026-09-09 06:10:52.199630 | instance | changed: [instance] => (item=osd2)
2026-09-09 06:10:52.200218 | instance |
2026-09-09 06:10:52.200258 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-09 06:10:52.200431 | instance | Wednesday 09 September 2026  06:10:52 +0000 (0:00:00.808)       0:00:32.705 ***
2026-09-09 06:10:55.464012 | instance | changed: [instance] => (item=osd0)
2026-09-09 06:10:55.464107 | instance | changed: [instance] => (item=osd1)
2026-09-09 06:10:55.464213 | instance | changed: [instance] => (item=osd2)
2026-09-09 06:10:55.464336 | instance |
2026-09-09 06:10:55.464482 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-09 06:10:55.464624 | instance | Wednesday 09 September 2026  06:10:55 +0000 (0:00:03.265)       0:00:35.971 ***
2026-09-09 06:10:57.525180 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-09 06:10:57.525586 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-09 06:10:57.525995 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-09 06:10:57.526329 | instance |
2026-09-09 06:10:57.526668 | instance | PLAY RECAP *********************************************************************
2026-09-09 06:10:57.527065 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-09 06:10:57.527371 | instance |
2026-09-09 06:10:57.527714 | instance | Wednesday 09 September 2026  06:10:57 +0000 (0:00:02.061)       0:00:38.033 ***
2026-09-09 06:10:57.528046 | instance | ===============================================================================
2026-09-09 06:10:57.528364 | instance | Install depedencies ---------------------------------------------------- 24.78s
2026-09-09 06:10:57.528691 | instance | Create a volume group for each loop device ------------------------------ 3.27s
2026-09-09 06:10:57.529054 | instance | Create a logical volume for each loop device ---------------------------- 2.06s
2026-09-09 06:10:57.529379 | instance | Gathering Facts --------------------------------------------------------- 1.31s
2026-09-09 06:10:57.529709 | instance | Start loop devices ------------------------------------------------------ 0.81s
2026-09-09 06:10:57.530020 | instance | Gathering Facts --------------------------------------------------------- 0.79s
2026-09-09 06:10:57.530348 | instance | Configure short hostname ------------------------------------------------ 0.77s
2026-09-09 06:10:57.530680 | instance | Purge "snapd" package --------------------------------------------------- 0.76s
2026-09-09 06:10:57.530995 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.74s
2026-09-09 06:10:57.531327 | instance | Set permissions on loopback devices ------------------------------------- 0.68s
2026-09-09 06:10:57.531634 | instance | Create devices for Ceph ------------------------------------------------- 0.63s
2026-09-09 06:10:57.531972 | instance | Start up service -------------------------------------------------------- 0.58s
2026-09-09 06:10:57.532303 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s
2026-09-09 06:10:57.532615 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-09-09 06:10:57.532949 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-09 06:10:57.533328 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-09 06:10:57.644185 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-09 06:10:57.660249 | instance | INFO     [csi > converge] Executing
2026-09-09 06:10:59.101040 | instance |
2026-09-09 06:10:59.101438 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-09 06:10:59.101791 | instance |
2026-09-09 06:10:59.102133 | instance | TASK [Gathering Facts] *********************************************************
2026-09-09 06:10:59.102443 | instance | Wednesday 09 September 2026  06:10:59 +0000 (0:00:00.007)       0:00:00.007 ***
2026-09-09 06:11:00.198970 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-09 06:11:00.199035 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-09 06:11:00.199046 | instance | interpreter could change the meaning of that path. See
2026-09-09 06:11:00.199057 | instance | https://docs.ansible.com/ansible-
2026-09-09 06:11:00.199066 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-09 06:11:00.207987 | instance | ok: [instance]
2026-09-09 06:11:00.208019 | instance |
2026-09-09 06:11:00.208031 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-09 06:11:00.208041 | instance | Wednesday 09 September 2026  06:11:00 +0000 (0:00:01.106)       0:00:01.114 ***
2026-09-09 06:11:00.269180 | instance | ok: [instance] => {
2026-09-09 06:11:00.269280 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-09 06:11:00.269500 | instance | }
2026-09-09 06:11:00.269753 | instance |
2026-09-09 06:11:00.269992 | instance | PLAY [all] *********************************************************************
2026-09-09 06:11:00.270242 | instance |
2026-09-09 06:11:00.270467 | instance | TASK [Gathering Facts] *********************************************************
2026-09-09 06:11:00.270728 | instance | Wednesday 09 September 2026  06:11:00 +0000 (0:00:00.061)       0:00:01.175 ***
2026-09-09 06:11:01.230748 | instance | ok: [instance]
2026-09-09 06:11:01.230875 | instance |
2026-09-09 06:11:01.231197 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-09 06:11:01.231398 | instance | Wednesday 09 September 2026  06:11:01 +0000 (0:00:00.960)       0:00:02.136 ***
2026-09-09 06:11:01.282945 | instance | skipping: [instance]
2026-09-09 06:11:01.283168 | instance |
2026-09-09 06:11:01.283416 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-09 06:11:01.283644 | instance | Wednesday 09 September 2026  06:11:01 +0000 (0:00:00.052)       0:00:02.189 ***
2026-09-09 06:11:01.506225 | instance | ok: [instance]
2026-09-09 06:11:01.506351 | instance |
2026-09-09 06:11:01.506675 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-09 06:11:01.506931 | instance |
2026-09-09 06:11:01.507174 | instance | TASK [Gathering Facts] *********************************************************
2026-09-09 06:11:01.507404 | instance | Wednesday 09 September 2026  06:11:01 +0000 (0:00:00.223)       0:00:02.412 ***
2026-09-09 06:11:02.490628 | instance | ok: [instance]
2026-09-09 06:11:02.491029 | instance |
2026-09-09 06:11:02.491439 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-09 06:11:02.491854 | instance | Wednesday 09 September 2026  06:11:02 +0000 (0:00:00.983)       0:00:03.396 ***
2026-09-09 06:11:02.865661 | instance | ok: [instance]
2026-09-09 06:11:02.866042 | instance |
2026-09-09 06:11:02.866309 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-09 06:11:02.866499 | instance | Wednesday 09 September 2026  06:11:02 +0000 (0:00:00.372)       0:00:03.769 ***
2026-09-09 06:11:02.920305 | instance | skipping: [instance]
2026-09-09 06:11:02.920382 | instance |
2026-09-09 06:11:02.920392 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-09 06:11:02.920402 | instance | Wednesday 09 September 2026  06:11:02 +0000 (0:00:00.056)       0:00:03.826 ***
2026-09-09 06:11:03.297106 | instance | changed: [instance]
2026-09-09 06:11:03.297190 | instance |
2026-09-09 06:11:03.297211 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-09 06:11:03.297391 | instance | Wednesday 09 September 2026  06:11:03 +0000 (0:00:00.377)       0:00:04.203 ***
2026-09-09 06:11:03.400322 | instance | ok: [instance] => {
2026-09-09 06:11:03.400613 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-09 06:11:03.400850 | instance | }
2026-09-09 06:11:03.401133 | instance |
2026-09-09 06:11:03.401375 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-09 06:11:03.401640 | instance | Wednesday 09 September 2026  06:11:03 +0000 (0:00:00.102)       0:00:04.306 ***
2026-09-09 06:11:04.043128 | instance | changed: [instance]
2026-09-09 06:11:04.043308 | instance |
2026-09-09 06:11:04.043593 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-09 06:11:04.043821 | instance | Wednesday 09 September 2026  06:11:04 +0000 (0:00:00.643)       0:00:04.949 ***
2026-09-09 06:11:04.114149 | instance | skipping: [instance]
2026-09-09 06:11:04.114346 | instance |
2026-09-09 06:11:04.114611 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-09 06:11:04.114862 | instance | Wednesday 09 September 2026  06:11:04 +0000 (0:00:00.071)       0:00:05.020 ***
2026-09-09 06:11:04.175343 | instance | skipping: [instance]
2026-09-09 06:11:04.175565 | instance |
2026-09-09 06:11:04.175815 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-09 06:11:04.176039 | instance | Wednesday 09 September 2026  06:11:04 +0000 (0:00:00.061)       0:00:05.082 ***
2026-09-09 06:11:04.427080 | instance | ok: [instance]
2026-09-09 06:11:04.427229 | instance |
2026-09-09 06:11:04.427382 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-09 06:11:04.427551 | instance | Wednesday 09 September 2026  06:11:04 +0000 (0:00:00.250)       0:00:05.333 ***
2026-09-09 06:11:05.975439 | instance | ok: [instance]
2026-09-09 06:11:05.975845 | instance |
2026-09-09 06:11:05.976208 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-09 06:11:05.976693 | instance | Wednesday 09 September 2026  06:11:05 +0000 (0:00:01.548)       0:00:06.881 ***
2026-09-09 06:11:06.065614 | instance | ok: [instance] => {
2026-09-09 06:11:06.065848 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-09 06:11:06.066366 | instance | }
2026-09-09 06:11:06.066706 | instance |
2026-09-09 06:11:06.067044 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-09 06:11:06.067408 | instance | Wednesday 09 September 2026  06:11:06 +0000 (0:00:00.090)       0:00:06.971 ***
2026-09-09 06:11:06.677890 | instance | changed: [instance]
2026-09-09 06:11:06.679745 | instance |
2026-09-09 06:11:06.680189 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-09 06:11:06.680520 | instance | Wednesday 09 September 2026  06:11:06 +0000 (0:00:00.610)       0:00:07.582 ***
2026-09-09 06:11:09.743537 | instance | changed: [instance]
2026-09-09 06:11:09.743612 | instance |
2026-09-09 06:11:09.743625 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-09 06:11:09.743636 | instance | Wednesday 09 September 2026  06:11:09 +0000 (0:00:03.066)       0:00:10.648 ***
2026-09-09 06:11:09.781212 | instance | skipping: [instance]
2026-09-09 06:11:09.781297 | instance |
2026-09-09 06:11:09.781729 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-09 06:11:09.781981 | instance | Wednesday 09 September 2026  06:11:09 +0000 (0:00:00.038)       0:00:10.687 ***
2026-09-09 06:11:09.823128 | instance | skipping: [instance]
2026-09-09 06:11:09.823216 | instance |
2026-09-09 06:11:09.823498 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-09 06:11:09.823730 | instance | Wednesday 09 September 2026  06:11:09 +0000 (0:00:00.042)       0:00:10.729 ***
2026-09-09 06:11:09.868330 | instance | skipping: [instance]
2026-09-09 06:11:09.868445 | instance |
2026-09-09 06:11:09.868659 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-09 06:11:09.868883 | instance | Wednesday 09 September 2026  06:11:09 +0000 (0:00:00.045)       0:00:10.774 ***
2026-09-09 06:11:16.062072 | instance | changed: [instance]
2026-09-09 06:11:16.062138 | instance |
2026-09-09 06:11:16.062149 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-09 06:11:16.062160 | instance | Wednesday 09 September 2026  06:11:16 +0000 (0:00:06.192)       0:00:16.967 ***
2026-09-09 06:11:16.775322 | instance | changed: [instance]
2026-09-09 06:11:16.776082 | instance |
2026-09-09 06:11:16.776144 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-09 06:11:16.776156 | instance | Wednesday 09 September 2026  06:11:16 +0000 (0:00:00.713)       0:00:17.681 ***
2026-09-09 06:11:17.874321 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-09 06:11:17.876124 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-09 06:11:17.876393 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-09 06:11:17.876417 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-09 06:11:17.876428 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-09 06:11:17.876441 | instance |
2026-09-09 06:11:17.876454 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-09 06:11:17.876467 | instance | Wednesday 09 September 2026  06:11:17 +0000 (0:00:01.098)       0:00:18.780 ***
2026-09-09 06:11:18.509503 | instance | changed: [instance]
2026-09-09 06:11:18.509607 | instance |
2026-09-09 06:11:18.509622 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-09 06:11:18.509770 | instance | Wednesday 09 September 2026  06:11:18 +0000 (0:00:00.618)       0:00:19.399 ***
2026-09-09 06:11:18.509943 | instance |
2026-09-09 06:11:18.510084 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-09 06:11:18.510236 | instance | Wednesday 09 September 2026  06:11:18 +0000 (0:00:00.017)       0:00:19.416 ***
2026-09-09 06:11:19.542892 | instance | ok: [instance]
2026-09-09 06:11:19.543099 | instance |
2026-09-09 06:11:19.543491 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-09 06:11:19.543798 | instance | Wednesday 09 September 2026  06:11:19 +0000 (0:00:01.032)       0:00:20.449 ***
2026-09-09 06:11:20.065040 | instance | changed: [instance]
2026-09-09 06:11:20.065292 | instance |
2026-09-09 06:11:20.065554 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-09 06:11:20.065824 | instance | Wednesday 09 September 2026  06:11:20 +0000 (0:00:00.522)       0:00:20.971 ***
2026-09-09 06:11:20.712163 | instance | changed: [instance]
2026-09-09 06:11:20.712370 | instance |
2026-09-09 06:11:20.712610 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-09 06:11:20.712848 | instance | Wednesday 09 September 2026  06:11:20 +0000 (0:00:00.647)       0:00:21.618 ***
2026-09-09 06:11:20.969315 | instance | ok: [instance]
2026-09-09 06:11:20.969457 | instance |
2026-09-09 06:11:20.969928 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-09 06:11:20.970223 | instance | Wednesday 09 September 2026  06:11:20 +0000 (0:00:00.256)       0:00:21.875 ***
2026-09-09 06:11:21.025089 | instance | ok: [instance] => {
2026-09-09 06:11:21.025269 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-09 06:11:21.025525 | instance | }
2026-09-09 06:11:21.025752 | instance |
2026-09-09 06:11:21.026001 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-09 06:11:21.026251 | instance | Wednesday 09 September 2026  06:11:21 +0000 (0:00:00.056)       0:00:21.931 ***
2026-09-09 06:11:21.917078 | instance | changed: [instance]
2026-09-09 06:11:21.917145 | instance |
2026-09-09 06:11:21.917154 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-09 06:11:21.917349 | instance | Wednesday 09 September 2026  06:11:21 +0000 (0:00:00.890)       0:00:22.822 ***
2026-09-09 06:11:26.366835 | instance | changed: [instance]
2026-09-09 06:11:26.367698 | instance |
2026-09-09 06:11:26.367762 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-09 06:11:26.367774 | instance | Wednesday 09 September 2026  06:11:26 +0000 (0:00:04.449)       0:00:27.272 ***
2026-09-09 06:11:27.750922 | instance | ok: [instance]
2026-09-09 06:11:27.751010 | instance |
2026-09-09 06:11:27.751213 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-09 06:11:27.751262 | instance | Wednesday 09 September 2026  06:11:27 +0000 (0:00:01.385)       0:00:28.657 ***
2026-09-09 06:11:28.156551 | instance | changed: [instance]
2026-09-09 06:11:28.157112 | instance |
2026-09-09 06:11:28.157349 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-09 06:11:28.157658 | instance | Wednesday 09 September 2026  06:11:28 +0000 (0:00:00.404)       0:00:29.062 ***
2026-09-09 06:11:28.665747 | instance | changed: [instance]
2026-09-09 06:11:28.665821 | instance |
2026-09-09 06:11:28.666207 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-09 06:11:28.666262 | instance | Wednesday 09 September 2026  06:11:28 +0000 (0:00:00.510)       0:00:29.572 ***
2026-09-09 06:11:29.290536 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-09 06:11:29.291754 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-09 06:11:29.291814 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-09 06:11:29.291825 | instance |
2026-09-09 06:11:29.291836 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-09 06:11:29.291846 | instance | Wednesday 09 September 2026  06:11:29 +0000 (0:00:00.624)       0:00:30.196 ***
2026-09-09 06:11:29.805518 | instance | changed: [instance]
2026-09-09 06:11:29.806029 | instance |
2026-09-09 06:11:29.806084 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-09 06:11:29.806093 | instance | Wednesday 09 September 2026  06:11:29 +0000 (0:00:00.515)       0:00:30.712 ***
2026-09-09 06:11:30.345099 | instance | changed: [instance]
2026-09-09 06:11:30.345147 | instance |
2026-09-09 06:11:30.345158 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-09 06:11:30.345735 | instance | Wednesday 09 September 2026  06:11:30 +0000 (0:00:00.510)       0:00:31.222 ***
2026-09-09 06:11:30.345783 | instance |
2026-09-09 06:11:30.345790 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-09 06:11:30.345804 | instance | Wednesday 09 September 2026  06:11:30 +0000 (0:00:00.028)       0:00:31.251 ***
2026-09-09 06:11:31.096452 | instance | ok: [instance]
2026-09-09 06:11:31.097048 | instance |
2026-09-09 06:11:31.097096 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-09 06:11:31.097214 | instance | Wednesday 09 September 2026  06:11:31 +0000 (0:00:00.751)       0:00:32.002 ***
2026-09-09 06:11:32.075758 | instance | changed: [instance]
2026-09-09 06:11:32.075841 | instance |
2026-09-09 06:11:32.075874 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-09 06:11:32.075890 | instance | Wednesday 09 September 2026  06:11:32 +0000 (0:00:00.978)       0:00:32.981 ***
2026-09-09 06:11:32.635338 | instance | changed: [instance]
2026-09-09 06:11:32.635396 | instance |
2026-09-09 06:11:32.635637 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-09 06:11:32.635653 | instance | Wednesday 09 September 2026  06:11:32 +0000 (0:00:00.561)       0:00:33.542 ***
2026-09-09 06:11:32.711756 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-09 06:11:32.712210 | instance |
2026-09-09 06:11:32.712229 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-09 06:11:32.712236 | instance | Wednesday 09 September 2026  06:11:32 +0000 (0:00:00.076)       0:00:33.618 ***
2026-09-09 06:11:38.736724 | instance | changed: [instance]
2026-09-09 06:11:38.736792 | instance |
2026-09-09 06:11:38.736804 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-09 06:11:38.736814 | instance | Wednesday 09 September 2026  06:11:38 +0000 (0:00:06.023)       0:00:39.641 ***
2026-09-09 06:11:39.433968 | instance | ok: [instance] => (item=chronyd)
2026-09-09 06:11:39.434094 | instance | ok: [instance] => (item=sshd)
2026-09-09 06:11:39.434336 | instance |
2026-09-09 06:11:39.434568 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-09 06:11:39.434822 | instance | Wednesday 09 September 2026  06:11:39 +0000 (0:00:00.697)       0:00:40.339 ***
2026-09-09 06:11:48.177592 | instance | changed: [instance]
2026-09-09 06:11:48.177762 | instance |
2026-09-09 06:11:48.178020 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-09 06:11:48.178284 | instance | Wednesday 09 September 2026  06:11:48 +0000 (0:00:08.743)       0:00:49.083 ***
2026-09-09 06:11:48.414161 | instance | ok: [instance]
2026-09-09 06:11:48.414446 | instance |
2026-09-09 06:11:48.414705 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-09 06:11:48.414948 | instance | Wednesday 09 September 2026  06:11:48 +0000 (0:00:00.235)       0:00:49.319 ***
2026-09-09 06:11:48.930778 | instance | changed: [instance]
2026-09-09 06:11:48.931045 | instance |
2026-09-09 06:11:48.931395 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-09 06:11:48.931690 | instance | Wednesday 09 September 2026  06:11:48 +0000 (0:00:00.516)       0:00:49.836 ***
2026-09-09 06:11:49.334313 | instance | changed: [instance]
2026-09-09 06:11:49.334535 | instance |
2026-09-09 06:11:49.334548 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-09 06:11:49.334558 | instance | Wednesday 09 September 2026  06:11:49 +0000 (0:00:00.403)       0:00:50.239 ***
2026-09-09 06:11:49.581366 | instance | ok: [instance]
2026-09-09 06:11:49.581458 | instance |
2026-09-09 06:11:49.581536 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-09 06:11:49.581669 | instance | Wednesday 09 September 2026  06:11:49 +0000 (0:00:00.248)       0:00:50.488 ***
2026-09-09 06:11:49.865561 | instance | ok: [instance] => (item=instance)
2026-09-09 06:11:49.865915 | instance |
2026-09-09 06:11:49.866347 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-09 06:11:49.866753 | instance | Wednesday 09 September 2026  06:11:49 +0000 (0:00:00.283)       0:00:50.771 ***
2026-09-09 06:11:49.934853 | instance | ok: [instance]
2026-09-09 06:11:49.935039 | instance |
2026-09-09 06:11:49.935333 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-09 06:11:49.935591 | instance | Wednesday 09 September 2026  06:11:49 +0000 (0:00:00.069)       0:00:50.841 ***
2026-09-09 06:11:50.008770 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-09 06:11:50.009016 | instance |
2026-09-09 06:11:50.009341 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-09 06:11:50.009614 | instance | Wednesday 09 September 2026  06:11:50 +0000 (0:00:00.073)       0:00:50.915 ***
2026-09-09 06:11:50.325026 | instance | changed: [instance]
2026-09-09 06:11:50.325132 | instance |
2026-09-09 06:11:50.325447 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-09 06:11:50.325663 | instance | Wednesday 09 September 2026  06:11:50 +0000 (0:00:00.315)       0:00:51.231 ***
2026-09-09 06:11:51.055826 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-09 06:11:51.056028 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-09 06:11:51.056286 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-09 06:11:51.056515 | instance |
2026-09-09 06:11:51.056742 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-09 06:11:51.057016 | instance | Wednesday 09 September 2026  06:11:51 +0000 (0:00:00.731)       0:00:51.962 ***
2026-09-09 06:13:27.978644 | instance | ok: [instance]
2026-09-09 06:13:27.978737 | instance |
2026-09-09 06:13:27.978745 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-09 06:13:27.978762 | instance | Wednesday 09 September 2026  06:13:27 +0000 (0:01:36.921)       0:02:28.884 ***
2026-09-09 06:13:28.256923 | instance | changed: [instance]
2026-09-09 06:13:28.257082 | instance |
2026-09-09 06:13:28.257386 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-09 06:13:28.257633 | instance | Wednesday 09 September 2026  06:13:28 +0000 (0:00:00.279)       0:02:29.163 ***
2026-09-09 06:13:28.301570 | instance | ok: [instance]
2026-09-09 06:13:28.302061 | instance |
2026-09-09 06:13:28.302121 | instance | TASK [Install Ceph host] *******************************************************
2026-09-09 06:13:28.302129 | instance | Wednesday 09 September 2026  06:13:28 +0000 (0:00:00.044)       0:02:29.207 ***
2026-09-09 06:13:28.390474 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-09 06:13:28.390529 | instance |
2026-09-09 06:13:28.390784 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-09 06:13:28.390834 | instance | Wednesday 09 September 2026  06:13:28 +0000 (0:00:00.089)       0:02:29.297 ***
2026-09-09 06:13:30.097365 | instance | ok: [instance]
2026-09-09 06:13:30.097423 | instance |
2026-09-09 06:13:30.097437 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-09 06:13:30.097444 | instance | Wednesday 09 September 2026  06:13:30 +0000 (0:00:01.706)       0:02:31.003 ***
2026-09-09 06:13:30.187481 | instance | ok: [instance] => (item=instance)
2026-09-09 06:13:30.187540 | instance |
2026-09-09 06:13:30.187886 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-09 06:13:30.187904 | instance | Wednesday 09 September 2026  06:13:30 +0000 (0:00:00.089)       0:02:31.092 ***
2026-09-09 06:13:30.575618 | instance | ok: [instance]
2026-09-09 06:13:30.575875 | instance |
2026-09-09 06:13:30.576211 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-09 06:13:30.576580 | instance | Wednesday 09 September 2026  06:13:30 +0000 (0:00:00.388)       0:02:31.481 ***
2026-09-09 06:13:33.079878 | instance | ok: [instance]
2026-09-09 06:13:33.080054 | instance |
2026-09-09 06:13:33.080342 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-09 06:13:33.080600 | instance | Wednesday 09 September 2026  06:13:33 +0000 (0:00:02.504)       0:02:33.985 ***
2026-09-09 06:14:26.641019 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-09 06:14:26.641446 | 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_y8ew2kus/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_y8ew2kus/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_y8ew2kus/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_y8ew2kus/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-09T06:14:26.438+0000 7f60e1173640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDb+KBqqGREICAAzvoXQ4S7MPLOMz7ba0siVQC+CfczmSUzhmoksHHqn0g=: Malformed input [buffer:3]\n2026-09-09T06:14:26.438+0000 7f60e1173640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-09T06:14:26.438+0000 7f60e1173640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDb+KBqqGREICAAzvoXQ4S7MPLOMz7ba0siVQC+CfczmSUzhmoksHHqn0g=: Malformed input [buffer:3]\n2026-09-09T06:14:26.438+0000 7f60e1173640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-09T06:14:26.438+0000 7f60e1173640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDb+KBqqGREICAAzvoXQ4S7MPLOMz7ba0siVQC+CfczmSUzhmoksHHqn0g=: Malformed input [buffer:3]\n2026-09-09T06:14:26.438+0000 7f60e1173640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-09T06:14:26.438+0000 7f60e1173640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-09 06:14:26.643982 | instance |
2026-09-09 06:14:26.644600 | instance | PLAY RECAP *********************************************************************
2026-09-09 06:14:26.645057 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-09 06:14:26.645096 | instance |
2026-09-09 06:14:26.645744 | instance | Wednesday 09 September 2026  06:14:26 +0000 (0:00:53.565)       0:03:27.551 ***
2026-09-09 06:14:26.645824 | instance | ===============================================================================
2026-09-09 06:14:26.647699 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 96.92s
2026-09-09 06:14:26.647743 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 53.57s
2026-09-09 06:14:26.647758 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 8.74s
2026-09-09 06:14:26.647771 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.19s
2026-09-09 06:14:26.647784 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.02s
2026-09-09 06:14:26.647797 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.45s
2026-09-09 06:14:26.647830 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.07s
2026-09-09 06:14:26.647849 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.50s
2026-09-09 06:14:26.647972 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.78s
2026-09-09 06:14:26.648385 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.71s
2026-09-09 06:14:26.648708 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.55s
2026-09-09 06:14:26.649118 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.39s
2026-09-09 06:14:26.649488 | instance | Gathering Facts --------------------------------------------------------- 1.11s
2026-09-09 06:14:26.649822 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.10s
2026-09-09 06:14:26.650117 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-09 06:14:26.650501 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.98s
2026-09-09 06:14:26.650886 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-09-09 06:14:26.651823 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.89s
2026-09-09 06:14:26.652156 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.73s
2026-09-09 06:14:26.652529 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.71s
2026-09-09 06:14:26.751697 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-09 06:14:26.752000 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-09 06:14:26.752184 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-09 06:14:27.105145 | instance | ERROR
2026-09-09 06:14:27.105521 | instance | {
2026-09-09 06:14:27.105601 | instance |   "delta": "0:04:22.263153",
2026-09-09 06:14:27.105645 | instance |   "end": "2026-09-09 06:14:26.829416",
2026-09-09 06:14:27.105697 | instance |   "msg": "non-zero return code",
2026-09-09 06:14:27.105749 | instance |   "rc": 2,
2026-09-09 06:14:27.105788 | instance |   "start": "2026-09-09 06:10:04.566263"
2026-09-09 06:14:27.105826 | instance | }
failure
2026-09-09 06:14:27.115978 | 
2026-09-09 06:14:27.116103 | PLAY RECAP
2026-09-09 06:14:27.116189 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-09 06:14:27.116244 | 
2026-09-09 06:14:27.332725 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-09 06:14:27.336728 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-09 06:14:27.897085 | 
2026-09-09 06:14:27.897194 | PLAY [all]
2026-09-09 06:14:27.909795 | 
2026-09-09 06:14:27.909874 | TASK [gather-host-logs : creating directory for system status]
2026-09-09 06:14:28.256053 | instance | changed
2026-09-09 06:14:28.265442 | 
2026-09-09 06:14:28.265600 | TASK [gather-host-logs : Get logs for each host]
2026-09-09 06:14:28.656462 | instance | + systemd-cgls --full --all --no-pager
2026-09-09 06:14:28.674179 | instance | + ip addr
2026-09-09 06:14:28.677398 | instance | + ip route
2026-09-09 06:14:28.680569 | instance | + lsblk
2026-09-09 06:14:28.688140 | instance | + mount
2026-09-09 06:14:28.691410 | instance | + docker images
2026-09-09 06:14:28.717531 | instance | + brctl show
2026-09-09 06:14:28.718108 | instance | /bin/bash: line 8: brctl: command not found
2026-09-09 06:14:28.718402 | instance | + ps aux --sort=-%mem
2026-09-09 06:14:28.740863 | instance | + dpkg -l
2026-09-09 06:14:28.748818 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-09 06:14:28.749293 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-09 06:14:28.765475 | instance | + '[' '!' -z '' ']'
2026-09-09 06:14:28.814843 | instance | ok: Runtime: 0:00:00.114289
2026-09-09 06:14:28.825249 | 
2026-09-09 06:14:28.825377 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-09 06:14:29.507790 | instance | changed:
2026-09-09 06:14:29.508093 | instance | created directory /var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/work/logs/instance
2026-09-09 06:14:29.508174 | instance | cd+++++++++ system/
2026-09-09 06:14:29.508224 | instance | >f+++++++++ system/brctl-show.txt
2026-09-09 06:14:29.508265 | instance | >f+++++++++ system/docker-images.txt
2026-09-09 06:14:29.508307 | instance | >f+++++++++ system/ip-addr.txt
2026-09-09 06:14:29.508353 | instance | >f+++++++++ system/ip-route.txt
2026-09-09 06:14:29.508395 | instance | >f+++++++++ system/lsblk.txt
2026-09-09 06:14:29.508440 | instance | >f+++++++++ system/mount.txt
2026-09-09 06:14:29.508479 | instance | >f+++++++++ system/packages.txt
2026-09-09 06:14:29.508567 | instance | >f+++++++++ system/ps.txt
2026-09-09 06:14:29.508614 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-09 06:14:29.525732 | 
2026-09-09 06:14:29.525860 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-09 06:14:29.748489 | instance | changed: "values"
2026-09-09 06:14:29.957713 | instance | changed: "releases"
2026-09-09 06:14:29.965079 | 
2026-09-09 06:14:29.965289 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-09 06:14:30.210057 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-09 06:14:30.498532 | instance | ok: Runtime: 0:00:00.007357
2026-09-09 06:14:30.508864 | 
2026-09-09 06:14:30.509025 | TASK [helm-release-status : Downloads logs to executor]
2026-09-09 06:14:31.044697 | instance | changed:
2026-09-09 06:14:31.044955 | instance | cd+++++++++ helm/
2026-09-09 06:14:31.045010 | instance | cd+++++++++ helm/releases/
2026-09-09 06:14:31.045052 | instance | cd+++++++++ helm/values/
2026-09-09 06:14:31.057650 | 
2026-09-09 06:14:31.057709 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-09 06:14:31.273675 | instance | changed
2026-09-09 06:14:31.285795 | 
2026-09-09 06:14:31.285998 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-09 06:14:31.586786 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-09 06:14:31.589949 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-09 06:14:31.595688 | instance | environment: line 1: kubectl: command not found
2026-09-09 06:14:31.597348 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-09 06:14:31.598448 | instance | environment: line 1: kubectl: command not found
2026-09-09 06:14:31.600327 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-09 06:14:31.601929 | instance | environment: line 1: kubectl: command not found
2026-09-09 06:14:31.603221 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-09 06:14:31.606105 | instance | environment: line 1: kubectl: command not found
2026-09-09 06:14:31.607337 | instance | environment: line 1: kubectl: command not found
2026-09-09 06:14:31.608349 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-09 06:14:31.608682 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-09 06:14:31.833036 | instance | ok: Runtime: 0:00:00.032099
2026-09-09 06:14:31.843085 | 
2026-09-09 06:14:31.843208 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-09 06:14:32.080125 | instance | changed
2026-09-09 06:14:32.089318 | 
2026-09-09 06:14:32.089424 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-09 06:14:32.334300 | instance | environment: line 5: kubectl: command not found
2026-09-09 06:14:32.335671 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-09 06:14:32.335714 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-09 06:14:32.336130 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-09 06:14:32.629521 | instance | ok: Runtime: 0:00:00.009566
2026-09-09 06:14:32.640159 | 
2026-09-09 06:14:32.640346 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-09 06:14:33.152283 | instance | changed:
2026-09-09 06:14:33.152624 | instance | cd+++++++++ objects/
2026-09-09 06:14:33.152665 | instance | cd+++++++++ objects/cluster/
2026-09-09 06:14:33.152698 | instance | cd+++++++++ objects/namespaced/
2026-09-09 06:14:33.162972 | 
2026-09-09 06:14:33.163060 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-09 06:14:33.395419 | instance | changed
2026-09-09 06:14:33.400111 | 
2026-09-09 06:14:33.400185 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-09 06:14:33.629303 | instance | changed
2026-09-09 06:14:33.639129 | 
2026-09-09 06:14:33.639314 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-09 06:14:33.907677 | instance | environment: line 3: kubectl: command not found
2026-09-09 06:14:34.187143 | instance | ok: Runtime: 0:00:00.009107
2026-09-09 06:14:34.197962 | 
2026-09-09 06:14:34.198180 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-09 06:14:34.730552 | instance | changed:
2026-09-09 06:14:34.730868 | instance | cd+++++++++ pod-logs/
2026-09-09 06:14:34.730949 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-09 06:14:34.751048 | 
2026-09-09 06:14:34.751201 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-09 06:14:34.945223 | instance | changed
2026-09-09 06:14:34.949598 | 
2026-09-09 06:14:34.949663 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-09 06:14:35.165033 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-09 06:14:35.482442 | instance | ok: Runtime: 0:00:00.051520
2026-09-09 06:14:35.492662 | 
2026-09-09 06:14:35.492882 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-09 06:14:35.758241 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-09 06:14:35.803158 | instance | ceph-mgr endpoints:
2026-09-09 06:14:36.045551 | instance | ok: Runtime: 0:00:00.052050
2026-09-09 06:14:36.055226 | 
2026-09-09 06:14:36.055376 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-09 06:14:36.314294 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-09 06:14:36.605689 | instance | ok: Runtime: 0:00:00.052229
2026-09-09 06:14:36.616482 | 
2026-09-09 06:14:36.616703 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-09 06:14:37.145742 | instance | changed: cd+++++++++ prometheus/
2026-09-09 06:14:37.164481 | 
2026-09-09 06:14:37.164707 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-09 06:14:37.395312 | instance | changed
2026-09-09 06:14:37.408645 | 
2026-09-09 06:14:37.408962 | TASK [gather-selenium-data : Get selenium data]
2026-09-09 06:14:37.706973 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-09 06:14:37.708505 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-09 06:14:37.957704 | instance | ERROR
2026-09-09 06:14:37.957983 | instance | {
2026-09-09 06:14:37.958020 | instance |   "delta": "0:00:00.005549",
2026-09-09 06:14:37.958045 | instance |   "end": "2026-09-09 06:14:37.708905",
2026-09-09 06:14:37.958070 | instance |   "msg": "non-zero return code",
2026-09-09 06:14:37.958092 | instance |   "rc": 1,
2026-09-09 06:14:37.958113 | instance |   "start": "2026-09-09 06:14:37.703356"
2026-09-09 06:14:37.958134 | instance | }
2026-09-09 06:14:37.958162 | instance | ERROR: Ignoring Errors
2026-09-09 06:14:37.964482 | 
2026-09-09 06:14:37.964603 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-09 06:14:38.498292 | instance | changed: cd+++++++++ selenium/
2026-09-09 06:14:38.508824 | 
2026-09-09 06:14:38.508958 | PLAY RECAP
2026-09-09 06:14:38.509050 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-09 06:14:38.509096 | 
2026-09-09 06:14:38.634380 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-09 06:14:38.636230 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-09 06:14:39.274160 | 
2026-09-09 06:14:39.274399 | PLAY [all]
2026-09-09 06:14:39.285594 | 
2026-09-09 06:14:39.285680 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-09 06:14:39.340247 | instance | skipping: Conditional result was False
2026-09-09 06:14:39.346921 | 
2026-09-09 06:14:39.347029 | TASK [fetch-output : Set log path for single node]
2026-09-09 06:14:39.400361 | instance | ok
2026-09-09 06:14:39.409598 | 
2026-09-09 06:14:39.409749 | LOOP [fetch-output : Ensure local output dirs]
2026-09-09 06:14:39.842713 | instance -> localhost | ok: "/var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/work/logs"
2026-09-09 06:14:40.074480 | instance -> localhost | changed: "/var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/work/artifacts"
2026-09-09 06:14:40.305473 | instance -> localhost | changed: "/var/lib/zuul/builds/312bed97db5e4eeb8e10e9f475bd26ac/work/docs"
2026-09-09 06:14:40.318061 | 
2026-09-09 06:14:40.318202 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-09 06:14:40.999191 | instance | changed: .d..t...... ./
2026-09-09 06:14:40.999982 | instance | changed: All items complete
2026-09-09 06:14:41.000587 | 
2026-09-09 06:14:41.495787 | instance | changed: .d..t...... ./
2026-09-09 06:14:42.004745 | instance | changed: .d..t...... ./
2026-09-09 06:14:42.017051 | 
2026-09-09 06:14:42.017195 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-09 06:14:42.456162 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007874
2026-09-09 06:14:42.725073 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008550
2026-09-09 06:14:42.738193 | 
2026-09-09 06:14:42.738298 | PLAY [all]
2026-09-09 06:14:42.743521 | 
2026-09-09 06:14:42.743581 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-09 06:14:43.150151 | instance | changed
2026-09-09 06:14:43.160698 | 
2026-09-09 06:14:43.160827 | PLAY RECAP
2026-09-09 06:14:43.160965 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-09 06:14:43.161014 | 
2026-09-09 06:14:43.285516 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-09 06:14:43.289294 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-09 06:14:43.828061 | 
2026-09-09 06:14:43.828174 | PLAY [localhost]
2026-09-09 06:14:43.837130 | 
2026-09-09 06:14:43.837199 | TASK [Generate Zuul manifest]
2026-09-09 06:14:43.855277 | localhost | ok
2026-09-09 06:14:43.870839 | 
2026-09-09 06:14:43.870937 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-09 06:14:44.263479 | localhost | changed
2026-09-09 06:14:44.275881 | 
2026-09-09 06:14:44.276001 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-09 06:14:44.316712 | localhost | ok
2026-09-09 06:14:44.330012 | 
2026-09-09 06:14:44.330145 | TASK [Upload logs]
2026-09-09 06:14:44.353120 | localhost | ok
2026-09-09 06:14:44.419286 | 
2026-09-09 06:14:44.419421 | TASK [Set zuul-log-path fact]
2026-09-09 06:14:44.435230 | localhost | ok
2026-09-09 06:14:44.445044 | 
2026-09-09 06:14:44.445107 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-09 06:14:44.481984 | localhost | ok
2026-09-09 06:14:44.489679 | 
2026-09-09 06:14:44.489763 | TASK [upload-logs : Create log directories]
2026-09-09 06:14:44.915159 | localhost | changed
2026-09-09 06:14:44.924173 | 
2026-09-09 06:14:44.924314 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-09 06:14:45.340570 | localhost -> localhost | ok: Runtime: 0:00:00.005209
2026-09-09 06:14:45.350345 | 
2026-09-09 06:14:45.350521 | TASK [upload-logs : Upload logs to log server]
2026-09-09 06:14:45.849398 | localhost | Output suppressed because no_log was given
2026-09-09 06:14:45.895355 | 
2026-09-09 06:14:45.895501 | LOOP [upload-logs : Compress console log and json output]
2026-09-09 06:14:45.953389 | localhost | skipping: Conditional result was False
2026-09-09 06:14:45.962577 | localhost | skipping: Conditional result was False
2026-09-09 06:14:45.977968 | 
2026-09-09 06:14:45.978147 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-09 06:14:46.018085 | localhost | skipping: Conditional result was False
2026-09-09 06:14:46.018446 | 
2026-09-09 06:14:46.022826 | localhost | skipping: Conditional result was False
2026-09-09 06:14:46.039675 | 
2026-09-09 06:14:46.039820 | LOOP [upload-logs : Upload console log and json output]