2026-08-22 14:31:06.457492 | Job console starting
2026-08-22 14:31:06.466421 | Updating git repos
2026-08-22 14:31:08.782167 | Cloning repos into workspace
2026-08-22 14:31:55.887818 | Restoring repo states
2026-08-22 14:32:09.744467 | Merging changes
2026-08-22 14:32:11.507865 | Checking out repos
2026-08-22 14:32:16.966347 | Preparing playbooks
2026-08-22 14:32:20.776081 | Running Ansible setup
2026-08-22 14:32:27.126044 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 14:32:27.779706 | 
2026-08-22 14:32:27.779850 | PLAY [localhost]
2026-08-22 14:32:27.788176 | 
2026-08-22 14:32:27.788251 | TASK [Gathering Facts]
2026-08-22 14:32:30.636184 | localhost | ok
2026-08-22 14:32:30.644212 | 
2026-08-22 14:32:30.644306 | TASK [Setup log path fact]
2026-08-22 14:32:30.664566 | localhost | ok
2026-08-22 14:32:30.677983 | 
2026-08-22 14:32:30.678058 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 14:32:30.706747 | localhost | ok
2026-08-22 14:32:30.717906 | 
2026-08-22 14:32:30.718041 | TASK [emit-job-header : Print job information]
2026-08-22 14:32:30.761028 | # Job Information
2026-08-22 14:32:30.761385 | Ansible Version: 2.16.18
2026-08-22 14:32:30.761435 | Job: atmosphere-molecule-csi-rbd
2026-08-22 14:32:30.761465 | Pipeline: check
2026-08-22 14:32:30.761492 | Executor: 2d72f0692154
2026-08-22 14:32:30.761518 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4094
2026-08-22 14:32:30.761550 | Event ID: 1102d690-9e35-11f1-80d8-c3d9e8a6d75a
2026-08-22 14:32:30.768762 | 
2026-08-22 14:32:30.769042 | LOOP [emit-job-header : Print node information]
2026-08-22 14:32:30.869892 | localhost | ok:
2026-08-22 14:32:30.870119 | localhost | # Node Information
2026-08-22 14:32:30.870159 | localhost | Inventory Hostname: instance
2026-08-22 14:32:30.870190 | localhost | Hostname: np0000190642
2026-08-22 14:32:30.870217 | localhost | Username: zuul
2026-08-22 14:32:30.870250 | localhost | Distro: Ubuntu 22.04
2026-08-22 14:32:30.870278 | localhost | Provider: yul1
2026-08-22 14:32:30.870305 | localhost | Region: ca-ymq-1
2026-08-22 14:32:30.870331 | localhost | Label: ubuntu-jammy
2026-08-22 14:32:30.870358 | localhost | Product Name: OpenStack Nova
2026-08-22 14:32:30.870383 | localhost | Interface IP: 199.19.213.103
2026-08-22 14:32:30.879224 | 
2026-08-22 14:32:30.879372 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 14:32:32.369192 | localhost -> localhost | changed
2026-08-22 14:32:32.377700 | 
2026-08-22 14:32:32.377823 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 14:32:33.674483 | localhost -> localhost | changed
2026-08-22 14:32:33.682738 | 
2026-08-22 14:32:33.682809 | PLAY [all]
2026-08-22 14:32:33.691658 | 
2026-08-22 14:32:33.691725 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 14:32:34.293397 | instance -> localhost | ok
2026-08-22 14:32:34.303597 | 
2026-08-22 14:32:34.303722 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 14:32:34.336084 | instance | ok
2026-08-22 14:32:34.351511 | instance | included: /var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 14:32:34.357680 | 
2026-08-22 14:32:34.357809 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 14:32:35.273647 | instance -> localhost | Generating public/private rsa key pair.
2026-08-22 14:32:35.273920 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/work/6907bd444eb04bb486496a869499d14d_id_rsa
2026-08-22 14:32:35.273979 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/work/6907bd444eb04bb486496a869499d14d_id_rsa.pub
2026-08-22 14:32:35.274026 | instance -> localhost | The key fingerprint is:
2026-08-22 14:32:35.274071 | instance -> localhost | SHA256:HZC9Lanek+pQftGOpZnj7rhMtrO/fI3fZg9kUMyVg60 zuul-build-sshkey
2026-08-22 14:32:35.274139 | instance -> localhost | The key's randomart image is:
2026-08-22 14:32:35.274263 | instance -> localhost | +---[RSA 3072]----+
2026-08-22 14:32:35.274314 | instance -> localhost | |        .o   o+.o|
2026-08-22 14:32:35.274357 | instance -> localhost | |        ...  oo+ |
2026-08-22 14:32:35.274399 | instance -> localhost | |          .+. . .|
2026-08-22 14:32:35.274441 | instance -> localhost | |         .+o.E   |
2026-08-22 14:32:35.274482 | instance -> localhost | |        S.o.o o  |
2026-08-22 14:32:35.274520 | instance -> localhost | |       o.  O o   |
2026-08-22 14:32:35.274562 | instance -> localhost | |      ..+.B..o.  |
2026-08-22 14:32:35.274603 | instance -> localhost | |       =o*+.o .oo|
2026-08-22 14:32:35.274647 | instance -> localhost | |       .OOB+ ..o+|
2026-08-22 14:32:35.274689 | instance -> localhost | +----[SHA256]-----+
2026-08-22 14:32:35.274790 | instance -> localhost | ok: Runtime: 0:00:00.507622
2026-08-22 14:32:35.284562 | 
2026-08-22 14:32:35.284713 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 14:32:35.319252 | instance | ok
2026-08-22 14:32:35.334748 | instance | included: /var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 14:32:35.347183 | 
2026-08-22 14:32:35.347349 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 14:32:35.379872 | instance | skipping: Conditional result was False
2026-08-22 14:32:35.389130 | 
2026-08-22 14:32:35.389268 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 14:32:35.915277 | instance | changed
2026-08-22 14:32:35.921526 | 
2026-08-22 14:32:35.921593 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 14:32:36.103905 | instance | ok
2026-08-22 14:32:36.108958 | 
2026-08-22 14:32:36.109042 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 14:32:36.568868 | instance | changed
2026-08-22 14:32:36.575682 | 
2026-08-22 14:32:36.575763 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 14:32:37.045312 | instance | changed
2026-08-22 14:32:37.053586 | 
2026-08-22 14:32:37.053664 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 14:32:37.080312 | instance | skipping: Conditional result was False
2026-08-22 14:32:37.093130 | 
2026-08-22 14:32:37.093251 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 14:32:37.475144 | instance -> localhost | changed
2026-08-22 14:32:37.490980 | 
2026-08-22 14:32:37.491049 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 14:32:37.774612 | instance -> localhost | Identity added: /var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/work/6907bd444eb04bb486496a869499d14d_id_rsa (zuul-build-sshkey)
2026-08-22 14:32:37.774811 | instance -> localhost | ok: Runtime: 0:00:00.014607
2026-08-22 14:32:37.780120 | 
2026-08-22 14:32:37.780180 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 14:32:38.112015 | instance | ok
2026-08-22 14:32:38.122594 | 
2026-08-22 14:32:38.122909 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 14:32:38.148682 | instance | skipping: Conditional result was False
2026-08-22 14:32:38.163878 | 
2026-08-22 14:32:38.163957 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 14:32:38.491287 | instance | ok
2026-08-22 14:32:38.498938 | 
2026-08-22 14:32:38.499050 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 14:32:41.919723 | instance | Output suppressed because no_log was given
2026-08-22 14:32:41.931854 | 
2026-08-22 14:32:41.931922 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 14:32:42.114723 | instance | ok: "logs"
2026-08-22 14:32:42.115007 | instance | ok: All items complete
2026-08-22 14:32:42.115037 | 
2026-08-22 14:32:42.274232 | instance | ok: "artifacts"
2026-08-22 14:32:42.434799 | instance | ok: "docs"
2026-08-22 14:32:42.451721 | 
2026-08-22 14:32:42.451878 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 14:32:42.633710 | instance | changed: "logs"
2026-08-22 14:32:42.797918 | instance | changed: "artifacts"
2026-08-22 14:32:43.133747 | instance | changed: "docs"
2026-08-22 14:32:43.153508 | 
2026-08-22 14:32:43.153612 | PLAY RECAP
2026-08-22 14:32:43.153664 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 14:32:43.153695 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 14:32:43.153719 | 
2026-08-22 14:32:43.358016 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 14:32:43.362093 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 14:32:44.082648 | 
2026-08-22 14:32:44.145713 | PLAY [all]
2026-08-22 14:32:44.170749 | 
2026-08-22 14:32:44.170903 | TASK [setup-uv : Extract archive]
2026-08-22 14:32:46.423708 | instance | changed
2026-08-22 14:32:46.432233 | 
2026-08-22 14:32:46.432315 | TASK [setup-uv : Print version]
2026-08-22 14:32:46.801668 | instance | uv 0.8.13
2026-08-22 14:32:47.245591 | instance | ok: Runtime: 0:00:00.012224
2026-08-22 14:32:47.254991 | 
2026-08-22 14:32:47.255063 | TASK [Install Ansible collection siblings]
2026-08-22 14:32:56.266329 | instance | changed:
2026-08-22 14:32:56.266575 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-22 14:32:56.266623 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-22 14:32:56.266660 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-22 14:32:56.266694 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-22 14:32:56.266727 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-22 14:32:56.266766 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-22 14:32:56.266797 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-22 14:32:56.266838 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-22 14:32:56.266868 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-22 14:32:56.266905 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-22 14:32:56.266933 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-22 14:32:56.266965 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-22 14:32:56.266997 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-22 14:32:56.267028 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-22 14:32:56.267060 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-22 14:32:56.267097 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-22 14:32:56.267130 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-22 14:32:56.267163 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-22 14:32:56.267190 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-22 14:32:56.267216 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-22 14:32:56.267242 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-22 14:32:56.267274 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-22 14:32:56.267309 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 14:32:56.267363 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-22 14:32:56.267401 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-22 14:32:56.267432 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 14:32:56.267460 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 14:32:56.267495 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 14:32:56.267522 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-22 14:32:56.267553 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-22 14:32:56.267591 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 14:32:56.267622 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 14:32:56.267649 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-22 14:32:56.267675 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-22 14:32:56.267720 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.267751 | instance | Process install dependency map
2026-08-22 14:32:56.267778 | instance | Starting collection install process
2026-08-22 14:32:56.267809 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-22 14:32:56.267840 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-22 14:32:56.267871 | instance | ansible.netcommon:* was installed successfully
2026-08-22 14:32:56.267907 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-22 14:32:56.267934 | instance | Creating virtual environment at: .venv
2026-08-22 14:32:56.267966 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 14:32:56.268002 | instance | Downloading setuptools (1.1MiB)
2026-08-22 14:32:56.268029 | instance | Downloading pygments (1.2MiB)
2026-08-22 14:32:56.268064 | instance | Downloading pydantic-core (2.0MiB)
2026-08-22 14:32:56.268100 | instance | Downloading ansible-core (2.1MiB)
2026-08-22 14:32:56.268126 | instance | Downloading kubernetes (1.9MiB)
2026-08-22 14:32:56.268158 | instance | Downloading openstacksdk (1.7MiB)
2026-08-22 14:32:56.268184 | instance | Downloading netaddr (2.2MiB)
2026-08-22 14:32:56.268210 | instance | Downloading rjsonnet (1.2MiB)
2026-08-22 14:32:56.268236 | instance | Downloading cryptography (4.2MiB)
2026-08-22 14:32:56.268262 | instance |  Downloading rjsonnet
2026-08-22 14:32:56.268296 | instance |    Building pyperclip==1.9.0
2026-08-22 14:32:56.268329 | instance |  Downloading pydantic-core
2026-08-22 14:32:56.268360 | instance |  Downloading netaddr
2026-08-22 14:32:56.268391 | instance |  Downloading pygments
2026-08-22 14:32:56.268422 | instance |  Downloading cryptography
2026-08-22 14:32:56.268447 | instance |  Downloading setuptools
2026-08-22 14:32:56.268472 | instance |  Downloading kubernetes
2026-08-22 14:32:56.268497 | instance |  Downloading ansible-core
2026-08-22 14:32:56.268523 | instance |  Downloading openstacksdk
2026-08-22 14:32:56.268555 | instance |       Built pyperclip==1.9.0
2026-08-22 14:32:56.268580 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 14:32:56.268612 | instance | Installed 83 packages in 38ms
2026-08-22 14:32:56.268643 | instance | Checked out ansible.posix to 1.6.0
2026-08-22 14:32:56.268673 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-22 14:32:56.268708 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.268738 | instance | Process install dependency map
2026-08-22 14:32:56.268780 | instance | Starting collection install process
2026-08-22 14:32:56.268808 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-22 14:32:56.268833 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-22 14:32:56.268859 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-22 14:32:56.268891 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.268926 | instance | 2.17.12
2026-08-22 14:32:56.268974 | instance | Checked out ansible.utils to v6.1.0
2026-08-22 14:32:56.269011 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-22 14:32:56.269039 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.269069 | instance | Process install dependency map
2026-08-22 14:32:56.269099 | instance | Starting collection install process
2026-08-22 14:32:56.269138 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-22 14:32:56.269178 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-22 14:32:56.269211 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-22 14:32:56.269248 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.269276 | instance | 2.17.12
2026-08-22 14:32:56.269307 | instance | Checked out atmosphere.common to v0.7.0
2026-08-22 14:32:56.269343 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-22 14:32:56.269371 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.269397 | instance | Process install dependency map
2026-08-22 14:32:56.269428 | instance | Starting collection install process
2026-08-22 14:32:56.269464 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-22 14:32:56.269494 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-22 14:32:56.269525 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-22 14:32:56.269556 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.269588 | instance | 2.17.12
2026-08-22 14:32:56.269619 | instance | Checked out community.crypto to 2.2.3
2026-08-22 14:32:56.269651 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-22 14:32:56.269679 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.269710 | instance | Process install dependency map
2026-08-22 14:32:56.269742 | instance | Starting collection install process
2026-08-22 14:32:56.269768 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-22 14:32:56.269799 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-22 14:32:56.269826 | instance | community.crypto:2.2.3 was installed successfully
2026-08-22 14:32:56.269858 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.269891 | instance | 2.17.12
2026-08-22 14:32:56.269923 | instance | Checked out community.general to 7.3.0
2026-08-22 14:32:56.269959 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-08-22 14:32:56.270004 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.270032 | instance | Process install dependency map
2026-08-22 14:32:56.270058 | instance | Starting collection install process
2026-08-22 14:32:56.270084 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-22 14:32:56.270109 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-22 14:32:56.270135 | instance | community.general:7.3.0 was installed successfully
2026-08-22 14:32:56.270161 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.270194 | instance | 2.17.12
2026-08-22 14:32:56.270221 | instance | Checked out community.mysql to 3.6.0
2026-08-22 14:32:56.270297 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-22 14:32:56.270333 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.270366 | instance | Process install dependency map
2026-08-22 14:32:56.270393 | instance | Starting collection install process
2026-08-22 14:32:56.270428 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-22 14:32:56.270454 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-22 14:32:56.270485 | instance | community.mysql:3.6.0 was installed successfully
2026-08-22 14:32:56.270516 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.270547 | instance | 2.17.12
2026-08-22 14:32:56.270578 | instance | Checked out kubernetes.core to 2.4.0
2026-08-22 14:32:56.270603 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-22 14:32:56.270628 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.270646 | instance | Process install dependency map
2026-08-22 14:32:56.270670 | instance | Starting collection install process
2026-08-22 14:32:56.270689 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-22 14:32:56.270707 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-22 14:32:56.270726 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-22 14:32:56.270745 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.270763 | instance | 2.17.12
2026-08-22 14:32:56.270782 | instance | Checked out openstack.cloud to 2.6.0
2026-08-22 14:32:56.270808 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-22 14:32:56.270835 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.270855 | instance | Process install dependency map
2026-08-22 14:32:56.270878 | instance | Starting collection install process
2026-08-22 14:32:56.270901 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-22 14:32:56.270923 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-22 14:32:56.270945 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-22 14:32:56.270968 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.270988 | instance | 2.17.12
2026-08-22 14:32:56.271023 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-22 14:32:56.271045 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-22 14:32:56.271064 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.271083 | instance | Process install dependency map
2026-08-22 14:32:56.271101 | instance | Starting collection install process
2026-08-22 14:32:56.271119 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-22 14:32:56.271137 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-22 14:32:56.271157 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-22 14:32:56.271176 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.271199 | instance | 2.17.12
2026-08-22 14:32:56.271224 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-22 14:32:56.271248 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-22 14:32:56.271267 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.271287 | instance | Process install dependency map
2026-08-22 14:32:56.271311 | instance | Starting collection install process
2026-08-22 14:32:56.271333 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-22 14:32:56.271357 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-22 14:32:56.271381 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-22 14:32:56.271404 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.271434 | instance | 2.17.12
2026-08-22 14:32:56.271463 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-22 14:32:56.271491 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-22 14:32:56.271514 | instance | Starting galaxy collection install process
2026-08-22 14:32:56.271537 | instance | Process install dependency map
2026-08-22 14:32:56.271560 | instance | Starting collection install process
2026-08-22 14:32:56.271578 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-22 14:32:56.271597 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-22 14:32:56.271616 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-22 14:32:56.271636 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 14:32:56.271662 | instance | 2.17.12
2026-08-22 14:32:56.277634 | 
2026-08-22 14:32:56.277686 | PLAY RECAP
2026-08-22 14:32:56.277733 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 14:32:56.277756 | 
2026-08-22 14:32:56.421218 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 14:32:56.483502 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 14:32:57.271151 | 
2026-08-22 14:32:57.271298 | PLAY [all]
2026-08-22 14:32:57.282608 | 
2026-08-22 14:32:57.282687 | TASK [Install "jq" for log collection]
2026-08-22 14:33:09.869967 | instance | changed
2026-08-22 14:33:09.871921 | 
2026-08-22 14:33:09.871979 | PLAY RECAP
2026-08-22 14:33:09.872029 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 14:33:09.872099 | 
2026-08-22 14:33:10.004851 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 14:33:10.009457 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 14:33:10.616498 | 
2026-08-22 14:33:10.616668 | PLAY [all]
2026-08-22 14:33:10.627630 | 
2026-08-22 14:33:10.627703 | LOOP [Check inventory directories]
2026-08-22 14:33:10.993216 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-22 14:33:11.148860 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-22 14:33:11.299003 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-22 14:33:11.307739 | 
2026-08-22 14:33:11.307936 | LOOP [Copy inventory file for Zuul]
2026-08-22 14:33:12.092297 | instance | changed:
2026-08-22 14:33:12.483981 | instance | {
2026-08-22 14:33:12.484168 | instance |   "ansible_loop_var": "item",
2026-08-22 14:33:12.484226 | instance |   "changed": false,
2026-08-22 14:33:12.484271 | instance |   "failed": false,
2026-08-22 14:33:12.484323 | instance |   "invocation": {
2026-08-22 14:33:12.484403 | instance |     "module_args": {
2026-08-22 14:33:12.484446 | instance |       "checksum_algorithm": "sha1",
2026-08-22 14:33:12.484486 | instance |       "follow": true,
2026-08-22 14:33:12.484525 | instance |       "get_attributes": true,
2026-08-22 14:33:12.484568 | instance |       "get_checksum": true,
2026-08-22 14:33:12.484607 | instance |       "get_mime": true,
2026-08-22 14:33:12.484646 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-22 14:33:12.484687 | instance |     }
2026-08-22 14:33:12.484726 | instance |   },
2026-08-22 14:33:12.484764 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-22 14:33:12.484802 | instance |   "stat": {
2026-08-22 14:33:12.484840 | instance |     "atime": 1787409169.0430005,
2026-08-22 14:33:12.484878 | instance |     "attr_flags": "e",
2026-08-22 14:33:12.484917 | instance |     "attributes": [
2026-08-22 14:33:12.484994 | instance |       "extents"
2026-08-22 14:33:12.485039 | instance |     ],
2026-08-22 14:33:12.485079 | instance |     "block_size": 4096,
2026-08-22 14:33:12.485116 | instance |     "blocks": 8,
2026-08-22 14:33:12.485154 | instance |     "charset": "unknown",
2026-08-22 14:33:12.485196 | instance |     "ctime": 1787409168.0509765,
2026-08-22 14:33:12.485234 | instance |     "dev": 64513,
2026-08-22 14:33:12.485272 | instance |     "device_type": 0,
2026-08-22 14:33:12.485310 | instance |     "executable": true,
2026-08-22 14:33:12.485347 | instance |     "exists": true,
2026-08-22 14:33:12.485384 | instance |     "gid": 1000,
2026-08-22 14:33:12.485421 | instance |     "gr_name": "zuul",
2026-08-22 14:33:12.485458 | instance |     "inode": 1048949,
2026-08-22 14:33:12.485495 | instance |     "isblk": false,
2026-08-22 14:33:12.485533 | instance |     "ischr": false,
2026-08-22 14:33:12.485571 | instance |     "isdir": true,
2026-08-22 14:33:12.485608 | instance |     "isfifo": false,
2026-08-22 14:33:12.485645 | instance |     "isgid": false,
2026-08-22 14:33:12.485681 | instance |     "islnk": false,
2026-08-22 14:33:12.485719 | instance |     "isreg": false,
2026-08-22 14:33:12.485756 | instance |     "issock": false,
2026-08-22 14:33:12.485793 | instance |     "isuid": false,
2026-08-22 14:33:12.485829 | instance |     "mimetype": "unknown",
2026-08-22 14:33:12.485865 | instance |     "mode": "0755",
2026-08-22 14:33:12.485901 | instance |     "mtime": 1787409168.0509765,
2026-08-22 14:33:12.485939 | instance |     "nlink": 22,
2026-08-22 14:33:12.485975 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-22 14:33:12.486012 | instance |     "pw_name": "zuul",
2026-08-22 14:33:12.486057 | instance |     "readable": true,
2026-08-22 14:33:12.486094 | instance |     "rgrp": true,
2026-08-22 14:33:12.486133 | instance |     "roth": true,
2026-08-22 14:33:12.486186 | instance |     "rusr": true,
2026-08-22 14:33:12.486226 | instance |     "size": 4096,
2026-08-22 14:33:12.486264 | instance |     "uid": 1000,
2026-08-22 14:33:12.486302 | instance |     "version": "2443075090",
2026-08-22 14:33:12.486340 | instance |     "wgrp": false,
2026-08-22 14:33:12.486382 | instance |     "woth": false,
2026-08-22 14:33:12.486422 | instance |     "writeable": true,
2026-08-22 14:33:12.486460 | instance |     "wusr": true,
2026-08-22 14:33:12.486499 | instance |     "xgrp": true,
2026-08-22 14:33:12.486538 | instance |     "xoth": true,
2026-08-22 14:33:12.486575 | instance |     "xusr": true
2026-08-22 14:33:12.486613 | instance |   }
2026-08-22 14:33:12.486651 | instance | }
2026-08-22 14:33:12.486744 | instance | ok: All items complete
2026-08-22 14:33:12.486791 | 
2026-08-22 14:33:12.495738 | instance | skipping: Conditional result was False
2026-08-22 14:33:12.496692 | instance | skipping: Conditional result was False
2026-08-22 14:33:12.507478 | 
2026-08-22 14:33:12.507661 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 14:33:12.882296 | instance | changed: 1 replacements made
2026-08-22 14:33:12.884820 | instance | skipping: Conditional result was False
2026-08-22 14:33:12.887763 | instance | skipping: Conditional result was False
2026-08-22 14:33:12.904243 | 
2026-08-22 14:33:12.904425 | TASK [Run Molecule scenario]
2026-08-22 14:33:13.863181 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-22 14:33:14.280238 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 14:33:14.280346 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-22 14:33:25.469461 | instance | INFO     [csi > dependency] Executing
2026-08-22 14:33:25.469647 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-22 14:33:25.469935 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-22 14:33:25.470085 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 14:33:25.478685 | instance | INFO     [csi > cleanup] Executing
2026-08-22 14:33:25.478988 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 14:33:25.486685 | instance | INFO     [csi > destroy] Executing
2026-08-22 14:33:25.486721 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-22 14:33:25.486809 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-22 14:33:25.494216 | instance | INFO     [csi > syntax] Executing
2026-08-22 14:33:27.118892 | instance |
2026-08-22 14:33:27.119079 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 14:33:27.183121 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-22 14:33:27.193079 | instance | INFO     [csi > create] Executing
2026-08-22 14:33:27.203067 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 14:33:27.215215 | instance | INFO     [csi > prepare] Executing
2026-08-22 14:33:28.038014 | instance |
2026-08-22 14:33:28.038257 | instance | PLAY [Prepare] *****************************************************************
2026-08-22 14:33:28.038513 | instance |
2026-08-22 14:33:28.038923 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 14:33:28.039300 | instance | Saturday 22 August 2026  14:33:28 +0000 (0:00:00.027)       0:00:00.027 *******
2026-08-22 14:33:29.169820 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 14:33:29.170220 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 14:33:29.170652 | instance | interpreter could change the meaning of that path. See
2026-08-22 14:33:29.171087 | instance | https://docs.ansible.com/ansible-
2026-08-22 14:33:29.171489 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 14:33:29.179500 | instance | ok: [instance]
2026-08-22 14:33:29.179626 | instance |
2026-08-22 14:33:29.179780 | instance | TASK [Configure short hostname] ************************************************
2026-08-22 14:33:29.179935 | instance | Saturday 22 August 2026  14:33:29 +0000 (0:00:01.144)       0:00:01.171 *******
2026-08-22 14:33:29.839967 | instance | changed: [instance]
2026-08-22 14:33:29.840354 | instance |
2026-08-22 14:33:29.840758 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 14:33:29.841073 | instance | Saturday 22 August 2026  14:33:29 +0000 (0:00:00.659)       0:00:01.831 *******
2026-08-22 14:33:30.119066 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 14:33:30.119400 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 14:33:30.119735 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 14:33:30.126709 | instance | changed: [instance]
2026-08-22 14:33:30.127090 | instance |
2026-08-22 14:33:30.127415 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-22 14:33:30.127692 | instance | Saturday 22 August 2026  14:33:30 +0000 (0:00:00.286)       0:00:02.118 *******
2026-08-22 14:33:30.916795 | instance | ok: [instance]
2026-08-22 14:33:30.917028 | instance |
2026-08-22 14:33:30.917315 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-22 14:33:30.917573 | instance |
2026-08-22 14:33:30.917853 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 14:33:30.918178 | instance | Saturday 22 August 2026  14:33:30 +0000 (0:00:00.789)       0:00:02.908 *******
2026-08-22 14:33:31.585424 | instance | ok: [instance]
2026-08-22 14:33:31.585675 | instance |
2026-08-22 14:33:31.586036 | instance | TASK [Install depedencies] *****************************************************
2026-08-22 14:33:31.586400 | instance | Saturday 22 August 2026  14:33:31 +0000 (0:00:00.669)       0:00:03.577 *******
2026-08-22 14:33:50.863619 | instance | changed: [instance]
2026-08-22 14:33:50.863818 | instance |
2026-08-22 14:33:50.864109 | instance | TASK [Start up service] ********************************************************
2026-08-22 14:33:50.864366 | instance | Saturday 22 August 2026  14:33:50 +0000 (0:00:19.277)       0:00:22.854 *******
2026-08-22 14:33:51.371852 | instance | ok: [instance]
2026-08-22 14:33:51.372055 | instance |
2026-08-22 14:33:51.372345 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-22 14:33:51.372587 | instance | Saturday 22 August 2026  14:33:51 +0000 (0:00:00.508)       0:00:23.363 *******
2026-08-22 14:33:51.654265 | instance | ok: [instance]
2026-08-22 14:33:51.654481 | instance |
2026-08-22 14:33:51.654810 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 14:33:51.655075 | instance | Saturday 22 August 2026  14:33:51 +0000 (0:00:00.282)       0:00:23.646 *******
2026-08-22 14:33:52.348173 | instance | changed: [instance]
2026-08-22 14:33:52.348412 | instance |
2026-08-22 14:33:52.348696 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-22 14:33:52.348976 | instance | Saturday 22 August 2026  14:33:52 +0000 (0:00:00.693)       0:00:24.339 *******
2026-08-22 14:33:52.535983 | instance | ok: [instance]
2026-08-22 14:33:52.536214 | instance |
2026-08-22 14:33:52.536487 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 14:33:52.536759 | instance | Saturday 22 August 2026  14:33:52 +0000 (0:00:00.187)       0:00:24.527 *******
2026-08-22 14:33:52.557988 | instance | skipping: [instance]
2026-08-22 14:33:52.558235 | instance |
2026-08-22 14:33:52.558504 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-22 14:33:52.558918 | instance | Saturday 22 August 2026  14:33:52 +0000 (0:00:00.022)       0:00:24.550 *******
2026-08-22 14:33:53.091382 | instance | changed: [instance] => (item=osd0)
2026-08-22 14:33:53.091650 | instance | changed: [instance] => (item=osd1)
2026-08-22 14:33:53.091958 | instance | changed: [instance] => (item=osd2)
2026-08-22 14:33:53.092239 | instance |
2026-08-22 14:33:53.092578 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-22 14:33:53.092888 | instance | Saturday 22 August 2026  14:33:53 +0000 (0:00:00.532)       0:00:25.082 *******
2026-08-22 14:33:53.690676 | instance | changed: [instance] => (item=osd0)
2026-08-22 14:33:53.690749 | instance | changed: [instance] => (item=osd1)
2026-08-22 14:33:53.691871 | instance | changed: [instance] => (item=osd2)
2026-08-22 14:33:53.691933 | instance |
2026-08-22 14:33:53.691946 | instance | TASK [Start loop devices] ******************************************************
2026-08-22 14:33:53.691957 | instance | Saturday 22 August 2026  14:33:53 +0000 (0:00:00.599)       0:00:25.682 *******
2026-08-22 14:33:54.368185 | instance | changed: [instance] => (item=osd0)
2026-08-22 14:33:54.368260 | instance | changed: [instance] => (item=osd1)
2026-08-22 14:33:54.368768 | instance | changed: [instance] => (item=osd2)
2026-08-22 14:33:54.368806 | instance |
2026-08-22 14:33:54.368811 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-22 14:33:54.368816 | instance | Saturday 22 August 2026  14:33:54 +0000 (0:00:00.677)       0:00:26.360 *******
2026-08-22 14:33:57.427781 | instance | changed: [instance] => (item=osd0)
2026-08-22 14:33:57.428677 | instance | changed: [instance] => (item=osd1)
2026-08-22 14:33:57.428726 | instance | changed: [instance] => (item=osd2)
2026-08-22 14:33:57.428734 | instance |
2026-08-22 14:33:57.428741 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 14:33:57.428749 | instance | Saturday 22 August 2026  14:33:57 +0000 (0:00:03.059)       0:00:29.419 *******
2026-08-22 14:33:59.306649 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-22 14:33:59.306698 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-22 14:33:59.306719 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-22 14:33:59.306725 | instance |
2026-08-22 14:33:59.306731 | instance | PLAY RECAP *********************************************************************
2026-08-22 14:33:59.306737 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 14:33:59.306743 | instance |
2026-08-22 14:33:59.306749 | instance | Saturday 22 August 2026  14:33:59 +0000 (0:00:01.876)       0:00:31.296 *******
2026-08-22 14:33:59.306754 | instance | ===============================================================================
2026-08-22 14:33:59.306760 | instance | Install depedencies ---------------------------------------------------- 19.28s
2026-08-22 14:33:59.306765 | instance | Create a volume group for each loop device ------------------------------ 3.06s
2026-08-22 14:33:59.306771 | instance | Create a logical volume for each loop device ---------------------------- 1.88s
2026-08-22 14:33:59.306776 | instance | Gathering Facts --------------------------------------------------------- 1.14s
2026-08-22 14:33:59.306782 | instance | Purge "snapd" package --------------------------------------------------- 0.79s
2026-08-22 14:33:59.306787 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.69s
2026-08-22 14:33:59.306793 | instance | Start loop devices ------------------------------------------------------ 0.68s
2026-08-22 14:33:59.306798 | instance | Gathering Facts --------------------------------------------------------- 0.67s
2026-08-22 14:33:59.306803 | instance | Configure short hostname ------------------------------------------------ 0.66s
2026-08-22 14:33:59.306814 | instance | Set permissions on loopback devices ------------------------------------- 0.60s
2026-08-22 14:33:59.307766 | instance | Create devices for Ceph ------------------------------------------------- 0.53s
2026-08-22 14:33:59.307835 | instance | Start up service -------------------------------------------------------- 0.51s
2026-08-22 14:33:59.307842 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-08-22 14:33:59.307849 | instance | Generate lvm.conf ------------------------------------------------------- 0.28s
2026-08-22 14:33:59.307855 | instance | Get list of all loopback devices ---------------------------------------- 0.19s
2026-08-22 14:33:59.307861 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-22 14:33:59.382127 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-22 14:33:59.394569 | instance | INFO     [csi > converge] Executing
2026-08-22 14:34:00.873617 | instance |
2026-08-22 14:34:00.874099 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-22 14:34:00.874430 | instance |
2026-08-22 14:34:00.874816 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 14:34:00.875157 | instance | Saturday 22 August 2026  14:34:00 +0000 (0:00:00.007)       0:00:00.007 *******
2026-08-22 14:34:01.797334 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 14:34:01.797893 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 14:34:01.798395 | instance | interpreter could change the meaning of that path. See
2026-08-22 14:34:01.798889 | instance | https://docs.ansible.com/ansible-
2026-08-22 14:34:01.799207 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 14:34:01.808396 | instance | ok: [instance]
2026-08-22 14:34:01.808668 | instance |
2026-08-22 14:34:01.808978 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-22 14:34:01.809277 | instance | Saturday 22 August 2026  14:34:01 +0000 (0:00:00.935)       0:00:00.942 *******
2026-08-22 14:34:01.854130 | instance | ok: [instance] => {
2026-08-22 14:34:01.854359 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-22 14:34:01.854616 | instance | }
2026-08-22 14:34:01.854814 | instance |
2026-08-22 14:34:01.855010 | instance | PLAY [all] *********************************************************************
2026-08-22 14:34:01.855195 | instance |
2026-08-22 14:34:01.855389 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 14:34:01.855633 | instance | Saturday 22 August 2026  14:34:01 +0000 (0:00:00.046)       0:00:00.989 *******
2026-08-22 14:34:02.697367 | instance | ok: [instance]
2026-08-22 14:34:02.697756 | instance |
2026-08-22 14:34:02.698097 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-08-22 14:34:02.698434 | instance | Saturday 22 August 2026  14:34:02 +0000 (0:00:00.842)       0:00:01.831 *******
2026-08-22 14:34:02.733972 | instance | skipping: [instance]
2026-08-22 14:34:02.734336 | instance |
2026-08-22 14:34:02.734748 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-22 14:34:02.735106 | instance | Saturday 22 August 2026  14:34:02 +0000 (0:00:00.037)       0:00:01.868 *******
2026-08-22 14:34:02.912970 | instance | ok: [instance]
2026-08-22 14:34:02.913348 | instance |
2026-08-22 14:34:02.913716 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 14:34:02.914032 | instance |
2026-08-22 14:34:02.914358 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 14:34:02.914736 | instance | Saturday 22 August 2026  14:34:02 +0000 (0:00:00.178)       0:00:02.047 *******
2026-08-22 14:34:03.767666 | instance | ok: [instance]
2026-08-22 14:34:03.767704 | instance |
2026-08-22 14:34:03.767709 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 14:34:03.767714 | instance | Saturday 22 August 2026  14:34:03 +0000 (0:00:00.854)       0:00:02.901 *******
2026-08-22 14:34:04.080492 | instance | ok: [instance]
2026-08-22 14:34:04.080563 | instance |
2026-08-22 14:34:04.080919 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 14:34:04.080956 | instance | Saturday 22 August 2026  14:34:04 +0000 (0:00:00.313)       0:00:03.214 *******
2026-08-22 14:34:04.120224 | instance | skipping: [instance]
2026-08-22 14:34:04.120596 | instance |
2026-08-22 14:34:04.120636 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 14:34:04.120642 | instance | Saturday 22 August 2026  14:34:04 +0000 (0:00:00.039)       0:00:03.254 *******
2026-08-22 14:34:04.461561 | instance | changed: [instance]
2026-08-22 14:34:04.461631 | instance |
2026-08-22 14:34:04.461931 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 14:34:04.461975 | instance | Saturday 22 August 2026  14:34:04 +0000 (0:00:00.341)       0:00:03.595 *******
2026-08-22 14:34:04.532194 | instance | ok: [instance] => {
2026-08-22 14:34:04.532275 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 14:34:04.532809 | instance | }
2026-08-22 14:34:04.532845 | instance |
2026-08-22 14:34:04.532851 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 14:34:04.532856 | instance | Saturday 22 August 2026  14:34:04 +0000 (0:00:00.070)       0:00:03.666 *******
2026-08-22 14:34:05.182274 | instance | changed: [instance]
2026-08-22 14:34:05.182351 | instance |
2026-08-22 14:34:05.182672 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 14:34:05.182743 | instance | Saturday 22 August 2026  14:34:05 +0000 (0:00:00.649)       0:00:04.316 *******
2026-08-22 14:34:05.235958 | instance | skipping: [instance]
2026-08-22 14:34:05.236058 | instance |
2026-08-22 14:34:05.236218 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 14:34:05.236403 | instance | Saturday 22 August 2026  14:34:05 +0000 (0:00:00.053)       0:00:04.370 *******
2026-08-22 14:34:05.285202 | instance | skipping: [instance]
2026-08-22 14:34:05.285278 | instance |
2026-08-22 14:34:05.285404 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 14:34:05.285592 | instance | Saturday 22 August 2026  14:34:05 +0000 (0:00:00.049)       0:00:04.419 *******
2026-08-22 14:34:05.488024 | instance | ok: [instance]
2026-08-22 14:34:05.488136 | instance |
2026-08-22 14:34:05.488327 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 14:34:05.488592 | instance | Saturday 22 August 2026  14:34:05 +0000 (0:00:00.202)       0:00:04.622 *******
2026-08-22 14:34:06.638645 | instance | ok: [instance]
2026-08-22 14:34:06.638727 | instance |
2026-08-22 14:34:06.638836 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 14:34:06.639039 | instance | Saturday 22 August 2026  14:34:06 +0000 (0:00:01.150)       0:00:05.773 *******
2026-08-22 14:34:06.710306 | instance | ok: [instance] => {
2026-08-22 14:34:06.710553 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 14:34:06.710787 | instance | }
2026-08-22 14:34:06.710992 | instance |
2026-08-22 14:34:06.711194 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 14:34:06.711386 | instance | Saturday 22 August 2026  14:34:06 +0000 (0:00:00.068)       0:00:05.841 *******
2026-08-22 14:34:07.611791 | instance | changed: [instance]
2026-08-22 14:34:07.612283 | instance |
2026-08-22 14:34:07.612638 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 14:34:07.613007 | instance | Saturday 22 August 2026  14:34:07 +0000 (0:00:00.903)       0:00:06.745 *******
2026-08-22 14:34:10.384500 | instance | changed: [instance]
2026-08-22 14:34:10.384558 | instance |
2026-08-22 14:34:10.384569 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 14:34:10.384579 | instance | Saturday 22 August 2026  14:34:10 +0000 (0:00:02.772)       0:00:09.517 *******
2026-08-22 14:34:10.416935 | instance | skipping: [instance]
2026-08-22 14:34:10.416976 | instance |
2026-08-22 14:34:10.416987 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 14:34:10.416997 | instance | Saturday 22 August 2026  14:34:10 +0000 (0:00:00.032)       0:00:09.550 *******
2026-08-22 14:34:10.448563 | instance | skipping: [instance]
2026-08-22 14:34:10.448614 | instance |
2026-08-22 14:34:10.448624 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 14:34:10.448634 | instance | Saturday 22 August 2026  14:34:10 +0000 (0:00:00.031)       0:00:09.581 *******
2026-08-22 14:34:10.479822 | instance | skipping: [instance]
2026-08-22 14:34:10.480160 | instance |
2026-08-22 14:34:10.480488 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 14:34:10.480811 | instance | Saturday 22 August 2026  14:34:10 +0000 (0:00:00.032)       0:00:09.614 *******
2026-08-22 14:34:15.325502 | instance | changed: [instance]
2026-08-22 14:34:15.325567 | instance |
2026-08-22 14:34:15.325579 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 14:34:15.325589 | instance | Saturday 22 August 2026  14:34:15 +0000 (0:00:04.844)       0:00:14.458 *******
2026-08-22 14:34:15.896320 | instance | changed: [instance]
2026-08-22 14:34:15.896410 | instance |
2026-08-22 14:34:15.896670 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 14:34:15.896685 | instance | Saturday 22 August 2026  14:34:15 +0000 (0:00:00.572)       0:00:15.030 *******
2026-08-22 14:34:16.787966 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-22 14:34:16.788038 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 14:34:16.788794 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 14:34:16.788909 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 14:34:16.788916 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 14:34:16.788920 | instance |
2026-08-22 14:34:16.788925 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 14:34:16.788929 | instance | Saturday 22 August 2026  14:34:16 +0000 (0:00:00.891)       0:00:15.922 *******
2026-08-22 14:34:17.352312 | instance | changed: [instance]
2026-08-22 14:34:17.352378 | instance |
2026-08-22 14:34:17.353003 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 14:34:17.353041 | instance | Saturday 22 August 2026  14:34:17 +0000 (0:00:00.547)       0:00:16.470 *******
2026-08-22 14:34:17.353046 | instance |
2026-08-22 14:34:17.353051 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 14:34:17.353055 | instance | Saturday 22 August 2026  14:34:17 +0000 (0:00:00.016)       0:00:16.486 *******
2026-08-22 14:34:18.330363 | instance | ok: [instance]
2026-08-22 14:34:18.330499 | instance |
2026-08-22 14:34:18.330966 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 14:34:18.331008 | instance | Saturday 22 August 2026  14:34:18 +0000 (0:00:00.977)       0:00:17.464 *******
2026-08-22 14:34:18.751581 | instance | changed: [instance]
2026-08-22 14:34:18.751676 | instance |
2026-08-22 14:34:18.751902 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 14:34:18.751935 | instance | Saturday 22 August 2026  14:34:18 +0000 (0:00:00.421)       0:00:17.886 *******
2026-08-22 14:34:19.285636 | instance | changed: [instance]
2026-08-22 14:34:19.285700 | instance |
2026-08-22 14:34:19.285974 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 14:34:19.286013 | instance | Saturday 22 August 2026  14:34:19 +0000 (0:00:00.534)       0:00:18.420 *******
2026-08-22 14:34:19.488358 | instance | ok: [instance]
2026-08-22 14:34:19.488451 | instance |
2026-08-22 14:34:19.488796 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 14:34:19.488836 | instance | Saturday 22 August 2026  14:34:19 +0000 (0:00:00.202)       0:00:18.622 *******
2026-08-22 14:34:19.542807 | instance | ok: [instance] => {
2026-08-22 14:34:19.542879 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 14:34:19.543017 | instance | }
2026-08-22 14:34:19.543661 | instance |
2026-08-22 14:34:19.543702 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 14:34:19.543711 | instance | Saturday 22 August 2026  14:34:19 +0000 (0:00:00.054)       0:00:18.676 *******
2026-08-22 14:34:20.349288 | instance | changed: [instance]
2026-08-22 14:34:20.349360 | instance |
2026-08-22 14:34:20.349654 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 14:34:20.349695 | instance | Saturday 22 August 2026  14:34:20 +0000 (0:00:00.806)       0:00:19.483 *******
2026-08-22 14:34:24.682704 | instance | changed: [instance]
2026-08-22 14:34:24.682788 | instance |
2026-08-22 14:34:24.682803 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 14:34:24.682952 | instance | Saturday 22 August 2026  14:34:24 +0000 (0:00:04.333)       0:00:23.817 *******
2026-08-22 14:34:25.719318 | instance | ok: [instance]
2026-08-22 14:34:25.719386 | instance |
2026-08-22 14:34:25.719665 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 14:34:25.719705 | instance | Saturday 22 August 2026  14:34:25 +0000 (0:00:01.036)       0:00:24.853 *******
2026-08-22 14:34:26.059048 | instance | changed: [instance]
2026-08-22 14:34:26.059195 | instance |
2026-08-22 14:34:26.059377 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 14:34:26.059606 | instance | Saturday 22 August 2026  14:34:26 +0000 (0:00:00.339)       0:00:25.193 *******
2026-08-22 14:34:26.501673 | instance | changed: [instance]
2026-08-22 14:34:26.501763 | instance |
2026-08-22 14:34:26.501925 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 14:34:26.502065 | instance | Saturday 22 August 2026  14:34:26 +0000 (0:00:00.442)       0:00:25.636 *******
2026-08-22 14:34:27.034718 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-22 14:34:27.035002 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 14:34:27.035271 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 14:34:27.035520 | instance |
2026-08-22 14:34:27.035783 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 14:34:27.036046 | instance | Saturday 22 August 2026  14:34:27 +0000 (0:00:00.532)       0:00:26.168 *******
2026-08-22 14:34:27.459233 | instance | changed: [instance]
2026-08-22 14:34:27.459450 | instance |
2026-08-22 14:34:27.459726 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 14:34:27.459986 | instance | Saturday 22 August 2026  14:34:27 +0000 (0:00:00.424)       0:00:26.593 *******
2026-08-22 14:34:27.925942 | instance | changed: [instance]
2026-08-22 14:34:27.926132 | instance |
2026-08-22 14:34:27.926461 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 14:34:27.926779 | instance | Saturday 22 August 2026  14:34:27 +0000 (0:00:00.447)       0:00:27.040 *******
2026-08-22 14:34:27.927071 | instance |
2026-08-22 14:34:27.927349 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 14:34:27.927623 | instance | Saturday 22 August 2026  14:34:27 +0000 (0:00:00.019)       0:00:27.060 *******
2026-08-22 14:34:28.618332 | instance | ok: [instance]
2026-08-22 14:34:28.618392 | instance |
2026-08-22 14:34:28.618404 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 14:34:28.618414 | instance | Saturday 22 August 2026  14:34:28 +0000 (0:00:00.691)       0:00:27.751 *******
2026-08-22 14:34:29.399509 | instance | changed: [instance]
2026-08-22 14:34:29.399592 | instance |
2026-08-22 14:34:29.399846 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 14:34:29.399862 | instance | Saturday 22 August 2026  14:34:29 +0000 (0:00:00.782)       0:00:28.534 *******
2026-08-22 14:34:29.924763 | instance | changed: [instance]
2026-08-22 14:34:29.924839 | instance |
2026-08-22 14:34:29.925077 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 14:34:29.925109 | instance | Saturday 22 August 2026  14:34:29 +0000 (0:00:00.525)       0:00:29.059 *******
2026-08-22 14:34:29.982113 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-22 14:34:29.982197 | instance |
2026-08-22 14:34:29.982470 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 14:34:29.982504 | instance | Saturday 22 August 2026  14:34:29 +0000 (0:00:00.057)       0:00:29.116 *******
2026-08-22 14:34:34.815009 | instance | changed: [instance]
2026-08-22 14:34:34.815080 | instance |
2026-08-22 14:34:34.815347 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 14:34:34.815474 | instance | Saturday 22 August 2026  14:34:34 +0000 (0:00:04.832)       0:00:33.949 *******
2026-08-22 14:34:35.431647 | instance | ok: [instance] => (item=chronyd)
2026-08-22 14:34:35.431752 | instance | ok: [instance] => (item=sshd)
2026-08-22 14:34:35.432315 | instance |
2026-08-22 14:34:35.432369 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 14:34:35.432377 | instance | Saturday 22 August 2026  14:34:35 +0000 (0:00:00.616)       0:00:34.565 *******
2026-08-22 14:34:36.693263 | instance | changed: [instance]
2026-08-22 14:34:36.693334 | instance |
2026-08-22 14:34:36.693600 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 14:34:36.693742 | instance | Saturday 22 August 2026  14:34:36 +0000 (0:00:01.261)       0:00:35.827 *******
2026-08-22 14:34:36.893710 | instance | ok: [instance]
2026-08-22 14:34:36.893788 | instance |
2026-08-22 14:34:36.894049 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 14:34:36.894095 | instance | Saturday 22 August 2026  14:34:36 +0000 (0:00:00.200)       0:00:36.028 *******
2026-08-22 14:34:37.318386 | instance | changed: [instance]
2026-08-22 14:34:37.318426 | instance |
2026-08-22 14:34:37.318431 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 14:34:37.318436 | instance | Saturday 22 August 2026  14:34:37 +0000 (0:00:00.424)       0:00:36.452 *******
2026-08-22 14:34:37.637295 | instance | changed: [instance]
2026-08-22 14:34:37.637363 | instance |
2026-08-22 14:34:37.637654 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 14:34:37.637691 | instance | Saturday 22 August 2026  14:34:37 +0000 (0:00:00.319)       0:00:36.771 *******
2026-08-22 14:34:37.817146 | instance | ok: [instance]
2026-08-22 14:34:37.817207 | instance |
2026-08-22 14:34:37.817505 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 14:34:37.817545 | instance | Saturday 22 August 2026  14:34:37 +0000 (0:00:00.179)       0:00:36.951 *******
2026-08-22 14:34:38.029740 | instance | ok: [instance] => (item=instance)
2026-08-22 14:34:38.029843 | instance |
2026-08-22 14:34:38.029855 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 14:34:38.030020 | instance | Saturday 22 August 2026  14:34:38 +0000 (0:00:00.212)       0:00:37.164 *******
2026-08-22 14:34:38.083220 | instance | ok: [instance]
2026-08-22 14:34:38.083573 | instance |
2026-08-22 14:34:38.083612 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 14:34:38.083618 | instance | Saturday 22 August 2026  14:34:38 +0000 (0:00:00.053)       0:00:37.217 *******
2026-08-22 14:34:38.152723 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-22 14:34:38.152784 | instance |
2026-08-22 14:34:38.152996 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 14:34:38.153018 | instance | Saturday 22 August 2026  14:34:38 +0000 (0:00:00.069)       0:00:37.287 *******
2026-08-22 14:34:38.415671 | instance | changed: [instance]
2026-08-22 14:34:38.415730 | instance |
2026-08-22 14:34:38.416009 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 14:34:38.416046 | instance | Saturday 22 August 2026  14:34:38 +0000 (0:00:00.262)       0:00:37.550 *******
2026-08-22 14:34:39.067766 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-22 14:34:39.067845 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 14:34:39.068356 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 14:34:39.068393 | instance |
2026-08-22 14:34:39.068399 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 14:34:39.068404 | instance | Saturday 22 August 2026  14:34:39 +0000 (0:00:00.652)       0:00:38.202 *******
2026-08-22 14:36:07.721601 | instance | ok: [instance]
2026-08-22 14:36:07.721668 | instance |
2026-08-22 14:36:07.721915 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 14:36:07.721930 | instance | Saturday 22 August 2026  14:36:07 +0000 (0:01:28.653)       0:02:06.856 *******
2026-08-22 14:36:07.943713 | instance | changed: [instance]
2026-08-22 14:36:07.943838 | instance |
2026-08-22 14:36:07.944008 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 14:36:07.944176 | instance | Saturday 22 August 2026  14:36:07 +0000 (0:00:00.221)       0:02:07.078 *******
2026-08-22 14:36:07.985836 | instance | ok: [instance]
2026-08-22 14:36:07.985904 | instance |
2026-08-22 14:36:07.986038 | instance | TASK [Install Ceph host] *******************************************************
2026-08-22 14:36:07.986160 | instance | Saturday 22 August 2026  14:36:07 +0000 (0:00:00.042)       0:02:07.120 *******
2026-08-22 14:36:08.068219 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-22 14:36:08.068285 | instance |
2026-08-22 14:36:08.068513 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 14:36:08.068530 | instance | Saturday 22 August 2026  14:36:08 +0000 (0:00:00.082)       0:02:07.202 *******
2026-08-22 14:36:09.661910 | instance | ok: [instance]
2026-08-22 14:36:09.661992 | instance |
2026-08-22 14:36:09.662122 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 14:36:09.662243 | instance | Saturday 22 August 2026  14:36:09 +0000 (0:00:01.593)       0:02:08.796 *******
2026-08-22 14:36:09.722261 | instance | ok: [instance] => (item=instance)
2026-08-22 14:36:09.722339 | instance |
2026-08-22 14:36:09.722432 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 14:36:09.722551 | instance | Saturday 22 August 2026  14:36:09 +0000 (0:00:00.060)       0:02:08.856 *******
2026-08-22 14:36:10.072541 | instance | ok: [instance]
2026-08-22 14:36:10.072619 | instance |
2026-08-22 14:36:10.072910 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 14:36:10.072945 | instance | Saturday 22 August 2026  14:36:10 +0000 (0:00:00.350)       0:02:09.206 *******
2026-08-22 14:36:13.102169 | instance | ok: [instance]
2026-08-22 14:36:13.102247 | instance |
2026-08-22 14:36:13.102505 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 14:36:13.102542 | instance | Saturday 22 August 2026  14:36:13 +0000 (0:00:03.029)       0:02:12.236 *******
2026-08-22 14:36:56.997370 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-22 14:36:56.998274 | 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_08umj12p/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_08umj12p/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_08umj12p/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_08umj12p/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-22T14:36:56.838+0000 7f37471f0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCrs4lqypbVHiAA7iXZor3ZAg4MpS2NMEoZ6TMuQY49MDYTrbz0mLF9rIU=: Malformed input [buffer:3]\n2026-08-22T14:36:56.838+0000 7f37471f0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T14:36:56.838+0000 7f37471f0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCrs4lqypbVHiAA7iXZor3ZAg4MpS2NMEoZ6TMuQY49MDYTrbz0mLF9rIU=: Malformed input [buffer:3]\n2026-08-22T14:36:56.838+0000 7f37471f0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T14:36:56.838+0000 7f37471f0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCrs4lqypbVHiAA7iXZor3ZAg4MpS2NMEoZ6TMuQY49MDYTrbz0mLF9rIU=: Malformed input [buffer:3]\n2026-08-22T14:36:56.838+0000 7f37471f0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T14:36:56.838+0000 7f37471f0640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-22 14:36:57.001866 | instance |
2026-08-22 14:36:57.002076 | instance | PLAY RECAP *********************************************************************
2026-08-22 14:36:57.006031 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-08-22 14:36:57.006089 | instance |
2026-08-22 14:36:57.006104 | instance | Saturday 22 August 2026  14:36:56 +0000 (0:00:43.899)       0:02:56.136 *******
2026-08-22 14:36:57.006111 | instance | ===============================================================================
2026-08-22 14:36:57.006117 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 88.65s
2026-08-22 14:36:57.006122 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 43.90s
2026-08-22 14:36:57.006127 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 4.84s
2026-08-22 14:36:57.006133 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.83s
2026-08-22 14:36:57.006138 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.33s
2026-08-22 14:36:57.006144 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 3.03s
2026-08-22 14:36:57.006163 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.77s
2026-08-22 14:36:57.006169 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.67s
2026-08-22 14:36:57.006174 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.59s
2026-08-22 14:36:57.006180 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.26s
2026-08-22 14:36:57.006185 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.15s
2026-08-22 14:36:57.006190 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.04s
2026-08-22 14:36:57.006196 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-08-22 14:36:57.006201 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.90s
2026-08-22 14:36:57.006206 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.89s
2026-08-22 14:36:57.006212 | instance | Gathering Facts --------------------------------------------------------- 0.85s
2026-08-22 14:36:57.006217 | instance | Gathering Facts --------------------------------------------------------- 0.84s
2026-08-22 14:36:57.006222 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.81s
2026-08-22 14:36:57.006228 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.78s
2026-08-22 14:36:57.006233 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.65s
2026-08-22 14:36:57.091284 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 14:36:57.091563 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-22 14:36:57.091759 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 14:36:57.531295 | instance | ERROR
2026-08-22 14:36:57.531526 | instance | {
2026-08-22 14:36:57.531567 | instance |   "delta": "0:03:43.858627",
2026-08-22 14:36:57.531596 | instance |   "end": "2026-08-22 14:36:57.142054",
2026-08-22 14:36:57.531622 | instance |   "msg": "non-zero return code",
2026-08-22 14:36:57.531647 | instance |   "rc": 2,
2026-08-22 14:36:57.531672 | instance |   "start": "2026-08-22 14:33:13.283427"
2026-08-22 14:36:57.535137 | instance | }
failure
2026-08-22 14:36:57.541722 | 
2026-08-22 14:36:57.541773 | PLAY RECAP
2026-08-22 14:36:57.541818 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 14:36:57.541840 | 
2026-08-22 14:36:57.716057 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 14:36:57.719848 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 14:36:59.489482 | 
2026-08-22 14:36:59.489649 | PLAY [all]
2026-08-22 14:36:59.504864 | 
2026-08-22 14:36:59.504975 | TASK [gather-host-logs : creating directory for system status]
2026-08-22 14:36:59.928034 | instance | changed
2026-08-22 14:36:59.935699 | 
2026-08-22 14:36:59.935797 | TASK [gather-host-logs : Get logs for each host]
2026-08-22 14:37:00.331981 | instance | + systemd-cgls --full --all --no-pager
2026-08-22 14:37:00.342064 | instance | + ip addr
2026-08-22 14:37:00.344408 | instance | + ip route
2026-08-22 14:37:00.347200 | instance | + lsblk
2026-08-22 14:37:00.350273 | instance | + mount
2026-08-22 14:37:00.353430 | instance | + docker images
2026-08-22 14:37:00.370554 | instance | + brctl show
2026-08-22 14:37:00.371102 | instance | /bin/bash: line 8: brctl: command not found
2026-08-22 14:37:00.371386 | instance | + ps aux --sort=-%mem
2026-08-22 14:37:00.387999 | instance | + dpkg -l
2026-08-22 14:37:00.398696 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-22 14:37:00.399204 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-22 14:37:00.417901 | instance | + '[' '!' -z '' ']'
2026-08-22 14:37:00.474854 | instance | ok: Runtime: 0:00:00.089842
2026-08-22 14:37:00.481823 | 
2026-08-22 14:37:00.481903 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-22 14:37:01.135536 | instance | changed:
2026-08-22 14:37:01.135803 | instance | created directory /var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/work/logs/instance
2026-08-22 14:37:01.135861 | instance | cd+++++++++ system/
2026-08-22 14:37:01.135918 | instance | >f+++++++++ system/brctl-show.txt
2026-08-22 14:37:01.135968 | instance | >f+++++++++ system/docker-images.txt
2026-08-22 14:37:01.136021 | instance | >f+++++++++ system/ip-addr.txt
2026-08-22 14:37:01.136071 | instance | >f+++++++++ system/ip-route.txt
2026-08-22 14:37:01.136113 | instance | >f+++++++++ system/lsblk.txt
2026-08-22 14:37:01.136152 | instance | >f+++++++++ system/mount.txt
2026-08-22 14:37:01.136189 | instance | >f+++++++++ system/packages.txt
2026-08-22 14:37:01.136229 | instance | >f+++++++++ system/ps.txt
2026-08-22 14:37:01.136280 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-22 14:37:01.146782 | 
2026-08-22 14:37:01.146894 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 14:37:01.333876 | instance | changed: "values"
2026-08-22 14:37:01.502533 | instance | changed: "releases"
2026-08-22 14:37:01.516470 | 
2026-08-22 14:37:01.516671 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 14:37:01.725796 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-22 14:37:02.061118 | instance | ok: Runtime: 0:00:00.005735
2026-08-22 14:37:02.066579 | 
2026-08-22 14:37:02.066666 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 14:37:02.667038 | instance | changed:
2026-08-22 14:37:02.667350 | instance | cd+++++++++ helm/
2026-08-22 14:37:02.667447 | instance | cd+++++++++ helm/releases/
2026-08-22 14:37:02.667489 | instance | cd+++++++++ helm/values/
2026-08-22 14:37:02.677675 | 
2026-08-22 14:37:02.677739 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 14:37:02.872100 | instance | changed
2026-08-22 14:37:02.878919 | 
2026-08-22 14:37:02.878995 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 14:37:03.127054 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 14:37:03.127706 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 14:37:03.132703 | instance | environment: line 1: kubectl: command not found
2026-08-22 14:37:03.133604 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 14:37:03.134205 | instance | environment: line 1: kubectl: command not found
2026-08-22 14:37:03.135483 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 14:37:03.137048 | instance | environment: line 1: kubectl: command not found
2026-08-22 14:37:03.137962 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 14:37:03.139919 | instance | environment: line 1: kubectl: command not found
2026-08-22 14:37:03.140717 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 14:37:03.142269 | instance | environment: line 1: kubectl: command not found
2026-08-22 14:37:03.143497 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 14:37:03.415423 | instance | ok: Runtime: 0:00:00.023719
2026-08-22 14:37:03.422318 | 
2026-08-22 14:37:03.422448 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 14:37:03.620196 | instance | changed
2026-08-22 14:37:03.625286 | 
2026-08-22 14:37:03.625362 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 14:37:03.827892 | instance | environment: line 5: kubectl: command not found
2026-08-22 14:37:03.828715 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 14:37:03.828910 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 14:37:03.828928 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 14:37:04.160916 | instance | ok: Runtime: 0:00:00.007748
2026-08-22 14:37:04.169710 | 
2026-08-22 14:37:04.170005 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 14:37:04.655119 | instance | changed:
2026-08-22 14:37:04.655360 | instance | cd+++++++++ objects/
2026-08-22 14:37:04.655402 | instance | cd+++++++++ objects/cluster/
2026-08-22 14:37:04.655435 | instance | cd+++++++++ objects/namespaced/
2026-08-22 14:37:04.665092 | 
2026-08-22 14:37:04.665159 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 14:37:04.858923 | instance | changed
2026-08-22 14:37:04.865294 | 
2026-08-22 14:37:04.865365 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 14:37:05.061307 | instance | changed
2026-08-22 14:37:05.068112 | 
2026-08-22 14:37:05.068248 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 14:37:05.276476 | instance | environment: line 3: kubectl: command not found
2026-08-22 14:37:05.607965 | instance | ok: Runtime: 0:00:00.007933
2026-08-22 14:37:05.615218 | 
2026-08-22 14:37:05.615306 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 14:37:06.083266 | instance | changed:
2026-08-22 14:37:06.083480 | instance | cd+++++++++ pod-logs/
2026-08-22 14:37:06.083571 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-22 14:37:06.094704 | 
2026-08-22 14:37:06.094774 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-22 14:37:06.291156 | instance | changed
2026-08-22 14:37:06.296538 | 
2026-08-22 14:37:06.296607 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-22 14:37:06.505391 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 14:37:06.834180 | instance | ok: Runtime: 0:00:00.037154
2026-08-22 14:37:06.841254 | 
2026-08-22 14:37:06.841350 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-22 14:37:07.042767 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 14:37:07.076925 | instance | ceph-mgr endpoints:
2026-08-22 14:37:07.381770 | instance | ok: Runtime: 0:00:00.040167
2026-08-22 14:37:07.389827 | 
2026-08-22 14:37:07.389910 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-22 14:37:07.594889 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-22 14:37:07.927505 | instance | ok: Runtime: 0:00:00.035456
2026-08-22 14:37:07.935263 | 
2026-08-22 14:37:07.935387 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-22 14:37:08.410035 | instance | changed: cd+++++++++ prometheus/
2026-08-22 14:37:08.420006 | 
2026-08-22 14:37:08.420077 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-22 14:37:08.616415 | instance | changed
2026-08-22 14:37:08.623466 | 
2026-08-22 14:37:08.623555 | TASK [gather-selenium-data : Get selenium data]
2026-08-22 14:37:08.876810 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-22 14:37:08.878447 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-22 14:37:09.161297 | instance | ERROR
2026-08-22 14:37:09.161533 | instance | {
2026-08-22 14:37:09.161589 | instance |   "delta": "0:00:00.006873",
2026-08-22 14:37:09.161619 | instance |   "end": "2026-08-22 14:37:08.878836",
2026-08-22 14:37:09.161648 | instance |   "msg": "non-zero return code",
2026-08-22 14:37:09.161675 | instance |   "rc": 1,
2026-08-22 14:37:09.161709 | instance |   "start": "2026-08-22 14:37:08.871963"
2026-08-22 14:37:09.161734 | instance | }
2026-08-22 14:37:09.161767 | instance | ERROR: Ignoring Errors
2026-08-22 14:37:09.168862 | 
2026-08-22 14:37:09.168973 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-22 14:37:09.651861 | instance | changed: cd+++++++++ selenium/
2026-08-22 14:37:09.657872 | 
2026-08-22 14:37:09.657954 | PLAY RECAP
2026-08-22 14:37:09.658075 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-22 14:37:09.658140 | 
2026-08-22 14:37:09.841062 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 14:37:09.843806 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 14:37:10.535910 | 
2026-08-22 14:37:10.536050 | PLAY [all]
2026-08-22 14:37:10.547972 | 
2026-08-22 14:37:10.548055 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 14:37:10.593630 | instance | skipping: Conditional result was False
2026-08-22 14:37:10.604909 | 
2026-08-22 14:37:10.605069 | TASK [fetch-output : Set log path for single node]
2026-08-22 14:37:10.649457 | instance | ok
2026-08-22 14:37:10.655762 | 
2026-08-22 14:37:10.655856 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 14:37:11.032842 | instance -> localhost | ok: "/var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/work/logs"
2026-08-22 14:37:11.290003 | instance -> localhost | changed: "/var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/work/artifacts"
2026-08-22 14:37:11.527489 | instance -> localhost | changed: "/var/lib/zuul/builds/6907bd444eb04bb486496a869499d14d/work/docs"
2026-08-22 14:37:11.547760 | 
2026-08-22 14:37:11.547930 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 14:37:12.206605 | instance | changed: .d..t...... ./
2026-08-22 14:37:12.206855 | instance | changed: All items complete
2026-08-22 14:37:12.206893 | 
2026-08-22 14:37:12.668645 | instance | changed: .d..t...... ./
2026-08-22 14:37:13.123829 | instance | changed: .d..t...... ./
2026-08-22 14:37:13.150280 | 
2026-08-22 14:37:13.150489 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 14:37:13.589841 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009252
2026-08-22 14:37:13.846369 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007044
2026-08-22 14:37:13.859688 | 
2026-08-22 14:37:13.859782 | PLAY [all]
2026-08-22 14:37:13.868061 | 
2026-08-22 14:37:13.868157 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 14:37:14.287020 | instance | changed
2026-08-22 14:37:14.294159 | 
2026-08-22 14:37:14.294225 | PLAY RECAP
2026-08-22 14:37:14.294287 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 14:37:14.294317 | 
2026-08-22 14:37:14.414980 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 14:37:14.418442 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 14:37:15.021325 | 
2026-08-22 14:37:15.021466 | PLAY [localhost]
2026-08-22 14:37:15.031733 | 
2026-08-22 14:37:15.031804 | TASK [Generate Zuul manifest]
2026-08-22 14:37:15.053661 | localhost | ok
2026-08-22 14:37:15.070743 | 
2026-08-22 14:37:15.070842 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 14:37:15.560117 | localhost | changed
2026-08-22 14:37:15.572604 | 
2026-08-22 14:37:15.572704 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 14:37:15.606479 | localhost | ok
2026-08-22 14:37:15.613990 | 
2026-08-22 14:37:15.614065 | TASK [Upload logs]
2026-08-22 14:37:15.640019 | localhost | ok
2026-08-22 14:37:15.792892 | 
2026-08-22 14:37:15.793081 | TASK [Set zuul-log-path fact]
2026-08-22 14:37:15.817019 | localhost | ok
2026-08-22 14:37:15.828647 | 
2026-08-22 14:37:15.828739 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 14:37:15.871989 | localhost | ok
2026-08-22 14:37:15.883878 | 
2026-08-22 14:37:15.883987 | TASK [upload-logs : Create log directories]
2026-08-22 14:37:16.300285 | localhost | changed
2026-08-22 14:37:16.307631 | 
2026-08-22 14:37:16.307770 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 14:37:16.772995 | localhost -> localhost | ok: Runtime: 0:00:00.006460
2026-08-22 14:37:16.778574 | 
2026-08-22 14:37:16.778644 | TASK [upload-logs : Upload logs to log server]
2026-08-22 14:37:17.301137 | localhost | Output suppressed because no_log was given
2026-08-22 14:37:17.345242 | 
2026-08-22 14:37:17.345403 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 14:37:17.396669 | localhost | skipping: Conditional result was False
2026-08-22 14:37:17.407802 | localhost | skipping: Conditional result was False
2026-08-22 14:37:17.419504 | 
2026-08-22 14:37:17.419743 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 14:37:17.464797 | localhost | skipping: Conditional result was False
2026-08-22 14:37:17.465309 | 
2026-08-22 14:37:17.468854 | localhost | skipping: Conditional result was False
2026-08-22 14:37:17.485376 | 
2026-08-22 14:37:17.485553 | LOOP [upload-logs : Upload console log and json output]