2026-09-01 18:16:41.399480 | Job console starting
2026-09-01 18:16:41.458078 | Updating git repos
2026-09-01 18:16:46.703912 | Cloning repos into workspace
2026-09-01 18:17:03.945361 | Restoring repo states
2026-09-01 18:17:08.163298 | Merging changes
2026-09-01 18:17:09.392185 | Checking out repos
2026-09-01 18:17:11.062073 | Preparing playbooks
2026-09-01 18:17:13.587866 | Running Ansible setup
2026-09-01 18:17:18.520114 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-01 18:17:19.192822 | 
2026-09-01 18:17:19.192987 | PLAY [localhost]
2026-09-01 18:17:19.200920 | 
2026-09-01 18:17:19.201018 | TASK [Gathering Facts]
2026-09-01 18:17:20.346531 | localhost | ok
2026-09-01 18:17:20.356273 | 
2026-09-01 18:17:20.356413 | TASK [Setup log path fact]
2026-09-01 18:17:20.379874 | localhost | ok
2026-09-01 18:17:20.393326 | 
2026-09-01 18:17:20.393593 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-01 18:17:20.424382 | localhost | ok
2026-09-01 18:17:20.434655 | 
2026-09-01 18:17:20.434790 | TASK [emit-job-header : Print job information]
2026-09-01 18:17:20.474534 | # Job Information
2026-09-01 18:17:20.474749 | Ansible Version: 2.16.18
2026-09-01 18:17:20.474795 | Job: atmosphere-molecule-csi-rbd
2026-09-01 18:17:20.474828 | Pipeline: check
2026-09-01 18:17:20.474857 | Executor: 2d72f0692154
2026-09-01 18:17:20.474887 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-09-01 18:17:20.474922 | Event ID: b7410a10-a630-11f1-8725-15fa9e9d0d19
2026-09-01 18:17:20.479046 | 
2026-09-01 18:17:20.479143 | LOOP [emit-job-header : Print node information]
2026-09-01 18:17:20.587238 | localhost | ok:
2026-09-01 18:17:20.764670 | localhost | # Node Information
2026-09-01 18:17:20.764856 | localhost | Inventory Hostname: instance
2026-09-01 18:17:20.764913 | localhost | Hostname: np0000191750
2026-09-01 18:17:20.764987 | localhost | Username: zuul
2026-09-01 18:17:20.765058 | localhost | Distro: Ubuntu 22.04
2026-09-01 18:17:20.765106 | localhost | Provider: yul1
2026-09-01 18:17:20.765149 | localhost | Region: ca-ymq-1
2026-09-01 18:17:20.765191 | localhost | Label: ubuntu-jammy
2026-09-01 18:17:20.765233 | localhost | Product Name: OpenStack Nova
2026-09-01 18:17:20.765273 | localhost | Interface IP: 162.253.55.20
2026-09-01 18:17:20.780405 | 
2026-09-01 18:17:20.780550 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-01 18:17:21.170629 | localhost -> localhost | changed
2026-09-01 18:17:21.180441 | 
2026-09-01 18:17:21.180542 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-01 18:17:22.117382 | localhost -> localhost | changed
2026-09-01 18:17:22.125545 | 
2026-09-01 18:17:22.125601 | PLAY [all]
2026-09-01 18:17:22.133901 | 
2026-09-01 18:17:22.133968 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-01 18:17:22.371960 | instance -> localhost | ok
2026-09-01 18:17:22.382227 | 
2026-09-01 18:17:22.382322 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-01 18:17:22.419269 | instance | ok
2026-09-01 18:17:22.433361 | instance | included: /var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-01 18:17:22.440407 | 
2026-09-01 18:17:22.440468 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-01 18:17:23.208554 | instance -> localhost | Generating public/private rsa key pair.
2026-09-01 18:17:23.208786 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/work/0c3c564e4b48466cb6e88c2bb99d6083_id_rsa
2026-09-01 18:17:23.208842 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/work/0c3c564e4b48466cb6e88c2bb99d6083_id_rsa.pub
2026-09-01 18:17:23.208877 | instance -> localhost | The key fingerprint is:
2026-09-01 18:17:23.208907 | instance -> localhost | SHA256:3YY0369PVg1TFbIbxadI4rZ3AdX6SVlM6q7sSynqz8k zuul-build-sshkey
2026-09-01 18:17:23.208982 | instance -> localhost | The key's randomart image is:
2026-09-01 18:17:23.209019 | instance -> localhost | +---[RSA 3072]----+
2026-09-01 18:17:23.209057 | instance -> localhost | |             oo**|
2026-09-01 18:17:23.209088 | instance -> localhost | |          . o ++=|
2026-09-01 18:17:23.209117 | instance -> localhost | |         .oo =+o+|
2026-09-01 18:17:23.209155 | instance -> localhost | |         oo=.o*=.|
2026-09-01 18:17:23.209186 | instance -> localhost | |        S.o.+.+o+|
2026-09-01 18:17:23.209214 | instance -> localhost | |          ...o.oo|
2026-09-01 18:17:23.209242 | instance -> localhost | |          ..o.. +|
2026-09-01 18:17:23.209270 | instance -> localhost | |         + = . + |
2026-09-01 18:17:23.209335 | instance -> localhost | |       .o.E.=....|
2026-09-01 18:17:23.209365 | instance -> localhost | +----[SHA256]-----+
2026-09-01 18:17:23.211916 | instance -> localhost | ok: Runtime: 0:00:00.337440
2026-09-01 18:17:23.222255 | 
2026-09-01 18:17:23.222327 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-01 18:17:23.258671 | instance | ok
2026-09-01 18:17:23.270859 | instance | included: /var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-01 18:17:23.280899 | 
2026-09-01 18:17:23.281008 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-01 18:17:23.317164 | instance | skipping: Conditional result was False
2026-09-01 18:17:23.329112 | 
2026-09-01 18:17:23.329190 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-01 18:17:23.881439 | instance | changed
2026-09-01 18:17:23.894222 | 
2026-09-01 18:17:23.894309 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-01 18:17:24.098922 | instance | ok
2026-09-01 18:17:24.152826 | 
2026-09-01 18:17:24.152946 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-01 18:17:24.646246 | instance | changed
2026-09-01 18:17:24.653069 | 
2026-09-01 18:17:24.653134 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-01 18:17:25.128139 | instance | changed
2026-09-01 18:17:25.134500 | 
2026-09-01 18:17:25.134580 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-01 18:17:25.160168 | instance | skipping: Conditional result was False
2026-09-01 18:17:25.166422 | 
2026-09-01 18:17:25.166500 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-01 18:17:25.556035 | instance -> localhost | changed
2026-09-01 18:17:25.571782 | 
2026-09-01 18:17:25.650611 | TASK [add-build-sshkey : Add back temp key]
2026-09-01 18:17:25.934954 | instance -> localhost | Identity added: /var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/work/0c3c564e4b48466cb6e88c2bb99d6083_id_rsa (zuul-build-sshkey)
2026-09-01 18:17:25.935152 | instance -> localhost | ok: Runtime: 0:00:00.012795
2026-09-01 18:17:25.940091 | 
2026-09-01 18:17:25.940161 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-01 18:17:26.298615 | instance | ok
2026-09-01 18:17:26.303203 | 
2026-09-01 18:17:26.303273 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-01 18:17:26.327921 | instance | skipping: Conditional result was False
2026-09-01 18:17:26.342980 | 
2026-09-01 18:17:26.343076 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-01 18:17:26.690691 | instance | ok
2026-09-01 18:17:26.699753 | 
2026-09-01 18:17:26.699842 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-01 18:17:30.225272 | instance | Output suppressed because no_log was given
2026-09-01 18:17:30.237686 | 
2026-09-01 18:17:30.237775 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-01 18:17:30.449164 | instance | ok: "logs"
2026-09-01 18:17:30.449466 | instance | ok: All items complete
2026-09-01 18:17:30.449504 | 
2026-09-01 18:17:30.592188 | instance | ok: "artifacts"
2026-09-01 18:17:30.733318 | instance | ok: "docs"
2026-09-01 18:17:30.758283 | 
2026-09-01 18:17:30.758453 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-01 18:17:30.931790 | instance | changed: "logs"
2026-09-01 18:17:31.079922 | instance | changed: "artifacts"
2026-09-01 18:17:32.134169 | instance | changed: "docs"
2026-09-01 18:17:32.153908 | 
2026-09-01 18:17:32.154065 | PLAY RECAP
2026-09-01 18:17:32.154135 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-01 18:17:32.154175 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:17:32.154206 | 
2026-09-01 18:17:32.307946 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-01 18:17:32.310608 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-01 18:17:32.914059 | 
2026-09-01 18:17:32.914195 | PLAY [all]
2026-09-01 18:17:32.925465 | 
2026-09-01 18:17:32.925539 | TASK [setup-uv : Extract archive]
2026-09-01 18:17:35.269791 | instance | changed
2026-09-01 18:17:35.277465 | 
2026-09-01 18:17:35.277572 | TASK [setup-uv : Print version]
2026-09-01 18:17:35.693621 | instance | uv 0.8.13
2026-09-01 18:17:35.813477 | instance | ok: Runtime: 0:00:00.012775
2026-09-01 18:17:35.826910 | 
2026-09-01 18:17:35.826980 | TASK [Install Ansible collection siblings]
2026-09-01 18:17:45.149877 | instance | changed:
2026-09-01 18:17:45.150111 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-01 18:17:45.150152 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-01 18:17:45.150273 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-01 18:17:45.150320 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-01 18:17:45.150351 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-01 18:17:45.150386 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-01 18:17:45.150415 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-01 18:17:45.150445 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-01 18:17:45.150474 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-01 18:17:45.150502 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-01 18:17:45.150528 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-01 18:17:45.150553 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-01 18:17:45.150578 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-01 18:17:45.150604 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-01 18:17:45.150630 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-01 18:17:45.150661 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-01 18:17:45.150688 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-01 18:17:45.150714 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-01 18:17:45.150739 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-01 18:17:45.150763 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-01 18:17:45.150787 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-01 18:17:45.150813 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-01 18:17:45.150839 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-01 18:17:45.150881 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-01 18:17:45.150909 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-01 18:17:45.150934 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-01 18:17:45.150959 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-01 18:17:45.150983 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-01 18:17:45.151008 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-01 18:17:45.151033 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-01 18:17:45.151057 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-01 18:17:45.151083 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-01 18:17:45.151109 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-01 18:17:45.151134 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-01 18:17:45.151159 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-01 18:17:45.151192 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.151217 | instance | Process install dependency map
2026-09-01 18:17:45.151241 | instance | Starting collection install process
2026-09-01 18:17:45.151266 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-01 18:17:45.151291 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-01 18:17:45.151316 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-01 18:17:45.151340 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-01 18:17:45.151364 | instance | Creating virtual environment at: .venv
2026-09-01 18:17:45.151387 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-01 18:17:45.151412 | instance | Downloading pygments (1.2MiB)
2026-09-01 18:17:45.151444 | instance | Downloading setuptools (1.1MiB)
2026-09-01 18:17:45.151469 | instance | Downloading kubernetes (1.9MiB)
2026-09-01 18:17:45.151494 | instance | Downloading cryptography (4.2MiB)
2026-09-01 18:17:45.151518 | instance | Downloading rjsonnet (1.2MiB)
2026-09-01 18:17:45.151543 | instance | Downloading ansible-core (2.1MiB)
2026-09-01 18:17:45.151567 | instance | Downloading openstacksdk (1.7MiB)
2026-09-01 18:17:45.151594 | instance | Downloading netaddr (2.2MiB)
2026-09-01 18:17:45.151621 | instance |    Building pyperclip==1.9.0
2026-09-01 18:17:45.151649 | instance |  Downloading rjsonnet
2026-09-01 18:17:45.151677 | instance |  Downloading netaddr
2026-09-01 18:17:45.151704 | instance |  Downloading pygments
2026-09-01 18:17:45.151731 | instance |  Downloading cryptography
2026-09-01 18:17:45.151758 | instance |  Downloading setuptools
2026-09-01 18:17:45.151783 | instance |  Downloading kubernetes
2026-09-01 18:17:45.151810 | instance |  Downloading ansible-core
2026-09-01 18:17:45.151835 | instance |  Downloading openstacksdk
2026-09-01 18:17:45.151861 | instance |       Built pyperclip==1.9.0
2026-09-01 18:17:45.151887 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-01 18:17:45.151914 | instance | Installed 79 packages in 64ms
2026-09-01 18:17:45.151948 | instance | Checked out ansible.posix to 1.6.0
2026-09-01 18:17:45.152023 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-01 18:17:45.152059 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.152086 | instance | Process install dependency map
2026-09-01 18:17:45.152123 | instance | Starting collection install process
2026-09-01 18:17:45.152151 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-01 18:17:45.152177 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-01 18:17:45.152204 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-01 18:17:45.152229 | instance | Checked out ansible.utils to v6.1.0
2026-09-01 18:17:45.152255 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-01 18:17:45.152282 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.152308 | instance | Process install dependency map
2026-09-01 18:17:45.152334 | instance | Starting collection install process
2026-09-01 18:17:45.152361 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-01 18:17:45.152386 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-01 18:17:45.152412 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-01 18:17:45.152439 | instance | Checked out atmosphere.common to v0.6.0
2026-09-01 18:17:45.152465 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-01 18:17:45.152492 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.152518 | instance | Process install dependency map
2026-09-01 18:17:45.152543 | instance | Starting collection install process
2026-09-01 18:17:45.152563 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-01 18:17:45.152582 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-01 18:17:45.152600 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-01 18:17:45.152619 | instance | Checked out community.crypto to 2.2.3
2026-09-01 18:17:45.152637 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-01 18:17:45.152655 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.152673 | instance | Process install dependency map
2026-09-01 18:17:45.152691 | instance | Starting collection install process
2026-09-01 18:17:45.152709 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-01 18:17:45.152727 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-01 18:17:45.152746 | instance | community.crypto:2.2.3 was installed successfully
2026-09-01 18:17:45.152766 | instance | Checked out community.general to 7.3.0
2026-09-01 18:17:45.152785 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-01 18:17:45.152804 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.152821 | instance | Process install dependency map
2026-09-01 18:17:45.152839 | instance | Starting collection install process
2026-09-01 18:17:45.152858 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-01 18:17:45.152877 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-01 18:17:45.152894 | instance | community.general:7.3.0 was installed successfully
2026-09-01 18:17:45.152919 | instance | Checked out community.mysql to 3.6.0
2026-09-01 18:17:45.152938 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-01 18:17:45.152957 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.152977 | instance | Process install dependency map
2026-09-01 18:17:45.152999 | instance | Starting collection install process
2026-09-01 18:17:45.153030 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-01 18:17:45.153051 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-01 18:17:45.153070 | instance | community.mysql:3.6.0 was installed successfully
2026-09-01 18:17:45.153088 | instance | Checked out kubernetes.core to 2.4.0
2026-09-01 18:17:45.153107 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-01 18:17:45.153125 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.153143 | instance | Process install dependency map
2026-09-01 18:17:45.153163 | instance | Starting collection install process
2026-09-01 18:17:45.153184 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-01 18:17:45.153203 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-01 18:17:45.153221 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-01 18:17:45.153240 | instance | Checked out openstack.cloud to 1.7.0
2026-09-01 18:17:45.153259 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-01 18:17:45.153277 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.153295 | instance | Process install dependency map
2026-09-01 18:17:45.153312 | instance | Starting collection install process
2026-09-01 18:17:45.153330 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-01 18:17:45.153349 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-01 18:17:45.153367 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-01 18:17:45.153387 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-01 18:17:45.153406 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-01 18:17:45.153424 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.153442 | instance | Process install dependency map
2026-09-01 18:17:45.153460 | instance | Starting collection install process
2026-09-01 18:17:45.153478 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-01 18:17:45.153496 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-01 18:17:45.153514 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-01 18:17:45.153533 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-01 18:17:45.153551 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-01 18:17:45.153570 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.153595 | instance | Process install dependency map
2026-09-01 18:17:45.153615 | instance | Starting collection install process
2026-09-01 18:17:45.153634 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-01 18:17:45.153651 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-01 18:17:45.153669 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-01 18:17:45.153687 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-01 18:17:45.153706 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-01 18:17:45.153724 | instance | Starting galaxy collection install process
2026-09-01 18:17:45.153741 | instance | Process install dependency map
2026-09-01 18:17:45.153758 | instance | Starting collection install process
2026-09-01 18:17:45.153776 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-01 18:17:45.153795 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-01 18:17:45.153814 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-01 18:17:45.159488 | 
2026-09-01 18:17:45.159536 | PLAY RECAP
2026-09-01 18:17:45.159584 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:17:45.159604 | 
2026-09-01 18:17:45.326405 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-01 18:17:45.330705 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-01 18:17:45.901731 | 
2026-09-01 18:17:45.902044 | PLAY [all]
2026-09-01 18:17:45.913208 | 
2026-09-01 18:17:45.913280 | TASK [Install "jq" for log collection]
2026-09-01 18:17:56.126817 | instance | changed
2026-09-01 18:17:56.129317 | 
2026-09-01 18:17:56.129404 | PLAY RECAP
2026-09-01 18:17:56.129476 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:17:56.129559 | 
2026-09-01 18:17:56.262864 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-01 18:17:56.267181 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-01 18:17:56.864284 | 
2026-09-01 18:17:56.864493 | PLAY [all]
2026-09-01 18:17:56.877848 | 
2026-09-01 18:17:56.880449 | LOOP [Check inventory directories]
2026-09-01 18:17:57.241106 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-01 18:17:57.413472 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-01 18:17:57.590124 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-01 18:17:57.598641 | 
2026-09-01 18:17:57.599115 | LOOP [Copy inventory file for Zuul]
2026-09-01 18:17:58.449902 | instance | changed:
2026-09-01 18:17:58.450142 | instance | {
2026-09-01 18:17:58.450179 | instance |   "ansible_loop_var": "item",
2026-09-01 18:17:58.450209 | instance |   "changed": false,
2026-09-01 18:17:58.450236 | instance |   "failed": false,
2026-09-01 18:17:58.450267 | instance |   "invocation": {
2026-09-01 18:17:58.450293 | instance |     "module_args": {
2026-09-01 18:17:58.450317 | instance |       "checksum_algorithm": "sha1",
2026-09-01 18:17:58.450341 | instance |       "follow": true,
2026-09-01 18:17:58.450366 | instance |       "get_attributes": true,
2026-09-01 18:17:58.450391 | instance |       "get_checksum": true,
2026-09-01 18:17:58.450416 | instance |       "get_mime": true,
2026-09-01 18:17:58.450441 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-01 18:17:58.450465 | instance |     }
2026-09-01 18:17:58.450501 | instance |   },
2026-09-01 18:17:58.450526 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-01 18:17:58.450550 | instance |   "stat": {
2026-09-01 18:17:58.450573 | instance |     "atime": 1788286657.7412965,
2026-09-01 18:17:58.450597 | instance |     "attr_flags": "e",
2026-09-01 18:17:58.450620 | instance |     "attributes": [
2026-09-01 18:17:58.450643 | instance |       "extents"
2026-09-01 18:17:58.450667 | instance |     ],
2026-09-01 18:17:58.450690 | instance |     "block_size": 4096,
2026-09-01 18:17:58.450713 | instance |     "blocks": 8,
2026-09-01 18:17:58.450736 | instance |     "charset": "unknown",
2026-09-01 18:17:58.450761 | instance |     "ctime": 1788286656.605287,
2026-09-01 18:17:58.450785 | instance |     "dev": 64513,
2026-09-01 18:17:58.450808 | instance |     "device_type": 0,
2026-09-01 18:17:58.450831 | instance |     "executable": true,
2026-09-01 18:17:58.450854 | instance |     "exists": true,
2026-09-01 18:17:58.450877 | instance |     "gid": 1000,
2026-09-01 18:17:58.450899 | instance |     "gr_name": "zuul",
2026-09-01 18:17:58.450923 | instance |     "inode": 1048814,
2026-09-01 18:17:58.450950 | instance |     "isblk": false,
2026-09-01 18:17:58.450979 | instance |     "ischr": false,
2026-09-01 18:17:58.451009 | instance |     "isdir": true,
2026-09-01 18:17:58.451038 | instance |     "isfifo": false,
2026-09-01 18:17:58.451066 | instance |     "isgid": false,
2026-09-01 18:17:58.451095 | instance |     "islnk": false,
2026-09-01 18:17:58.451123 | instance |     "isreg": false,
2026-09-01 18:17:58.451151 | instance |     "issock": false,
2026-09-01 18:17:58.451178 | instance |     "isuid": false,
2026-09-01 18:17:58.451207 | instance |     "mimetype": "unknown",
2026-09-01 18:17:58.451230 | instance |     "mode": "0755",
2026-09-01 18:17:58.451254 | instance |     "mtime": 1788286656.605287,
2026-09-01 18:17:58.451278 | instance |     "nlink": 22,
2026-09-01 18:17:58.451301 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-01 18:17:58.451324 | instance |     "pw_name": "zuul",
2026-09-01 18:17:58.451349 | instance |     "readable": true,
2026-09-01 18:17:58.451373 | instance |     "rgrp": true,
2026-09-01 18:17:58.451396 | instance |     "roth": true,
2026-09-01 18:17:58.451419 | instance |     "rusr": true,
2026-09-01 18:17:58.451441 | instance |     "size": 4096,
2026-09-01 18:17:58.451465 | instance |     "uid": 1000,
2026-09-01 18:17:58.451489 | instance |     "version": "4161063878",
2026-09-01 18:17:58.451512 | instance |     "wgrp": false,
2026-09-01 18:17:58.451538 | instance |     "woth": false,
2026-09-01 18:17:58.451563 | instance |     "writeable": true,
2026-09-01 18:17:58.451587 | instance |     "wusr": true,
2026-09-01 18:17:58.451610 | instance |     "xgrp": true,
2026-09-01 18:17:58.451633 | instance |     "xoth": true,
2026-09-01 18:17:58.451655 | instance |     "xusr": true
2026-09-01 18:17:58.451679 | instance |   }
2026-09-01 18:17:58.451702 | instance | }
2026-09-01 18:17:58.451748 | instance | ok: All items complete
2026-09-01 18:17:58.451776 | 
2026-09-01 18:17:58.453563 | instance | skipping: Conditional result was False
2026-09-01 18:17:58.454262 | instance | skipping: Conditional result was False
2026-09-01 18:17:58.470071 | 
2026-09-01 18:17:58.470253 | LOOP [Switch "ansible_host" to private IP]
2026-09-01 18:17:58.819878 | instance | changed: 1 replacements made
2026-09-01 18:17:58.821524 | instance | skipping: Conditional result was False
2026-09-01 18:17:58.822322 | instance | skipping: Conditional result was False
2026-09-01 18:17:58.840531 | 
2026-09-01 18:17:58.840684 | TASK [Run Molecule scenario]
2026-09-01 18:17:59.766817 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-01 18:18:00.171351 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-01 18:18:00.171466 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-01 18:18:11.028757 | instance | INFO     [csi > dependency] Executing
2026-09-01 18:18:11.029100 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-01 18:18:11.029489 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-01 18:18:11.029728 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-01 18:18:11.044363 | instance | INFO     [csi > cleanup] Executing
2026-09-01 18:18:11.044952 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-01 18:18:11.058502 | instance | INFO     [csi > destroy] Executing
2026-09-01 18:18:11.058541 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-01 18:18:11.058648 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-01 18:18:11.068198 | instance | INFO     [csi > syntax] Executing
2026-09-01 18:18:12.492695 | instance |
2026-09-01 18:18:12.492908 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-01 18:18:12.559428 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-01 18:18:12.575028 | instance | INFO     [csi > create] Executing
2026-09-01 18:18:12.578536 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-01 18:18:12.592822 | instance | INFO     [csi > prepare] Executing
2026-09-01 18:18:13.414370 | instance |
2026-09-01 18:18:13.414674 | instance | PLAY [Prepare] *****************************************************************
2026-09-01 18:18:13.415021 | instance |
2026-09-01 18:18:13.415362 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:18:13.415701 | instance | Tuesday 01 September 2026  18:18:13 +0000 (0:00:00.027)       0:00:00.027 *****
2026-09-01 18:18:14.709227 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-01 18:18:14.709554 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-01 18:18:14.709812 | instance | interpreter could change the meaning of that path. See
2026-09-01 18:18:14.710102 | instance | https://docs.ansible.com/ansible-
2026-09-01 18:18:14.710352 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-01 18:18:14.719968 | instance | ok: [instance]
2026-09-01 18:18:14.720214 | instance |
2026-09-01 18:18:14.720453 | instance | TASK [Configure short hostname] ************************************************
2026-09-01 18:18:14.720693 | instance | Tuesday 01 September 2026  18:18:14 +0000 (0:00:01.306)       0:00:01.334 *****
2026-09-01 18:18:15.501544 | instance | changed: [instance]
2026-09-01 18:18:15.501688 | instance |
2026-09-01 18:18:15.501995 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-01 18:18:15.502233 | instance | Tuesday 01 September 2026  18:18:15 +0000 (0:00:00.781)       0:00:02.115 *****
2026-09-01 18:18:15.794581 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-01 18:18:15.794731 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-01 18:18:15.794991 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-01 18:18:15.804726 | instance | changed: [instance]
2026-09-01 18:18:15.804992 | instance |
2026-09-01 18:18:15.805288 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-01 18:18:15.805579 | instance | Tuesday 01 September 2026  18:18:15 +0000 (0:00:00.303)       0:00:02.419 *****
2026-09-01 18:18:16.579223 | instance | ok: [instance]
2026-09-01 18:18:16.579304 | instance |
2026-09-01 18:18:16.579379 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-01 18:18:16.579481 | instance |
2026-09-01 18:18:16.579590 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:18:16.579706 | instance | Tuesday 01 September 2026  18:18:16 +0000 (0:00:00.774)       0:00:03.194 *****
2026-09-01 18:18:17.305962 | instance | ok: [instance]
2026-09-01 18:18:17.306131 | instance |
2026-09-01 18:18:17.306373 | instance | TASK [Install depedencies] *****************************************************
2026-09-01 18:18:17.306613 | instance | Tuesday 01 September 2026  18:18:17 +0000 (0:00:00.725)       0:00:03.920 *****
2026-09-01 18:18:40.283333 | instance | changed: [instance]
2026-09-01 18:18:40.283554 | instance |
2026-09-01 18:18:40.283794 | instance | TASK [Start up service] ********************************************************
2026-09-01 18:18:40.284064 | instance | Tuesday 01 September 2026  18:18:40 +0000 (0:00:22.977)       0:00:26.897 *****
2026-09-01 18:18:40.833729 | instance | ok: [instance]
2026-09-01 18:18:40.833920 | instance |
2026-09-01 18:18:40.834153 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-01 18:18:40.834383 | instance | Tuesday 01 September 2026  18:18:40 +0000 (0:00:00.550)       0:00:27.448 *****
2026-09-01 18:18:41.174237 | instance | ok: [instance]
2026-09-01 18:18:41.174424 | instance |
2026-09-01 18:18:41.174679 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-01 18:18:41.174909 | instance | Tuesday 01 September 2026  18:18:41 +0000 (0:00:00.340)       0:00:27.788 *****
2026-09-01 18:18:41.901215 | instance | changed: [instance]
2026-09-01 18:18:41.901272 | instance |
2026-09-01 18:18:41.901285 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-01 18:18:41.901295 | instance | Tuesday 01 September 2026  18:18:41 +0000 (0:00:00.726)       0:00:28.514 *****
2026-09-01 18:18:42.110498 | instance | ok: [instance]
2026-09-01 18:18:42.110536 | instance |
2026-09-01 18:18:42.110544 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-01 18:18:42.110551 | instance | Tuesday 01 September 2026  18:18:42 +0000 (0:00:00.210)       0:00:28.725 *****
2026-09-01 18:18:42.135959 | instance | skipping: [instance]
2026-09-01 18:18:42.136021 | instance |
2026-09-01 18:18:42.136032 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-01 18:18:42.136041 | instance | Tuesday 01 September 2026  18:18:42 +0000 (0:00:00.025)       0:00:28.750 *****
2026-09-01 18:18:42.727173 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:18:42.728327 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:18:42.728393 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:18:42.728404 | instance |
2026-09-01 18:18:42.728414 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-01 18:18:42.728424 | instance | Tuesday 01 September 2026  18:18:42 +0000 (0:00:00.591)       0:00:29.341 *****
2026-09-01 18:18:43.394730 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:18:43.395968 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:18:43.396072 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:18:43.396084 | instance |
2026-09-01 18:18:43.396095 | instance | TASK [Start loop devices] ******************************************************
2026-09-01 18:18:43.396105 | instance | Tuesday 01 September 2026  18:18:43 +0000 (0:00:00.667)       0:00:30.009 *****
2026-09-01 18:18:44.130968 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:18:44.132085 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:18:44.132159 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:18:44.132170 | instance |
2026-09-01 18:18:44.132180 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-01 18:18:44.132197 | instance | Tuesday 01 September 2026  18:18:44 +0000 (0:00:00.736)       0:00:30.745 *****
2026-09-01 18:18:47.332089 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:18:47.333378 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:18:47.333444 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:18:47.333455 | instance |
2026-09-01 18:18:47.333466 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-01 18:18:47.333476 | instance | Tuesday 01 September 2026  18:18:47 +0000 (0:00:03.201)       0:00:33.946 *****
2026-09-01 18:18:49.315486 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-01 18:18:49.316091 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-01 18:18:49.316197 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-01 18:18:49.316216 | instance |
2026-09-01 18:18:49.319289 | instance | PLAY RECAP *********************************************************************
2026-09-01 18:18:49.319388 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-01 18:18:49.319405 | instance |
2026-09-01 18:18:49.319418 | instance | Tuesday 01 September 2026  18:18:49 +0000 (0:00:01.984)       0:00:35.930 *****
2026-09-01 18:18:49.319431 | instance | ===============================================================================
2026-09-01 18:18:49.319443 | instance | Install depedencies ---------------------------------------------------- 22.98s
2026-09-01 18:18:49.319456 | instance | Create a volume group for each loop device ------------------------------ 3.20s
2026-09-01 18:18:49.319468 | instance | Create a logical volume for each loop device ---------------------------- 1.98s
2026-09-01 18:18:49.319481 | instance | Gathering Facts --------------------------------------------------------- 1.31s
2026-09-01 18:18:49.319494 | instance | Configure short hostname ------------------------------------------------ 0.78s
2026-09-01 18:18:49.319506 | instance | Purge "snapd" package --------------------------------------------------- 0.77s
2026-09-01 18:18:49.319517 | instance | Start loop devices ------------------------------------------------------ 0.74s
2026-09-01 18:18:49.319529 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.73s
2026-09-01 18:18:49.319549 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-09-01 18:18:49.319644 | instance | Set permissions on loopback devices ------------------------------------- 0.67s
2026-09-01 18:18:49.320050 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-09-01 18:18:49.320401 | instance | Start up service -------------------------------------------------------- 0.55s
2026-09-01 18:18:49.320706 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s
2026-09-01 18:18:49.320999 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-09-01 18:18:49.321286 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-01 18:18:49.321600 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-01 18:18:49.407861 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-01 18:18:49.422948 | instance | INFO     [csi > converge] Executing
2026-09-01 18:18:50.887799 | instance |
2026-09-01 18:18:50.888341 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-01 18:18:50.888792 | instance |
2026-09-01 18:18:50.889224 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:18:50.889690 | instance | Tuesday 01 September 2026  18:18:50 +0000 (0:00:00.007)       0:00:00.007 *****
2026-09-01 18:18:51.947719 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-01 18:18:51.947811 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-01 18:18:51.947826 | instance | interpreter could change the meaning of that path. See
2026-09-01 18:18:51.947839 | instance | https://docs.ansible.com/ansible-
2026-09-01 18:18:51.947859 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-01 18:18:51.960615 | instance | ok: [instance]
2026-09-01 18:18:51.960836 | instance |
2026-09-01 18:18:51.961072 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-01 18:18:51.961328 | instance | Tuesday 01 September 2026  18:18:51 +0000 (0:00:01.072)       0:00:01.080 *****
2026-09-01 18:18:52.023296 | instance | ok: [instance] => {
2026-09-01 18:18:52.023608 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-01 18:18:52.023899 | instance | }
2026-09-01 18:18:52.024224 | instance |
2026-09-01 18:18:52.024536 | instance | PLAY [all] *********************************************************************
2026-09-01 18:18:52.024752 | instance |
2026-09-01 18:18:52.025337 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:18:52.025584 | instance | Tuesday 01 September 2026  18:18:52 +0000 (0:00:00.062)       0:00:01.143 *****
2026-09-01 18:18:53.045934 | instance | ok: [instance]
2026-09-01 18:18:53.046312 | instance |
2026-09-01 18:18:53.046616 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-01 18:18:53.046911 | instance | Tuesday 01 September 2026  18:18:53 +0000 (0:00:01.021)       0:00:02.165 *****
2026-09-01 18:18:53.265227 | instance | ok: [instance]
2026-09-01 18:18:53.265285 | instance |
2026-09-01 18:18:53.265495 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-01 18:18:53.265569 | instance |
2026-09-01 18:18:53.265777 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:18:53.265847 | instance | Tuesday 01 September 2026  18:18:53 +0000 (0:00:00.220)       0:00:02.385 *****
2026-09-01 18:18:54.270076 | instance | ok: [instance]
2026-09-01 18:18:54.270856 | instance |
2026-09-01 18:18:54.270934 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-01 18:18:54.270962 | instance | Tuesday 01 September 2026  18:18:54 +0000 (0:00:01.004)       0:00:03.389 *****
2026-09-01 18:18:54.586421 | instance | ok: [instance]
2026-09-01 18:18:54.587107 | instance |
2026-09-01 18:18:54.587159 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-01 18:18:54.587172 | instance | Tuesday 01 September 2026  18:18:54 +0000 (0:00:00.316)       0:00:03.705 *****
2026-09-01 18:18:54.634774 | instance | skipping: [instance]
2026-09-01 18:18:54.635253 | instance |
2026-09-01 18:18:54.635303 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-01 18:18:54.635312 | instance | Tuesday 01 September 2026  18:18:54 +0000 (0:00:00.048)       0:00:03.754 *****
2026-09-01 18:18:55.007850 | instance | changed: [instance]
2026-09-01 18:18:55.008087 | instance |
2026-09-01 18:18:55.008478 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-01 18:18:55.008786 | instance | Tuesday 01 September 2026  18:18:55 +0000 (0:00:00.372)       0:00:04.127 *****
2026-09-01 18:18:55.101620 | instance | ok: [instance] => {
2026-09-01 18:18:55.101898 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-01 18:18:55.102261 | instance | }
2026-09-01 18:18:55.102551 | instance |
2026-09-01 18:18:55.102854 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-01 18:18:55.103152 | instance | Tuesday 01 September 2026  18:18:55 +0000 (0:00:00.093)       0:00:04.221 *****
2026-09-01 18:18:55.747327 | instance | changed: [instance]
2026-09-01 18:18:55.747583 | instance |
2026-09-01 18:18:55.748025 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-01 18:18:55.748428 | instance | Tuesday 01 September 2026  18:18:55 +0000 (0:00:00.645)       0:00:04.866 *****
2026-09-01 18:18:55.807499 | instance | skipping: [instance]
2026-09-01 18:18:55.807861 | instance |
2026-09-01 18:18:55.808246 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-01 18:18:55.808549 | instance | Tuesday 01 September 2026  18:18:55 +0000 (0:00:00.059)       0:00:04.926 *****
2026-09-01 18:18:55.864691 | instance | skipping: [instance]
2026-09-01 18:18:55.864791 | instance |
2026-09-01 18:18:55.864959 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-01 18:18:55.865212 | instance | Tuesday 01 September 2026  18:18:55 +0000 (0:00:00.056)       0:00:04.983 *****
2026-09-01 18:18:56.108861 | instance | ok: [instance]
2026-09-01 18:18:56.109064 | instance |
2026-09-01 18:18:56.109453 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-01 18:18:56.109762 | instance | Tuesday 01 September 2026  18:18:56 +0000 (0:00:00.244)       0:00:05.228 *****
2026-09-01 18:18:57.356612 | instance | ok: [instance]
2026-09-01 18:18:57.356851 | instance |
2026-09-01 18:18:57.357044 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-01 18:18:57.357253 | instance | Tuesday 01 September 2026  18:18:57 +0000 (0:00:01.245)       0:00:06.473 *****
2026-09-01 18:18:57.444345 | instance | ok: [instance] => {
2026-09-01 18:18:57.444628 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-01 18:18:57.444863 | instance | }
2026-09-01 18:18:57.445071 | instance |
2026-09-01 18:18:57.445334 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-01 18:18:57.445563 | instance | Tuesday 01 September 2026  18:18:57 +0000 (0:00:00.087)       0:00:06.561 *****
2026-09-01 18:18:58.128109 | instance | changed: [instance]
2026-09-01 18:18:58.128314 | instance |
2026-09-01 18:18:58.128563 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-01 18:18:58.128796 | instance | Tuesday 01 September 2026  18:18:58 +0000 (0:00:00.686)       0:00:07.247 *****
2026-09-01 18:19:01.138620 | instance | changed: [instance]
2026-09-01 18:19:01.138784 | instance |
2026-09-01 18:19:01.139045 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-01 18:19:01.139324 | instance | Tuesday 01 September 2026  18:19:01 +0000 (0:00:03.010)       0:00:10.258 *****
2026-09-01 18:19:01.178178 | instance | skipping: [instance]
2026-09-01 18:19:01.178394 | instance |
2026-09-01 18:19:01.178627 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-01 18:19:01.178867 | instance | Tuesday 01 September 2026  18:19:01 +0000 (0:00:00.039)       0:00:10.298 *****
2026-09-01 18:19:01.218979 | instance | skipping: [instance]
2026-09-01 18:19:01.219251 | instance |
2026-09-01 18:19:01.219820 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-01 18:19:01.220178 | instance | Tuesday 01 September 2026  18:19:01 +0000 (0:00:00.040)       0:00:10.338 *****
2026-09-01 18:19:01.259041 | instance | skipping: [instance]
2026-09-01 18:19:01.259292 | instance |
2026-09-01 18:19:01.259533 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-01 18:19:01.259767 | instance | Tuesday 01 September 2026  18:19:01 +0000 (0:00:00.040)       0:00:10.379 *****
2026-09-01 18:19:06.675654 | instance | changed: [instance]
2026-09-01 18:19:06.675828 | instance |
2026-09-01 18:19:06.676126 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-01 18:19:06.676369 | instance | Tuesday 01 September 2026  18:19:06 +0000 (0:00:05.416)       0:00:15.795 *****
2026-09-01 18:19:07.325927 | instance | changed: [instance]
2026-09-01 18:19:07.326117 | instance |
2026-09-01 18:19:07.326396 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-01 18:19:07.326579 | instance | Tuesday 01 September 2026  18:19:07 +0000 (0:00:00.650)       0:00:16.445 *****
2026-09-01 18:19:08.373601 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-01 18:19:08.373648 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-01 18:19:08.373654 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-01 18:19:08.373659 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-01 18:19:08.374563 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-01 18:19:08.374639 | instance |
2026-09-01 18:19:08.374658 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-01 18:19:08.374668 | instance | Tuesday 01 September 2026  18:19:08 +0000 (0:00:01.047)       0:00:17.492 *****
2026-09-01 18:19:09.004601 | instance | changed: [instance]
2026-09-01 18:19:09.005689 | instance |
2026-09-01 18:19:09.005715 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-01 18:19:09.005724 | instance | Tuesday 01 September 2026  18:19:08 +0000 (0:00:00.603)       0:00:18.096 *****
2026-09-01 18:19:09.005731 | instance |
2026-09-01 18:19:09.005738 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-01 18:19:09.005746 | instance | Tuesday 01 September 2026  18:19:08 +0000 (0:00:00.028)       0:00:18.124 *****
2026-09-01 18:19:09.987790 | instance | ok: [instance]
2026-09-01 18:19:09.988087 | instance |
2026-09-01 18:19:09.988564 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-01 18:19:09.988635 | instance | Tuesday 01 September 2026  18:19:09 +0000 (0:00:00.982)       0:00:19.106 *****
2026-09-01 18:19:10.472096 | instance | changed: [instance]
2026-09-01 18:19:10.472791 | instance |
2026-09-01 18:19:10.472861 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-01 18:19:10.472873 | instance | Tuesday 01 September 2026  18:19:10 +0000 (0:00:00.484)       0:00:19.591 *****
2026-09-01 18:19:11.033577 | instance | changed: [instance]
2026-09-01 18:19:11.033656 | instance |
2026-09-01 18:19:11.034051 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-01 18:19:11.034107 | instance | Tuesday 01 September 2026  18:19:11 +0000 (0:00:00.561)       0:00:20.153 *****
2026-09-01 18:19:11.279236 | instance | ok: [instance]
2026-09-01 18:19:11.279966 | instance |
2026-09-01 18:19:11.280066 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-01 18:19:11.280079 | instance | Tuesday 01 September 2026  18:19:11 +0000 (0:00:00.245)       0:00:20.398 *****
2026-09-01 18:19:11.351745 | instance | ok: [instance] => {
2026-09-01 18:19:11.352228 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-01 18:19:11.352312 | instance | }
2026-09-01 18:19:11.352324 | instance |
2026-09-01 18:19:11.352344 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-01 18:19:11.352354 | instance | Tuesday 01 September 2026  18:19:11 +0000 (0:00:00.072)       0:00:20.471 *****
2026-09-01 18:19:12.512929 | instance | changed: [instance]
2026-09-01 18:19:12.513705 | instance |
2026-09-01 18:19:12.514013 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-01 18:19:12.514029 | instance | Tuesday 01 September 2026  18:19:12 +0000 (0:00:01.160)       0:00:21.631 *****
2026-09-01 18:19:16.864088 | instance | changed: [instance]
2026-09-01 18:19:16.864663 | instance |
2026-09-01 18:19:16.864733 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-01 18:19:16.864742 | instance | Tuesday 01 September 2026  18:19:16 +0000 (0:00:04.351)       0:00:25.983 *****
2026-09-01 18:19:18.169411 | instance | ok: [instance]
2026-09-01 18:19:18.170116 | instance |
2026-09-01 18:19:18.170189 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-01 18:19:18.170202 | instance | Tuesday 01 September 2026  18:19:18 +0000 (0:00:01.305)       0:00:27.288 *****
2026-09-01 18:19:18.555693 | instance | changed: [instance]
2026-09-01 18:19:18.555809 | instance |
2026-09-01 18:19:18.555825 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-01 18:19:18.556070 | instance | Tuesday 01 September 2026  18:19:18 +0000 (0:00:00.386)       0:00:27.675 *****
2026-09-01 18:19:19.107239 | instance | changed: [instance]
2026-09-01 18:19:19.108110 | instance |
2026-09-01 18:19:19.108335 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-01 18:19:19.108352 | instance | Tuesday 01 September 2026  18:19:19 +0000 (0:00:00.550)       0:00:28.226 *****
2026-09-01 18:19:19.749283 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-01 18:19:19.750490 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-01 18:19:19.750546 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-01 18:19:19.750557 | instance |
2026-09-01 18:19:19.750567 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-01 18:19:19.750576 | instance | Tuesday 01 September 2026  18:19:19 +0000 (0:00:00.642)       0:00:28.868 *****
2026-09-01 18:19:20.245807 | instance | changed: [instance]
2026-09-01 18:19:20.246501 | instance |
2026-09-01 18:19:20.246568 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-01 18:19:20.246581 | instance | Tuesday 01 September 2026  18:19:20 +0000 (0:00:00.496)       0:00:29.365 *****
2026-09-01 18:19:20.784553 | instance | changed: [instance]
2026-09-01 18:19:20.784647 | instance |
2026-09-01 18:19:20.785419 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-01 18:19:20.785478 | instance | Tuesday 01 September 2026  18:19:20 +0000 (0:00:00.505)       0:00:29.870 *****
2026-09-01 18:19:20.785486 | instance |
2026-09-01 18:19:20.785493 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-01 18:19:20.785499 | instance | Tuesday 01 September 2026  18:19:20 +0000 (0:00:00.033)       0:00:29.904 *****
2026-09-01 18:19:21.611768 | instance | ok: [instance]
2026-09-01 18:19:21.611866 | instance |
2026-09-01 18:19:21.611881 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-01 18:19:21.612082 | instance | Tuesday 01 September 2026  18:19:21 +0000 (0:00:00.827)       0:00:30.731 *****
2026-09-01 18:19:22.616985 | instance | changed: [instance]
2026-09-01 18:19:22.617833 | instance |
2026-09-01 18:19:22.617898 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-01 18:19:22.617911 | instance | Tuesday 01 September 2026  18:19:22 +0000 (0:00:01.004)       0:00:31.736 *****
2026-09-01 18:19:23.234410 | instance | changed: [instance]
2026-09-01 18:19:23.234489 | instance |
2026-09-01 18:19:23.234843 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-01 18:19:23.234883 | instance | Tuesday 01 September 2026  18:19:23 +0000 (0:00:00.618)       0:00:32.354 *****
2026-09-01 18:19:23.310504 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-01 18:19:23.311274 | instance |
2026-09-01 18:19:23.311332 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-01 18:19:23.311343 | instance | Tuesday 01 September 2026  18:19:23 +0000 (0:00:00.075)       0:00:32.429 *****
2026-09-01 18:19:28.617977 | instance | changed: [instance]
2026-09-01 18:19:28.618056 | instance |
2026-09-01 18:19:28.618386 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-01 18:19:28.618664 | instance | Tuesday 01 September 2026  18:19:28 +0000 (0:00:05.307)       0:00:37.737 *****
2026-09-01 18:19:29.340726 | instance | ok: [instance] => (item=chronyd)
2026-09-01 18:19:29.341674 | instance | ok: [instance] => (item=sshd)
2026-09-01 18:19:29.341753 | instance |
2026-09-01 18:19:29.341764 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-01 18:19:29.341775 | instance | Tuesday 01 September 2026  18:19:29 +0000 (0:00:00.722)       0:00:38.460 *****
2026-09-01 18:19:29.691851 | instance | changed: [instance]
2026-09-01 18:19:29.692197 | instance |
2026-09-01 18:19:29.692323 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-01 18:19:29.692646 | instance | Tuesday 01 September 2026  18:19:29 +0000 (0:00:00.350)       0:00:38.810 *****
2026-09-01 18:19:29.924680 | instance | ok: [instance]
2026-09-01 18:19:29.924783 | instance |
2026-09-01 18:19:29.924793 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-01 18:19:29.924802 | instance | Tuesday 01 September 2026  18:19:29 +0000 (0:00:00.231)       0:00:39.041 *****
2026-09-01 18:19:30.395808 | instance | changed: [instance]
2026-09-01 18:19:30.395882 | instance |
2026-09-01 18:19:30.395894 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-01 18:19:30.395904 | instance | Tuesday 01 September 2026  18:19:30 +0000 (0:00:00.472)       0:00:39.514 *****
2026-09-01 18:19:30.799535 | instance | changed: [instance]
2026-09-01 18:19:30.799647 | instance |
2026-09-01 18:19:30.800087 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-01 18:19:30.800174 | instance | Tuesday 01 September 2026  18:19:30 +0000 (0:00:00.404)       0:00:39.918 *****
2026-09-01 18:19:30.938880 | instance | ok: [instance]
2026-09-01 18:19:30.939042 | instance |
2026-09-01 18:19:30.939273 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-01 18:19:30.939530 | instance | Tuesday 01 September 2026  18:19:30 +0000 (0:00:00.139)       0:00:40.058 *****
2026-09-01 18:19:31.198988 | instance | ok: [instance] => (item=instance)
2026-09-01 18:19:31.199108 | instance |
2026-09-01 18:19:31.199292 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-01 18:19:31.199565 | instance | Tuesday 01 September 2026  18:19:31 +0000 (0:00:00.260)       0:00:40.318 *****
2026-09-01 18:19:31.261328 | instance | ok: [instance]
2026-09-01 18:19:31.261503 | instance |
2026-09-01 18:19:31.261720 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-01 18:19:31.261917 | instance | Tuesday 01 September 2026  18:19:31 +0000 (0:00:00.062)       0:00:40.381 *****
2026-09-01 18:19:31.346648 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-01 18:19:31.346870 | instance |
2026-09-01 18:19:31.347115 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-01 18:19:31.347350 | instance | Tuesday 01 September 2026  18:19:31 +0000 (0:00:00.085)       0:00:40.466 *****
2026-09-01 18:19:31.672645 | instance | changed: [instance]
2026-09-01 18:19:31.672847 | instance |
2026-09-01 18:19:31.673077 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-01 18:19:31.673332 | instance | Tuesday 01 September 2026  18:19:31 +0000 (0:00:00.325)       0:00:40.792 *****
2026-09-01 18:19:32.462975 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-01 18:19:32.463200 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-01 18:19:32.463433 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-01 18:19:32.463661 | instance |
2026-09-01 18:19:32.463882 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-01 18:19:32.464131 | instance | Tuesday 01 September 2026  18:19:32 +0000 (0:00:00.790)       0:00:41.582 *****
2026-09-01 18:21:09.423758 | instance | ok: [instance]
2026-09-01 18:21:09.423946 | instance |
2026-09-01 18:21:09.424296 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-01 18:21:09.424526 | instance | Tuesday 01 September 2026  18:21:09 +0000 (0:01:36.959)       0:02:18.542 *****
2026-09-01 18:21:09.695073 | instance | changed: [instance]
2026-09-01 18:21:09.695149 | instance |
2026-09-01 18:21:09.695162 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-01 18:21:09.695173 | instance | Tuesday 01 September 2026  18:21:09 +0000 (0:00:00.271)       0:02:18.813 *****
2026-09-01 18:21:09.815888 | instance | ok: [instance]
2026-09-01 18:21:09.816092 | instance |
2026-09-01 18:21:09.816108 | instance | TASK [Install Ceph host] *******************************************************
2026-09-01 18:21:09.816122 | instance | Tuesday 01 September 2026  18:21:09 +0000 (0:00:00.122)       0:02:18.935 *****
2026-09-01 18:21:09.922593 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-01 18:21:09.922634 | instance |
2026-09-01 18:21:09.922640 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-01 18:21:09.922646 | instance | Tuesday 01 September 2026  18:21:09 +0000 (0:00:00.106)       0:02:19.042 *****
2026-09-01 18:21:11.628422 | instance | ok: [instance]
2026-09-01 18:21:11.628631 | instance |
2026-09-01 18:21:11.628897 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-01 18:21:11.629175 | instance | Tuesday 01 September 2026  18:21:11 +0000 (0:00:01.705)       0:02:20.747 *****
2026-09-01 18:21:11.712265 | instance | ok: [instance] => (item=instance)
2026-09-01 18:21:11.712465 | instance |
2026-09-01 18:21:11.712705 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-01 18:21:11.712939 | instance | Tuesday 01 September 2026  18:21:11 +0000 (0:00:00.084)       0:02:20.832 *****
2026-09-01 18:21:12.074454 | instance | ok: [instance]
2026-09-01 18:21:12.074642 | instance |
2026-09-01 18:21:12.074885 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-01 18:21:12.075146 | instance | Tuesday 01 September 2026  18:21:12 +0000 (0:00:00.361)       0:02:21.193 *****
2026-09-01 18:21:14.509300 | instance | ok: [instance]
2026-09-01 18:21:14.509663 | instance |
2026-09-01 18:21:14.509898 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-01 18:21:14.510147 | instance | Tuesday 01 September 2026  18:21:14 +0000 (0:00:02.434)       0:02:23.628 *****
2026-09-01 18:22:06.939288 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-01 18:22:06.939395 | 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_cm743x_3/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_cm743x_3/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_cm743x_3/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_cm743x_3/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-01T18:22:06.755+0000 7f5a737b3640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBtF5dqqqiSFiAAByQ1UYIOReoznNkXr23AnjBbdxUSzN9X5cG8y9Wf2Tk=: Malformed input [buffer:3]\n2026-09-01T18:22:06.755+0000 7f5a737b3640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:22:06.759+0000 7f5a737b3640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBtF5dqqqiSFiAAByQ1UYIOReoznNkXr23AnjBbdxUSzN9X5cG8y9Wf2Tk=: Malformed input [buffer:3]\n2026-09-01T18:22:06.759+0000 7f5a737b3640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:22:06.759+0000 7f5a737b3640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBtF5dqqqiSFiAAByQ1UYIOReoznNkXr23AnjBbdxUSzN9X5cG8y9Wf2Tk=: Malformed input [buffer:3]\n2026-09-01T18:22:06.759+0000 7f5a737b3640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:22:06.759+0000 7f5a737b3640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-01 18:22:06.941585 | instance |
2026-09-01 18:22:06.941810 | instance | PLAY RECAP *********************************************************************
2026-09-01 18:22:06.942217 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-01 18:22:06.942333 | instance |
2026-09-01 18:22:06.942570 | instance | Tuesday 01 September 2026  18:22:06 +0000 (0:00:52.433)       0:03:16.061 *****
2026-09-01 18:22:06.942791 | instance | ===============================================================================
2026-09-01 18:22:06.943016 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 96.96s
2026-09-01 18:22:06.943263 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 52.43s
2026-09-01 18:22:06.943616 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.42s
2026-09-01 18:22:06.943721 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.31s
2026-09-01 18:22:06.943944 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.35s
2026-09-01 18:22:06.944231 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.01s
2026-09-01 18:22:06.944718 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.43s
2026-09-01 18:22:06.944730 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.81s
2026-09-01 18:22:06.944896 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.71s
2026-09-01 18:22:06.945162 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.31s
2026-09-01 18:22:06.945377 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.25s
2026-09-01 18:22:06.945597 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.16s
2026-09-01 18:22:06.945815 | instance | Gathering Facts --------------------------------------------------------- 1.07s
2026-09-01 18:22:06.946038 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.05s
2026-09-01 18:22:06.946284 | instance | Gathering Facts --------------------------------------------------------- 1.02s
2026-09-01 18:22:06.946507 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.00s
2026-09-01 18:22:06.946726 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-09-01 18:22:06.946940 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.79s
2026-09-01 18:22:06.947204 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.72s
2026-09-01 18:22:06.947430 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.69s
2026-09-01 18:22:07.041356 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-01 18:22:07.041537 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-01 18:22:07.041685 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-01 18:22:07.208819 | instance | ERROR
2026-09-01 18:22:07.209218 | instance | {
2026-09-01 18:22:07.209284 | instance |   "delta": "0:04:07.922378",
2026-09-01 18:22:07.209330 | instance |   "end": "2026-09-01 18:22:07.127226",
2026-09-01 18:22:07.209371 | instance |   "msg": "non-zero return code",
2026-09-01 18:22:07.209411 | instance |   "rc": 2,
2026-09-01 18:22:07.209452 | instance |   "start": "2026-09-01 18:17:59.204848"
2026-09-01 18:22:07.209491 | instance | }
failure
2026-09-01 18:22:07.216762 | 
2026-09-01 18:22:07.216819 | PLAY RECAP
2026-09-01 18:22:07.216869 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:22:07.216891 | 
2026-09-01 18:22:07.358365 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-01 18:22:07.361926 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-01 18:22:08.010043 | 
2026-09-01 18:22:08.010173 | PLAY [all]
2026-09-01 18:22:08.025634 | 
2026-09-01 18:22:08.025716 | TASK [gather-host-logs : creating directory for system status]
2026-09-01 18:22:08.405322 | instance | changed
2026-09-01 18:22:08.514534 | 
2026-09-01 18:22:08.514775 | TASK [gather-host-logs : Get logs for each host]
2026-09-01 18:22:08.905007 | instance | + systemd-cgls --full --all --no-pager
2026-09-01 18:22:08.920804 | instance | + ip addr
2026-09-01 18:22:08.923632 | instance | + ip route
2026-09-01 18:22:08.926113 | instance | + lsblk
2026-09-01 18:22:08.932250 | instance | + mount
2026-09-01 18:22:08.935135 | instance | + docker images
2026-09-01 18:22:08.958262 | instance | + brctl show
2026-09-01 18:22:08.958761 | instance | /bin/bash: line 8: brctl: command not found
2026-09-01 18:22:08.959097 | instance | + ps aux --sort=-%mem
2026-09-01 18:22:08.981623 | instance | + dpkg -l
2026-09-01 18:22:08.992683 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-01 18:22:08.993365 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-01 18:22:09.013313 | instance | + '[' '!' -z '' ']'
2026-09-01 18:22:09.064852 | instance | ok: Runtime: 0:00:00.113339
2026-09-01 18:22:09.071240 | 
2026-09-01 18:22:09.071310 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-01 18:22:09.837771 | instance | changed:
2026-09-01 18:22:09.837983 | instance | created directory /var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/work/logs/instance
2026-09-01 18:22:09.838014 | instance | cd+++++++++ system/
2026-09-01 18:22:09.838038 | instance | >f+++++++++ system/brctl-show.txt
2026-09-01 18:22:09.838060 | instance | >f+++++++++ system/docker-images.txt
2026-09-01 18:22:09.838080 | instance | >f+++++++++ system/ip-addr.txt
2026-09-01 18:22:09.838130 | instance | >f+++++++++ system/ip-route.txt
2026-09-01 18:22:09.838160 | instance | >f+++++++++ system/lsblk.txt
2026-09-01 18:22:09.838182 | instance | >f+++++++++ system/mount.txt
2026-09-01 18:22:09.838201 | instance | >f+++++++++ system/packages.txt
2026-09-01 18:22:09.838221 | instance | >f+++++++++ system/ps.txt
2026-09-01 18:22:09.838243 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-01 18:22:09.849854 | 
2026-09-01 18:22:09.849927 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-01 18:22:10.051207 | instance | changed: "values"
2026-09-01 18:22:10.882203 | instance | changed: "releases"
2026-09-01 18:22:10.895195 | 
2026-09-01 18:22:10.895621 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-01 18:22:11.123452 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-01 18:22:11.431143 | instance | ok: Runtime: 0:00:00.005460
2026-09-01 18:22:11.436356 | 
2026-09-01 18:22:11.436435 | TASK [helm-release-status : Downloads logs to executor]
2026-09-01 18:22:11.915537 | instance | changed:
2026-09-01 18:22:11.915701 | instance | cd+++++++++ helm/
2026-09-01 18:22:11.915730 | instance | cd+++++++++ helm/releases/
2026-09-01 18:22:11.915753 | instance | cd+++++++++ helm/values/
2026-09-01 18:22:11.924892 | 
2026-09-01 18:22:11.924958 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-01 18:22:12.139086 | instance | changed
2026-09-01 18:22:12.146881 | 
2026-09-01 18:22:12.146965 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-01 18:22:12.419293 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:22:12.419522 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:22:12.424375 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:22:12.425726 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:22:12.426702 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:22:12.428111 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:22:12.429385 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:22:12.430811 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:22:12.431545 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:22:12.432983 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:22:12.434382 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:22:12.435598 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:22:12.683251 | instance | ok: Runtime: 0:00:00.023593
2026-09-01 18:22:12.689242 | 
2026-09-01 18:22:12.689312 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-01 18:22:12.895335 | instance | changed
2026-09-01 18:22:12.902769 | 
2026-09-01 18:22:12.903008 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-01 18:22:13.125433 | instance | environment: line 5: kubectl: command not found
2026-09-01 18:22:13.126439 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:22:13.126478 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:22:13.127328 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:22:13.439514 | instance | ok: Runtime: 0:00:00.008985
2026-09-01 18:22:13.445193 | 
2026-09-01 18:22:13.445263 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-01 18:22:13.943919 | instance | changed:
2026-09-01 18:22:13.944103 | instance | cd+++++++++ objects/
2026-09-01 18:22:13.944131 | instance | cd+++++++++ objects/cluster/
2026-09-01 18:22:13.944160 | instance | cd+++++++++ objects/namespaced/
2026-09-01 18:22:13.953900 | 
2026-09-01 18:22:13.954052 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-01 18:22:14.161183 | instance | changed
2026-09-01 18:22:14.167224 | 
2026-09-01 18:22:14.167290 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-01 18:22:14.384227 | instance | changed
2026-09-01 18:22:14.475725 | 
2026-09-01 18:22:14.475871 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-01 18:22:14.707124 | instance | environment: line 3: kubectl: command not found
2026-09-01 18:22:15.019198 | instance | ok: Runtime: 0:00:00.007834
2026-09-01 18:22:15.026026 | 
2026-09-01 18:22:15.026109 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-01 18:22:15.606482 | instance | changed:
2026-09-01 18:22:15.606722 | instance | cd+++++++++ pod-logs/
2026-09-01 18:22:15.606751 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-01 18:22:15.626023 | 
2026-09-01 18:22:15.626326 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-01 18:22:15.835286 | instance | changed
2026-09-01 18:22:15.841066 | 
2026-09-01 18:22:15.841134 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-01 18:22:16.070565 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-01 18:22:16.377664 | instance | ok: Runtime: 0:00:00.052083
2026-09-01 18:22:16.385194 | 
2026-09-01 18:22:16.385288 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-01 18:22:16.619361 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-01 18:22:16.670389 | instance | ceph-mgr endpoints:
2026-09-01 18:22:16.927824 | instance | ok: Runtime: 0:00:00.055914
2026-09-01 18:22:16.934066 | 
2026-09-01 18:22:16.934138 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-01 18:22:17.169535 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-01 18:22:17.478885 | instance | ok: Runtime: 0:00:00.052239
2026-09-01 18:22:17.486391 | 
2026-09-01 18:22:17.486483 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-01 18:22:17.998128 | instance | changed: cd+++++++++ prometheus/
2026-09-01 18:22:18.007779 | 
2026-09-01 18:22:18.007845 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-01 18:22:18.215977 | instance | changed
2026-09-01 18:22:18.247927 | 
2026-09-01 18:22:18.248025 | TASK [gather-selenium-data : Get selenium data]
2026-09-01 18:22:18.514272 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-01 18:22:18.515787 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-01 18:22:18.831891 | instance | ERROR
2026-09-01 18:22:18.832129 | instance | {
2026-09-01 18:22:18.832161 | instance |   "delta": "0:00:00.006944",
2026-09-01 18:22:18.832182 | instance |   "end": "2026-09-01 18:22:18.516230",
2026-09-01 18:22:18.832204 | instance |   "msg": "non-zero return code",
2026-09-01 18:22:18.832224 | instance |   "rc": 1,
2026-09-01 18:22:18.832243 | instance |   "start": "2026-09-01 18:22:18.509286"
2026-09-01 18:22:18.832262 | instance | }
2026-09-01 18:22:18.832286 | instance | ERROR: Ignoring Errors
2026-09-01 18:22:18.837299 | 
2026-09-01 18:22:18.837389 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-01 18:22:19.349110 | instance | changed: cd+++++++++ selenium/
2026-09-01 18:22:19.356749 | 
2026-09-01 18:22:19.356805 | PLAY RECAP
2026-09-01 18:22:19.356866 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-01 18:22:19.356895 | 
2026-09-01 18:22:19.649879 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-01 18:22:19.653030 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-01 18:22:20.296342 | 
2026-09-01 18:22:20.296468 | PLAY [all]
2026-09-01 18:22:20.308263 | 
2026-09-01 18:22:20.308342 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-01 18:22:20.354018 | instance | skipping: Conditional result was False
2026-09-01 18:22:20.362359 | 
2026-09-01 18:22:20.362471 | TASK [fetch-output : Set log path for single node]
2026-09-01 18:22:20.407202 | instance | ok
2026-09-01 18:22:20.413594 | 
2026-09-01 18:22:20.413665 | LOOP [fetch-output : Ensure local output dirs]
2026-09-01 18:22:20.846083 | instance -> localhost | ok: "/var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/work/logs"
2026-09-01 18:22:21.047558 | instance -> localhost | changed: "/var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/work/artifacts"
2026-09-01 18:22:21.278608 | instance -> localhost | changed: "/var/lib/zuul/builds/0c3c564e4b48466cb6e88c2bb99d6083/work/docs"
2026-09-01 18:22:21.293763 | 
2026-09-01 18:22:21.293920 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-01 18:22:22.004892 | instance | changed: .d..t...... ./
2026-09-01 18:22:22.005194 | instance | changed: All items complete
2026-09-01 18:22:22.005234 | 
2026-09-01 18:22:22.525760 | instance | changed: .d..t...... ./
2026-09-01 18:22:22.993421 | instance | changed: .d..t...... ./
2026-09-01 18:22:23.014245 | 
2026-09-01 18:22:23.014413 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-01 18:22:23.595968 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009031
2026-09-01 18:22:24.022215 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.193671
2026-09-01 18:22:24.042075 | 
2026-09-01 18:22:24.042213 | PLAY [all]
2026-09-01 18:22:24.048374 | 
2026-09-01 18:22:24.048439 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-01 18:22:24.511882 | instance | changed
2026-09-01 18:22:24.517629 | 
2026-09-01 18:22:24.517684 | PLAY RECAP
2026-09-01 18:22:24.517732 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-01 18:22:24.517754 | 
2026-09-01 18:22:24.681346 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-01 18:22:24.685718 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-01 18:22:25.277188 | 
2026-09-01 18:22:25.277331 | PLAY [localhost]
2026-09-01 18:22:25.287371 | 
2026-09-01 18:22:25.287440 | TASK [Generate Zuul manifest]
2026-09-01 18:22:25.305560 | localhost | ok
2026-09-01 18:22:25.349434 | 
2026-09-01 18:22:25.349511 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-01 18:22:25.747775 | localhost | changed
2026-09-01 18:22:25.867647 | 
2026-09-01 18:22:25.867820 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-01 18:22:25.922982 | localhost | ok
2026-09-01 18:22:25.937851 | 
2026-09-01 18:22:25.937937 | TASK [Upload logs]
2026-09-01 18:22:25.974124 | localhost | ok
2026-09-01 18:22:26.036987 | 
2026-09-01 18:22:26.037130 | TASK [Set zuul-log-path fact]
2026-09-01 18:22:26.057836 | localhost | ok
2026-09-01 18:22:26.074561 | 
2026-09-01 18:22:26.074657 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-01 18:22:26.118239 | localhost | ok
2026-09-01 18:22:26.127321 | 
2026-09-01 18:22:26.127392 | TASK [upload-logs : Create log directories]
2026-09-01 18:22:26.636676 | localhost | changed
2026-09-01 18:22:26.643504 | 
2026-09-01 18:22:26.643640 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-01 18:22:27.098196 | localhost -> localhost | ok: Runtime: 0:00:00.006125
2026-09-01 18:22:27.103365 | 
2026-09-01 18:22:27.103439 | TASK [upload-logs : Upload logs to log server]
2026-09-01 18:22:27.575494 | localhost | Output suppressed because no_log was given
2026-09-01 18:22:27.628613 | 
2026-09-01 18:22:27.628753 | LOOP [upload-logs : Compress console log and json output]
2026-09-01 18:22:27.673399 | localhost | skipping: Conditional result was False
2026-09-01 18:22:27.680711 | localhost | skipping: Conditional result was False
2026-09-01 18:22:27.694735 | 
2026-09-01 18:22:27.694874 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-01 18:22:27.739314 | localhost | skipping: Conditional result was False
2026-09-01 18:22:27.739600 | 
2026-09-01 18:22:27.745484 | localhost | skipping: Conditional result was False
2026-09-01 18:22:27.760813 | 
2026-09-01 18:22:27.761089 | LOOP [upload-logs : Upload console log and json output]