2026-09-16 02:07:03.278295 | Job console starting
2026-09-16 02:07:03.289928 | Updating git repos
2026-09-16 02:07:09.723623 | Cloning repos into workspace
2026-09-16 02:07:28.407203 | Restoring repo states
2026-09-16 02:07:37.190069 | Merging changes
2026-09-16 02:07:38.389092 | Checking out repos
2026-09-16 02:07:40.442493 | Preparing playbooks
2026-09-16 02:07:43.124390 | Running Ansible setup
2026-09-16 02:07:46.552998 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 02:07:47.119538 | 
2026-09-16 02:07:47.119672 | PLAY [localhost]
2026-09-16 02:07:47.126522 | 
2026-09-16 02:07:47.126587 | TASK [Gathering Facts]
2026-09-16 02:07:48.054112 | localhost | ok
2026-09-16 02:07:48.067637 | 
2026-09-16 02:07:48.067836 | TASK [Setup log path fact]
2026-09-16 02:07:48.090185 | localhost | ok
2026-09-16 02:07:48.109630 | 
2026-09-16 02:07:48.109763 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 02:07:48.150860 | localhost | ok
2026-09-16 02:07:48.162104 | 
2026-09-16 02:07:48.162263 | TASK [emit-job-header : Print job information]
2026-09-16 02:07:48.214025 | # Job Information
2026-09-16 02:07:48.220896 | Ansible Version: 2.16.18
2026-09-16 02:07:48.220995 | Job: atmosphere-molecule-csi-rbd
2026-09-16 02:07:48.221042 | Pipeline: check
2026-09-16 02:07:48.221083 | Executor: 2d72f0692154
2026-09-16 02:07:48.221134 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-09-16 02:07:48.221338 | Event ID: cd957bf0-b172-11f1-809d-08cc2b426a5f
2026-09-16 02:07:48.226800 | 
2026-09-16 02:07:48.226921 | LOOP [emit-job-header : Print node information]
2026-09-16 02:07:48.339366 | localhost | ok:
2026-09-16 02:07:48.339976 | localhost | # Node Information
2026-09-16 02:07:48.340039 | localhost | Inventory Hostname: instance
2026-09-16 02:07:48.340083 | localhost | Hostname: np0000192900
2026-09-16 02:07:48.340121 | localhost | Username: zuul
2026-09-16 02:07:48.340184 | localhost | Distro: Ubuntu 22.04
2026-09-16 02:07:48.340225 | localhost | Provider: yul1
2026-09-16 02:07:48.340262 | localhost | Region: ca-ymq-1
2026-09-16 02:07:48.340298 | localhost | Label: ubuntu-jammy
2026-09-16 02:07:48.340333 | localhost | Product Name: OpenStack Nova
2026-09-16 02:07:48.340368 | localhost | Interface IP: 162.253.55.59
2026-09-16 02:07:48.358285 | 
2026-09-16 02:07:48.358518 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-16 02:07:48.792180 | localhost -> localhost | changed
2026-09-16 02:07:48.802222 | 
2026-09-16 02:07:48.802358 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-16 02:07:49.718082 | localhost -> localhost | changed
2026-09-16 02:07:49.724606 | 
2026-09-16 02:07:49.724660 | PLAY [all]
2026-09-16 02:07:49.732923 | 
2026-09-16 02:07:49.732990 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-16 02:07:49.973064 | instance -> localhost | ok
2026-09-16 02:07:50.002424 | 
2026-09-16 02:07:50.002566 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-16 02:07:50.034521 | instance | ok
2026-09-16 02:07:50.051960 | instance | included: /var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-16 02:07:50.057007 | 
2026-09-16 02:07:50.057063 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-16 02:07:51.550406 | instance -> localhost | Generating public/private rsa key pair.
2026-09-16 02:07:51.550767 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/work/1052396110484c86b8b5712e6431225d_id_rsa
2026-09-16 02:07:51.550840 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/work/1052396110484c86b8b5712e6431225d_id_rsa.pub
2026-09-16 02:07:51.550886 | instance -> localhost | The key fingerprint is:
2026-09-16 02:07:51.550928 | instance -> localhost | SHA256:BrC51oO+d08d0Af3K4hdagaoA2xN1SO/jAzlYEocVgA zuul-build-sshkey
2026-09-16 02:07:51.550989 | instance -> localhost | The key's randomart image is:
2026-09-16 02:07:51.551031 | instance -> localhost | +---[RSA 3072]----+
2026-09-16 02:07:51.551080 | instance -> localhost | | Eo+=o...   . .  |
2026-09-16 02:07:51.551121 | instance -> localhost | |  ooo* o.o . o . |
2026-09-16 02:07:51.551185 | instance -> localhost | |  .+=.=.o.o ... .|
2026-09-16 02:07:51.551227 | instance -> localhost | |  ...=.o .+.+.  .|
2026-09-16 02:07:51.551266 | instance -> localhost | |    +o= S..*.. . |
2026-09-16 02:07:51.551303 | instance -> localhost | |   o  .= oo. ..  |
2026-09-16 02:07:51.551341 | instance -> localhost | |    .     . .    |
2026-09-16 02:07:51.551379 | instance -> localhost | |     .. ..       |
2026-09-16 02:07:51.551421 | instance -> localhost | |    .. . ..      |
2026-09-16 02:07:51.551459 | instance -> localhost | +----[SHA256]-----+
2026-09-16 02:07:51.551791 | instance -> localhost | ok: Runtime: 0:00:01.083136
2026-09-16 02:07:51.563459 | 
2026-09-16 02:07:51.563586 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-16 02:07:51.596448 | instance | ok
2026-09-16 02:07:51.611546 | instance | included: /var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-16 02:07:51.620313 | 
2026-09-16 02:07:51.620387 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-16 02:07:51.644480 | instance | skipping: Conditional result was False
2026-09-16 02:07:51.654169 | 
2026-09-16 02:07:51.654250 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-16 02:07:52.123118 | instance | changed
2026-09-16 02:07:52.131311 | 
2026-09-16 02:07:52.131395 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-16 02:07:52.313862 | instance | ok
2026-09-16 02:07:52.318825 | 
2026-09-16 02:07:52.318893 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-16 02:07:52.748593 | instance | changed
2026-09-16 02:07:52.753348 | 
2026-09-16 02:07:52.753752 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-16 02:07:53.233987 | instance | changed
2026-09-16 02:07:53.247781 | 
2026-09-16 02:07:53.247928 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-16 02:07:53.276367 | instance | skipping: Conditional result was False
2026-09-16 02:07:53.291422 | 
2026-09-16 02:07:53.291528 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-16 02:07:53.690273 | instance -> localhost | changed
2026-09-16 02:07:53.709746 | 
2026-09-16 02:07:53.709870 | TASK [add-build-sshkey : Add back temp key]
2026-09-16 02:07:54.027425 | instance -> localhost | Identity added: /var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/work/1052396110484c86b8b5712e6431225d_id_rsa (zuul-build-sshkey)
2026-09-16 02:07:54.027735 | instance -> localhost | ok: Runtime: 0:00:00.019624
2026-09-16 02:07:54.037415 | 
2026-09-16 02:07:54.037532 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-16 02:07:54.368782 | instance | ok
2026-09-16 02:07:54.379701 | 
2026-09-16 02:07:54.379836 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-16 02:07:54.397892 | instance | skipping: Conditional result was False
2026-09-16 02:07:54.420889 | 
2026-09-16 02:07:54.420974 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-16 02:07:54.749881 | instance | ok
2026-09-16 02:07:54.760405 | 
2026-09-16 02:07:54.761192 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-16 02:07:58.299120 | instance | Output suppressed because no_log was given
2026-09-16 02:07:58.309515 | 
2026-09-16 02:07:58.309595 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-16 02:07:58.542341 | instance | ok: "logs"
2026-09-16 02:07:58.542695 | instance | ok: All items complete
2026-09-16 02:07:58.542749 | 
2026-09-16 02:07:58.723324 | instance | ok: "artifacts"
2026-09-16 02:07:58.880915 | instance | ok: "docs"
2026-09-16 02:07:58.893734 | 
2026-09-16 02:07:58.893898 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-16 02:07:59.068412 | instance | changed: "logs"
2026-09-16 02:07:59.210297 | instance | changed: "artifacts"
2026-09-16 02:07:59.352094 | instance | changed: "docs"
2026-09-16 02:07:59.369584 | 
2026-09-16 02:07:59.369770 | PLAY RECAP
2026-09-16 02:07:59.369869 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-16 02:07:59.369931 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 02:07:59.369976 | 
2026-09-16 02:07:59.555577 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 02:07:59.559262 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 02:08:00.200134 | 
2026-09-16 02:08:00.200261 | PLAY [all]
2026-09-16 02:08:00.209789 | 
2026-09-16 02:08:00.209860 | TASK [setup-uv : Extract archive]
2026-09-16 02:08:02.658953 | instance | changed
2026-09-16 02:08:02.670599 | 
2026-09-16 02:08:02.670810 | TASK [setup-uv : Print version]
2026-09-16 02:08:03.030374 | instance | uv 0.8.13
2026-09-16 02:08:03.217035 | instance | ok: Runtime: 0:00:00.012160
2026-09-16 02:08:03.236251 | 
2026-09-16 02:08:03.236387 | TASK [Install Ansible collection siblings]
2026-09-16 02:08:13.011290 | instance | changed:
2026-09-16 02:08:13.011479 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 02:08:13.011506 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 02:08:13.011525 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 02:08:13.011542 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 02:08:13.011558 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 02:08:13.011578 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 02:08:13.011595 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 02:08:13.011613 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 02:08:13.011630 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 02:08:13.011646 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 02:08:13.011663 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 02:08:13.011678 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 02:08:13.011694 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-16 02:08:13.011710 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-16 02:08:13.011726 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-16 02:08:13.011742 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-16 02:08:13.011758 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-16 02:08:13.011774 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-16 02:08:13.011789 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-16 02:08:13.011804 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-16 02:08:13.011820 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-16 02:08:13.011836 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-16 02:08:13.011854 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 02:08:13.011885 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-16 02:08:13.011902 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 02:08:13.011918 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 02:08:13.011933 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-16 02:08:13.011949 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-16 02:08:13.011964 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 02:08:13.011980 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-16 02:08:13.011996 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-16 02:08:13.012012 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 02:08:13.012027 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 02:08:13.012043 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-16 02:08:13.012058 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 02:08:13.012080 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.012095 | instance | Process install dependency map
2026-09-16 02:08:13.012111 | instance | Starting collection install process
2026-09-16 02:08:13.012126 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-16 02:08:13.012141 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-16 02:08:13.012157 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-16 02:08:13.012184 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-16 02:08:13.012201 | instance | Creating virtual environment at: .venv
2026-09-16 02:08:13.012216 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 02:08:13.012232 | instance | Downloading openstacksdk (1.7MiB)
2026-09-16 02:08:13.012248 | instance | Downloading cryptography (4.2MiB)
2026-09-16 02:08:13.012264 | instance | Downloading rjsonnet (1.2MiB)
2026-09-16 02:08:13.012279 | instance | Downloading netaddr (2.2MiB)
2026-09-16 02:08:13.012294 | instance | Downloading kubernetes (1.9MiB)
2026-09-16 02:08:13.012309 | instance | Downloading pygments (1.2MiB)
2026-09-16 02:08:13.012325 | instance | Downloading ansible-core (2.1MiB)
2026-09-16 02:08:13.012340 | instance | Downloading setuptools (1.1MiB)
2026-09-16 02:08:13.012356 | instance |    Building pyperclip==1.9.0
2026-09-16 02:08:13.012372 | instance |  Downloading rjsonnet
2026-09-16 02:08:13.012389 | instance |  Downloading netaddr
2026-09-16 02:08:13.012407 | instance |  Downloading pygments
2026-09-16 02:08:13.012425 | instance |  Downloading cryptography
2026-09-16 02:08:13.012440 | instance |  Downloading setuptools
2026-09-16 02:08:13.012456 | instance |  Downloading kubernetes
2026-09-16 02:08:13.012471 | instance |  Downloading ansible-core
2026-09-16 02:08:13.012486 | instance |  Downloading openstacksdk
2026-09-16 02:08:13.012527 | instance |       Built pyperclip==1.9.0
2026-09-16 02:08:13.012551 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 02:08:13.012570 | instance | Installed 79 packages in 120ms
2026-09-16 02:08:13.012585 | instance | Checked out ansible.posix to 1.6.0
2026-09-16 02:08:13.012601 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 02:08:13.012616 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.012632 | instance | Process install dependency map
2026-09-16 02:08:13.012651 | instance | Starting collection install process
2026-09-16 02:08:13.012667 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-16 02:08:13.012682 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-16 02:08:13.012696 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-16 02:08:13.012711 | instance | Checked out ansible.utils to v6.1.0
2026-09-16 02:08:13.012726 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 02:08:13.012741 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.012756 | instance | Process install dependency map
2026-09-16 02:08:13.012771 | instance | Starting collection install process
2026-09-16 02:08:13.012785 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-16 02:08:13.012800 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-16 02:08:13.012815 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-16 02:08:13.012835 | instance | Checked out atmosphere.common to v0.6.0
2026-09-16 02:08:13.012859 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 02:08:13.012882 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.012899 | instance | Process install dependency map
2026-09-16 02:08:13.012914 | instance | Starting collection install process
2026-09-16 02:08:13.012930 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-16 02:08:13.012945 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-16 02:08:13.012960 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-16 02:08:13.012974 | instance | Checked out community.crypto to 2.2.3
2026-09-16 02:08:13.012990 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 02:08:13.013005 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.013020 | instance | Process install dependency map
2026-09-16 02:08:13.013035 | instance | Starting collection install process
2026-09-16 02:08:13.013050 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-16 02:08:13.013065 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-16 02:08:13.013084 | instance | community.crypto:2.2.3 was installed successfully
2026-09-16 02:08:13.013108 | instance | Checked out community.general to 7.3.0
2026-09-16 02:08:13.013124 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 02:08:13.013140 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.013155 | instance | Process install dependency map
2026-09-16 02:08:13.013182 | instance | Starting collection install process
2026-09-16 02:08:13.013202 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-16 02:08:13.013226 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-16 02:08:13.013246 | instance | community.general:7.3.0 was installed successfully
2026-09-16 02:08:13.013266 | instance | Checked out community.mysql to 3.6.0
2026-09-16 02:08:13.013282 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 02:08:13.013301 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.013324 | instance | Process install dependency map
2026-09-16 02:08:13.013344 | instance | Starting collection install process
2026-09-16 02:08:13.013359 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-16 02:08:13.013374 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-16 02:08:13.013389 | instance | community.mysql:3.6.0 was installed successfully
2026-09-16 02:08:13.013409 | instance | Checked out kubernetes.core to 2.4.0
2026-09-16 02:08:13.013432 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 02:08:13.013452 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.013471 | instance | Process install dependency map
2026-09-16 02:08:13.013490 | instance | Starting collection install process
2026-09-16 02:08:13.013507 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-16 02:08:13.013531 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-16 02:08:13.013552 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-16 02:08:13.013572 | instance | Checked out openstack.cloud to 1.7.0
2026-09-16 02:08:13.013592 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 02:08:13.013613 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.013637 | instance | Process install dependency map
2026-09-16 02:08:13.013659 | instance | Starting collection install process
2026-09-16 02:08:13.013679 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-16 02:08:13.013699 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-16 02:08:13.013719 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-16 02:08:13.013738 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-16 02:08:13.013758 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 02:08:13.013778 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.013798 | instance | Process install dependency map
2026-09-16 02:08:13.013814 | instance | Starting collection install process
2026-09-16 02:08:13.013831 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-16 02:08:13.013847 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-16 02:08:13.013862 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-16 02:08:13.013878 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-16 02:08:13.013898 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 02:08:13.013921 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.013950 | instance | Process install dependency map
2026-09-16 02:08:13.013970 | instance | Starting collection install process
2026-09-16 02:08:13.013990 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-16 02:08:13.014014 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-16 02:08:13.014036 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-16 02:08:13.014056 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-16 02:08:13.014076 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 02:08:13.014097 | instance | Starting galaxy collection install process
2026-09-16 02:08:13.014119 | instance | Process install dependency map
2026-09-16 02:08:13.014139 | instance | Starting collection install process
2026-09-16 02:08:13.014159 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-16 02:08:13.014193 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-16 02:08:13.014214 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-16 02:08:13.019113 | 
2026-09-16 02:08:13.019155 | PLAY RECAP
2026-09-16 02:08:13.019212 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 02:08:13.019233 | 
2026-09-16 02:08:13.128331 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 02:08:13.133640 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 02:08:13.701773 | 
2026-09-16 02:08:13.701911 | PLAY [all]
2026-09-16 02:08:13.712072 | 
2026-09-16 02:08:13.712150 | TASK [Install "jq" for log collection]
2026-09-16 02:08:22.881413 | instance | changed
2026-09-16 02:08:22.887142 | 
2026-09-16 02:08:22.887291 | PLAY RECAP
2026-09-16 02:08:22.887395 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 02:08:22.887494 | 
2026-09-16 02:08:23.062977 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 02:08:23.068005 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 02:08:23.636836 | 
2026-09-16 02:08:23.636980 | PLAY [all]
2026-09-16 02:08:23.648165 | 
2026-09-16 02:08:23.648261 | LOOP [Check inventory directories]
2026-09-16 02:08:24.015741 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-16 02:08:24.164887 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-16 02:08:24.331519 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-16 02:08:24.353989 | 
2026-09-16 02:08:24.354268 | LOOP [Copy inventory file for Zuul]
2026-09-16 02:08:25.257167 | instance | changed:
2026-09-16 02:08:25.257516 | instance | {
2026-09-16 02:08:25.257572 | instance |   "ansible_loop_var": "item",
2026-09-16 02:08:25.257615 | instance |   "changed": false,
2026-09-16 02:08:25.257653 | instance |   "failed": false,
2026-09-16 02:08:25.257696 | instance |   "invocation": {
2026-09-16 02:08:25.257732 | instance |     "module_args": {
2026-09-16 02:08:25.257767 | instance |       "checksum_algorithm": "sha1",
2026-09-16 02:08:25.257802 | instance |       "follow": true,
2026-09-16 02:08:25.257836 | instance |       "get_attributes": true,
2026-09-16 02:08:25.257873 | instance |       "get_checksum": true,
2026-09-16 02:08:25.257908 | instance |       "get_mime": true,
2026-09-16 02:08:25.257942 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-16 02:08:25.257996 | instance |     }
2026-09-16 02:08:25.258031 | instance |   },
2026-09-16 02:08:25.258065 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-16 02:08:25.258098 | instance |   "stat": {
2026-09-16 02:08:25.258131 | instance |     "atime": 1789524485.3875725,
2026-09-16 02:08:25.258164 | instance |     "attr_flags": "e",
2026-09-16 02:08:25.258221 | instance |     "attributes": [
2026-09-16 02:08:25.258256 | instance |       "extents"
2026-09-16 02:08:25.258289 | instance |     ],
2026-09-16 02:08:25.258322 | instance |     "block_size": 4096,
2026-09-16 02:08:25.258355 | instance |     "blocks": 8,
2026-09-16 02:08:25.258388 | instance |     "charset": "unknown",
2026-09-16 02:08:25.258425 | instance |     "ctime": 1789524484.1515028,
2026-09-16 02:08:25.258459 | instance |     "dev": 64513,
2026-09-16 02:08:25.258493 | instance |     "device_type": 0,
2026-09-16 02:08:25.258525 | instance |     "executable": true,
2026-09-16 02:08:25.258558 | instance |     "exists": true,
2026-09-16 02:08:25.258591 | instance |     "gid": 1000,
2026-09-16 02:08:25.258624 | instance |     "gr_name": "zuul",
2026-09-16 02:08:25.258657 | instance |     "inode": 1048097,
2026-09-16 02:08:25.258690 | instance |     "isblk": false,
2026-09-16 02:08:25.258724 | instance |     "ischr": false,
2026-09-16 02:08:25.258757 | instance |     "isdir": true,
2026-09-16 02:08:25.258790 | instance |     "isfifo": false,
2026-09-16 02:08:25.258823 | instance |     "isgid": false,
2026-09-16 02:08:25.258855 | instance |     "islnk": false,
2026-09-16 02:08:25.258887 | instance |     "isreg": false,
2026-09-16 02:08:25.258918 | instance |     "issock": false,
2026-09-16 02:08:25.258950 | instance |     "isuid": false,
2026-09-16 02:08:25.258982 | instance |     "mimetype": "unknown",
2026-09-16 02:08:25.259014 | instance |     "mode": "0755",
2026-09-16 02:08:25.259047 | instance |     "mtime": 1789524484.1515028,
2026-09-16 02:08:25.259081 | instance |     "nlink": 22,
2026-09-16 02:08:25.259114 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-16 02:08:25.259148 | instance |     "pw_name": "zuul",
2026-09-16 02:08:25.259200 | instance |     "readable": true,
2026-09-16 02:08:25.259236 | instance |     "rgrp": true,
2026-09-16 02:08:25.259270 | instance |     "roth": true,
2026-09-16 02:08:25.259304 | instance |     "rusr": true,
2026-09-16 02:08:25.259337 | instance |     "size": 4096,
2026-09-16 02:08:25.259371 | instance |     "uid": 1000,
2026-09-16 02:08:25.259405 | instance |     "version": "296733437",
2026-09-16 02:08:25.259439 | instance |     "wgrp": false,
2026-09-16 02:08:25.259476 | instance |     "woth": false,
2026-09-16 02:08:25.259511 | instance |     "writeable": true,
2026-09-16 02:08:25.259546 | instance |     "wusr": true,
2026-09-16 02:08:25.259578 | instance |     "xgrp": true,
2026-09-16 02:08:25.259612 | instance |     "xoth": true,
2026-09-16 02:08:25.259644 | instance |     "xusr": true
2026-09-16 02:08:25.259678 | instance |   }
2026-09-16 02:08:25.259712 | instance | }
2026-09-16 02:08:25.259775 | instance | ok: All items complete
2026-09-16 02:08:25.259817 | 
2026-09-16 02:08:25.262372 | instance | skipping: Conditional result was False
2026-09-16 02:08:25.263495 | instance | skipping: Conditional result was False
2026-09-16 02:08:25.275966 | 
2026-09-16 02:08:25.276148 | LOOP [Switch "ansible_host" to private IP]
2026-09-16 02:08:25.648105 | instance | changed: 1 replacements made
2026-09-16 02:08:25.655808 | instance | skipping: Conditional result was False
2026-09-16 02:08:25.663747 | instance | skipping: Conditional result was False
2026-09-16 02:08:25.680678 | 
2026-09-16 02:08:25.680870 | TASK [Run Molecule scenario]
2026-09-16 02:08:26.596565 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-16 02:08:27.005078 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-16 02:08:27.005156 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-16 02:08:37.943299 | instance | INFO     [csi > dependency] Executing
2026-09-16 02:08:37.943907 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-16 02:08:37.944357 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-16 02:08:37.944633 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-16 02:08:37.960816 | instance | INFO     [csi > cleanup] Executing
2026-09-16 02:08:37.961453 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 02:08:37.974593 | instance | INFO     [csi > destroy] Executing
2026-09-16 02:08:37.974683 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-16 02:08:37.974791 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-16 02:08:37.984986 | instance | INFO     [csi > syntax] Executing
2026-09-16 02:08:39.509905 | instance |
2026-09-16 02:08:39.510654 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 02:08:39.574336 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-16 02:08:39.592031 | instance | INFO     [csi > create] Executing
2026-09-16 02:08:39.595865 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 02:08:39.610669 | instance | INFO     [csi > prepare] Executing
2026-09-16 02:08:40.417282 | instance |
2026-09-16 02:08:40.417521 | instance | PLAY [Prepare] *****************************************************************
2026-09-16 02:08:40.417776 | instance |
2026-09-16 02:08:40.418059 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 02:08:40.418309 | instance | Wednesday 16 September 2026  02:08:40 +0000 (0:00:00.023)       0:00:00.023 ***
2026-09-16 02:08:41.672756 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 02:08:41.672970 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 02:08:41.673215 | instance | interpreter could change the meaning of that path. See
2026-09-16 02:08:41.673457 | instance | https://docs.ansible.com/ansible-
2026-09-16 02:08:41.673689 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 02:08:41.682608 | instance | ok: [instance]
2026-09-16 02:08:41.682861 | instance |
2026-09-16 02:08:41.683096 | instance | TASK [Configure short hostname] ************************************************
2026-09-16 02:08:41.683334 | instance | Wednesday 16 September 2026  02:08:41 +0000 (0:00:01.266)       0:00:01.289 ***
2026-09-16 02:08:42.468131 | instance | changed: [instance]
2026-09-16 02:08:42.468348 | instance |
2026-09-16 02:08:42.468599 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-16 02:08:42.468881 | instance | Wednesday 16 September 2026  02:08:42 +0000 (0:00:00.784)       0:00:02.074 ***
2026-09-16 02:08:42.764317 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-16 02:08:42.764540 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-16 02:08:42.764812 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-16 02:08:42.775276 | instance | changed: [instance]
2026-09-16 02:08:42.775584 | instance |
2026-09-16 02:08:42.775855 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-16 02:08:42.776114 | instance | Wednesday 16 September 2026  02:08:42 +0000 (0:00:00.307)       0:00:02.382 ***
2026-09-16 02:08:43.626912 | instance | ok: [instance]
2026-09-16 02:08:43.627147 | instance |
2026-09-16 02:08:43.627451 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-16 02:08:43.627733 | instance |
2026-09-16 02:08:43.627996 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 02:08:43.628252 | instance | Wednesday 16 September 2026  02:08:43 +0000 (0:00:00.851)       0:00:03.233 ***
2026-09-16 02:08:44.360018 | instance | ok: [instance]
2026-09-16 02:08:44.360346 | instance |
2026-09-16 02:08:44.360742 | instance | TASK [Install depedencies] *****************************************************
2026-09-16 02:08:44.361791 | instance | Wednesday 16 September 2026  02:08:44 +0000 (0:00:00.732)       0:00:03.966 ***
2026-09-16 02:09:08.050751 | instance | changed: [instance]
2026-09-16 02:09:08.050852 | instance |
2026-09-16 02:09:08.051015 | instance | TASK [Start up service] ********************************************************
2026-09-16 02:09:08.051170 | instance | Wednesday 16 September 2026  02:09:08 +0000 (0:00:23.690)       0:00:27.657 ***
2026-09-16 02:09:08.573541 | instance | ok: [instance]
2026-09-16 02:09:08.573737 | instance |
2026-09-16 02:09:08.573986 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-16 02:09:08.574315 | instance | Wednesday 16 September 2026  02:09:08 +0000 (0:00:00.523)       0:00:28.180 ***
2026-09-16 02:09:08.903900 | instance | ok: [instance]
2026-09-16 02:09:08.904158 | instance |
2026-09-16 02:09:08.904461 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-16 02:09:08.904728 | instance | Wednesday 16 September 2026  02:09:08 +0000 (0:00:00.330)       0:00:28.510 ***
2026-09-16 02:09:09.639949 | instance | changed: [instance]
2026-09-16 02:09:09.639995 | instance |
2026-09-16 02:09:09.640005 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-16 02:09:09.640117 | instance | Wednesday 16 September 2026  02:09:09 +0000 (0:00:00.735)       0:00:29.246 ***
2026-09-16 02:09:09.859691 | instance | ok: [instance]
2026-09-16 02:09:09.860376 | instance |
2026-09-16 02:09:09.860407 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-16 02:09:09.860419 | instance | Wednesday 16 September 2026  02:09:09 +0000 (0:00:00.220)       0:00:29.466 ***
2026-09-16 02:09:09.883803 | instance | skipping: [instance]
2026-09-16 02:09:09.884699 | instance |
2026-09-16 02:09:09.884729 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-16 02:09:09.884740 | instance | Wednesday 16 September 2026  02:09:09 +0000 (0:00:00.024)       0:00:29.491 ***
2026-09-16 02:09:10.468887 | instance | changed: [instance] => (item=osd0)
2026-09-16 02:09:10.468997 | instance | changed: [instance] => (item=osd1)
2026-09-16 02:09:10.469202 | instance | changed: [instance] => (item=osd2)
2026-09-16 02:09:10.469419 | instance |
2026-09-16 02:09:10.469602 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-16 02:09:10.469781 | instance | Wednesday 16 September 2026  02:09:10 +0000 (0:00:00.585)       0:00:30.076 ***
2026-09-16 02:09:11.101092 | instance | changed: [instance] => (item=osd0)
2026-09-16 02:09:11.101333 | instance | changed: [instance] => (item=osd1)
2026-09-16 02:09:11.101672 | instance | changed: [instance] => (item=osd2)
2026-09-16 02:09:11.101957 | instance |
2026-09-16 02:09:11.102275 | instance | TASK [Start loop devices] ******************************************************
2026-09-16 02:09:11.102567 | instance | Wednesday 16 September 2026  02:09:11 +0000 (0:00:00.631)       0:00:30.707 ***
2026-09-16 02:09:11.819000 | instance | changed: [instance] => (item=osd0)
2026-09-16 02:09:11.819235 | instance | changed: [instance] => (item=osd1)
2026-09-16 02:09:11.819605 | instance | changed: [instance] => (item=osd2)
2026-09-16 02:09:11.819898 | instance |
2026-09-16 02:09:11.820222 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-16 02:09:11.820760 | instance | Wednesday 16 September 2026  02:09:11 +0000 (0:00:00.718)       0:00:31.426 ***
2026-09-16 02:09:15.139479 | instance | changed: [instance] => (item=osd0)
2026-09-16 02:09:15.139543 | instance | changed: [instance] => (item=osd1)
2026-09-16 02:09:15.140666 | instance | changed: [instance] => (item=osd2)
2026-09-16 02:09:15.140748 | instance |
2026-09-16 02:09:15.140760 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-16 02:09:15.140771 | instance | Wednesday 16 September 2026  02:09:15 +0000 (0:00:03.319)       0:00:34.745 ***
2026-09-16 02:09:17.109184 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-16 02:09:17.110100 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-16 02:09:17.110145 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-16 02:09:17.110155 | instance |
2026-09-16 02:09:17.110165 | instance | PLAY RECAP *********************************************************************
2026-09-16 02:09:17.110463 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-16 02:09:17.110558 | instance |
2026-09-16 02:09:17.111431 | instance | Wednesday 16 September 2026  02:09:17 +0000 (0:00:01.971)       0:00:36.716 ***
2026-09-16 02:09:17.111470 | instance | ===============================================================================
2026-09-16 02:09:17.111475 | instance | Install depedencies ---------------------------------------------------- 23.69s
2026-09-16 02:09:17.111636 | instance | Create a volume group for each loop device ------------------------------ 3.32s
2026-09-16 02:09:17.111965 | instance | Create a logical volume for each loop device ---------------------------- 1.97s
2026-09-16 02:09:17.112277 | instance | Gathering Facts --------------------------------------------------------- 1.27s
2026-09-16 02:09:17.112595 | instance | Purge "snapd" package --------------------------------------------------- 0.85s
2026-09-16 02:09:17.112906 | instance | Configure short hostname ------------------------------------------------ 0.79s
2026-09-16 02:09:17.113232 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.74s
2026-09-16 02:09:17.113514 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-09-16 02:09:17.113834 | instance | Start loop devices ------------------------------------------------------ 0.72s
2026-09-16 02:09:17.114159 | instance | Set permissions on loopback devices ------------------------------------- 0.63s
2026-09-16 02:09:17.114466 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-09-16 02:09:17.114769 | instance | Start up service -------------------------------------------------------- 0.52s
2026-09-16 02:09:17.115094 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s
2026-09-16 02:09:17.115469 | instance | Ensure hostname inside hosts file --------------------------------------- 0.31s
2026-09-16 02:09:17.115803 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-16 02:09:17.116170 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-16 02:09:17.237210 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-16 02:09:17.252490 | instance | INFO     [csi > converge] Executing
2026-09-16 02:09:18.774595 | instance |
2026-09-16 02:09:18.775018 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-16 02:09:18.775406 | instance |
2026-09-16 02:09:18.775731 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 02:09:18.776093 | instance | Wednesday 16 September 2026  02:09:18 +0000 (0:00:00.007)       0:00:00.007 ***
2026-09-16 02:09:19.839481 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 02:09:19.839719 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 02:09:19.839734 | instance | interpreter could change the meaning of that path. See
2026-09-16 02:09:19.839741 | instance | https://docs.ansible.com/ansible-
2026-09-16 02:09:19.839984 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 02:09:19.845414 | instance | ok: [instance]
2026-09-16 02:09:19.845643 | instance |
2026-09-16 02:09:19.845871 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-16 02:09:19.846117 | instance | Wednesday 16 September 2026  02:09:19 +0000 (0:00:01.071)       0:00:01.078 ***
2026-09-16 02:09:19.921734 | instance | ok: [instance] => {
2026-09-16 02:09:19.922012 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-16 02:09:19.922460 | instance | }
2026-09-16 02:09:19.922719 | instance |
2026-09-16 02:09:19.923085 | instance | PLAY [all] *********************************************************************
2026-09-16 02:09:19.923420 | instance |
2026-09-16 02:09:19.923769 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 02:09:19.924134 | instance | Wednesday 16 September 2026  02:09:19 +0000 (0:00:00.075)       0:00:01.154 ***
2026-09-16 02:09:20.884761 | instance | ok: [instance]
2026-09-16 02:09:20.885163 | instance |
2026-09-16 02:09:20.885497 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-16 02:09:20.885821 | instance | Wednesday 16 September 2026  02:09:20 +0000 (0:00:00.962)       0:00:02.117 ***
2026-09-16 02:09:21.100197 | instance | ok: [instance]
2026-09-16 02:09:21.100550 | instance |
2026-09-16 02:09:21.100848 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-16 02:09:21.101144 | instance |
2026-09-16 02:09:21.101431 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 02:09:21.101717 | instance | Wednesday 16 September 2026  02:09:21 +0000 (0:00:00.215)       0:00:02.332 ***
2026-09-16 02:09:22.082898 | instance | ok: [instance]
2026-09-16 02:09:22.083317 | instance |
2026-09-16 02:09:22.083729 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 02:09:22.084105 | instance | Wednesday 16 September 2026  02:09:22 +0000 (0:00:00.981)       0:00:03.313 ***
2026-09-16 02:09:22.402139 | instance | ok: [instance]
2026-09-16 02:09:22.402498 | instance |
2026-09-16 02:09:22.402811 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 02:09:22.403163 | instance | Wednesday 16 September 2026  02:09:22 +0000 (0:00:00.321)       0:00:03.634 ***
2026-09-16 02:09:22.454386 | instance | skipping: [instance]
2026-09-16 02:09:22.454718 | instance |
2026-09-16 02:09:22.455040 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-16 02:09:22.455342 | instance | Wednesday 16 September 2026  02:09:22 +0000 (0:00:00.052)       0:00:03.687 ***
2026-09-16 02:09:22.763499 | instance | changed: [instance]
2026-09-16 02:09:22.763559 | instance |
2026-09-16 02:09:22.763686 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 02:09:22.763932 | instance | Wednesday 16 September 2026  02:09:22 +0000 (0:00:00.308)       0:00:03.996 ***
2026-09-16 02:09:22.859932 | instance | ok: [instance] => {
2026-09-16 02:09:22.860245 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-16 02:09:22.860451 | instance | }
2026-09-16 02:09:22.860662 | instance |
2026-09-16 02:09:22.860894 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 02:09:22.861185 | instance | Wednesday 16 September 2026  02:09:22 +0000 (0:00:00.096)       0:00:04.093 ***
2026-09-16 02:09:23.691226 | instance | changed: [instance]
2026-09-16 02:09:23.691503 | instance |
2026-09-16 02:09:23.691733 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 02:09:23.691974 | instance | Wednesday 16 September 2026  02:09:23 +0000 (0:00:00.830)       0:00:04.923 ***
2026-09-16 02:09:23.741253 | instance | skipping: [instance]
2026-09-16 02:09:23.741443 | instance |
2026-09-16 02:09:23.741717 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 02:09:23.741946 | instance | Wednesday 16 September 2026  02:09:23 +0000 (0:00:00.050)       0:00:04.974 ***
2026-09-16 02:09:23.793326 | instance | skipping: [instance]
2026-09-16 02:09:23.793522 | instance |
2026-09-16 02:09:23.793758 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 02:09:23.793997 | instance | Wednesday 16 September 2026  02:09:23 +0000 (0:00:00.052)       0:00:05.026 ***
2026-09-16 02:09:24.028668 | instance | ok: [instance]
2026-09-16 02:09:24.028881 | instance |
2026-09-16 02:09:24.029174 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 02:09:24.029424 | instance | Wednesday 16 September 2026  02:09:24 +0000 (0:00:00.235)       0:00:05.261 ***
2026-09-16 02:09:25.741745 | instance | ok: [instance]
2026-09-16 02:09:25.742195 | instance |
2026-09-16 02:09:25.742544 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 02:09:25.742862 | instance | Wednesday 16 September 2026  02:09:25 +0000 (0:00:01.712)       0:00:06.974 ***
2026-09-16 02:09:25.840280 | instance | ok: [instance] => {
2026-09-16 02:09:25.840617 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-16 02:09:25.841105 | instance | }
2026-09-16 02:09:25.841547 | instance |
2026-09-16 02:09:25.842032 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 02:09:25.842489 | instance | Wednesday 16 September 2026  02:09:25 +0000 (0:00:00.094)       0:00:07.069 ***
2026-09-16 02:09:26.702951 | instance | changed: [instance]
2026-09-16 02:09:26.703318 | instance |
2026-09-16 02:09:26.703693 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 02:09:26.704023 | instance | Wednesday 16 September 2026  02:09:26 +0000 (0:00:00.863)       0:00:07.933 ***
2026-09-16 02:09:29.588051 | instance | changed: [instance]
2026-09-16 02:09:29.588391 | instance |
2026-09-16 02:09:29.588700 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-16 02:09:29.589032 | instance | Wednesday 16 September 2026  02:09:29 +0000 (0:00:02.887)       0:00:10.821 ***
2026-09-16 02:09:29.627634 | instance | skipping: [instance]
2026-09-16 02:09:29.628029 | instance |
2026-09-16 02:09:29.628379 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-16 02:09:29.628722 | instance | Wednesday 16 September 2026  02:09:29 +0000 (0:00:00.039)       0:00:10.860 ***
2026-09-16 02:09:29.667271 | instance | skipping: [instance]
2026-09-16 02:09:29.667526 | instance |
2026-09-16 02:09:29.667971 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-16 02:09:29.668200 | instance | Wednesday 16 September 2026  02:09:29 +0000 (0:00:00.040)       0:00:10.900 ***
2026-09-16 02:09:29.707237 | instance | skipping: [instance]
2026-09-16 02:09:29.707581 | instance |
2026-09-16 02:09:29.707885 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-16 02:09:29.708213 | instance | Wednesday 16 September 2026  02:09:29 +0000 (0:00:00.039)       0:00:10.940 ***
2026-09-16 02:09:35.777174 | instance | changed: [instance]
2026-09-16 02:09:35.777701 | instance |
2026-09-16 02:09:35.778123 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-16 02:09:35.778480 | instance | Wednesday 16 September 2026  02:09:35 +0000 (0:00:06.069)       0:00:17.009 ***
2026-09-16 02:09:36.404994 | instance | changed: [instance]
2026-09-16 02:09:36.405394 | instance |
2026-09-16 02:09:36.405777 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-16 02:09:36.406144 | instance | Wednesday 16 September 2026  02:09:36 +0000 (0:00:00.628)       0:00:17.637 ***
2026-09-16 02:09:37.387464 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-16 02:09:37.387523 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-16 02:09:37.387535 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-16 02:09:37.388681 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-16 02:09:37.388766 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-16 02:09:37.388779 | instance |
2026-09-16 02:09:37.388797 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-16 02:09:37.388807 | instance | Wednesday 16 September 2026  02:09:37 +0000 (0:00:00.981)       0:00:18.619 ***
2026-09-16 02:09:38.036576 | instance | changed: [instance]
2026-09-16 02:09:38.036624 | instance |
2026-09-16 02:09:38.036632 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-16 02:09:38.036638 | instance | Wednesday 16 September 2026  02:09:38 +0000 (0:00:00.621)       0:00:19.240 ***
2026-09-16 02:09:38.036644 | instance |
2026-09-16 02:09:38.036649 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 02:09:38.036655 | instance | Wednesday 16 September 2026  02:09:38 +0000 (0:00:00.028)       0:00:19.268 ***
2026-09-16 02:09:38.940402 | instance | ok: [instance]
2026-09-16 02:09:38.940482 | instance |
2026-09-16 02:09:38.940840 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-16 02:09:38.940898 | instance | Wednesday 16 September 2026  02:09:38 +0000 (0:00:00.904)       0:00:20.173 ***
2026-09-16 02:09:39.414687 | instance | changed: [instance]
2026-09-16 02:09:39.414789 | instance |
2026-09-16 02:09:39.414802 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-16 02:09:39.414812 | instance | Wednesday 16 September 2026  02:09:39 +0000 (0:00:00.473)       0:00:20.646 ***
2026-09-16 02:09:40.067073 | instance | changed: [instance]
2026-09-16 02:09:40.067200 | instance |
2026-09-16 02:09:40.067461 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 02:09:40.067740 | instance | Wednesday 16 September 2026  02:09:40 +0000 (0:00:00.653)       0:00:21.299 ***
2026-09-16 02:09:40.324135 | instance | ok: [instance]
2026-09-16 02:09:40.324281 | instance |
2026-09-16 02:09:40.324529 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 02:09:40.324763 | instance | Wednesday 16 September 2026  02:09:40 +0000 (0:00:00.256)       0:00:21.556 ***
2026-09-16 02:09:40.394791 | instance | ok: [instance] => {
2026-09-16 02:09:40.395081 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-16 02:09:40.395328 | instance | }
2026-09-16 02:09:40.395662 | instance |
2026-09-16 02:09:40.395947 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 02:09:40.396194 | instance | Wednesday 16 September 2026  02:09:40 +0000 (0:00:00.070)       0:00:21.627 ***
2026-09-16 02:09:41.338379 | instance | changed: [instance]
2026-09-16 02:09:41.338641 | instance |
2026-09-16 02:09:41.338925 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 02:09:41.339228 | instance | Wednesday 16 September 2026  02:09:41 +0000 (0:00:00.943)       0:00:22.571 ***
2026-09-16 02:09:45.843530 | instance | changed: [instance]
2026-09-16 02:09:45.843616 | instance |
2026-09-16 02:09:45.843666 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-16 02:09:45.843984 | instance | Wednesday 16 September 2026  02:09:45 +0000 (0:00:04.504)       0:00:27.076 ***
2026-09-16 02:09:46.902660 | instance | ok: [instance]
2026-09-16 02:09:46.902740 | instance |
2026-09-16 02:09:46.903123 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-16 02:09:46.903197 | instance | Wednesday 16 September 2026  02:09:46 +0000 (0:00:01.059)       0:00:28.135 ***
2026-09-16 02:09:47.288700 | instance | changed: [instance]
2026-09-16 02:09:47.288842 | instance |
2026-09-16 02:09:47.289152 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-16 02:09:47.289214 | instance | Wednesday 16 September 2026  02:09:47 +0000 (0:00:00.386)       0:00:28.521 ***
2026-09-16 02:09:47.804070 | instance | changed: [instance]
2026-09-16 02:09:47.804161 | instance |
2026-09-16 02:09:47.804176 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-16 02:09:47.804344 | instance | Wednesday 16 September 2026  02:09:47 +0000 (0:00:00.514)       0:00:29.036 ***
2026-09-16 02:09:48.407448 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-16 02:09:48.407555 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-16 02:09:48.408020 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-16 02:09:48.408071 | instance |
2026-09-16 02:09:48.408078 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-16 02:09:48.408083 | instance | Wednesday 16 September 2026  02:09:48 +0000 (0:00:00.604)       0:00:29.640 ***
2026-09-16 02:09:48.892921 | instance | changed: [instance]
2026-09-16 02:09:48.893661 | instance |
2026-09-16 02:09:48.893741 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-16 02:09:48.893754 | instance | Wednesday 16 September 2026  02:09:48 +0000 (0:00:00.484)       0:00:30.125 ***
2026-09-16 02:09:49.409562 | instance | changed: [instance]
2026-09-16 02:09:49.409627 | instance |
2026-09-16 02:09:49.410188 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-16 02:09:49.410204 | instance | Wednesday 16 September 2026  02:09:49 +0000 (0:00:00.501)       0:00:30.626 ***
2026-09-16 02:09:49.410209 | instance |
2026-09-16 02:09:49.410213 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 02:09:49.410217 | instance | Wednesday 16 September 2026  02:09:49 +0000 (0:00:00.016)       0:00:30.643 ***
2026-09-16 02:09:50.166222 | instance | ok: [instance]
2026-09-16 02:09:50.166279 | instance |
2026-09-16 02:09:50.166288 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-16 02:09:50.166303 | instance | Wednesday 16 September 2026  02:09:50 +0000 (0:00:00.755)       0:00:31.399 ***
2026-09-16 02:09:51.202463 | instance | changed: [instance]
2026-09-16 02:09:51.203164 | instance |
2026-09-16 02:09:51.203229 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-16 02:09:51.203241 | instance | Wednesday 16 September 2026  02:09:51 +0000 (0:00:01.036)       0:00:32.435 ***
2026-09-16 02:09:51.799427 | instance | changed: [instance]
2026-09-16 02:09:51.799966 | instance |
2026-09-16 02:09:51.800007 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-16 02:09:51.800015 | instance | Wednesday 16 September 2026  02:09:51 +0000 (0:00:00.597)       0:00:33.032 ***
2026-09-16 02:09:51.884268 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-16 02:09:51.885057 | instance |
2026-09-16 02:09:51.885118 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-16 02:09:51.885131 | instance | Wednesday 16 September 2026  02:09:51 +0000 (0:00:00.084)       0:00:33.116 ***
2026-09-16 02:09:57.253930 | instance | changed: [instance]
2026-09-16 02:09:57.254020 | instance |
2026-09-16 02:09:57.254355 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-16 02:09:57.254543 | instance | Wednesday 16 September 2026  02:09:57 +0000 (0:00:05.370)       0:00:38.487 ***
2026-09-16 02:09:57.932963 | instance | ok: [instance] => (item=chronyd)
2026-09-16 02:09:57.933923 | instance | ok: [instance] => (item=sshd)
2026-09-16 02:09:57.933955 | instance |
2026-09-16 02:09:57.933966 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-16 02:09:57.933976 | instance | Wednesday 16 September 2026  02:09:57 +0000 (0:00:00.678)       0:00:39.165 ***
2026-09-16 02:10:12.528313 | instance | changed: [instance]
2026-09-16 02:10:12.528414 | instance |
2026-09-16 02:10:12.528426 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-16 02:10:12.528436 | instance | Wednesday 16 September 2026  02:10:12 +0000 (0:00:14.594)       0:00:53.760 ***
2026-09-16 02:10:12.766037 | instance | ok: [instance]
2026-09-16 02:10:12.766833 | instance |
2026-09-16 02:10:12.767116 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-16 02:10:12.767130 | instance | Wednesday 16 September 2026  02:10:12 +0000 (0:00:00.237)       0:00:53.997 ***
2026-09-16 02:10:13.262744 | instance | changed: [instance]
2026-09-16 02:10:13.263518 | instance |
2026-09-16 02:10:13.263621 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-16 02:10:13.263637 | instance | Wednesday 16 September 2026  02:10:13 +0000 (0:00:00.497)       0:00:54.495 ***
2026-09-16 02:10:13.653396 | instance | changed: [instance]
2026-09-16 02:10:13.653472 | instance |
2026-09-16 02:10:13.653482 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-16 02:10:13.653491 | instance | Wednesday 16 September 2026  02:10:13 +0000 (0:00:00.390)       0:00:54.885 ***
2026-09-16 02:10:13.799718 | instance | ok: [instance]
2026-09-16 02:10:13.799829 | instance |
2026-09-16 02:10:13.799842 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-16 02:10:13.799866 | instance | Wednesday 16 September 2026  02:10:13 +0000 (0:00:00.145)       0:00:55.031 ***
2026-09-16 02:10:14.044525 | instance | ok: [instance] => (item=instance)
2026-09-16 02:10:14.044610 | instance |
2026-09-16 02:10:14.044675 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-16 02:10:14.044803 | instance | Wednesday 16 September 2026  02:10:14 +0000 (0:00:00.246)       0:00:55.278 ***
2026-09-16 02:10:14.125401 | instance | ok: [instance]
2026-09-16 02:10:14.126048 | instance |
2026-09-16 02:10:14.126127 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-16 02:10:14.126138 | instance | Wednesday 16 September 2026  02:10:14 +0000 (0:00:00.079)       0:00:55.357 ***
2026-09-16 02:10:14.213291 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-16 02:10:14.213411 | instance |
2026-09-16 02:10:14.213419 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-16 02:10:14.213554 | instance | Wednesday 16 September 2026  02:10:14 +0000 (0:00:00.088)       0:00:55.446 ***
2026-09-16 02:10:14.532389 | instance | changed: [instance]
2026-09-16 02:10:14.533133 | instance |
2026-09-16 02:10:14.533199 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-16 02:10:14.533212 | instance | Wednesday 16 September 2026  02:10:14 +0000 (0:00:00.318)       0:00:55.764 ***
2026-09-16 02:10:15.305382 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-16 02:10:15.305456 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-16 02:10:15.305918 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-16 02:10:15.305962 | instance |
2026-09-16 02:10:15.305968 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-16 02:10:15.305972 | instance | Wednesday 16 September 2026  02:10:15 +0000 (0:00:00.773)       0:00:56.538 ***
2026-09-16 02:11:48.262721 | instance | ok: [instance]
2026-09-16 02:11:48.263162 | instance |
2026-09-16 02:11:48.263214 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-16 02:11:48.263225 | instance | Wednesday 16 September 2026  02:11:48 +0000 (0:01:32.956)       0:02:29.495 ***
2026-09-16 02:11:48.509245 | instance | changed: [instance]
2026-09-16 02:11:48.509357 | instance |
2026-09-16 02:11:48.509499 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-16 02:11:48.509636 | instance | Wednesday 16 September 2026  02:11:48 +0000 (0:00:00.247)       0:02:29.742 ***
2026-09-16 02:11:48.537831 | instance | ok: [instance]
2026-09-16 02:11:48.537934 | instance |
2026-09-16 02:11:48.538091 | instance | TASK [Install Ceph host] *******************************************************
2026-09-16 02:11:48.538222 | instance | Wednesday 16 September 2026  02:11:48 +0000 (0:00:00.028)       0:02:29.771 ***
2026-09-16 02:11:48.610424 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-16 02:11:48.610492 | instance |
2026-09-16 02:11:48.610699 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-16 02:11:48.610717 | instance | Wednesday 16 September 2026  02:11:48 +0000 (0:00:00.072)       0:02:29.843 ***
2026-09-16 02:11:50.219090 | instance | ok: [instance]
2026-09-16 02:11:50.219429 | instance |
2026-09-16 02:11:50.219764 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-16 02:11:50.220136 | instance | Wednesday 16 September 2026  02:11:50 +0000 (0:00:01.607)       0:02:31.451 ***
2026-09-16 02:11:50.289611 | instance | ok: [instance] => (item=instance)
2026-09-16 02:11:50.289743 | instance |
2026-09-16 02:11:50.289918 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-16 02:11:50.290108 | instance | Wednesday 16 September 2026  02:11:50 +0000 (0:00:00.071)       0:02:31.522 ***
2026-09-16 02:11:50.671314 | instance | ok: [instance]
2026-09-16 02:11:50.671401 | instance |
2026-09-16 02:11:50.672150 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-16 02:11:50.672745 | instance | Wednesday 16 September 2026  02:11:50 +0000 (0:00:00.381)       0:02:31.904 ***
2026-09-16 02:11:52.989112 | instance | ok: [instance]
2026-09-16 02:11:52.989973 | instance |
2026-09-16 02:11:52.990039 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-16 02:11:52.990052 | instance | Wednesday 16 September 2026  02:11:52 +0000 (0:00:02.317)       0:02:34.221 ***
2026-09-16 02:12:44.268813 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-16 02:12:44.268930 | 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_l_9s5taj/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_l_9s5taj/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_l_9s5taj/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_l_9s5taj/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-16T02:12:44.090+0000 7fa051d0c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC8+qlq4m3KNiAAeiYMrOlVGhshsS3rAsnN2WQ6GB5WpXT2WYW0CvNmWXI=: Malformed input [buffer:3]\n2026-09-16T02:12:44.090+0000 7fa051d0c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T02:12:44.090+0000 7fa051d0c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC8+qlq4m3KNiAAeiYMrOlVGhshsS3rAsnN2WQ6GB5WpXT2WYW0CvNmWXI=: Malformed input [buffer:3]\n2026-09-16T02:12:44.090+0000 7fa051d0c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T02:12:44.090+0000 7fa051d0c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC8+qlq4m3KNiAAeiYMrOlVGhshsS3rAsnN2WQ6GB5WpXT2WYW0CvNmWXI=: Malformed input [buffer:3]\n2026-09-16T02:12:44.090+0000 7fa051d0c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T02:12:44.090+0000 7fa051d0c640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-16 02:12:44.269855 | instance |
2026-09-16 02:12:44.271480 | instance | PLAY RECAP *********************************************************************
2026-09-16 02:12:44.271540 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-16 02:12:44.271552 | instance |
2026-09-16 02:12:44.271562 | instance | Wednesday 16 September 2026  02:12:44 +0000 (0:00:51.282)       0:03:25.503 ***
2026-09-16 02:12:44.271572 | instance | ===============================================================================
2026-09-16 02:12:44.271611 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 92.96s
2026-09-16 02:12:44.271621 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.28s
2026-09-16 02:12:44.271630 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 14.59s
2026-09-16 02:12:44.271639 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.07s
2026-09-16 02:12:44.271648 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.37s
2026-09-16 02:12:44.271657 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.50s
2026-09-16 02:12:44.271666 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.89s
2026-09-16 02:12:44.271675 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.32s
2026-09-16 02:12:44.271706 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.71s
2026-09-16 02:12:44.271716 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.66s
2026-09-16 02:12:44.271755 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.61s
2026-09-16 02:12:44.271921 | instance | Gathering Facts --------------------------------------------------------- 1.07s
2026-09-16 02:12:44.272080 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.06s
2026-09-16 02:12:44.272226 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.04s
2026-09-16 02:12:44.272394 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-16 02:12:44.272562 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.98s
2026-09-16 02:12:44.272733 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-09-16 02:12:44.272893 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.94s
2026-09-16 02:12:44.273066 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.86s
2026-09-16 02:12:44.273236 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.83s
2026-09-16 02:12:44.388196 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 02:12:44.388477 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-16 02:12:44.388841 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 02:12:44.572750 | instance | ERROR
2026-09-16 02:12:44.572966 | instance | {
2026-09-16 02:12:44.573028 | instance |   "delta": "0:04:18.407401",
2026-09-16 02:12:44.573070 | instance |   "end": "2026-09-16 02:12:44.461483",
2026-09-16 02:12:44.573118 | instance |   "msg": "non-zero return code",
2026-09-16 02:12:44.573169 | instance |   "rc": 2,
2026-09-16 02:12:44.573207 | instance |   "start": "2026-09-16 02:08:26.054082"
2026-09-16 02:12:44.573245 | instance | }
failure
2026-09-16 02:12:44.581853 | 
2026-09-16 02:12:44.581952 | PLAY RECAP
2026-09-16 02:12:44.582034 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-16 02:12:44.582079 | 
2026-09-16 02:12:44.731023 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 02:12:44.735008 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 02:12:45.826001 | 
2026-09-16 02:12:45.826134 | PLAY [all]
2026-09-16 02:12:45.839680 | 
2026-09-16 02:12:45.839777 | TASK [gather-host-logs : creating directory for system status]
2026-09-16 02:12:46.230043 | instance | changed
2026-09-16 02:12:46.240339 | 
2026-09-16 02:12:46.240735 | TASK [gather-host-logs : Get logs for each host]
2026-09-16 02:12:46.623797 | instance | + systemd-cgls --full --all --no-pager
2026-09-16 02:12:46.644294 | instance | + ip addr
2026-09-16 02:12:46.647710 | instance | + ip route
2026-09-16 02:12:46.651172 | instance | + lsblk
2026-09-16 02:12:46.654581 | instance | + mount
2026-09-16 02:12:46.658285 | instance | + docker images
2026-09-16 02:12:46.677751 | instance | + brctl show
2026-09-16 02:12:46.678267 | instance | /bin/bash: line 8: brctl: command not found
2026-09-16 02:12:46.678543 | instance | + ip neigh
2026-09-16 02:12:46.681160 | instance | + bridge fdb show
2026-09-16 02:12:46.684305 | instance | + ps aux --sort=-%mem
2026-09-16 02:12:46.714173 | instance | + dpkg -l
2026-09-16 02:12:46.725105 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-16 02:12:46.725614 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-16 02:12:46.741831 | instance | + '[' '!' -z '' ']'
2026-09-16 02:12:46.798246 | instance | ok: Runtime: 0:00:00.123125
2026-09-16 02:12:46.809509 | 
2026-09-16 02:12:46.809682 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-16 02:12:47.493380 | instance | changed:
2026-09-16 02:12:47.493624 | instance | created directory /var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/work/logs/instance
2026-09-16 02:12:47.493682 | instance | cd+++++++++ system/
2026-09-16 02:12:47.493726 | instance | >f+++++++++ system/brctl-show.txt
2026-09-16 02:12:47.493766 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-16 02:12:47.493803 | instance | >f+++++++++ system/docker-images.txt
2026-09-16 02:12:47.493969 | instance | >f+++++++++ system/ip-addr.txt
2026-09-16 02:12:47.494025 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-16 02:12:47.494066 | instance | >f+++++++++ system/ip-route.txt
2026-09-16 02:12:47.494109 | instance | >f+++++++++ system/lsblk.txt
2026-09-16 02:12:47.494148 | instance | >f+++++++++ system/mount.txt
2026-09-16 02:12:47.494185 | instance | >f+++++++++ system/packages.txt
2026-09-16 02:12:47.494222 | instance | >f+++++++++ system/ps.txt
2026-09-16 02:12:47.494258 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-16 02:12:47.511487 | 
2026-09-16 02:12:47.511660 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-16 02:12:47.737453 | instance | changed: "values"
2026-09-16 02:12:47.920524 | instance | changed: "releases"
2026-09-16 02:12:47.938879 | 
2026-09-16 02:12:47.939080 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-16 02:12:48.203653 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-16 02:12:48.493228 | instance | ok: Runtime: 0:00:00.003503
2026-09-16 02:12:48.502748 | 
2026-09-16 02:12:48.502885 | TASK [helm-release-status : Downloads logs to executor]
2026-09-16 02:12:49.019849 | instance | changed:
2026-09-16 02:12:49.020035 | instance | cd+++++++++ helm/
2026-09-16 02:12:49.020070 | instance | cd+++++++++ helm/releases/
2026-09-16 02:12:49.020098 | instance | cd+++++++++ helm/values/
2026-09-16 02:12:49.031702 | 
2026-09-16 02:12:49.031790 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-16 02:12:49.251775 | instance | changed
2026-09-16 02:12:49.256901 | 
2026-09-16 02:12:49.256973 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-16 02:12:49.539450 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 02:12:49.539540 | instance |
2026-09-16 02:12:49.545338 | instance | environment: line 1: kubectl: command not found
2026-09-16 02:12:49.546607 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 02:12:49.547940 | instance | environment: line 1: kubectl: command not found
2026-09-16 02:12:49.549658 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 02:12:49.550574 | instance | environment: line 1: kubectl: command not found
2026-09-16 02:12:49.551660 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 02:12:49.553476 | instance | environment: line 1: kubectl: command not found
2026-09-16 02:12:49.555264 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 02:12:49.555766 | instance | environment: line 1: kubectl: command not found
2026-09-16 02:12:49.557186 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 02:12:49.787046 | instance | ok: Runtime: 0:00:00.025408
2026-09-16 02:12:49.796058 | 
2026-09-16 02:12:49.796177 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-16 02:12:50.002151 | instance | changed
2026-09-16 02:12:50.011802 | 
2026-09-16 02:12:50.011942 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-16 02:12:50.256750 | instance | environment: line 5: kubectl: command not found
2026-09-16 02:12:50.257208 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 02:12:50.257421 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 02:12:50.257529 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 02:12:50.561203 | instance | ok: Runtime: 0:00:00.006802
2026-09-16 02:12:50.571608 | 
2026-09-16 02:12:50.571746 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-16 02:12:51.071071 | instance | changed:
2026-09-16 02:12:51.071327 | instance | cd+++++++++ objects/
2026-09-16 02:12:51.071408 | instance | cd+++++++++ objects/cluster/
2026-09-16 02:12:51.071453 | instance | cd+++++++++ objects/namespaced/
2026-09-16 02:12:51.088639 | 
2026-09-16 02:12:51.088771 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-16 02:12:51.304824 | instance | changed
2026-09-16 02:12:51.313957 | 
2026-09-16 02:12:51.314086 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-16 02:12:51.578842 | instance | changed
2026-09-16 02:12:51.582999 | 
2026-09-16 02:12:51.583063 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-16 02:12:51.829977 | instance | environment: line 3: kubectl: command not found
2026-09-16 02:12:52.121258 | instance | ok: Runtime: 0:00:00.007600
2026-09-16 02:12:52.127803 | 
2026-09-16 02:12:52.127891 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-16 02:12:52.697346 | instance | changed:
2026-09-16 02:12:52.697639 | instance | cd+++++++++ pod-logs/
2026-09-16 02:12:52.697696 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-16 02:12:52.716656 | 
2026-09-16 02:12:52.716962 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-16 02:12:52.934178 | instance | changed
2026-09-16 02:12:52.945855 | 
2026-09-16 02:12:52.946143 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-16 02:12:53.201275 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 02:12:53.497280 | instance | ok: Runtime: 0:00:00.053132
2026-09-16 02:12:53.506347 | 
2026-09-16 02:12:53.506495 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-16 02:12:53.766589 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 02:12:53.815587 | instance | ceph-mgr endpoints:
2026-09-16 02:12:54.055760 | instance | ok: Runtime: 0:00:00.056675
2026-09-16 02:12:54.065738 | 
2026-09-16 02:12:54.065873 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-16 02:12:54.327249 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-16 02:12:54.614330 | instance | ok: Runtime: 0:00:00.057806
2026-09-16 02:12:54.624255 | 
2026-09-16 02:12:54.624410 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-16 02:12:55.154931 | instance | changed: cd+++++++++ prometheus/
2026-09-16 02:12:55.172311 | 
2026-09-16 02:12:55.172485 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-16 02:12:55.400746 | instance | changed
2026-09-16 02:12:55.409856 | 
2026-09-16 02:12:55.409987 | TASK [gather-selenium-data : Get selenium data]
2026-09-16 02:12:55.711198 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-16 02:12:55.713190 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-16 02:12:55.957034 | instance | ERROR
2026-09-16 02:12:55.957349 | instance | {
2026-09-16 02:12:55.957438 | instance |   "delta": "0:00:00.008357",
2026-09-16 02:12:55.957481 | instance |   "end": "2026-09-16 02:12:55.713688",
2026-09-16 02:12:55.957518 | instance |   "msg": "non-zero return code",
2026-09-16 02:12:55.957553 | instance |   "rc": 1,
2026-09-16 02:12:55.957588 | instance |   "start": "2026-09-16 02:12:55.705331"
2026-09-16 02:12:55.957622 | instance | }
2026-09-16 02:12:55.957667 | instance | ERROR: Ignoring Errors
2026-09-16 02:12:55.966959 | 
2026-09-16 02:12:55.967090 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-16 02:12:56.496641 | instance | changed: cd+++++++++ selenium/
2026-09-16 02:12:56.505556 | 
2026-09-16 02:12:56.505628 | PLAY RECAP
2026-09-16 02:12:56.505697 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-16 02:12:56.505737 | 
2026-09-16 02:12:56.635877 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 02:12:56.641113 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 02:12:57.242485 | 
2026-09-16 02:12:57.242624 | PLAY [all]
2026-09-16 02:12:57.253261 | 
2026-09-16 02:12:57.253334 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-16 02:12:57.286993 | instance | skipping: Conditional result was False
2026-09-16 02:12:57.291724 | 
2026-09-16 02:12:57.291797 | TASK [fetch-output : Set log path for single node]
2026-09-16 02:12:57.341328 | instance | ok
2026-09-16 02:12:57.349107 | 
2026-09-16 02:12:57.349189 | LOOP [fetch-output : Ensure local output dirs]
2026-09-16 02:12:57.738499 | instance -> localhost | ok: "/var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/work/logs"
2026-09-16 02:12:58.002389 | instance -> localhost | changed: "/var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/work/artifacts"
2026-09-16 02:12:58.219242 | instance -> localhost | changed: "/var/lib/zuul/builds/1052396110484c86b8b5712e6431225d/work/docs"
2026-09-16 02:12:58.242623 | 
2026-09-16 02:12:58.242841 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-16 02:12:58.908601 | instance | changed: .d..t...... ./
2026-09-16 02:12:58.908973 | instance | changed: All items complete
2026-09-16 02:12:58.909043 | 
2026-09-16 02:12:59.384805 | instance | changed: .d..t...... ./
2026-09-16 02:12:59.877530 | instance | changed: .d..t...... ./
2026-09-16 02:12:59.901264 | 
2026-09-16 02:12:59.901524 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-16 02:13:00.344658 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.010130
2026-09-16 02:13:00.605425 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008846
2026-09-16 02:13:00.618672 | 
2026-09-16 02:13:00.618744 | PLAY [all]
2026-09-16 02:13:00.625408 | 
2026-09-16 02:13:00.625490 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-16 02:13:01.043799 | instance | changed
2026-09-16 02:13:01.054463 | 
2026-09-16 02:13:01.054554 | PLAY RECAP
2026-09-16 02:13:01.054638 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-16 02:13:01.054681 | 
2026-09-16 02:13:01.198722 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 02:13:01.202566 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-16 02:13:01.765701 | 
2026-09-16 02:13:01.765900 | PLAY [localhost]
2026-09-16 02:13:01.776227 | 
2026-09-16 02:13:01.776353 | TASK [Generate Zuul manifest]
2026-09-16 02:13:01.794248 | localhost | ok
2026-09-16 02:13:01.810288 | 
2026-09-16 02:13:01.810386 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-16 02:13:02.193108 | localhost | changed
2026-09-16 02:13:02.206068 | 
2026-09-16 02:13:02.206168 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-16 02:13:02.244695 | localhost | ok
2026-09-16 02:13:02.252599 | 
2026-09-16 02:13:02.252696 | TASK [Upload logs]
2026-09-16 02:13:02.273815 | localhost | ok
2026-09-16 02:13:02.333165 | 
2026-09-16 02:13:02.333312 | TASK [Set zuul-log-path fact]
2026-09-16 02:13:02.351315 | localhost | ok
2026-09-16 02:13:02.361629 | 
2026-09-16 02:13:02.361689 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 02:13:02.399814 | localhost | ok
2026-09-16 02:13:02.407907 | 
2026-09-16 02:13:02.407991 | TASK [upload-logs : Create log directories]
2026-09-16 02:13:02.834969 | localhost | changed
2026-09-16 02:13:02.844867 | 
2026-09-16 02:13:02.845008 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-16 02:13:03.285411 | localhost -> localhost | ok: Runtime: 0:00:00.004641
2026-09-16 02:13:03.296280 | 
2026-09-16 02:13:03.296442 | TASK [upload-logs : Upload logs to log server]
2026-09-16 02:13:03.806220 | localhost | Output suppressed because no_log was given
2026-09-16 02:13:03.860887 | 
2026-09-16 02:13:03.861051 | LOOP [upload-logs : Compress console log and json output]
2026-09-16 02:13:03.923827 | localhost | skipping: Conditional result was False
2026-09-16 02:13:03.931437 | localhost | skipping: Conditional result was False
2026-09-16 02:13:03.944001 | 
2026-09-16 02:13:03.944184 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-16 02:13:04.002595 | localhost | skipping: Conditional result was False
2026-09-16 02:13:04.002992 | 
2026-09-16 02:13:04.008415 | localhost | skipping: Conditional result was False
2026-09-16 02:13:04.017793 | 
2026-09-16 02:13:04.017982 | LOOP [upload-logs : Upload console log and json output]