2026-08-28 21:39:21.946126 | Job console starting
2026-08-28 21:39:21.955671 | Updating git repos
2026-08-28 21:39:24.620412 | Cloning repos into workspace
2026-08-28 21:39:40.715947 | Restoring repo states
2026-08-28 21:39:57.345449 | Merging changes
2026-08-28 21:39:58.657727 | Checking out repos
2026-08-28 21:40:00.365083 | Preparing playbooks
2026-08-28 21:40:02.047101 | Running Ansible setup
2026-08-28 21:40:05.738569 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-28 21:40:06.330342 | 
2026-08-28 21:40:06.330477 | PLAY [localhost]
2026-08-28 21:40:06.337702 | 
2026-08-28 21:40:06.337776 | TASK [Gathering Facts]
2026-08-28 21:40:07.173991 | localhost | ok
2026-08-28 21:40:07.181755 | 
2026-08-28 21:40:07.182027 | TASK [Setup log path fact]
2026-08-28 21:40:07.203393 | localhost | ok
2026-08-28 21:40:07.216114 | 
2026-08-28 21:40:07.216179 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-28 21:40:07.245736 | localhost | ok
2026-08-28 21:40:07.253034 | 
2026-08-28 21:40:07.253101 | TASK [emit-job-header : Print job information]
2026-08-28 21:40:07.293666 | # Job Information
2026-08-28 21:40:07.293823 | Ansible Version: 2.16.18
2026-08-28 21:40:07.293866 | Job: atmosphere-molecule-csi-rbd
2026-08-28 21:40:07.293896 | Pipeline: check
2026-08-28 21:40:07.293924 | Executor: 2d72f0692154
2026-08-28 21:40:07.293950 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-08-28 21:40:07.293983 | Event ID: 68c498d0-a328-11f1-8f3a-618b567df04c
2026-08-28 21:40:07.297681 | 
2026-08-28 21:40:07.297765 | LOOP [emit-job-header : Print node information]
2026-08-28 21:40:07.393874 | localhost | ok:
2026-08-28 21:40:07.394582 | localhost | # Node Information
2026-08-28 21:40:07.394630 | localhost | Inventory Hostname: instance
2026-08-28 21:40:07.394660 | localhost | Hostname: np0000191423
2026-08-28 21:40:07.394687 | localhost | Username: zuul
2026-08-28 21:40:07.394719 | localhost | Distro: Ubuntu 22.04
2026-08-28 21:40:07.394746 | localhost | Provider: yul1
2026-08-28 21:40:07.394772 | localhost | Region: ca-ymq-1
2026-08-28 21:40:07.394799 | localhost | Label: ubuntu-jammy
2026-08-28 21:40:07.394824 | localhost | Product Name: OpenStack Nova
2026-08-28 21:40:07.394849 | localhost | Interface IP: 162.253.55.166
2026-08-28 21:40:07.414076 | 
2026-08-28 21:40:07.414195 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-28 21:40:07.803901 | localhost -> localhost | changed
2026-08-28 21:40:07.812147 | 
2026-08-28 21:40:07.812245 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-28 21:40:08.657178 | localhost -> localhost | changed
2026-08-28 21:40:08.665240 | 
2026-08-28 21:40:08.665291 | PLAY [all]
2026-08-28 21:40:08.674451 | 
2026-08-28 21:40:08.674520 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-28 21:40:08.891915 | instance -> localhost | ok
2026-08-28 21:40:08.903064 | 
2026-08-28 21:40:08.903128 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-28 21:40:08.936423 | instance | ok
2026-08-28 21:40:08.951460 | instance | included: /var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-28 21:40:08.957339 | 
2026-08-28 21:40:08.957400 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-28 21:40:10.168879 | instance -> localhost | Generating public/private rsa key pair.
2026-08-28 21:40:10.169104 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/work/3b5e32a9bdbf422b8b11d9bcde81dc3c_id_rsa
2026-08-28 21:40:10.169133 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/work/3b5e32a9bdbf422b8b11d9bcde81dc3c_id_rsa.pub
2026-08-28 21:40:10.169156 | instance -> localhost | The key fingerprint is:
2026-08-28 21:40:10.169177 | instance -> localhost | SHA256:b0d84Od0S9jKGAmiYdintfVlj9NHec6LqJzRhhwD39g zuul-build-sshkey
2026-08-28 21:40:10.169209 | instance -> localhost | The key's randomart image is:
2026-08-28 21:40:10.169230 | instance -> localhost | +---[RSA 3072]----+
2026-08-28 21:40:10.169262 | instance -> localhost | |                 |
2026-08-28 21:40:10.169284 | instance -> localhost | |    o           .|
2026-08-28 21:40:10.169304 | instance -> localhost | |   . + = o  .o .o|
2026-08-28 21:40:10.169324 | instance -> localhost | |    . * * *o+.*+.|
2026-08-28 21:40:10.169345 | instance -> localhost | |     o .S= E+++==|
2026-08-28 21:40:10.169364 | instance -> localhost | |        ..=.===.+|
2026-08-28 21:40:10.169384 | instance -> localhost | |         +o=.+.o |
2026-08-28 21:40:10.169404 | instance -> localhost | |        ..=.     |
2026-08-28 21:40:10.169427 | instance -> localhost | |         +       |
2026-08-28 21:40:10.169448 | instance -> localhost | +----[SHA256]-----+
2026-08-28 21:40:10.169501 | instance -> localhost | ok: Runtime: 0:00:00.782497
2026-08-28 21:40:10.177004 | 
2026-08-28 21:40:10.177071 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-28 21:40:10.211920 | instance | ok
2026-08-28 21:40:10.224417 | instance | included: /var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-28 21:40:10.231603 | 
2026-08-28 21:40:10.231662 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-28 21:40:10.258137 | instance | skipping: Conditional result was False
2026-08-28 21:40:10.268448 | 
2026-08-28 21:40:10.268538 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-28 21:40:10.719401 | instance | changed
2026-08-28 21:40:10.726648 | 
2026-08-28 21:40:10.726713 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-28 21:40:10.923993 | instance | ok
2026-08-28 21:40:10.930978 | 
2026-08-28 21:40:10.931064 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-28 21:40:11.433760 | instance | changed
2026-08-28 21:40:11.440523 | 
2026-08-28 21:40:11.440613 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-28 21:40:11.922071 | instance | changed
2026-08-28 21:40:11.929961 | 
2026-08-28 21:40:11.930039 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-28 21:40:11.955395 | instance | skipping: Conditional result was False
2026-08-28 21:40:11.963414 | 
2026-08-28 21:40:11.963507 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-28 21:40:12.311837 | instance -> localhost | changed
2026-08-28 21:40:12.322570 | 
2026-08-28 21:40:12.322638 | TASK [add-build-sshkey : Add back temp key]
2026-08-28 21:40:12.599185 | instance -> localhost | Identity added: /var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/work/3b5e32a9bdbf422b8b11d9bcde81dc3c_id_rsa (zuul-build-sshkey)
2026-08-28 21:40:12.599453 | instance -> localhost | ok: Runtime: 0:00:00.015088
2026-08-28 21:40:12.605876 | 
2026-08-28 21:40:12.605943 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-28 21:40:12.934814 | instance | ok
2026-08-28 21:40:12.940500 | 
2026-08-28 21:40:12.940565 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-28 21:40:12.965094 | instance | skipping: Conditional result was False
2026-08-28 21:40:12.980247 | 
2026-08-28 21:40:12.980333 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-28 21:40:13.327175 | instance | ok
2026-08-28 21:40:13.334304 | 
2026-08-28 21:40:13.334386 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-28 21:40:16.708683 | instance | Output suppressed because no_log was given
2026-08-28 21:40:16.719025 | 
2026-08-28 21:40:16.719087 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-28 21:40:16.930555 | instance | ok: "logs"
2026-08-28 21:40:16.930855 | instance | ok: All items complete
2026-08-28 21:40:16.930883 | 
2026-08-28 21:40:17.076842 | instance | ok: "artifacts"
2026-08-28 21:40:17.928386 | instance | ok: "docs"
2026-08-28 21:40:17.939886 | 
2026-08-28 21:40:17.940032 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-28 21:40:18.138051 | instance | changed: "logs"
2026-08-28 21:40:18.329640 | instance | changed: "artifacts"
2026-08-28 21:40:18.505150 | instance | changed: "docs"
2026-08-28 21:40:18.525477 | 
2026-08-28 21:40:18.525595 | PLAY RECAP
2026-08-28 21:40:18.525640 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-28 21:40:18.525670 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-28 21:40:18.525692 | 
2026-08-28 21:40:18.656048 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-28 21:40:18.659996 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-28 21:40:19.230725 | 
2026-08-28 21:40:19.230853 | PLAY [all]
2026-08-28 21:40:19.241514 | 
2026-08-28 21:40:19.241594 | TASK [setup-uv : Extract archive]
2026-08-28 21:40:21.526366 | instance | changed
2026-08-28 21:40:21.533578 | 
2026-08-28 21:40:21.533697 | TASK [setup-uv : Print version]
2026-08-28 21:40:21.890047 | instance | uv 0.8.13
2026-08-28 21:40:22.072329 | instance | ok: Runtime: 0:00:00.010863
2026-08-28 21:40:22.081787 | 
2026-08-28 21:40:22.081861 | TASK [Install Ansible collection siblings]
2026-08-28 21:40:30.690700 | instance | changed:
2026-08-28 21:40:30.690873 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-28 21:40:30.690903 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-28 21:40:30.690926 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-28 21:40:30.690946 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-28 21:40:30.690966 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-28 21:40:30.690991 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-28 21:40:30.691014 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-28 21:40:30.691036 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-28 21:40:30.691056 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-28 21:40:30.691075 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-28 21:40:30.691094 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-28 21:40:30.691113 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-28 21:40:30.691131 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-28 21:40:30.691149 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-28 21:40:30.691168 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-28 21:40:30.691187 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-28 21:40:30.691206 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-28 21:40:30.691224 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-28 21:40:30.691243 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-28 21:40:30.691261 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-28 21:40:30.691279 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-28 21:40:30.691297 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-28 21:40:30.691317 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-28 21:40:30.691473 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-28 21:40:30.691499 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-28 21:40:30.691519 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-28 21:40:30.691538 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-28 21:40:30.691557 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-28 21:40:30.691575 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-28 21:40:30.691593 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-28 21:40:30.691611 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-28 21:40:30.691630 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-28 21:40:30.691648 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-28 21:40:30.691667 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-28 21:40:30.691694 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.691714 | instance | Process install dependency map
2026-08-28 21:40:30.691733 | instance | Starting collection install process
2026-08-28 21:40:30.691753 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-28 21:40:30.691771 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-28 21:40:30.691789 | instance | ansible.netcommon:* was installed successfully
2026-08-28 21:40:30.691807 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-28 21:40:30.691825 | instance | Creating virtual environment at: .venv
2026-08-28 21:40:30.691844 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-28 21:40:30.691864 | instance | Downloading setuptools (1.1MiB)
2026-08-28 21:40:30.691892 | instance | Downloading ansible-core (2.1MiB)
2026-08-28 21:40:30.691915 | instance | Downloading netaddr (2.2MiB)
2026-08-28 21:40:30.691934 | instance | Downloading kubernetes (1.9MiB)
2026-08-28 21:40:30.691952 | instance | Downloading pygments (1.2MiB)
2026-08-28 21:40:30.691970 | instance | Downloading openstacksdk (1.7MiB)
2026-08-28 21:40:30.691990 | instance | Downloading pydantic-core (2.0MiB)
2026-08-28 21:40:30.692009 | instance | Downloading rjsonnet (1.2MiB)
2026-08-28 21:40:30.692027 | instance | Downloading cryptography (4.2MiB)
2026-08-28 21:40:30.692045 | instance |    Building pyperclip==1.9.0
2026-08-28 21:40:30.692065 | instance |  Downloading rjsonnet
2026-08-28 21:40:30.692084 | instance |  Downloading pydantic-core
2026-08-28 21:40:30.692104 | instance |  Downloading netaddr
2026-08-28 21:40:30.692124 | instance |  Downloading pygments
2026-08-28 21:40:30.692143 | instance |  Downloading cryptography
2026-08-28 21:40:30.692162 | instance |  Downloading setuptools
2026-08-28 21:40:30.692180 | instance |  Downloading kubernetes
2026-08-28 21:40:30.692199 | instance |  Downloading ansible-core
2026-08-28 21:40:30.692217 | instance |  Downloading openstacksdk
2026-08-28 21:40:30.692234 | instance |       Built pyperclip==1.9.0
2026-08-28 21:40:30.692253 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-28 21:40:30.692271 | instance | Installed 83 packages in 143ms
2026-08-28 21:40:30.692290 | instance | Checked out ansible.posix to 1.6.0
2026-08-28 21:40:30.692308 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-28 21:40:30.692326 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.692344 | instance | Process install dependency map
2026-08-28 21:40:30.692368 | instance | Starting collection install process
2026-08-28 21:40:30.692387 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-28 21:40:30.692405 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-28 21:40:30.692423 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-28 21:40:30.692442 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.692459 | instance | 2.17.12
2026-08-28 21:40:30.692478 | instance | Checked out ansible.utils to v6.1.0
2026-08-28 21:40:30.692497 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-28 21:40:30.692515 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.692533 | instance | Process install dependency map
2026-08-28 21:40:30.692551 | instance | Starting collection install process
2026-08-28 21:40:30.692570 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-28 21:40:30.692588 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-28 21:40:30.692607 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-28 21:40:30.692625 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.692643 | instance | 2.17.12
2026-08-28 21:40:30.692661 | instance | Checked out atmosphere.common to v0.7.0
2026-08-28 21:40:30.692679 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-28 21:40:30.692698 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.692717 | instance | Process install dependency map
2026-08-28 21:40:30.692735 | instance | Starting collection install process
2026-08-28 21:40:30.692753 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-28 21:40:30.692771 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-28 21:40:30.692790 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-28 21:40:30.692808 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.692827 | instance | 2.17.12
2026-08-28 21:40:30.692846 | instance | Checked out community.crypto to 2.2.3
2026-08-28 21:40:30.692865 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-28 21:40:30.692885 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.692903 | instance | Process install dependency map
2026-08-28 21:40:30.692921 | instance | Starting collection install process
2026-08-28 21:40:30.692940 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-28 21:40:30.692981 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-28 21:40:30.693000 | instance | community.crypto:2.2.3 was installed successfully
2026-08-28 21:40:30.693018 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.693036 | instance | 2.17.12
2026-08-28 21:40:30.693054 | instance | Checked out community.general to 7.3.0
2026-08-28 21:40:30.693073 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-08-28 21:40:30.693097 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.693116 | instance | Process install dependency map
2026-08-28 21:40:30.693134 | instance | Starting collection install process
2026-08-28 21:40:30.693152 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-28 21:40:30.693170 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-28 21:40:30.693187 | instance | community.general:7.3.0 was installed successfully
2026-08-28 21:40:30.693206 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.693224 | instance | 2.17.12
2026-08-28 21:40:30.693242 | instance | Checked out community.mysql to 3.6.0
2026-08-28 21:40:30.693263 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-28 21:40:30.693281 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.693300 | instance | Process install dependency map
2026-08-28 21:40:30.693317 | instance | Starting collection install process
2026-08-28 21:40:30.693335 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-28 21:40:30.693352 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-28 21:40:30.693371 | instance | community.mysql:3.6.0 was installed successfully
2026-08-28 21:40:30.693392 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.693414 | instance | 2.17.12
2026-08-28 21:40:30.693436 | instance | Checked out kubernetes.core to 2.4.0
2026-08-28 21:40:30.693459 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-28 21:40:30.693482 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.693504 | instance | Process install dependency map
2026-08-28 21:40:30.693526 | instance | Starting collection install process
2026-08-28 21:40:30.693549 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-28 21:40:30.693571 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-28 21:40:30.693591 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-28 21:40:30.693609 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.693628 | instance | 2.17.12
2026-08-28 21:40:30.693647 | instance | Checked out openstack.cloud to 2.6.0
2026-08-28 21:40:30.693666 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-28 21:40:30.693684 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.693702 | instance | Process install dependency map
2026-08-28 21:40:30.693721 | instance | Starting collection install process
2026-08-28 21:40:30.693738 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-28 21:40:30.693756 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-28 21:40:30.693774 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-28 21:40:30.693792 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.693810 | instance | 2.17.12
2026-08-28 21:40:30.693835 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-28 21:40:30.693855 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-28 21:40:30.693873 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.693892 | instance | Process install dependency map
2026-08-28 21:40:30.693911 | instance | Starting collection install process
2026-08-28 21:40:30.693929 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-28 21:40:30.693946 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-28 21:40:30.693964 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-28 21:40:30.693982 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.694000 | instance | 2.17.12
2026-08-28 21:40:30.694018 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-28 21:40:30.694036 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-28 21:40:30.694054 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.694073 | instance | Process install dependency map
2026-08-28 21:40:30.694091 | instance | Starting collection install process
2026-08-28 21:40:30.694110 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-28 21:40:30.694128 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-28 21:40:30.694146 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-28 21:40:30.694164 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.694182 | instance | 2.17.12
2026-08-28 21:40:30.694200 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-28 21:40:30.694217 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-28 21:40:30.694236 | instance | Starting galaxy collection install process
2026-08-28 21:40:30.694254 | instance | Process install dependency map
2026-08-28 21:40:30.694271 | instance | Starting collection install process
2026-08-28 21:40:30.694289 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-28 21:40:30.694307 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-28 21:40:30.694325 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-28 21:40:30.694343 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-28 21:40:30.694362 | instance | 2.17.12
2026-08-28 21:40:30.699617 | 
2026-08-28 21:40:30.699657 | PLAY RECAP
2026-08-28 21:40:30.699700 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-28 21:40:30.699721 | 
2026-08-28 21:40:30.812940 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-28 21:40:30.815239 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-28 21:40:31.375244 | 
2026-08-28 21:40:31.375384 | PLAY [all]
2026-08-28 21:40:31.386202 | 
2026-08-28 21:40:31.386272 | TASK [Install "jq" for log collection]
2026-08-28 21:40:39.656046 | instance | changed
2026-08-28 21:40:39.659721 | 
2026-08-28 21:40:39.659845 | PLAY RECAP
2026-08-28 21:40:39.659953 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-28 21:40:39.660075 | 
2026-08-28 21:40:39.783041 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-28 21:40:39.787069 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-28 21:40:40.353409 | 
2026-08-28 21:40:40.353565 | PLAY [all]
2026-08-28 21:40:40.372138 | 
2026-08-28 21:40:40.372334 | LOOP [Check inventory directories]
2026-08-28 21:40:40.715749 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-28 21:40:40.883695 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-28 21:40:41.046887 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-28 21:40:41.070026 | 
2026-08-28 21:40:41.070230 | LOOP [Copy inventory file for Zuul]
2026-08-28 21:40:41.889105 | instance | changed:
2026-08-28 21:40:41.889357 | instance | {
2026-08-28 21:40:41.889395 | instance |   "ansible_loop_var": "item",
2026-08-28 21:40:41.889427 | instance |   "changed": false,
2026-08-28 21:40:41.889455 | instance |   "failed": false,
2026-08-28 21:40:41.889487 | instance |   "invocation": {
2026-08-28 21:40:41.889514 | instance |     "module_args": {
2026-08-28 21:40:41.889541 | instance |       "checksum_algorithm": "sha1",
2026-08-28 21:40:41.889566 | instance |       "follow": true,
2026-08-28 21:40:41.889591 | instance |       "get_attributes": true,
2026-08-28 21:40:41.889618 | instance |       "get_checksum": true,
2026-08-28 21:40:41.889644 | instance |       "get_mime": true,
2026-08-28 21:40:41.889668 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-28 21:40:41.889693 | instance |     }
2026-08-28 21:40:41.889718 | instance |   },
2026-08-28 21:40:41.889743 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-28 21:40:41.889768 | instance |   "stat": {
2026-08-28 21:40:41.889792 | instance |     "atime": 1787953223.8837252,
2026-08-28 21:40:41.889817 | instance |     "attr_flags": "e",
2026-08-28 21:40:41.889844 | instance |     "attributes": [
2026-08-28 21:40:41.889872 | instance |       "extents"
2026-08-28 21:40:41.889897 | instance |     ],
2026-08-28 21:40:41.889922 | instance |     "block_size": 4096,
2026-08-28 21:40:41.889946 | instance |     "blocks": 8,
2026-08-28 21:40:41.889970 | instance |     "charset": "unknown",
2026-08-28 21:40:41.889996 | instance |     "ctime": 1787953222.899724,
2026-08-28 21:40:41.890020 | instance |     "dev": 64513,
2026-08-28 21:40:41.890045 | instance |     "device_type": 0,
2026-08-28 21:40:41.890069 | instance |     "executable": true,
2026-08-28 21:40:41.890093 | instance |     "exists": true,
2026-08-28 21:40:41.890117 | instance |     "gid": 1000,
2026-08-28 21:40:41.890141 | instance |     "gr_name": "zuul",
2026-08-28 21:40:41.890166 | instance |     "inode": 1047801,
2026-08-28 21:40:41.890190 | instance |     "isblk": false,
2026-08-28 21:40:41.890215 | instance |     "ischr": false,
2026-08-28 21:40:41.890239 | instance |     "isdir": true,
2026-08-28 21:40:41.890264 | instance |     "isfifo": false,
2026-08-28 21:40:41.890288 | instance |     "isgid": false,
2026-08-28 21:40:41.890312 | instance |     "islnk": false,
2026-08-28 21:40:41.890337 | instance |     "isreg": false,
2026-08-28 21:40:41.890361 | instance |     "issock": false,
2026-08-28 21:40:41.890385 | instance |     "isuid": false,
2026-08-28 21:40:41.890408 | instance |     "mimetype": "unknown",
2026-08-28 21:40:41.890432 | instance |     "mode": "0755",
2026-08-28 21:40:41.890456 | instance |     "mtime": 1787953222.899724,
2026-08-28 21:40:41.890480 | instance |     "nlink": 23,
2026-08-28 21:40:41.890504 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-28 21:40:41.890529 | instance |     "pw_name": "zuul",
2026-08-28 21:40:41.890554 | instance |     "readable": true,
2026-08-28 21:40:41.890578 | instance |     "rgrp": true,
2026-08-28 21:40:41.890603 | instance |     "roth": true,
2026-08-28 21:40:41.890640 | instance |     "rusr": true,
2026-08-28 21:40:41.890666 | instance |     "size": 4096,
2026-08-28 21:40:41.890691 | instance |     "uid": 1000,
2026-08-28 21:40:41.890715 | instance |     "version": "2894239908",
2026-08-28 21:40:41.890740 | instance |     "wgrp": false,
2026-08-28 21:40:41.890766 | instance |     "woth": false,
2026-08-28 21:40:41.890791 | instance |     "writeable": true,
2026-08-28 21:40:41.890816 | instance |     "wusr": true,
2026-08-28 21:40:41.890840 | instance |     "xgrp": true,
2026-08-28 21:40:41.890864 | instance |     "xoth": true,
2026-08-28 21:40:41.890888 | instance |     "xusr": true
2026-08-28 21:40:41.890912 | instance |   }
2026-08-28 21:40:41.890936 | instance | }
2026-08-28 21:40:41.890987 | instance | ok: All items complete
2026-08-28 21:40:41.891017 | 
2026-08-28 21:40:41.892751 | instance | skipping: Conditional result was False
2026-08-28 21:40:41.893591 | instance | skipping: Conditional result was False
2026-08-28 21:40:41.910488 | 
2026-08-28 21:40:41.910693 | LOOP [Switch "ansible_host" to private IP]
2026-08-28 21:40:42.267331 | instance | changed: 1 replacements made
2026-08-28 21:40:42.269633 | instance | skipping: Conditional result was False
2026-08-28 21:40:42.273201 | instance | skipping: Conditional result was False
2026-08-28 21:40:42.291866 | 
2026-08-28 21:40:42.292077 | TASK [Run Molecule scenario]
2026-08-28 21:40:43.176582 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-28 21:40:43.561749 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-28 21:40:43.561861 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-28 21:40:53.999468 | instance | INFO     [csi > dependency] Executing
2026-08-28 21:40:53.999881 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-28 21:40:54.000266 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-28 21:40:54.000436 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-28 21:40:54.014944 | instance | INFO     [csi > cleanup] Executing
2026-08-28 21:40:54.015589 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-28 21:40:54.028632 | instance | INFO     [csi > destroy] Executing
2026-08-28 21:40:54.028737 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-28 21:40:54.028829 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-28 21:40:54.037662 | instance | INFO     [csi > syntax] Executing
2026-08-28 21:40:55.439035 | instance |
2026-08-28 21:40:55.439275 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-28 21:40:55.493978 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-28 21:40:55.508854 | instance | INFO     [csi > create] Executing
2026-08-28 21:40:55.512255 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-28 21:40:55.526571 | instance | INFO     [csi > prepare] Executing
2026-08-28 21:40:56.278854 | instance |
2026-08-28 21:40:56.279066 | instance | PLAY [Prepare] *****************************************************************
2026-08-28 21:40:56.279282 | instance |
2026-08-28 21:40:56.279544 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 21:40:56.279774 | instance | Friday 28 August 2026  21:40:56 +0000 (0:00:00.024)       0:00:00.024 *********
2026-08-28 21:40:57.533332 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-28 21:40:57.533587 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-28 21:40:57.533816 | instance | interpreter could change the meaning of that path. See
2026-08-28 21:40:57.534042 | instance | https://docs.ansible.com/ansible-
2026-08-28 21:40:57.534267 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-28 21:40:57.545339 | instance | ok: [instance]
2026-08-28 21:40:57.545567 | instance |
2026-08-28 21:40:57.545793 | instance | TASK [Configure short hostname] ************************************************
2026-08-28 21:40:57.546019 | instance | Friday 28 August 2026  21:40:57 +0000 (0:00:01.267)       0:00:01.291 *********
2026-08-28 21:40:58.323909 | instance | changed: [instance]
2026-08-28 21:40:58.324141 | instance |
2026-08-28 21:40:58.324421 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-28 21:40:58.324750 | instance | Friday 28 August 2026  21:40:58 +0000 (0:00:00.778)       0:00:02.069 *********
2026-08-28 21:40:58.599686 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-28 21:40:58.600011 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-28 21:40:58.600314 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-28 21:40:58.609263 | instance | changed: [instance]
2026-08-28 21:40:58.609504 | instance |
2026-08-28 21:40:58.609742 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-28 21:40:58.609969 | instance | Friday 28 August 2026  21:40:58 +0000 (0:00:00.285)       0:00:02.355 *********
2026-08-28 21:40:59.507955 | instance | ok: [instance]
2026-08-28 21:40:59.508153 | instance |
2026-08-28 21:40:59.508415 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-28 21:40:59.508644 | instance |
2026-08-28 21:40:59.508877 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 21:40:59.509113 | instance | Friday 28 August 2026  21:40:59 +0000 (0:00:00.898)       0:00:03.254 *********
2026-08-28 21:41:00.281226 | instance | ok: [instance]
2026-08-28 21:41:00.281512 | instance |
2026-08-28 21:41:00.281809 | instance | TASK [Install depedencies] *****************************************************
2026-08-28 21:41:00.282053 | instance | Friday 28 August 2026  21:41:00 +0000 (0:00:00.772)       0:00:04.026 *********
2026-08-28 21:41:22.411907 | instance | changed: [instance]
2026-08-28 21:41:22.412051 | instance |
2026-08-28 21:41:22.412287 | instance | TASK [Start up service] ********************************************************
2026-08-28 21:41:22.412546 | instance | Friday 28 August 2026  21:41:22 +0000 (0:00:22.130)       0:00:26.157 *********
2026-08-28 21:41:22.946591 | instance | ok: [instance]
2026-08-28 21:41:22.946839 | instance |
2026-08-28 21:41:22.947088 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-28 21:41:22.947333 | instance | Friday 28 August 2026  21:41:22 +0000 (0:00:00.534)       0:00:26.692 *********
2026-08-28 21:41:23.242645 | instance | ok: [instance]
2026-08-28 21:41:23.242923 | instance |
2026-08-28 21:41:23.243169 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-28 21:41:23.243402 | instance | Friday 28 August 2026  21:41:23 +0000 (0:00:00.296)       0:00:26.988 *********
2026-08-28 21:41:23.924836 | instance | changed: [instance]
2026-08-28 21:41:23.924895 | instance |
2026-08-28 21:41:23.924908 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-28 21:41:23.924918 | instance | Friday 28 August 2026  21:41:23 +0000 (0:00:00.680)       0:00:27.669 *********
2026-08-28 21:41:24.130323 | instance | ok: [instance]
2026-08-28 21:41:24.131153 | instance |
2026-08-28 21:41:24.131200 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-28 21:41:24.131213 | instance | Friday 28 August 2026  21:41:24 +0000 (0:00:00.206)       0:00:27.876 *********
2026-08-28 21:41:24.154318 | instance | skipping: [instance]
2026-08-28 21:41:24.154574 | instance |
2026-08-28 21:41:24.154835 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-28 21:41:24.154855 | instance | Friday 28 August 2026  21:41:24 +0000 (0:00:00.024)       0:00:27.900 *********
2026-08-28 21:41:24.713922 | instance | changed: [instance] => (item=osd0)
2026-08-28 21:41:24.713972 | instance | changed: [instance] => (item=osd1)
2026-08-28 21:41:24.713982 | instance | changed: [instance] => (item=osd2)
2026-08-28 21:41:24.713992 | instance |
2026-08-28 21:41:24.714001 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-28 21:41:24.714010 | instance | Friday 28 August 2026  21:41:24 +0000 (0:00:00.557)       0:00:28.458 *********
2026-08-28 21:41:25.328450 | instance | changed: [instance] => (item=osd0)
2026-08-28 21:41:25.329624 | instance | changed: [instance] => (item=osd1)
2026-08-28 21:41:25.329673 | instance | changed: [instance] => (item=osd2)
2026-08-28 21:41:25.329685 | instance |
2026-08-28 21:41:25.329695 | instance | TASK [Start loop devices] ******************************************************
2026-08-28 21:41:25.329704 | instance | Friday 28 August 2026  21:41:25 +0000 (0:00:00.615)       0:00:29.074 *********
2026-08-28 21:41:26.022237 | instance | changed: [instance] => (item=osd0)
2026-08-28 21:41:26.022648 | instance | changed: [instance] => (item=osd1)
2026-08-28 21:41:26.023616 | instance | changed: [instance] => (item=osd2)
2026-08-28 21:41:26.023672 | instance |
2026-08-28 21:41:26.023684 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-28 21:41:26.023696 | instance | Friday 28 August 2026  21:41:26 +0000 (0:00:00.693)       0:00:29.767 *********
2026-08-28 21:41:29.101662 | instance | changed: [instance] => (item=osd0)
2026-08-28 21:41:29.102640 | instance | changed: [instance] => (item=osd1)
2026-08-28 21:41:29.102702 | instance | changed: [instance] => (item=osd2)
2026-08-28 21:41:29.103324 | instance |
2026-08-28 21:41:29.103630 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-28 21:41:29.103647 | instance | Friday 28 August 2026  21:41:29 +0000 (0:00:03.078)       0:00:32.846 *********
2026-08-28 21:41:31.062287 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-28 21:41:31.062598 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-28 21:41:31.063047 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-28 21:41:31.063074 | instance |
2026-08-28 21:41:31.063947 | instance | PLAY RECAP *********************************************************************
2026-08-28 21:41:31.063998 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-28 21:41:31.064011 | instance |
2026-08-28 21:41:31.064050 | instance | Friday 28 August 2026  21:41:31 +0000 (0:00:01.961)       0:00:34.808 *********
2026-08-28 21:41:31.066594 | instance | ===============================================================================
2026-08-28 21:41:31.066660 | instance | Install depedencies ---------------------------------------------------- 22.13s
2026-08-28 21:41:31.066672 | instance | Create a volume group for each loop device ------------------------------ 3.08s
2026-08-28 21:41:31.066681 | instance | Create a logical volume for each loop device ---------------------------- 1.96s
2026-08-28 21:41:31.066691 | instance | Gathering Facts --------------------------------------------------------- 1.27s
2026-08-28 21:41:31.066719 | instance | Purge "snapd" package --------------------------------------------------- 0.90s
2026-08-28 21:41:31.066728 | instance | Configure short hostname ------------------------------------------------ 0.78s
2026-08-28 21:41:31.066736 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-08-28 21:41:31.066745 | instance | Start loop devices ------------------------------------------------------ 0.69s
2026-08-28 21:41:31.066753 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.68s
2026-08-28 21:41:31.066762 | instance | Set permissions on loopback devices ------------------------------------- 0.62s
2026-08-28 21:41:31.066778 | instance | Create devices for Ceph ------------------------------------------------- 0.56s
2026-08-28 21:41:31.067004 | instance | Start up service -------------------------------------------------------- 0.53s
2026-08-28 21:41:31.067330 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-08-28 21:41:31.067621 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-08-28 21:41:31.067933 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-08-28 21:41:31.068223 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-28 21:41:31.140398 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-28 21:41:31.154797 | instance | INFO     [csi > converge] Executing
2026-08-28 21:41:32.462636 | instance |
2026-08-28 21:41:32.463032 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-28 21:41:32.463319 | instance |
2026-08-28 21:41:32.463611 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 21:41:32.463925 | instance | Friday 28 August 2026  21:41:32 +0000 (0:00:00.006)       0:00:00.006 *********
2026-08-28 21:41:33.515809 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-28 21:41:33.515863 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-28 21:41:33.515877 | instance | interpreter could change the meaning of that path. See
2026-08-28 21:41:33.515886 | instance | https://docs.ansible.com/ansible-
2026-08-28 21:41:33.515897 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-28 21:41:33.529509 | instance | ok: [instance]
2026-08-28 21:41:33.529544 | instance |
2026-08-28 21:41:33.529555 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-28 21:41:33.529565 | instance | Friday 28 August 2026  21:41:33 +0000 (0:00:01.065)       0:00:01.072 *********
2026-08-28 21:41:33.590586 | instance | ok: [instance] => {
2026-08-28 21:41:33.590639 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-28 21:41:33.590651 | instance | }
2026-08-28 21:41:33.590660 | instance |
2026-08-28 21:41:33.590674 | instance | PLAY [all] *********************************************************************
2026-08-28 21:41:33.590843 | instance |
2026-08-28 21:41:33.591091 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 21:41:33.591390 | instance | Friday 28 August 2026  21:41:33 +0000 (0:00:00.060)       0:00:01.133 *********
2026-08-28 21:41:34.550598 | instance | ok: [instance]
2026-08-28 21:41:34.550924 | instance |
2026-08-28 21:41:34.551249 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-08-28 21:41:34.551542 | instance | Friday 28 August 2026  21:41:34 +0000 (0:00:00.960)       0:00:02.094 *********
2026-08-28 21:41:34.593256 | instance | skipping: [instance]
2026-08-28 21:41:34.593350 | instance |
2026-08-28 21:41:34.593488 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-28 21:41:34.593690 | instance | Friday 28 August 2026  21:41:34 +0000 (0:00:00.043)       0:00:02.137 *********
2026-08-28 21:41:34.804066 | instance | ok: [instance]
2026-08-28 21:41:34.804135 | instance |
2026-08-28 21:41:34.804244 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-28 21:41:34.804322 | instance |
2026-08-28 21:41:34.804462 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 21:41:34.804602 | instance | Friday 28 August 2026  21:41:34 +0000 (0:00:00.211)       0:00:02.348 *********
2026-08-28 21:41:35.719219 | instance | ok: [instance]
2026-08-28 21:41:35.719425 | instance |
2026-08-28 21:41:35.719773 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-28 21:41:35.720078 | instance | Friday 28 August 2026  21:41:35 +0000 (0:00:00.912)       0:00:03.261 *********
2026-08-28 21:41:36.049510 | instance | ok: [instance]
2026-08-28 21:41:36.049675 | instance |
2026-08-28 21:41:36.049823 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-28 21:41:36.049964 | instance | Friday 28 August 2026  21:41:36 +0000 (0:00:00.330)       0:00:03.592 *********
2026-08-28 21:41:36.081416 | instance | skipping: [instance]
2026-08-28 21:41:36.081431 | instance |
2026-08-28 21:41:36.081436 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-28 21:41:36.081440 | instance | Friday 28 August 2026  21:41:36 +0000 (0:00:00.033)       0:00:03.625 *********
2026-08-28 21:41:36.431999 | instance | changed: [instance]
2026-08-28 21:41:36.432722 | instance |
2026-08-28 21:41:36.432754 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-28 21:41:36.432765 | instance | Friday 28 August 2026  21:41:36 +0000 (0:00:00.349)       0:00:03.975 *********
2026-08-28 21:41:36.521079 | instance | ok: [instance] => {
2026-08-28 21:41:36.521362 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-28 21:41:36.522293 | instance | }
2026-08-28 21:41:36.522324 | instance |
2026-08-28 21:41:36.522335 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-28 21:41:36.522344 | instance | Friday 28 August 2026  21:41:36 +0000 (0:00:00.089)       0:00:04.065 *********
2026-08-28 21:41:37.147127 | instance | changed: [instance]
2026-08-28 21:41:37.147862 | instance |
2026-08-28 21:41:37.147907 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-28 21:41:37.147919 | instance | Friday 28 August 2026  21:41:37 +0000 (0:00:00.625)       0:00:04.690 *********
2026-08-28 21:41:37.216503 | instance | skipping: [instance]
2026-08-28 21:41:37.216632 | instance |
2026-08-28 21:41:37.216841 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-28 21:41:37.217027 | instance | Friday 28 August 2026  21:41:37 +0000 (0:00:00.069)       0:00:04.760 *********
2026-08-28 21:41:37.279564 | instance | skipping: [instance]
2026-08-28 21:41:37.279694 | instance |
2026-08-28 21:41:37.279905 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-28 21:41:37.280098 | instance | Friday 28 August 2026  21:41:37 +0000 (0:00:00.063)       0:00:04.823 *********
2026-08-28 21:41:37.512923 | instance | ok: [instance]
2026-08-28 21:41:37.513117 | instance |
2026-08-28 21:41:37.513462 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-28 21:41:37.513758 | instance | Friday 28 August 2026  21:41:37 +0000 (0:00:00.232)       0:00:05.056 *********
2026-08-28 21:41:38.694658 | instance | ok: [instance]
2026-08-28 21:41:38.694848 | instance |
2026-08-28 21:41:38.695193 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-28 21:41:38.695541 | instance | Friday 28 August 2026  21:41:38 +0000 (0:00:01.181)       0:00:06.238 *********
2026-08-28 21:41:38.779136 | instance | ok: [instance] => {
2026-08-28 21:41:38.779565 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-28 21:41:38.779793 | instance | }
2026-08-28 21:41:38.779978 | instance |
2026-08-28 21:41:38.780168 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-28 21:41:38.780373 | instance | Friday 28 August 2026  21:41:38 +0000 (0:00:00.084)       0:00:06.322 *********
2026-08-28 21:41:39.551581 | instance | changed: [instance]
2026-08-28 21:41:39.551759 | instance |
2026-08-28 21:41:39.552320 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-28 21:41:39.552373 | instance | Friday 28 August 2026  21:41:39 +0000 (0:00:00.772)       0:00:07.095 *********
2026-08-28 21:41:42.389597 | instance | changed: [instance]
2026-08-28 21:41:42.389662 | instance |
2026-08-28 21:41:42.389976 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-28 21:41:42.390013 | instance | Friday 28 August 2026  21:41:42 +0000 (0:00:02.838)       0:00:09.933 *********
2026-08-28 21:41:42.421645 | instance | skipping: [instance]
2026-08-28 21:41:42.422371 | instance |
2026-08-28 21:41:42.422410 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-28 21:41:42.422418 | instance | Friday 28 August 2026  21:41:42 +0000 (0:00:00.031)       0:00:09.965 *********
2026-08-28 21:41:42.459066 | instance | skipping: [instance]
2026-08-28 21:41:42.459829 | instance |
2026-08-28 21:41:42.459882 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-28 21:41:42.459894 | instance | Friday 28 August 2026  21:41:42 +0000 (0:00:00.036)       0:00:10.002 *********
2026-08-28 21:41:42.499486 | instance | skipping: [instance]
2026-08-28 21:41:42.500241 | instance |
2026-08-28 21:41:42.500280 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-28 21:41:42.500292 | instance | Friday 28 August 2026  21:41:42 +0000 (0:00:00.040)       0:00:10.043 *********
2026-08-28 21:41:47.584643 | instance | changed: [instance]
2026-08-28 21:41:47.585401 | instance |
2026-08-28 21:41:47.585448 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-28 21:41:47.585460 | instance | Friday 28 August 2026  21:41:47 +0000 (0:00:05.084)       0:00:15.128 *********
2026-08-28 21:41:48.197974 | instance | changed: [instance]
2026-08-28 21:41:48.198604 | instance |
2026-08-28 21:41:48.198664 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-28 21:41:48.198772 | instance | Friday 28 August 2026  21:41:48 +0000 (0:00:00.613)       0:00:15.741 *********
2026-08-28 21:41:49.190642 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-28 21:41:49.190698 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-28 21:41:49.190710 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-28 21:41:49.190729 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-28 21:41:49.191695 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-28 21:41:49.191749 | instance |
2026-08-28 21:41:49.191761 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-28 21:41:49.191771 | instance | Friday 28 August 2026  21:41:49 +0000 (0:00:00.991)       0:00:16.733 *********
2026-08-28 21:41:49.810835 | instance | changed: [instance]
2026-08-28 21:41:49.810914 | instance |
2026-08-28 21:41:49.811717 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-28 21:41:49.811761 | instance | Friday 28 August 2026  21:41:49 +0000 (0:00:00.596)       0:00:17.329 *********
2026-08-28 21:41:49.811768 | instance |
2026-08-28 21:41:49.811774 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-28 21:41:49.811780 | instance | Friday 28 August 2026  21:41:49 +0000 (0:00:00.025)       0:00:17.355 *********
2026-08-28 21:41:50.832886 | instance | ok: [instance]
2026-08-28 21:41:50.833134 | instance |
2026-08-28 21:41:50.833492 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-28 21:41:50.833836 | instance | Friday 28 August 2026  21:41:50 +0000 (0:00:01.021)       0:00:18.376 *********
2026-08-28 21:41:51.284624 | instance | changed: [instance]
2026-08-28 21:41:51.284987 | instance |
2026-08-28 21:41:51.285330 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-28 21:41:51.285637 | instance | Friday 28 August 2026  21:41:51 +0000 (0:00:00.451)       0:00:18.828 *********
2026-08-28 21:41:51.871201 | instance | changed: [instance]
2026-08-28 21:41:51.871274 | instance |
2026-08-28 21:41:51.871624 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-28 21:41:51.871666 | instance | Friday 28 August 2026  21:41:51 +0000 (0:00:00.586)       0:00:19.415 *********
2026-08-28 21:41:52.119439 | instance | ok: [instance]
2026-08-28 21:41:52.119493 | instance |
2026-08-28 21:41:52.119505 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-28 21:41:52.119515 | instance | Friday 28 August 2026  21:41:52 +0000 (0:00:00.247)       0:00:19.662 *********
2026-08-28 21:41:52.191980 | instance | ok: [instance] => {
2026-08-28 21:41:52.192731 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-28 21:41:52.192787 | instance | }
2026-08-28 21:41:52.192795 | instance |
2026-08-28 21:41:52.192802 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-28 21:41:52.192809 | instance | Friday 28 August 2026  21:41:52 +0000 (0:00:00.073)       0:00:19.736 *********
2026-08-28 21:41:53.143042 | instance | changed: [instance]
2026-08-28 21:41:53.143764 | instance |
2026-08-28 21:41:53.143815 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-28 21:41:53.143827 | instance | Friday 28 August 2026  21:41:53 +0000 (0:00:00.950)       0:00:20.686 *********
2026-08-28 21:41:57.416461 | instance | changed: [instance]
2026-08-28 21:41:57.416534 | instance |
2026-08-28 21:41:57.416887 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-28 21:41:57.416933 | instance | Friday 28 August 2026  21:41:57 +0000 (0:00:04.273)       0:00:24.960 *********
2026-08-28 21:41:58.504708 | instance | ok: [instance]
2026-08-28 21:41:58.505440 | instance |
2026-08-28 21:41:58.505493 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-28 21:41:58.505505 | instance | Friday 28 August 2026  21:41:58 +0000 (0:00:01.087)       0:00:26.048 *********
2026-08-28 21:41:58.857731 | instance | changed: [instance]
2026-08-28 21:41:58.857794 | instance |
2026-08-28 21:41:58.858057 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-28 21:41:58.858096 | instance | Friday 28 August 2026  21:41:58 +0000 (0:00:00.353)       0:00:26.402 *********
2026-08-28 21:41:59.350990 | instance | changed: [instance]
2026-08-28 21:41:59.351956 | instance |
2026-08-28 21:41:59.352022 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-28 21:41:59.352035 | instance | Friday 28 August 2026  21:41:59 +0000 (0:00:00.492)       0:00:26.894 *********
2026-08-28 21:41:59.965757 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-28 21:41:59.966551 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-28 21:41:59.966590 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-28 21:41:59.966595 | instance |
2026-08-28 21:41:59.967716 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-28 21:41:59.967933 | instance | Friday 28 August 2026  21:41:59 +0000 (0:00:00.615)       0:00:27.509 *********
2026-08-28 21:42:00.467741 | instance | changed: [instance]
2026-08-28 21:42:00.468509 | instance |
2026-08-28 21:42:00.468563 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-28 21:42:00.468575 | instance | Friday 28 August 2026  21:42:00 +0000 (0:00:00.501)       0:00:28.011 *********
2026-08-28 21:42:00.974093 | instance | changed: [instance]
2026-08-28 21:42:00.974681 | instance |
2026-08-28 21:42:00.974744 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-28 21:42:00.974774 | instance | Friday 28 August 2026  21:42:00 +0000 (0:00:00.479)       0:00:28.490 *********
2026-08-28 21:42:00.974792 | instance |
2026-08-28 21:42:00.975019 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-28 21:42:00.975071 | instance | Friday 28 August 2026  21:42:00 +0000 (0:00:00.027)       0:00:28.518 *********
2026-08-28 21:42:01.755701 | instance | ok: [instance]
2026-08-28 21:42:01.756460 | instance |
2026-08-28 21:42:01.756525 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-28 21:42:01.756538 | instance | Friday 28 August 2026  21:42:01 +0000 (0:00:00.780)       0:00:29.299 *********
2026-08-28 21:42:02.823880 | instance | changed: [instance]
2026-08-28 21:42:02.824564 | instance |
2026-08-28 21:42:02.824617 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-28 21:42:02.824629 | instance | Friday 28 August 2026  21:42:02 +0000 (0:00:01.068)       0:00:30.367 *********
2026-08-28 21:42:03.446108 | instance | changed: [instance]
2026-08-28 21:42:03.446599 | instance |
2026-08-28 21:42:03.446666 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-28 21:42:03.446677 | instance | Friday 28 August 2026  21:42:03 +0000 (0:00:00.622)       0:00:30.990 *********
2026-08-28 21:42:03.523646 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-28 21:42:03.523696 | instance |
2026-08-28 21:42:03.523708 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-28 21:42:03.523718 | instance | Friday 28 August 2026  21:42:03 +0000 (0:00:00.076)       0:00:31.066 *********
2026-08-28 21:42:08.413041 | instance | changed: [instance]
2026-08-28 21:42:08.413877 | instance |
2026-08-28 21:42:08.413930 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-28 21:42:08.413942 | instance | Friday 28 August 2026  21:42:08 +0000 (0:00:04.890)       0:00:35.956 *********
2026-08-28 21:42:09.085251 | instance | ok: [instance] => (item=chronyd)
2026-08-28 21:42:09.086193 | instance | ok: [instance] => (item=sshd)
2026-08-28 21:42:09.086247 | instance |
2026-08-28 21:42:09.086259 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-28 21:42:09.086270 | instance | Friday 28 August 2026  21:42:09 +0000 (0:00:00.672)       0:00:36.629 *********
2026-08-28 21:42:10.503309 | instance | changed: [instance]
2026-08-28 21:42:10.503998 | instance |
2026-08-28 21:42:10.504165 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-28 21:42:10.504177 | instance | Friday 28 August 2026  21:42:10 +0000 (0:00:01.418)       0:00:38.047 *********
2026-08-28 21:42:10.715735 | instance | ok: [instance]
2026-08-28 21:42:10.716150 | instance |
2026-08-28 21:42:10.716171 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-28 21:42:10.716178 | instance | Friday 28 August 2026  21:42:10 +0000 (0:00:00.212)       0:00:38.259 *********
2026-08-28 21:42:11.182672 | instance | changed: [instance]
2026-08-28 21:42:11.183126 | instance |
2026-08-28 21:42:11.183147 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-28 21:42:11.183155 | instance | Friday 28 August 2026  21:42:11 +0000 (0:00:00.466)       0:00:38.726 *********
2026-08-28 21:42:11.523207 | instance | changed: [instance]
2026-08-28 21:42:11.523261 | instance |
2026-08-28 21:42:11.523469 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-28 21:42:11.523498 | instance | Friday 28 August 2026  21:42:11 +0000 (0:00:00.341)       0:00:39.067 *********
2026-08-28 21:42:11.723440 | instance | ok: [instance]
2026-08-28 21:42:11.723519 | instance |
2026-08-28 21:42:11.723850 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-28 21:42:11.723871 | instance | Friday 28 August 2026  21:42:11 +0000 (0:00:00.199)       0:00:39.267 *********
2026-08-28 21:42:11.975616 | instance | ok: [instance] => (item=instance)
2026-08-28 21:42:11.976265 | instance |
2026-08-28 21:42:11.976315 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-28 21:42:11.976329 | instance | Friday 28 August 2026  21:42:11 +0000 (0:00:00.251)       0:00:39.519 *********
2026-08-28 21:42:12.039509 | instance | ok: [instance]
2026-08-28 21:42:12.040024 | instance |
2026-08-28 21:42:12.040175 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-28 21:42:12.040185 | instance | Friday 28 August 2026  21:42:12 +0000 (0:00:00.064)       0:00:39.583 *********
2026-08-28 21:42:12.120333 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-28 21:42:12.120665 | instance |
2026-08-28 21:42:12.120700 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-28 21:42:12.120706 | instance | Friday 28 August 2026  21:42:12 +0000 (0:00:00.080)       0:00:39.664 *********
2026-08-28 21:42:12.423601 | instance | changed: [instance]
2026-08-28 21:42:12.424302 | instance |
2026-08-28 21:42:12.424355 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-28 21:42:12.424366 | instance | Friday 28 August 2026  21:42:12 +0000 (0:00:00.302)       0:00:39.967 *********
2026-08-28 21:42:13.104651 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-28 21:42:13.105380 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-28 21:42:13.105424 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-28 21:42:13.105430 | instance |
2026-08-28 21:42:13.105435 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-28 21:42:13.105440 | instance | Friday 28 August 2026  21:42:13 +0000 (0:00:00.681)       0:00:40.648 *********
2026-08-28 21:43:42.375530 | instance | ok: [instance]
2026-08-28 21:43:42.376276 | instance |
2026-08-28 21:43:42.376309 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-28 21:43:42.376321 | instance | Friday 28 August 2026  21:43:42 +0000 (0:01:29.270)       0:02:09.919 *********
2026-08-28 21:43:42.615037 | instance | changed: [instance]
2026-08-28 21:43:42.615263 | instance |
2026-08-28 21:43:42.615499 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-28 21:43:42.615734 | instance | Friday 28 August 2026  21:43:42 +0000 (0:00:00.239)       0:02:10.158 *********
2026-08-28 21:43:42.653403 | instance | ok: [instance]
2026-08-28 21:43:42.653607 | instance |
2026-08-28 21:43:42.653868 | instance | TASK [Install Ceph host] *******************************************************
2026-08-28 21:43:42.654093 | instance | Friday 28 August 2026  21:43:42 +0000 (0:00:00.038)       0:02:10.197 *********
2026-08-28 21:43:42.732979 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-28 21:43:42.733027 | instance |
2026-08-28 21:43:42.733271 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-28 21:43:42.733306 | instance | Friday 28 August 2026  21:43:42 +0000 (0:00:00.080)       0:02:10.277 *********
2026-08-28 21:43:44.631244 | instance | ok: [instance]
2026-08-28 21:43:44.631459 | instance |
2026-08-28 21:43:44.631730 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-28 21:43:44.631989 | instance | Friday 28 August 2026  21:43:44 +0000 (0:00:01.897)       0:02:12.175 *********
2026-08-28 21:43:44.706458 | instance | ok: [instance] => (item=instance)
2026-08-28 21:43:44.706695 | instance |
2026-08-28 21:43:44.706961 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-28 21:43:44.707196 | instance | Friday 28 August 2026  21:43:44 +0000 (0:00:00.075)       0:02:12.250 *********
2026-08-28 21:43:45.073755 | instance | ok: [instance]
2026-08-28 21:43:45.074315 | instance |
2026-08-28 21:43:45.074900 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-28 21:43:45.075149 | instance | Friday 28 August 2026  21:43:45 +0000 (0:00:00.367)       0:02:12.617 *********
2026-08-28 21:43:47.353309 | instance | ok: [instance]
2026-08-28 21:43:47.354044 | instance |
2026-08-28 21:43:47.354099 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-28 21:43:47.354113 | instance | Friday 28 August 2026  21:43:47 +0000 (0:00:02.279)       0:02:14.896 *********
2026-08-28 21:44:38.986367 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-28 21:44:38.987293 | 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_7svjcziu/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_7svjcziu/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_7svjcziu/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_7svjcziu/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-08-28T21:44:38.802+0000 7f3c14323640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDmAJJqIfWhNiAA32vmOtIKEL2/9z7UDUn7G/ltQDSmVIz6tNrZAFfCEMc=: Malformed input [buffer:3]\n2026-08-28T21:44:38.802+0000 7f3c14323640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-28T21:44:38.802+0000 7f3c14323640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDmAJJqIfWhNiAA32vmOtIKEL2/9z7UDUn7G/ltQDSmVIz6tNrZAFfCEMc=: Malformed input [buffer:3]\n2026-08-28T21:44:38.802+0000 7f3c14323640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-28T21:44:38.802+0000 7f3c14323640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDmAJJqIfWhNiAA32vmOtIKEL2/9z7UDUn7G/ltQDSmVIz6tNrZAFfCEMc=: Malformed input [buffer:3]\n2026-08-28T21:44:38.802+0000 7f3c14323640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-28T21:44:38.802+0000 7f3c14323640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-28 21:44:38.990109 | instance |
2026-08-28 21:44:38.990356 | instance | PLAY RECAP *********************************************************************
2026-08-28 21:44:38.992235 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-08-28 21:44:38.992323 | instance |
2026-08-28 21:44:38.992338 | instance | Friday 28 August 2026  21:44:38 +0000 (0:00:51.637)       0:03:06.534 *********
2026-08-28 21:44:38.992350 | instance | ===============================================================================
2026-08-28 21:44:38.992360 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 89.27s
2026-08-28 21:44:38.992370 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.64s
2026-08-28 21:44:38.992379 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.08s
2026-08-28 21:44:38.992400 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.89s
2026-08-28 21:44:38.994564 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.27s
2026-08-28 21:44:38.994581 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.84s
2026-08-28 21:44:38.994616 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.28s
2026-08-28 21:44:38.994626 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.90s
2026-08-28 21:44:38.994634 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.80s
2026-08-28 21:44:38.994643 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.42s
2026-08-28 21:44:38.994651 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.18s
2026-08-28 21:44:38.994660 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.09s
2026-08-28 21:44:38.994668 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.07s
2026-08-28 21:44:38.994751 | instance | Gathering Facts --------------------------------------------------------- 1.07s
2026-08-28 21:44:38.995032 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.99s
2026-08-28 21:44:38.995260 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-08-28 21:44:38.995498 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.95s
2026-08-28 21:44:38.995724 | instance | Gathering Facts --------------------------------------------------------- 0.91s
2026-08-28 21:44:38.995980 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s
2026-08-28 21:44:38.996202 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.68s
2026-08-28 21:44:39.096945 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-28 21:44:39.097055 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-28 21:44:39.097078 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-28 21:44:39.622141 | instance | ERROR
2026-08-28 21:44:39.622501 | instance | {
2026-08-28 21:44:39.622572 | instance |   "delta": "0:03:56.520458",
2026-08-28 21:44:39.622658 | instance |   "end": "2026-08-28 21:44:39.162708",
2026-08-28 21:44:39.622752 | instance |   "msg": "non-zero return code",
2026-08-28 21:44:39.622805 | instance |   "rc": 2,
2026-08-28 21:44:39.622907 | instance |   "start": "2026-08-28 21:40:42.642250"
2026-08-28 21:44:39.622956 | instance | }
failure
2026-08-28 21:44:39.629767 | 
2026-08-28 21:44:39.629824 | PLAY RECAP
2026-08-28 21:44:39.629876 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-28 21:44:39.629897 | 
2026-08-28 21:44:39.781873 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-28 21:44:39.784231 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-28 21:44:40.491705 | 
2026-08-28 21:44:40.491838 | PLAY [all]
2026-08-28 21:44:40.506409 | 
2026-08-28 21:44:40.506486 | TASK [gather-host-logs : creating directory for system status]
2026-08-28 21:44:40.887463 | instance | changed
2026-08-28 21:44:40.896152 | 
2026-08-28 21:44:40.896252 | TASK [gather-host-logs : Get logs for each host]
2026-08-28 21:44:41.263037 | instance | + systemd-cgls --full --all --no-pager
2026-08-28 21:44:41.285323 | instance | + ip addr
2026-08-28 21:44:41.288383 | instance | + ip route
2026-08-28 21:44:41.290902 | instance | + lsblk
2026-08-28 21:44:41.296074 | instance | + mount
2026-08-28 21:44:41.298895 | instance | + docker images
2026-08-28 21:44:41.324828 | instance | + brctl show
2026-08-28 21:44:41.325344 | instance | /bin/bash: line 8: brctl: command not found
2026-08-28 21:44:41.325903 | instance | + ps aux --sort=-%mem
2026-08-28 21:44:41.364765 | instance | + dpkg -l
2026-08-28 21:44:41.375918 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-28 21:44:41.376467 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-28 21:44:41.397347 | instance | + '[' '!' -z '' ']'
2026-08-28 21:44:41.449086 | instance | ok: Runtime: 0:00:00.138583
2026-08-28 21:44:41.456472 | 
2026-08-28 21:44:41.456549 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-28 21:44:42.771549 | instance | changed:
2026-08-28 21:44:42.771781 | instance | created directory /var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/work/logs/instance
2026-08-28 21:44:42.771821 | instance | cd+++++++++ system/
2026-08-28 21:44:42.771852 | instance | >f+++++++++ system/brctl-show.txt
2026-08-28 21:44:42.771882 | instance | >f+++++++++ system/docker-images.txt
2026-08-28 21:44:42.771910 | instance | >f+++++++++ system/ip-addr.txt
2026-08-28 21:44:42.771941 | instance | >f+++++++++ system/ip-route.txt
2026-08-28 21:44:42.771970 | instance | >f+++++++++ system/lsblk.txt
2026-08-28 21:44:42.771998 | instance | >f+++++++++ system/mount.txt
2026-08-28 21:44:42.772027 | instance | >f+++++++++ system/packages.txt
2026-08-28 21:44:42.772056 | instance | >f+++++++++ system/ps.txt
2026-08-28 21:44:42.772085 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-28 21:44:42.781408 | 
2026-08-28 21:44:42.781478 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-28 21:44:42.979998 | instance | changed: "values"
2026-08-28 21:44:43.157860 | instance | changed: "releases"
2026-08-28 21:44:43.174832 | 
2026-08-28 21:44:43.175013 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-28 21:44:43.405263 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-28 21:44:43.711533 | instance | ok: Runtime: 0:00:00.005360
2026-08-28 21:44:43.720662 | 
2026-08-28 21:44:43.720751 | TASK [helm-release-status : Downloads logs to executor]
2026-08-28 21:44:44.233248 | instance | changed:
2026-08-28 21:44:44.233459 | instance | cd+++++++++ helm/
2026-08-28 21:44:44.234382 | instance | cd+++++++++ helm/releases/
2026-08-28 21:44:44.234430 | instance | cd+++++++++ helm/values/
2026-08-28 21:44:44.245382 | 
2026-08-28 21:44:44.245451 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-28 21:44:44.474028 | instance | changed
2026-08-28 21:44:44.480855 | 
2026-08-28 21:44:44.481060 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-28 21:44:44.749543 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 21:44:44.749658 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 21:44:44.755414 | instance | environment: line 1: kubectl: command not found
2026-08-28 21:44:44.756907 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 21:44:44.757205 | instance | environment: line 1: kubectl: command not found
2026-08-28 21:44:44.758900 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 21:44:44.760410 | instance | environment: line 1: kubectl: command not found
2026-08-28 21:44:44.762232 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 21:44:44.762863 | instance | environment: line 1: kubectl: command not found
2026-08-28 21:44:44.763721 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 21:44:44.765921 | instance | environment: line 1: kubectl: command not found
2026-08-28 21:44:44.767011 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 21:44:45.080117 | instance | ok: Runtime: 0:00:00.023812
2026-08-28 21:44:45.085352 | 
2026-08-28 21:44:45.085424 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-28 21:44:45.304101 | instance | changed
2026-08-28 21:44:45.309879 | 
2026-08-28 21:44:45.309953 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-28 21:44:45.535791 | instance | environment: line 5: kubectl: command not found
2026-08-28 21:44:45.536729 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 21:44:45.536919 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 21:44:45.537188 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 21:44:45.846605 | instance | ok: Runtime: 0:00:00.008296
2026-08-28 21:44:45.853367 | 
2026-08-28 21:44:45.853446 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-28 21:44:46.343783 | instance | changed:
2026-08-28 21:44:46.344006 | instance | cd+++++++++ objects/
2026-08-28 21:44:46.344034 | instance | cd+++++++++ objects/cluster/
2026-08-28 21:44:46.344057 | instance | cd+++++++++ objects/namespaced/
2026-08-28 21:44:46.353028 | 
2026-08-28 21:44:46.353098 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-28 21:44:46.568602 | instance | changed
2026-08-28 21:44:46.574721 | 
2026-08-28 21:44:46.574790 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-28 21:44:46.791462 | instance | changed
2026-08-28 21:44:46.850004 | 
2026-08-28 21:44:46.850131 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-28 21:44:47.075496 | instance | environment: line 3: kubectl: command not found
2026-08-28 21:44:47.390519 | instance | ok: Runtime: 0:00:00.007730
2026-08-28 21:44:47.396657 | 
2026-08-28 21:44:47.396729 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-28 21:44:47.911959 | instance | changed:
2026-08-28 21:44:47.912151 | instance | cd+++++++++ pod-logs/
2026-08-28 21:44:47.912190 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-28 21:44:47.923595 | 
2026-08-28 21:44:47.923658 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-28 21:44:48.140669 | instance | changed
2026-08-28 21:44:48.147157 | 
2026-08-28 21:44:48.147293 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-28 21:44:48.373299 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-28 21:44:48.685450 | instance | ok: Runtime: 0:00:00.052500
2026-08-28 21:44:48.692420 | 
2026-08-28 21:44:48.692522 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-28 21:44:48.941932 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-28 21:44:48.964916 | instance | ceph-mgr endpoints:
2026-08-28 21:44:49.231828 | instance | ok: Runtime: 0:00:00.028261
2026-08-28 21:44:49.249319 | 
2026-08-28 21:44:49.249396 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-28 21:44:49.472063 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-28 21:44:49.785718 | instance | ok: Runtime: 0:00:00.051294
2026-08-28 21:44:49.791525 | 
2026-08-28 21:44:49.791594 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-28 21:44:50.289388 | instance | changed: cd+++++++++ prometheus/
2026-08-28 21:44:50.299413 | 
2026-08-28 21:44:50.299477 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-28 21:44:50.507990 | instance | changed
2026-08-28 21:44:50.514210 | 
2026-08-28 21:44:50.514278 | TASK [gather-selenium-data : Get selenium data]
2026-08-28 21:44:50.781094 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-28 21:44:50.782520 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-28 21:44:51.050357 | instance | ERROR
2026-08-28 21:44:51.050521 | instance | {
2026-08-28 21:44:51.050561 | instance |   "delta": "0:00:00.005539",
2026-08-28 21:44:51.050589 | instance |   "end": "2026-08-28 21:44:50.782847",
2026-08-28 21:44:51.050618 | instance |   "msg": "non-zero return code",
2026-08-28 21:44:51.050644 | instance |   "rc": 1,
2026-08-28 21:44:51.050669 | instance |   "start": "2026-08-28 21:44:50.777308"
2026-08-28 21:44:51.050694 | instance | }
2026-08-28 21:44:51.050726 | instance | ERROR: Ignoring Errors
2026-08-28 21:44:51.057280 | 
2026-08-28 21:44:51.057348 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-28 21:44:51.568060 | instance | changed: cd+++++++++ selenium/
2026-08-28 21:44:51.575430 | 
2026-08-28 21:44:51.575478 | PLAY RECAP
2026-08-28 21:44:51.575525 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-28 21:44:51.575547 | 
2026-08-28 21:44:51.695735 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-28 21:44:51.700217 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-28 21:44:52.372523 | 
2026-08-28 21:44:52.372672 | PLAY [all]
2026-08-28 21:44:52.384812 | 
2026-08-28 21:44:52.384891 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-28 21:44:52.431779 | instance | skipping: Conditional result was False
2026-08-28 21:44:52.442530 | 
2026-08-28 21:44:52.442691 | TASK [fetch-output : Set log path for single node]
2026-08-28 21:44:52.487391 | instance | ok
2026-08-28 21:44:52.491939 | 
2026-08-28 21:44:52.492002 | LOOP [fetch-output : Ensure local output dirs]
2026-08-28 21:44:52.925389 | instance -> localhost | ok: "/var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/work/logs"
2026-08-28 21:44:53.139338 | instance -> localhost | changed: "/var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/work/artifacts"
2026-08-28 21:44:53.349479 | instance -> localhost | changed: "/var/lib/zuul/builds/3b5e32a9bdbf422b8b11d9bcde81dc3c/work/docs"
2026-08-28 21:44:53.364679 | 
2026-08-28 21:44:53.364846 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-28 21:44:54.051841 | instance | changed: .d..t...... ./
2026-08-28 21:44:54.052173 | instance | changed: All items complete
2026-08-28 21:44:54.052215 | 
2026-08-28 21:44:54.522823 | instance | changed: .d..t...... ./
2026-08-28 21:44:55.003835 | instance | changed: .d..t...... ./
2026-08-28 21:44:55.028525 | 
2026-08-28 21:44:55.028692 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-28 21:44:55.454412 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.010111
2026-08-28 21:44:55.676418 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007069
2026-08-28 21:44:55.690854 | 
2026-08-28 21:44:55.691002 | PLAY [all]
2026-08-28 21:44:55.697276 | 
2026-08-28 21:44:55.697346 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-28 21:44:56.135988 | instance | changed
2026-08-28 21:44:56.142682 | 
2026-08-28 21:44:56.142733 | PLAY RECAP
2026-08-28 21:44:56.142785 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-28 21:44:56.142807 | 
2026-08-28 21:44:56.297057 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-28 21:44:56.301476 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-28 21:44:56.878498 | 
2026-08-28 21:44:56.878631 | PLAY [localhost]
2026-08-28 21:44:56.888461 | 
2026-08-28 21:44:56.888531 | TASK [Generate Zuul manifest]
2026-08-28 21:44:56.910317 | localhost | ok
2026-08-28 21:44:56.934664 | 
2026-08-28 21:44:56.934769 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-28 21:44:57.309461 | localhost | changed
2026-08-28 21:44:57.344579 | 
2026-08-28 21:44:57.344716 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-28 21:44:57.377213 | localhost | ok
2026-08-28 21:44:57.385187 | 
2026-08-28 21:44:57.385250 | TASK [Upload logs]
2026-08-28 21:44:57.407799 | localhost | ok
2026-08-28 21:44:57.501465 | 
2026-08-28 21:44:57.501620 | TASK [Set zuul-log-path fact]
2026-08-28 21:44:57.521354 | localhost | ok
2026-08-28 21:44:57.534359 | 
2026-08-28 21:44:57.534430 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-28 21:44:57.567770 | localhost | ok
2026-08-28 21:44:57.574570 | 
2026-08-28 21:44:57.574636 | TASK [upload-logs : Create log directories]
2026-08-28 21:44:57.972842 | localhost | changed
2026-08-28 21:44:57.977937 | 
2026-08-28 21:44:57.978013 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-28 21:44:58.366462 | localhost -> localhost | ok: Runtime: 0:00:00.007929
2026-08-28 21:44:58.373373 | 
2026-08-28 21:44:58.373441 | TASK [upload-logs : Upload logs to log server]
2026-08-28 21:44:58.841194 | localhost | Output suppressed because no_log was given
2026-08-28 21:44:58.886076 | 
2026-08-28 21:44:58.886249 | LOOP [upload-logs : Compress console log and json output]
2026-08-28 21:44:58.932195 | localhost | skipping: Conditional result was False
2026-08-28 21:44:58.939202 | localhost | skipping: Conditional result was False
2026-08-28 21:44:58.951700 | 
2026-08-28 21:44:58.951842 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-28 21:44:58.992010 | localhost | skipping: Conditional result was False
2026-08-28 21:44:58.992290 | 
2026-08-28 21:44:58.996294 | localhost | skipping: Conditional result was False
2026-08-28 21:44:59.006399 | 
2026-08-28 21:44:59.006510 | LOOP [upload-logs : Upload console log and json output]