2026-09-02 01:06:14.558090 | Job console starting
2026-09-02 01:06:14.568387 | Updating git repos
2026-09-02 01:06:22.862180 | Cloning repos into workspace
2026-09-02 01:06:43.385039 | Restoring repo states
2026-09-02 01:06:55.250383 | Merging changes
2026-09-02 01:06:56.701554 | Checking out repos
2026-09-02 01:06:58.604139 | Preparing playbooks
2026-09-02 01:07:00.785812 | Running Ansible setup
2026-09-02 01:07:04.571204 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 01:07:05.222328 | 
2026-09-02 01:07:05.222704 | PLAY [localhost]
2026-09-02 01:07:05.230683 | 
2026-09-02 01:07:05.230755 | TASK [Gathering Facts]
2026-09-02 01:07:06.191542 | localhost | ok
2026-09-02 01:07:06.202345 | 
2026-09-02 01:07:06.202434 | TASK [Setup log path fact]
2026-09-02 01:07:06.221846 | localhost | ok
2026-09-02 01:07:06.231676 | 
2026-09-02 01:07:06.231744 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 01:07:06.259620 | localhost | ok
2026-09-02 01:07:06.267173 | 
2026-09-02 01:07:06.267240 | TASK [emit-job-header : Print job information]
2026-09-02 01:07:06.306990 | # Job Information
2026-09-02 01:07:06.307143 | Ansible Version: 2.16.18
2026-09-02 01:07:06.307186 | Job: atmosphere-molecule-csi-rbd
2026-09-02 01:07:06.307218 | Pipeline: check
2026-09-02 01:07:06.307246 | Executor: 2d72f0692154
2026-09-02 01:07:06.307274 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-09-02 01:07:06.307306 | Event ID: 57709950-a669-11f1-8f9f-d0613c211edb
2026-09-02 01:07:06.310967 | 
2026-09-02 01:07:06.311055 | LOOP [emit-job-header : Print node information]
2026-09-02 01:07:06.409949 | localhost | ok:
2026-09-02 01:07:06.410192 | localhost | # Node Information
2026-09-02 01:07:06.410231 | localhost | Inventory Hostname: instance
2026-09-02 01:07:06.410262 | localhost | Hostname: np0000191807
2026-09-02 01:07:06.410290 | localhost | Username: zuul
2026-09-02 01:07:06.410323 | localhost | Distro: Ubuntu 22.04
2026-09-02 01:07:06.410351 | localhost | Provider: yul1
2026-09-02 01:07:06.410377 | localhost | Region: ca-ymq-1
2026-09-02 01:07:06.410403 | localhost | Label: ubuntu-jammy
2026-09-02 01:07:06.410428 | localhost | Product Name: OpenStack Nova
2026-09-02 01:07:06.410454 | localhost | Interface IP: 162.253.55.38
2026-09-02 01:07:06.427943 | 
2026-09-02 01:07:06.428058 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-02 01:07:06.990700 | localhost -> localhost | changed
2026-09-02 01:07:07.000834 | 
2026-09-02 01:07:07.000929 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-02 01:07:07.947858 | localhost -> localhost | changed
2026-09-02 01:07:07.959201 | 
2026-09-02 01:07:07.959283 | PLAY [all]
2026-09-02 01:07:07.976361 | 
2026-09-02 01:07:07.976614 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-02 01:07:08.226852 | instance -> localhost | ok
2026-09-02 01:07:08.233013 | 
2026-09-02 01:07:08.233081 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-02 01:07:08.264203 | instance | ok
2026-09-02 01:07:08.278116 | instance | included: /var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-02 01:07:08.332114 | 
2026-09-02 01:07:08.332234 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-02 01:07:09.028651 | instance -> localhost | Generating public/private rsa key pair.
2026-09-02 01:07:09.028811 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/work/f2439387643349df88f12f20b84f7fd4_id_rsa
2026-09-02 01:07:09.028842 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/work/f2439387643349df88f12f20b84f7fd4_id_rsa.pub
2026-09-02 01:07:09.028866 | instance -> localhost | The key fingerprint is:
2026-09-02 01:07:09.028888 | instance -> localhost | SHA256:xr1BwrKy3lG5ZW4R1KYOTU5w05FBdwvEBFHQWNSWits zuul-build-sshkey
2026-09-02 01:07:09.028925 | instance -> localhost | The key's randomart image is:
2026-09-02 01:07:09.028947 | instance -> localhost | +---[RSA 3072]----+
2026-09-02 01:07:09.029023 | instance -> localhost | |        ..+B^@o o|
2026-09-02 01:07:09.029054 | instance -> localhost | |       . ooo=+.=.|
2026-09-02 01:07:09.029076 | instance -> localhost | |      . o=oo. o. |
2026-09-02 01:07:09.029098 | instance -> localhost | |       +.=+o .   |
2026-09-02 01:07:09.029118 | instance -> localhost | |    . . So* o    |
2026-09-02 01:07:09.029138 | instance -> localhost | |     o o =.= E   |
2026-09-02 01:07:09.029158 | instance -> localhost | |    . . . +      |
2026-09-02 01:07:09.029179 | instance -> localhost | |   . . . .       |
2026-09-02 01:07:09.029202 | instance -> localhost | |    . .          |
2026-09-02 01:07:09.029224 | instance -> localhost | +----[SHA256]-----+
2026-09-02 01:07:09.029273 | instance -> localhost | ok: Runtime: 0:00:00.246788
2026-09-02 01:07:09.041968 | 
2026-09-02 01:07:09.042178 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-02 01:07:09.077312 | instance | ok
2026-09-02 01:07:09.087980 | instance | included: /var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-02 01:07:09.095455 | 
2026-09-02 01:07:09.095521 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-02 01:07:09.119693 | instance | skipping: Conditional result was False
2026-09-02 01:07:09.128573 | 
2026-09-02 01:07:09.128717 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-02 01:07:09.568325 | instance | changed
2026-09-02 01:07:09.579440 | 
2026-09-02 01:07:09.579514 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-02 01:07:09.798441 | instance | ok
2026-09-02 01:07:09.803515 | 
2026-09-02 01:07:09.803592 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-02 01:07:10.348367 | instance | changed
2026-09-02 01:07:10.353307 | 
2026-09-02 01:07:10.353398 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-02 01:07:10.880438 | instance | changed
2026-09-02 01:07:10.887895 | 
2026-09-02 01:07:10.887956 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-02 01:07:10.911424 | instance | skipping: Conditional result was False
2026-09-02 01:07:10.920318 | 
2026-09-02 01:07:10.920379 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-02 01:07:11.298279 | instance -> localhost | changed
2026-09-02 01:07:11.312703 | 
2026-09-02 01:07:11.312769 | TASK [add-build-sshkey : Add back temp key]
2026-09-02 01:07:11.575873 | instance -> localhost | Identity added: /var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/work/f2439387643349df88f12f20b84f7fd4_id_rsa (zuul-build-sshkey)
2026-09-02 01:07:11.576044 | instance -> localhost | ok: Runtime: 0:00:00.013958
2026-09-02 01:07:11.581101 | 
2026-09-02 01:07:11.581162 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-02 01:07:11.926137 | instance | ok
2026-09-02 01:07:11.930665 | 
2026-09-02 01:07:11.930736 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-02 01:07:11.954799 | instance | skipping: Conditional result was False
2026-09-02 01:07:11.970094 | 
2026-09-02 01:07:11.970166 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-02 01:07:12.302704 | instance | ok
2026-09-02 01:07:12.308346 | 
2026-09-02 01:07:12.308422 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-02 01:07:15.854820 | instance | Output suppressed because no_log was given
2026-09-02 01:07:15.863624 | 
2026-09-02 01:07:15.863689 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-02 01:07:16.079455 | instance | ok: "logs"
2026-09-02 01:07:16.079739 | instance | ok: All items complete
2026-09-02 01:07:16.079769 | 
2026-09-02 01:07:16.264103 | instance | ok: "artifacts"
2026-09-02 01:07:16.426576 | instance | ok: "docs"
2026-09-02 01:07:16.433821 | 
2026-09-02 01:07:16.433949 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-02 01:07:16.633122 | instance | changed: "logs"
2026-09-02 01:07:16.785847 | instance | changed: "artifacts"
2026-09-02 01:07:16.958241 | instance | changed: "docs"
2026-09-02 01:07:16.971128 | 
2026-09-02 01:07:16.971239 | PLAY RECAP
2026-09-02 01:07:16.971288 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-02 01:07:16.971317 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:07:16.971366 | 
2026-09-02 01:07:17.116938 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 01:07:17.119731 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 01:07:17.723370 | 
2026-09-02 01:07:17.723495 | PLAY [all]
2026-09-02 01:07:17.733814 | 
2026-09-02 01:07:17.733887 | TASK [setup-uv : Extract archive]
2026-09-02 01:07:20.859670 | instance | changed
2026-09-02 01:07:20.867298 | 
2026-09-02 01:07:20.867408 | TASK [setup-uv : Print version]
2026-09-02 01:07:21.236500 | instance | uv 0.8.13
2026-09-02 01:07:21.406113 | instance | ok: Runtime: 0:00:00.011194
2026-09-02 01:07:21.417406 | 
2026-09-02 01:07:21.417490 | TASK [Install Ansible collection siblings]
2026-09-02 01:07:30.590366 | instance | changed:
2026-09-02 01:07:30.590590 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-02 01:07:30.590630 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-02 01:07:30.590661 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-02 01:07:30.590689 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-02 01:07:30.590717 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-02 01:07:30.590749 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-02 01:07:30.590778 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-02 01:07:30.590808 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-02 01:07:30.590834 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-02 01:07:30.590861 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-02 01:07:30.590888 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-02 01:07:30.590914 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-02 01:07:30.590940 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-02 01:07:30.590966 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-02 01:07:30.590992 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-02 01:07:30.591019 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-02 01:07:30.591045 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-02 01:07:30.591071 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-02 01:07:30.591097 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-02 01:07:30.591122 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-02 01:07:30.591148 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-02 01:07:30.591175 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-02 01:07:30.591202 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 01:07:30.591245 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-02 01:07:30.591272 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 01:07:30.591298 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 01:07:30.591323 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-02 01:07:30.591348 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-02 01:07:30.591373 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 01:07:30.591398 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-02 01:07:30.591422 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-02 01:07:30.591448 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 01:07:30.591474 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 01:07:30.591499 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-02 01:07:30.591524 | 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-02 01:07:30.591558 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.591585 | instance | Process install dependency map
2026-09-02 01:07:30.591610 | instance | Starting collection install process
2026-09-02 01:07:30.591636 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-02 01:07:30.591661 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-02 01:07:30.591686 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-02 01:07:30.591711 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-02 01:07:30.591736 | instance | Creating virtual environment at: .venv
2026-09-02 01:07:30.591760 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 01:07:30.591786 | instance | Downloading cryptography (4.2MiB)
2026-09-02 01:07:30.591815 | instance | Downloading setuptools (1.1MiB)
2026-09-02 01:07:30.591840 | instance | Downloading openstacksdk (1.7MiB)
2026-09-02 01:07:30.591866 | instance | Downloading kubernetes (1.9MiB)
2026-09-02 01:07:30.591891 | instance | Downloading rjsonnet (1.2MiB)
2026-09-02 01:07:30.591917 | instance | Downloading ansible-core (2.1MiB)
2026-09-02 01:07:30.591943 | instance | Downloading netaddr (2.2MiB)
2026-09-02 01:07:30.591969 | instance | Downloading pygments (1.2MiB)
2026-09-02 01:07:30.591994 | instance |    Building pyperclip==1.9.0
2026-09-02 01:07:30.592021 | instance |  Downloading rjsonnet
2026-09-02 01:07:30.592047 | instance |  Downloading netaddr
2026-09-02 01:07:30.592073 | instance |  Downloading pygments
2026-09-02 01:07:30.592100 | instance |  Downloading cryptography
2026-09-02 01:07:30.592125 | instance |  Downloading setuptools
2026-09-02 01:07:30.592151 | instance |  Downloading kubernetes
2026-09-02 01:07:30.592176 | instance |  Downloading ansible-core
2026-09-02 01:07:30.592201 | instance |  Downloading openstacksdk
2026-09-02 01:07:30.592227 | instance |       Built pyperclip==1.9.0
2026-09-02 01:07:30.592252 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 01:07:30.592278 | instance | Installed 79 packages in 78ms
2026-09-02 01:07:30.592303 | instance | Checked out ansible.posix to 1.6.0
2026-09-02 01:07:30.592328 | 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-02 01:07:30.592354 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.592380 | instance | Process install dependency map
2026-09-02 01:07:30.592413 | instance | Starting collection install process
2026-09-02 01:07:30.592439 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-02 01:07:30.592464 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-02 01:07:30.592489 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-02 01:07:30.592514 | instance | Checked out ansible.utils to v6.1.0
2026-09-02 01:07:30.592539 | 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-02 01:07:30.592563 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.592588 | instance | Process install dependency map
2026-09-02 01:07:30.592613 | instance | Starting collection install process
2026-09-02 01:07:30.592637 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-02 01:07:30.592662 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-02 01:07:30.592687 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-02 01:07:30.592712 | instance | Checked out atmosphere.common to v0.6.0
2026-09-02 01:07:30.592739 | 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-02 01:07:30.592765 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.592791 | instance | Process install dependency map
2026-09-02 01:07:30.592822 | instance | Starting collection install process
2026-09-02 01:07:30.592847 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-02 01:07:30.592871 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-02 01:07:30.592895 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-02 01:07:30.592920 | instance | Checked out community.crypto to 2.2.3
2026-09-02 01:07:30.592945 | 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-02 01:07:30.592995 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.593022 | instance | Process install dependency map
2026-09-02 01:07:30.593046 | instance | Starting collection install process
2026-09-02 01:07:30.593071 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-02 01:07:30.593096 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-02 01:07:30.593125 | instance | community.crypto:2.2.3 was installed successfully
2026-09-02 01:07:30.593149 | instance | Checked out community.general to 7.3.0
2026-09-02 01:07:30.593174 | 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-02 01:07:30.593198 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.593222 | instance | Process install dependency map
2026-09-02 01:07:30.593246 | instance | Starting collection install process
2026-09-02 01:07:30.593271 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-02 01:07:30.593295 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-02 01:07:30.593320 | instance | community.general:7.3.0 was installed successfully
2026-09-02 01:07:30.593351 | instance | Checked out community.mysql to 3.6.0
2026-09-02 01:07:30.593377 | 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-02 01:07:30.593401 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.593426 | instance | Process install dependency map
2026-09-02 01:07:30.593449 | instance | Starting collection install process
2026-09-02 01:07:30.593473 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-02 01:07:30.593497 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-02 01:07:30.593522 | instance | community.mysql:3.6.0 was installed successfully
2026-09-02 01:07:30.593546 | instance | Checked out kubernetes.core to 2.4.0
2026-09-02 01:07:30.593571 | 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-02 01:07:30.593597 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.593622 | instance | Process install dependency map
2026-09-02 01:07:30.593649 | instance | Starting collection install process
2026-09-02 01:07:30.593674 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-02 01:07:30.593700 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-02 01:07:30.593724 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-02 01:07:30.593749 | instance | Checked out openstack.cloud to 1.7.0
2026-09-02 01:07:30.593775 | 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-02 01:07:30.593800 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.593824 | instance | Process install dependency map
2026-09-02 01:07:30.593848 | instance | Starting collection install process
2026-09-02 01:07:30.593873 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-02 01:07:30.593897 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-02 01:07:30.593921 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-02 01:07:30.593946 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-02 01:07:30.593971 | 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-02 01:07:30.593996 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.594021 | instance | Process install dependency map
2026-09-02 01:07:30.594045 | instance | Starting collection install process
2026-09-02 01:07:30.594070 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-02 01:07:30.594095 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-02 01:07:30.594120 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-02 01:07:30.594144 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-02 01:07:30.594202 | 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-02 01:07:30.594229 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.594264 | instance | Process install dependency map
2026-09-02 01:07:30.594289 | instance | Starting collection install process
2026-09-02 01:07:30.594314 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-02 01:07:30.594339 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-02 01:07:30.594364 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-02 01:07:30.594389 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-02 01:07:30.594414 | 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-02 01:07:30.594439 | instance | Starting galaxy collection install process
2026-09-02 01:07:30.594463 | instance | Process install dependency map
2026-09-02 01:07:30.594488 | instance | Starting collection install process
2026-09-02 01:07:30.594513 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-02 01:07:30.594537 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-02 01:07:30.594562 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-02 01:07:30.600574 | 
2026-09-02 01:07:30.600626 | PLAY RECAP
2026-09-02 01:07:30.600672 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:07:30.600693 | 
2026-09-02 01:07:30.792837 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 01:07:30.798237 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 01:07:31.387549 | 
2026-09-02 01:07:31.387671 | PLAY [all]
2026-09-02 01:07:31.398849 | 
2026-09-02 01:07:31.398926 | TASK [Install "jq" for log collection]
2026-09-02 01:07:40.366296 | instance | changed
2026-09-02 01:07:40.368122 | 
2026-09-02 01:07:40.368180 | PLAY RECAP
2026-09-02 01:07:40.368229 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:07:40.368278 | 
2026-09-02 01:07:40.480457 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 01:07:40.487246 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 01:07:41.079211 | 
2026-09-02 01:07:41.079358 | PLAY [all]
2026-09-02 01:07:41.090383 | 
2026-09-02 01:07:41.090458 | LOOP [Check inventory directories]
2026-09-02 01:07:41.456013 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-02 01:07:41.639102 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-02 01:07:41.826826 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-02 01:07:41.845557 | 
2026-09-02 01:07:41.845873 | LOOP [Copy inventory file for Zuul]
2026-09-02 01:07:42.732510 | instance | changed:
2026-09-02 01:07:42.732697 | instance | {
2026-09-02 01:07:42.732724 | instance |   "ansible_loop_var": "item",
2026-09-02 01:07:42.732747 | instance |   "changed": false,
2026-09-02 01:07:42.732768 | instance |   "failed": false,
2026-09-02 01:07:42.732794 | instance |   "invocation": {
2026-09-02 01:07:42.732813 | instance |     "module_args": {
2026-09-02 01:07:42.732831 | instance |       "checksum_algorithm": "sha1",
2026-09-02 01:07:42.732849 | instance |       "follow": true,
2026-09-02 01:07:42.732866 | instance |       "get_attributes": true,
2026-09-02 01:07:42.732886 | instance |       "get_checksum": true,
2026-09-02 01:07:42.732904 | instance |       "get_mime": true,
2026-09-02 01:07:42.732921 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-02 01:07:42.732939 | instance |     }
2026-09-02 01:07:42.732986 | instance |   },
2026-09-02 01:07:42.733007 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-02 01:07:42.733025 | instance |   "stat": {
2026-09-02 01:07:42.733042 | instance |     "atime": 1788311243.3996952,
2026-09-02 01:07:42.733059 | instance |     "attr_flags": "e",
2026-09-02 01:07:42.733077 | instance |     "attributes": [
2026-09-02 01:07:42.733094 | instance |       "extents"
2026-09-02 01:07:42.733111 | instance |     ],
2026-09-02 01:07:42.733128 | instance |     "block_size": 4096,
2026-09-02 01:07:42.733145 | instance |     "blocks": 8,
2026-09-02 01:07:42.733162 | instance |     "charset": "unknown",
2026-09-02 01:07:42.733180 | instance |     "ctime": 1788311242.2756755,
2026-09-02 01:07:42.733198 | instance |     "dev": 64513,
2026-09-02 01:07:42.733216 | instance |     "device_type": 0,
2026-09-02 01:07:42.733234 | instance |     "executable": true,
2026-09-02 01:07:42.733251 | instance |     "exists": true,
2026-09-02 01:07:42.733268 | instance |     "gid": 1000,
2026-09-02 01:07:42.733285 | instance |     "gr_name": "zuul",
2026-09-02 01:07:42.733302 | instance |     "inode": 1047502,
2026-09-02 01:07:42.733319 | instance |     "isblk": false,
2026-09-02 01:07:42.733336 | instance |     "ischr": false,
2026-09-02 01:07:42.733353 | instance |     "isdir": true,
2026-09-02 01:07:42.733371 | instance |     "isfifo": false,
2026-09-02 01:07:42.733387 | instance |     "isgid": false,
2026-09-02 01:07:42.733404 | instance |     "islnk": false,
2026-09-02 01:07:42.733421 | instance |     "isreg": false,
2026-09-02 01:07:42.733437 | instance |     "issock": false,
2026-09-02 01:07:42.733454 | instance |     "isuid": false,
2026-09-02 01:07:42.733471 | instance |     "mimetype": "unknown",
2026-09-02 01:07:42.733488 | instance |     "mode": "0755",
2026-09-02 01:07:42.733505 | instance |     "mtime": 1788311242.2756755,
2026-09-02 01:07:42.733522 | instance |     "nlink": 22,
2026-09-02 01:07:42.733538 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-02 01:07:42.733556 | instance |     "pw_name": "zuul",
2026-09-02 01:07:42.733574 | instance |     "readable": true,
2026-09-02 01:07:42.733592 | instance |     "rgrp": true,
2026-09-02 01:07:42.733609 | instance |     "roth": true,
2026-09-02 01:07:42.733626 | instance |     "rusr": true,
2026-09-02 01:07:42.733643 | instance |     "size": 4096,
2026-09-02 01:07:42.733660 | instance |     "uid": 1000,
2026-09-02 01:07:42.733677 | instance |     "version": "748143247",
2026-09-02 01:07:42.733694 | instance |     "wgrp": false,
2026-09-02 01:07:42.733712 | instance |     "woth": false,
2026-09-02 01:07:42.733730 | instance |     "writeable": true,
2026-09-02 01:07:42.733748 | instance |     "wusr": true,
2026-09-02 01:07:42.733765 | instance |     "xgrp": true,
2026-09-02 01:07:42.733782 | instance |     "xoth": true,
2026-09-02 01:07:42.733799 | instance |     "xusr": true
2026-09-02 01:07:42.733815 | instance |   }
2026-09-02 01:07:42.733832 | instance | }
2026-09-02 01:07:42.733866 | instance | ok: All items complete
2026-09-02 01:07:42.733887 | 
2026-09-02 01:07:42.735102 | instance | skipping: Conditional result was False
2026-09-02 01:07:42.735743 | instance | skipping: Conditional result was False
2026-09-02 01:07:42.749982 | 
2026-09-02 01:07:42.750376 | LOOP [Switch "ansible_host" to private IP]
2026-09-02 01:07:43.148577 | instance | changed: 1 replacements made
2026-09-02 01:07:43.149912 | instance | skipping: Conditional result was False
2026-09-02 01:07:43.150485 | instance | skipping: Conditional result was False
2026-09-02 01:07:43.169093 | 
2026-09-02 01:07:43.169306 | TASK [Run Molecule scenario]
2026-09-02 01:07:44.199062 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-02 01:07:44.593410 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-02 01:07:44.593532 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-02 01:07:54.930441 | instance | INFO     [csi > dependency] Executing
2026-09-02 01:07:54.930622 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-02 01:07:54.930741 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-02 01:07:54.930850 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-02 01:07:54.937967 | instance | INFO     [csi > cleanup] Executing
2026-09-02 01:07:54.938293 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 01:07:54.945421 | instance | INFO     [csi > destroy] Executing
2026-09-02 01:07:54.945470 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-02 01:07:54.945635 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-02 01:07:54.952410 | instance | INFO     [csi > syntax] Executing
2026-09-02 01:07:56.444776 | instance |
2026-09-02 01:07:56.445205 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-02 01:07:56.501738 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-02 01:07:56.515883 | instance | INFO     [csi > create] Executing
2026-09-02 01:07:56.520181 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 01:07:56.539877 | instance | INFO     [csi > prepare] Executing
2026-09-02 01:07:57.297792 | instance |
2026-09-02 01:07:57.298077 | instance | PLAY [Prepare] *****************************************************************
2026-09-02 01:07:57.298324 | instance |
2026-09-02 01:07:57.298588 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:07:57.298857 | instance | Wednesday 02 September 2026  01:07:57 +0000 (0:00:00.023)       0:00:00.023 ***
2026-09-02 01:07:58.538185 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 01:07:58.538466 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 01:07:58.538854 | instance | interpreter could change the meaning of that path. See
2026-09-02 01:07:58.539224 | instance | https://docs.ansible.com/ansible-
2026-09-02 01:07:58.539602 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 01:07:58.557408 | instance | ok: [instance]
2026-09-02 01:07:58.557681 | instance |
2026-09-02 01:07:58.557936 | instance | TASK [Configure short hostname] ************************************************
2026-09-02 01:07:58.558185 | instance | Wednesday 02 September 2026  01:07:58 +0000 (0:00:01.258)       0:00:01.282 ***
2026-09-02 01:07:59.306852 | instance | changed: [instance]
2026-09-02 01:07:59.307059 | instance |
2026-09-02 01:07:59.307322 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-02 01:07:59.307587 | instance | Wednesday 02 September 2026  01:07:59 +0000 (0:00:00.750)       0:00:02.033 ***
2026-09-02 01:07:59.590416 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-02 01:07:59.590676 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-02 01:07:59.590926 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-02 01:07:59.601227 | instance | changed: [instance]
2026-09-02 01:07:59.601464 | instance |
2026-09-02 01:07:59.601875 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-02 01:07:59.602158 | instance | Wednesday 02 September 2026  01:07:59 +0000 (0:00:00.294)       0:00:02.327 ***
2026-09-02 01:08:00.398223 | instance | ok: [instance]
2026-09-02 01:08:00.398311 | instance |
2026-09-02 01:08:00.398423 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-02 01:08:00.398538 | instance |
2026-09-02 01:08:00.398650 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:00.398770 | instance | Wednesday 02 September 2026  01:08:00 +0000 (0:00:00.796)       0:00:03.124 ***
2026-09-02 01:08:01.153896 | instance | ok: [instance]
2026-09-02 01:08:01.154104 | instance |
2026-09-02 01:08:01.154385 | instance | TASK [Install depedencies] *****************************************************
2026-09-02 01:08:01.154659 | instance | Wednesday 02 September 2026  01:08:01 +0000 (0:00:00.755)       0:00:03.880 ***
2026-09-02 01:08:24.556228 | instance | changed: [instance]
2026-09-02 01:08:24.556429 | instance |
2026-09-02 01:08:24.556680 | instance | TASK [Start up service] ********************************************************
2026-09-02 01:08:24.556908 | instance | Wednesday 02 September 2026  01:08:24 +0000 (0:00:23.402)       0:00:27.282 ***
2026-09-02 01:08:25.096831 | instance | ok: [instance]
2026-09-02 01:08:25.096916 | instance |
2026-09-02 01:08:25.097067 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-02 01:08:25.097242 | instance | Wednesday 02 September 2026  01:08:25 +0000 (0:00:00.541)       0:00:27.823 ***
2026-09-02 01:08:25.407991 | instance | ok: [instance]
2026-09-02 01:08:25.408070 | instance |
2026-09-02 01:08:25.408260 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-02 01:08:25.408429 | instance | Wednesday 02 September 2026  01:08:25 +0000 (0:00:00.310)       0:00:28.134 ***
2026-09-02 01:08:26.112839 | instance | changed: [instance]
2026-09-02 01:08:26.112898 | instance |
2026-09-02 01:08:26.112910 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-02 01:08:26.112941 | instance | Wednesday 02 September 2026  01:08:26 +0000 (0:00:00.704)       0:00:28.838 ***
2026-09-02 01:08:26.321845 | instance | ok: [instance]
2026-09-02 01:08:26.322125 | instance |
2026-09-02 01:08:26.322495 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-02 01:08:26.322988 | instance | Wednesday 02 September 2026  01:08:26 +0000 (0:00:00.209)       0:00:29.048 ***
2026-09-02 01:08:26.349208 | instance | skipping: [instance]
2026-09-02 01:08:26.349544 | instance |
2026-09-02 01:08:26.349847 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-02 01:08:26.350168 | instance | Wednesday 02 September 2026  01:08:26 +0000 (0:00:00.027)       0:00:29.075 ***
2026-09-02 01:08:26.932719 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:26.933178 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:26.933518 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:26.933826 | instance |
2026-09-02 01:08:26.934178 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-02 01:08:26.934499 | instance | Wednesday 02 September 2026  01:08:26 +0000 (0:00:00.581)       0:00:29.656 ***
2026-09-02 01:08:27.601948 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:27.602085 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:27.602258 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:27.602427 | instance |
2026-09-02 01:08:27.602580 | instance | TASK [Start loop devices] ******************************************************
2026-09-02 01:08:27.602726 | instance | Wednesday 02 September 2026  01:08:27 +0000 (0:00:00.670)       0:00:30.327 ***
2026-09-02 01:08:28.341127 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:28.341462 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:28.341862 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:28.342244 | instance |
2026-09-02 01:08:28.342579 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-02 01:08:28.342973 | instance | Wednesday 02 September 2026  01:08:28 +0000 (0:00:00.740)       0:00:31.067 ***
2026-09-02 01:08:31.476103 | instance | changed: [instance] => (item=osd0)
2026-09-02 01:08:31.476152 | instance | changed: [instance] => (item=osd1)
2026-09-02 01:08:31.476163 | instance | changed: [instance] => (item=osd2)
2026-09-02 01:08:31.476173 | instance |
2026-09-02 01:08:31.476606 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-02 01:08:31.476701 | instance | Wednesday 02 September 2026  01:08:31 +0000 (0:00:03.133)       0:00:34.201 ***
2026-09-02 01:08:33.494825 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-02 01:08:33.500259 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-02 01:08:33.500331 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-02 01:08:33.500343 | instance |
2026-09-02 01:08:33.500354 | instance | PLAY RECAP *********************************************************************
2026-09-02 01:08:33.500364 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-02 01:08:33.500374 | instance |
2026-09-02 01:08:33.500382 | instance | Wednesday 02 September 2026  01:08:33 +0000 (0:00:02.021)       0:00:36.222 ***
2026-09-02 01:08:33.500391 | instance | ===============================================================================
2026-09-02 01:08:33.500400 | instance | Install depedencies ---------------------------------------------------- 23.40s
2026-09-02 01:08:33.500409 | instance | Create a volume group for each loop device ------------------------------ 3.13s
2026-09-02 01:08:33.500418 | instance | Create a logical volume for each loop device ---------------------------- 2.02s
2026-09-02 01:08:33.500427 | instance | Gathering Facts --------------------------------------------------------- 1.26s
2026-09-02 01:08:33.500435 | instance | Purge "snapd" package --------------------------------------------------- 0.80s
2026-09-02 01:08:33.500444 | instance | Gathering Facts --------------------------------------------------------- 0.76s
2026-09-02 01:08:33.500453 | instance | Configure short hostname ------------------------------------------------ 0.75s
2026-09-02 01:08:33.500462 | instance | Start loop devices ------------------------------------------------------ 0.74s
2026-09-02 01:08:33.500470 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.70s
2026-09-02 01:08:33.500479 | instance | Set permissions on loopback devices ------------------------------------- 0.67s
2026-09-02 01:08:33.500488 | instance | Create devices for Ceph ------------------------------------------------- 0.58s
2026-09-02 01:08:33.500496 | instance | Start up service -------------------------------------------------------- 0.54s
2026-09-02 01:08:33.500505 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s
2026-09-02 01:08:33.500514 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-02 01:08:33.500523 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-02 01:08:33.500531 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-02 01:08:33.569164 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-02 01:08:33.584960 | instance | INFO     [csi > converge] Executing
2026-09-02 01:08:34.915778 | instance |
2026-09-02 01:08:34.916064 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-02 01:08:34.916204 | instance |
2026-09-02 01:08:34.916340 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:34.916470 | instance | Wednesday 02 September 2026  01:08:34 +0000 (0:00:00.006)       0:00:00.006 ***
2026-09-02 01:08:35.920698 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 01:08:35.921122 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 01:08:35.921446 | instance | interpreter could change the meaning of that path. See
2026-09-02 01:08:35.921744 | instance | https://docs.ansible.com/ansible-
2026-09-02 01:08:35.922082 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 01:08:35.937984 | instance | ok: [instance]
2026-09-02 01:08:35.938366 | instance |
2026-09-02 01:08:35.938668 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-02 01:08:35.938959 | instance | Wednesday 02 September 2026  01:08:35 +0000 (0:00:01.019)       0:00:01.026 ***
2026-09-02 01:08:36.004214 | instance | ok: [instance] => {
2026-09-02 01:08:36.004463 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-02 01:08:36.004655 | instance | }
2026-09-02 01:08:36.004857 | instance |
2026-09-02 01:08:36.005069 | instance | PLAY [all] *********************************************************************
2026-09-02 01:08:36.005287 | instance |
2026-09-02 01:08:36.005479 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:36.005684 | instance | Wednesday 02 September 2026  01:08:35 +0000 (0:00:00.067)       0:00:01.093 ***
2026-09-02 01:08:36.946125 | instance | ok: [instance]
2026-09-02 01:08:36.946581 | instance |
2026-09-02 01:08:36.947061 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-02 01:08:36.947517 | instance | Wednesday 02 September 2026  01:08:36 +0000 (0:00:00.943)       0:00:02.036 ***
2026-09-02 01:08:37.157256 | instance | ok: [instance]
2026-09-02 01:08:37.157363 | instance |
2026-09-02 01:08:37.157376 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-02 01:08:37.157398 | instance |
2026-09-02 01:08:37.157652 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 01:08:37.157895 | instance | Wednesday 02 September 2026  01:08:37 +0000 (0:00:00.210)       0:00:02.247 ***
2026-09-02 01:08:38.102565 | instance | ok: [instance]
2026-09-02 01:08:38.102613 | instance |
2026-09-02 01:08:38.102624 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 01:08:38.102643 | instance | Wednesday 02 September 2026  01:08:38 +0000 (0:00:00.944)       0:00:03.192 ***
2026-09-02 01:08:38.419946 | instance | ok: [instance]
2026-09-02 01:08:38.420676 | instance |
2026-09-02 01:08:38.420725 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 01:08:38.420737 | instance | Wednesday 02 September 2026  01:08:38 +0000 (0:00:00.318)       0:00:03.510 ***
2026-09-02 01:08:38.476901 | instance | skipping: [instance]
2026-09-02 01:08:38.477282 | instance |
2026-09-02 01:08:38.477426 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-02 01:08:38.477668 | instance | Wednesday 02 September 2026  01:08:38 +0000 (0:00:00.056)       0:00:03.567 ***
2026-09-02 01:08:38.840959 | instance | changed: [instance]
2026-09-02 01:08:38.841680 | instance |
2026-09-02 01:08:38.841728 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 01:08:38.841739 | instance | Wednesday 02 September 2026  01:08:38 +0000 (0:00:00.364)       0:00:03.931 ***
2026-09-02 01:08:38.937663 | instance | ok: [instance] => {
2026-09-02 01:08:38.937906 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-02 01:08:38.938257 | instance | }
2026-09-02 01:08:38.938541 | instance |
2026-09-02 01:08:38.938837 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 01:08:38.939160 | instance | Wednesday 02 September 2026  01:08:38 +0000 (0:00:00.096)       0:00:04.028 ***
2026-09-02 01:08:39.633269 | instance | changed: [instance]
2026-09-02 01:08:39.633373 | instance |
2026-09-02 01:08:39.633391 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 01:08:39.633400 | instance | Wednesday 02 September 2026  01:08:39 +0000 (0:00:00.695)       0:00:04.723 ***
2026-09-02 01:08:39.706578 | instance | skipping: [instance]
2026-09-02 01:08:39.706717 | instance |
2026-09-02 01:08:39.706736 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 01:08:39.706936 | instance | Wednesday 02 September 2026  01:08:39 +0000 (0:00:00.072)       0:00:04.796 ***
2026-09-02 01:08:39.775341 | instance | skipping: [instance]
2026-09-02 01:08:39.775462 | instance |
2026-09-02 01:08:39.775763 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 01:08:39.775835 | instance | Wednesday 02 September 2026  01:08:39 +0000 (0:00:00.068)       0:00:04.865 ***
2026-09-02 01:08:40.036639 | instance | ok: [instance]
2026-09-02 01:08:40.037104 | instance |
2026-09-02 01:08:40.037151 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 01:08:40.037159 | instance | Wednesday 02 September 2026  01:08:40 +0000 (0:00:00.261)       0:00:05.127 ***
2026-09-02 01:08:41.303799 | instance | ok: [instance]
2026-09-02 01:08:41.303995 | instance |
2026-09-02 01:08:41.304384 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 01:08:41.304690 | instance | Wednesday 02 September 2026  01:08:41 +0000 (0:00:01.267)       0:00:06.394 ***
2026-09-02 01:08:41.374408 | instance | ok: [instance] => {
2026-09-02 01:08:41.374462 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-02 01:08:41.374568 | instance | }
2026-09-02 01:08:41.374657 | instance |
2026-09-02 01:08:41.374798 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 01:08:41.374929 | instance | Wednesday 02 September 2026  01:08:41 +0000 (0:00:00.071)       0:00:06.465 ***
2026-09-02 01:08:42.123368 | instance | changed: [instance]
2026-09-02 01:08:42.123417 | instance |
2026-09-02 01:08:42.123428 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 01:08:42.123438 | instance | Wednesday 02 September 2026  01:08:42 +0000 (0:00:00.747)       0:00:07.213 ***
2026-09-02 01:08:45.014748 | instance | changed: [instance]
2026-09-02 01:08:45.015471 | instance |
2026-09-02 01:08:45.015502 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-02 01:08:45.015514 | instance | Wednesday 02 September 2026  01:08:45 +0000 (0:00:02.892)       0:00:10.105 ***
2026-09-02 01:08:45.054224 | instance | skipping: [instance]
2026-09-02 01:08:45.054982 | instance |
2026-09-02 01:08:45.055014 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-02 01:08:45.055026 | instance | Wednesday 02 September 2026  01:08:45 +0000 (0:00:00.039)       0:00:10.145 ***
2026-09-02 01:08:45.094838 | instance | skipping: [instance]
2026-09-02 01:08:45.095848 | instance |
2026-09-02 01:08:45.095882 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-02 01:08:45.095894 | instance | Wednesday 02 September 2026  01:08:45 +0000 (0:00:00.040)       0:00:10.185 ***
2026-09-02 01:08:45.133924 | instance | skipping: [instance]
2026-09-02 01:08:45.134129 | instance |
2026-09-02 01:08:45.134529 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-02 01:08:45.134854 | instance | Wednesday 02 September 2026  01:08:45 +0000 (0:00:00.038)       0:00:10.224 ***
2026-09-02 01:08:50.507055 | instance | changed: [instance]
2026-09-02 01:08:50.507320 | instance |
2026-09-02 01:08:50.507525 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-02 01:08:50.507868 | instance | Wednesday 02 September 2026  01:08:50 +0000 (0:00:05.373)       0:00:15.597 ***
2026-09-02 01:08:51.140068 | instance | changed: [instance]
2026-09-02 01:08:51.140196 | instance |
2026-09-02 01:08:51.140215 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-02 01:08:51.140358 | instance | Wednesday 02 September 2026  01:08:51 +0000 (0:00:00.633)       0:00:16.231 ***
2026-09-02 01:08:52.152637 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-02 01:08:52.154446 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-02 01:08:52.154500 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-02 01:08:52.154511 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-02 01:08:52.154521 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-02 01:08:52.154531 | instance |
2026-09-02 01:08:52.154548 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-02 01:08:52.154558 | instance | Wednesday 02 September 2026  01:08:52 +0000 (0:00:01.011)       0:00:17.243 ***
2026-09-02 01:08:52.793897 | instance | changed: [instance]
2026-09-02 01:08:52.794978 | instance |
2026-09-02 01:08:52.795028 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-02 01:08:52.795037 | instance | Wednesday 02 September 2026  01:08:52 +0000 (0:00:00.610)       0:00:17.854 ***
2026-09-02 01:08:52.795044 | instance |
2026-09-02 01:08:52.795051 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 01:08:52.795057 | instance | Wednesday 02 September 2026  01:08:52 +0000 (0:00:00.030)       0:00:17.884 ***
2026-09-02 01:08:53.613608 | instance | ok: [instance]
2026-09-02 01:08:53.613824 | instance |
2026-09-02 01:08:53.614264 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-02 01:08:53.614588 | instance | Wednesday 02 September 2026  01:08:53 +0000 (0:00:00.819)       0:00:18.704 ***
2026-09-02 01:08:54.078602 | instance | changed: [instance]
2026-09-02 01:08:54.078835 | instance |
2026-09-02 01:08:54.079433 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-02 01:08:54.079776 | instance | Wednesday 02 September 2026  01:08:54 +0000 (0:00:00.464)       0:00:19.169 ***
2026-09-02 01:08:54.601070 | instance | changed: [instance]
2026-09-02 01:08:54.601933 | instance |
2026-09-02 01:08:54.601987 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 01:08:54.601998 | instance | Wednesday 02 September 2026  01:08:54 +0000 (0:00:00.523)       0:00:19.692 ***
2026-09-02 01:08:54.816257 | instance | ok: [instance]
2026-09-02 01:08:54.816332 | instance |
2026-09-02 01:08:54.816677 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 01:08:54.816715 | instance | Wednesday 02 September 2026  01:08:54 +0000 (0:00:00.215)       0:00:19.907 ***
2026-09-02 01:08:54.894149 | instance | ok: [instance] => {
2026-09-02 01:08:54.895440 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-02 01:08:54.895472 | instance | }
2026-09-02 01:08:54.895484 | instance |
2026-09-02 01:08:54.895494 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 01:08:54.895504 | instance | Wednesday 02 September 2026  01:08:54 +0000 (0:00:00.077)       0:00:19.985 ***
2026-09-02 01:09:40.054271 | instance | changed: [instance]
2026-09-02 01:09:40.055113 | instance |
2026-09-02 01:09:40.055152 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 01:09:40.055168 | instance | Wednesday 02 September 2026  01:09:40 +0000 (0:00:45.159)       0:01:05.144 ***
2026-09-02 01:09:44.375894 | instance | changed: [instance]
2026-09-02 01:09:44.376627 | instance |
2026-09-02 01:09:44.376680 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-02 01:09:44.376692 | instance | Wednesday 02 September 2026  01:09:44 +0000 (0:00:04.321)       0:01:09.466 ***
2026-09-02 01:09:45.426337 | instance | ok: [instance]
2026-09-02 01:09:45.426415 | instance |
2026-09-02 01:09:45.426622 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-02 01:09:45.426659 | instance | Wednesday 02 September 2026  01:09:45 +0000 (0:00:01.051)       0:01:10.517 ***
2026-09-02 01:09:45.845198 | instance | changed: [instance]
2026-09-02 01:09:45.845915 | instance |
2026-09-02 01:09:45.845966 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-02 01:09:45.845978 | instance | Wednesday 02 September 2026  01:09:45 +0000 (0:00:00.418)       0:01:10.935 ***
2026-09-02 01:09:46.354356 | instance | changed: [instance]
2026-09-02 01:09:46.355082 | instance |
2026-09-02 01:09:46.355132 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-02 01:09:46.355144 | instance | Wednesday 02 September 2026  01:09:46 +0000 (0:00:00.509)       0:01:11.444 ***
2026-09-02 01:09:46.964559 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-02 01:09:46.965200 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-02 01:09:46.965427 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-02 01:09:46.965454 | instance |
2026-09-02 01:09:46.965905 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-02 01:09:46.965957 | instance | Wednesday 02 September 2026  01:09:46 +0000 (0:00:00.610)       0:01:12.055 ***
2026-09-02 01:09:47.420934 | instance | changed: [instance]
2026-09-02 01:09:47.421208 | instance |
2026-09-02 01:09:47.421436 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-02 01:09:47.421470 | instance | Wednesday 02 September 2026  01:09:47 +0000 (0:00:00.457)       0:01:12.512 ***
2026-09-02 01:09:47.937435 | instance | changed: [instance]
2026-09-02 01:09:47.937546 | instance |
2026-09-02 01:09:47.938349 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-02 01:09:47.938386 | instance | Wednesday 02 September 2026  01:09:47 +0000 (0:00:00.488)       0:01:13.000 ***
2026-09-02 01:09:47.938394 | instance |
2026-09-02 01:09:47.938401 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 01:09:47.938407 | instance | Wednesday 02 September 2026  01:09:47 +0000 (0:00:00.028)       0:01:13.028 ***
2026-09-02 01:09:48.708287 | instance | ok: [instance]
2026-09-02 01:09:48.708337 | instance |
2026-09-02 01:09:48.708349 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-02 01:09:48.708366 | instance | Wednesday 02 September 2026  01:09:48 +0000 (0:00:00.769)       0:01:13.798 ***
2026-09-02 01:09:49.615863 | instance | changed: [instance]
2026-09-02 01:09:49.616573 | instance |
2026-09-02 01:09:49.616625 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-02 01:09:49.616636 | instance | Wednesday 02 September 2026  01:09:49 +0000 (0:00:00.908)       0:01:14.706 ***
2026-09-02 01:09:50.243305 | instance | changed: [instance]
2026-09-02 01:09:50.243428 | instance |
2026-09-02 01:09:50.243694 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-02 01:09:50.243737 | instance | Wednesday 02 September 2026  01:09:50 +0000 (0:00:00.627)       0:01:15.334 ***
2026-09-02 01:09:50.302212 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-02 01:09:50.302249 | instance |
2026-09-02 01:09:50.302266 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-02 01:09:50.303019 | instance | Wednesday 02 September 2026  01:09:50 +0000 (0:00:00.056)       0:01:15.391 ***
2026-09-02 01:09:55.180242 | instance | changed: [instance]
2026-09-02 01:09:55.180287 | instance |
2026-09-02 01:09:55.180294 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-02 01:09:55.180301 | instance | Wednesday 02 September 2026  01:09:55 +0000 (0:00:04.879)       0:01:20.270 ***
2026-09-02 01:09:55.845587 | instance | ok: [instance] => (item=chronyd)
2026-09-02 01:09:55.846521 | instance | ok: [instance] => (item=sshd)
2026-09-02 01:09:55.846575 | instance |
2026-09-02 01:09:55.846586 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-02 01:09:55.846596 | instance | Wednesday 02 September 2026  01:09:55 +0000 (0:00:00.665)       0:01:20.936 ***
2026-09-02 01:09:57.332795 | instance | changed: [instance]
2026-09-02 01:09:57.333011 | instance |
2026-09-02 01:09:57.333596 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-02 01:09:57.333652 | instance | Wednesday 02 September 2026  01:09:57 +0000 (0:00:01.487)       0:01:22.423 ***
2026-09-02 01:09:57.560401 | instance | ok: [instance]
2026-09-02 01:09:57.561145 | instance |
2026-09-02 01:09:57.561198 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-02 01:09:57.561210 | instance | Wednesday 02 September 2026  01:09:57 +0000 (0:00:00.227)       0:01:22.650 ***
2026-09-02 01:09:58.266740 | instance | changed: [instance]
2026-09-02 01:09:58.267207 | instance |
2026-09-02 01:09:58.267250 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-02 01:09:58.267258 | instance | Wednesday 02 September 2026  01:09:58 +0000 (0:00:00.706)       0:01:23.357 ***
2026-09-02 01:09:58.637793 | instance | changed: [instance]
2026-09-02 01:09:58.638400 | instance |
2026-09-02 01:09:58.638440 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-02 01:09:58.638448 | instance | Wednesday 02 September 2026  01:09:58 +0000 (0:00:00.370)       0:01:23.728 ***
2026-09-02 01:09:58.768864 | instance | ok: [instance]
2026-09-02 01:09:58.769249 | instance |
2026-09-02 01:09:58.769369 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-02 01:09:58.769623 | instance | Wednesday 02 September 2026  01:09:58 +0000 (0:00:00.130)       0:01:23.859 ***
2026-09-02 01:09:59.008064 | instance | ok: [instance] => (item=instance)
2026-09-02 01:09:59.008782 | instance |
2026-09-02 01:09:59.008829 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-02 01:09:59.008841 | instance | Wednesday 02 September 2026  01:09:59 +0000 (0:00:00.239)       0:01:24.098 ***
2026-09-02 01:09:59.076589 | instance | ok: [instance]
2026-09-02 01:09:59.077172 | instance |
2026-09-02 01:09:59.077229 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-02 01:09:59.077351 | instance | Wednesday 02 September 2026  01:09:59 +0000 (0:00:00.068)       0:01:24.167 ***
2026-09-02 01:09:59.170405 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-02 01:09:59.170908 | instance |
2026-09-02 01:09:59.170938 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-02 01:09:59.170946 | instance | Wednesday 02 September 2026  01:09:59 +0000 (0:00:00.094)       0:01:24.261 ***
2026-09-02 01:09:59.502085 | instance | changed: [instance]
2026-09-02 01:09:59.502132 | instance |
2026-09-02 01:09:59.502144 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-02 01:09:59.502155 | instance | Wednesday 02 September 2026  01:09:59 +0000 (0:00:00.330)       0:01:24.591 ***
2026-09-02 01:10:00.237724 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-02 01:10:00.239605 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-02 01:10:00.239647 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-02 01:10:00.239665 | instance |
2026-09-02 01:10:00.239681 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-02 01:10:00.239696 | instance | Wednesday 02 September 2026  01:10:00 +0000 (0:00:00.736)       0:01:25.328 ***
2026-09-02 01:11:29.431866 | instance | ok: [instance]
2026-09-02 01:11:29.432579 | instance |
2026-09-02 01:11:29.432611 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-02 01:11:29.432622 | instance | Wednesday 02 September 2026  01:11:29 +0000 (0:01:29.194)       0:02:54.522 ***
2026-09-02 01:11:29.690998 | instance | changed: [instance]
2026-09-02 01:11:29.691212 | instance |
2026-09-02 01:11:29.691472 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-02 01:11:29.691742 | instance | Wednesday 02 September 2026  01:11:29 +0000 (0:00:00.258)       0:02:54.781 ***
2026-09-02 01:11:29.788133 | instance | ok: [instance]
2026-09-02 01:11:29.788222 | instance |
2026-09-02 01:11:29.788239 | instance | TASK [Install Ceph host] *******************************************************
2026-09-02 01:11:29.788388 | instance | Wednesday 02 September 2026  01:11:29 +0000 (0:00:00.098)       0:02:54.879 ***
2026-09-02 01:11:29.876236 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-02 01:11:29.876294 | instance |
2026-09-02 01:11:29.876522 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-02 01:11:29.876569 | instance | Wednesday 02 September 2026  01:11:29 +0000 (0:00:00.087)       0:02:54.967 ***
2026-09-02 01:11:31.500987 | instance | ok: [instance]
2026-09-02 01:11:31.501275 | instance |
2026-09-02 01:11:31.501584 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-02 01:11:31.501912 | instance | Wednesday 02 September 2026  01:11:31 +0000 (0:00:01.624)       0:02:56.591 ***
2026-09-02 01:11:31.582348 | instance | ok: [instance] => (item=instance)
2026-09-02 01:11:31.582541 | instance |
2026-09-02 01:11:31.582789 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-02 01:11:31.583037 | instance | Wednesday 02 September 2026  01:11:31 +0000 (0:00:00.081)       0:02:56.672 ***
2026-09-02 01:11:31.945164 | instance | ok: [instance]
2026-09-02 01:11:31.945293 | instance |
2026-09-02 01:11:31.945576 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-02 01:11:31.945628 | instance | Wednesday 02 September 2026  01:11:31 +0000 (0:00:00.363)       0:02:57.036 ***
2026-09-02 01:11:34.409007 | instance | ok: [instance]
2026-09-02 01:11:34.409171 | instance |
2026-09-02 01:11:34.409567 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-02 01:11:34.409614 | instance | Wednesday 02 September 2026  01:11:34 +0000 (0:00:02.463)       0:02:59.499 ***
2026-09-02 01:12:31.046627 | 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-02 01:12:31.047541 | 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_2rn23z8w/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_2rn23z8w/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_2rn23z8w/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_2rn23z8w/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-02T01:12:30.879+0000 7f89ddb53640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCYd5dqA4lGLCAACfFbEkUiV/o6Lj/cUpBMsn4coYd9NJ7JjTqdNr1HHoE=: Malformed input [buffer:3]\n2026-09-02T01:12:30.879+0000 7f89ddb53640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:12:30.879+0000 7f89ddb53640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCYd5dqA4lGLCAACfFbEkUiV/o6Lj/cUpBMsn4coYd9NJ7JjTqdNr1HHoE=: Malformed input [buffer:3]\n2026-09-02T01:12:30.879+0000 7f89ddb53640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:12:30.879+0000 7f89ddb53640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCYd5dqA4lGLCAACfFbEkUiV/o6Lj/cUpBMsn4coYd9NJ7JjTqdNr1HHoE=: Malformed input [buffer:3]\n2026-09-02T01:12:30.879+0000 7f89ddb53640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T01:12:30.879+0000 7f89ddb53640 -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-02 01:12:31.050399 | instance |
2026-09-02 01:12:31.050646 | instance | PLAY RECAP *********************************************************************
2026-09-02 01:12:31.052321 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-02 01:12:31.052366 | instance |
2026-09-02 01:12:31.052378 | instance | Wednesday 02 September 2026  01:12:31 +0000 (0:00:56.642)       0:03:56.141 ***
2026-09-02 01:12:31.052388 | instance | ===============================================================================
2026-09-02 01:12:31.052397 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 89.19s
2026-09-02 01:12:31.052406 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 56.64s
2026-09-02 01:12:31.052420 | instance | vexxhost.containers.download_artifact : Download item ------------------ 45.16s
2026-09-02 01:12:31.053163 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.37s
2026-09-02 01:12:31.053197 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.88s
2026-09-02 01:12:31.053208 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.32s
2026-09-02 01:12:31.053377 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.89s
2026-09-02 01:12:31.053803 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.46s
2026-09-02 01:12:31.054411 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.62s
2026-09-02 01:12:31.054442 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.59s
2026-09-02 01:12:31.055281 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.49s
2026-09-02 01:12:31.055309 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.27s
2026-09-02 01:12:31.055319 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.05s
2026-09-02 01:12:31.055332 | instance | Gathering Facts --------------------------------------------------------- 1.02s
2026-09-02 01:12:31.055513 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s
2026-09-02 01:12:31.055833 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-09-02 01:12:31.056138 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-09-02 01:12:31.056460 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.91s
2026-09-02 01:12:31.056771 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.75s
2026-09-02 01:12:31.057074 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.74s
2026-09-02 01:12:31.150821 | 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-02 01:12:31.151076 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-02 01:12:31.151255 | 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-02 01:12:31.580121 | instance | ERROR
2026-09-02 01:12:31.580402 | instance | {
2026-09-02 01:12:31.580466 | instance |   "delta": "0:04:47.668148",
2026-09-02 01:12:31.580512 | instance |   "end": "2026-09-02 01:12:31.212999",
2026-09-02 01:12:31.580555 | instance |   "msg": "non-zero return code",
2026-09-02 01:12:31.580595 | instance |   "rc": 2,
2026-09-02 01:12:31.580635 | instance |   "start": "2026-09-02 01:07:43.544851"
2026-09-02 01:12:31.580675 | instance | }
failure
2026-09-02 01:12:31.587465 | 
2026-09-02 01:12:31.587512 | PLAY RECAP
2026-09-02 01:12:31.587555 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-02 01:12:31.587576 | 
2026-09-02 01:12:31.764254 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 01:12:31.768303 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 01:12:32.396553 | 
2026-09-02 01:12:32.396712 | PLAY [all]
2026-09-02 01:12:32.411077 | 
2026-09-02 01:12:32.411155 | TASK [gather-host-logs : creating directory for system status]
2026-09-02 01:12:32.778319 | instance | changed
2026-09-02 01:12:32.784906 | 
2026-09-02 01:12:32.785017 | TASK [gather-host-logs : Get logs for each host]
2026-09-02 01:12:33.171065 | instance | + systemd-cgls --full --all --no-pager
2026-09-02 01:12:33.185132 | instance | + ip addr
2026-09-02 01:12:33.187274 | instance | + ip route
2026-09-02 01:12:33.190026 | instance | + lsblk
2026-09-02 01:12:33.193013 | instance | + mount
2026-09-02 01:12:33.194524 | instance | + docker images
2026-09-02 01:12:33.217011 | instance | + brctl show
2026-09-02 01:12:33.217471 | instance | /bin/bash: line 8: brctl: command not found
2026-09-02 01:12:33.217774 | instance | + ps aux --sort=-%mem
2026-09-02 01:12:33.231147 | instance | + dpkg -l
2026-09-02 01:12:33.241731 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-02 01:12:33.242243 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-02 01:12:33.260803 | instance | + '[' '!' -z '' ']'
2026-09-02 01:12:33.327667 | instance | ok: Runtime: 0:00:00.094578
2026-09-02 01:12:33.334132 | 
2026-09-02 01:12:33.334210 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-02 01:12:34.006244 | instance | changed:
2026-09-02 01:12:34.006422 | instance | created directory /var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/work/logs/instance
2026-09-02 01:12:34.006450 | instance | cd+++++++++ system/
2026-09-02 01:12:34.006472 | instance | >f+++++++++ system/brctl-show.txt
2026-09-02 01:12:34.006494 | instance | >f+++++++++ system/docker-images.txt
2026-09-02 01:12:34.006513 | instance | >f+++++++++ system/ip-addr.txt
2026-09-02 01:12:34.006535 | instance | >f+++++++++ system/ip-route.txt
2026-09-02 01:12:34.006558 | instance | >f+++++++++ system/lsblk.txt
2026-09-02 01:12:34.006578 | instance | >f+++++++++ system/mount.txt
2026-09-02 01:12:34.006598 | instance | >f+++++++++ system/packages.txt
2026-09-02 01:12:34.006617 | instance | >f+++++++++ system/ps.txt
2026-09-02 01:12:34.006638 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-02 01:12:34.015683 | 
2026-09-02 01:12:34.015753 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-02 01:12:34.225785 | instance | changed: "values"
2026-09-02 01:12:34.404488 | instance | changed: "releases"
2026-09-02 01:12:34.417958 | 
2026-09-02 01:12:34.418110 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-02 01:12:34.636182 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-02 01:12:34.956326 | instance | ok: Runtime: 0:00:00.004969
2026-09-02 01:12:34.963114 | 
2026-09-02 01:12:34.963183 | TASK [helm-release-status : Downloads logs to executor]
2026-09-02 01:12:35.449670 | instance | changed:
2026-09-02 01:12:35.449926 | instance | cd+++++++++ helm/
2026-09-02 01:12:35.449964 | instance | cd+++++++++ helm/releases/
2026-09-02 01:12:35.449994 | instance | cd+++++++++ helm/values/
2026-09-02 01:12:35.460314 | 
2026-09-02 01:12:35.460387 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-02 01:12:35.676452 | instance | changed
2026-09-02 01:12:35.684077 | 
2026-09-02 01:12:35.684152 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-02 01:12:35.944649 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:35.944958 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:35.950470 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.952338 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.952956 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.954110 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.955290 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.957096 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.958363 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.959058 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:35.960921 | instance | environment: line 1: kubectl: command not found
2026-09-02 01:12:35.962369 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 01:12:36.221689 | instance | ok: Runtime: 0:00:00.024698
2026-09-02 01:12:36.227396 | 
2026-09-02 01:12:36.227462 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-02 01:12:36.454961 | instance | changed
2026-09-02 01:12:36.460083 | 
2026-09-02 01:12:36.460158 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-02 01:12:36.685842 | instance | environment: line 5: kubectl: command not found
2026-09-02 01:12:36.686812 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:36.687175 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:36.687445 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 01:12:37.006050 | instance | ok: Runtime: 0:00:00.009671
2026-09-02 01:12:37.012789 | 
2026-09-02 01:12:37.012856 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-02 01:12:37.490614 | instance | changed:
2026-09-02 01:12:37.490848 | instance | cd+++++++++ objects/
2026-09-02 01:12:37.490887 | instance | cd+++++++++ objects/cluster/
2026-09-02 01:12:37.490924 | instance | cd+++++++++ objects/namespaced/
2026-09-02 01:12:37.502062 | 
2026-09-02 01:12:37.502146 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-02 01:12:37.714065 | instance | changed
2026-09-02 01:12:37.719581 | 
2026-09-02 01:12:37.719666 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-02 01:12:37.936046 | instance | changed
2026-09-02 01:12:37.941347 | 
2026-09-02 01:12:37.941420 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-02 01:12:38.206583 | instance | environment: line 3: kubectl: command not found
2026-09-02 01:12:38.476827 | instance | ok: Runtime: 0:00:00.010344
2026-09-02 01:12:38.482624 | 
2026-09-02 01:12:38.482799 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-02 01:12:38.981104 | instance | changed:
2026-09-02 01:12:38.993863 | instance | cd+++++++++ pod-logs/
2026-09-02 01:12:38.993957 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-02 01:12:39.007548 | 
2026-09-02 01:12:39.007617 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-02 01:12:39.220373 | instance | changed
2026-09-02 01:12:39.226222 | 
2026-09-02 01:12:39.226311 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-02 01:12:39.470773 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 01:12:39.781831 | instance | ok: Runtime: 0:00:00.047617
2026-09-02 01:12:39.786854 | 
2026-09-02 01:12:39.786926 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-02 01:12:40.026203 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 01:12:40.071734 | instance | ceph-mgr endpoints:
2026-09-02 01:12:40.321806 | instance | ok: Runtime: 0:00:00.051696
2026-09-02 01:12:40.328293 | 
2026-09-02 01:12:40.328357 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-02 01:12:40.551622 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-02 01:12:40.865117 | instance | ok: Runtime: 0:00:00.045968
2026-09-02 01:12:40.872507 | 
2026-09-02 01:12:40.872573 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-02 01:12:41.377295 | instance | changed: cd+++++++++ prometheus/
2026-09-02 01:12:41.389400 | 
2026-09-02 01:12:41.389467 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-02 01:12:41.599816 | instance | changed
2026-09-02 01:12:41.622819 | 
2026-09-02 01:12:41.622896 | TASK [gather-selenium-data : Get selenium data]
2026-09-02 01:12:41.866257 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-02 01:12:41.867739 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-02 01:12:42.155810 | instance | ERROR
2026-09-02 01:12:42.156374 | instance | {
2026-09-02 01:12:42.156414 | instance |   "delta": "0:00:00.005809",
2026-09-02 01:12:42.156435 | instance |   "end": "2026-09-02 01:12:41.868081",
2026-09-02 01:12:42.156458 | instance |   "msg": "non-zero return code",
2026-09-02 01:12:42.156480 | instance |   "rc": 1,
2026-09-02 01:12:42.156501 | instance |   "start": "2026-09-02 01:12:41.862272"
2026-09-02 01:12:42.156520 | instance | }
2026-09-02 01:12:42.156544 | instance | ERROR: Ignoring Errors
2026-09-02 01:12:42.161620 | 
2026-09-02 01:12:42.161687 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-02 01:12:42.657253 | instance | changed: cd+++++++++ selenium/
2026-09-02 01:12:42.664557 | 
2026-09-02 01:12:42.664613 | PLAY RECAP
2026-09-02 01:12:42.664662 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-02 01:12:42.664684 | 
2026-09-02 01:12:42.816208 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 01:12:42.818494 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 01:12:43.562308 | 
2026-09-02 01:12:43.562450 | PLAY [all]
2026-09-02 01:12:43.574614 | 
2026-09-02 01:12:43.593411 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-02 01:12:43.650977 | instance | skipping: Conditional result was False
2026-09-02 01:12:43.658856 | 
2026-09-02 01:12:43.658968 | TASK [fetch-output : Set log path for single node]
2026-09-02 01:12:43.700537 | instance | ok
2026-09-02 01:12:43.705302 | 
2026-09-02 01:12:43.705373 | LOOP [fetch-output : Ensure local output dirs]
2026-09-02 01:12:44.163982 | instance -> localhost | ok: "/var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/work/logs"
2026-09-02 01:12:44.384228 | instance -> localhost | changed: "/var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/work/artifacts"
2026-09-02 01:12:44.588384 | instance -> localhost | changed: "/var/lib/zuul/builds/f2439387643349df88f12f20b84f7fd4/work/docs"
2026-09-02 01:12:44.606583 | 
2026-09-02 01:12:44.606729 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-02 01:12:45.259157 | instance | changed: .d..t...... ./
2026-09-02 01:12:45.259400 | instance | changed: All items complete
2026-09-02 01:12:45.259437 | 
2026-09-02 01:12:45.758739 | instance | changed: .d..t...... ./
2026-09-02 01:12:46.216034 | instance | changed: .d..t...... ./
2026-09-02 01:12:46.235214 | 
2026-09-02 01:12:46.235343 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-02 01:12:46.725483 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007747
2026-09-02 01:12:46.994108 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008538
2026-09-02 01:12:47.007650 | 
2026-09-02 01:12:47.008350 | PLAY [all]
2026-09-02 01:12:47.014533 | 
2026-09-02 01:12:47.014621 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-02 01:12:47.446479 | instance | changed
2026-09-02 01:12:47.454850 | 
2026-09-02 01:12:47.454903 | PLAY RECAP
2026-09-02 01:12:47.454950 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-02 01:12:47.454972 | 
2026-09-02 01:12:47.580882 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 01:12:47.583970 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-02 01:12:48.157441 | 
2026-09-02 01:12:48.157572 | PLAY [localhost]
2026-09-02 01:12:48.167120 | 
2026-09-02 01:12:48.167191 | TASK [Generate Zuul manifest]
2026-09-02 01:12:48.188776 | localhost | ok
2026-09-02 01:12:48.206464 | 
2026-09-02 01:12:48.206539 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-02 01:12:48.572697 | localhost | changed
2026-09-02 01:12:48.586586 | 
2026-09-02 01:12:48.586672 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-02 01:12:48.618781 | localhost | ok
2026-09-02 01:12:48.627773 | 
2026-09-02 01:12:48.627845 | TASK [Upload logs]
2026-09-02 01:12:48.650329 | localhost | ok
2026-09-02 01:12:48.717098 | 
2026-09-02 01:12:48.717234 | TASK [Set zuul-log-path fact]
2026-09-02 01:12:48.736537 | localhost | ok
2026-09-02 01:12:48.748774 | 
2026-09-02 01:12:48.748846 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 01:12:48.776923 | localhost | ok
2026-09-02 01:12:48.783748 | 
2026-09-02 01:12:48.783817 | TASK [upload-logs : Create log directories]
2026-09-02 01:12:49.189218 | localhost | changed
2026-09-02 01:12:49.196117 | 
2026-09-02 01:12:49.196231 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-02 01:12:49.597221 | localhost -> localhost | ok: Runtime: 0:00:00.006313
2026-09-02 01:12:49.606259 | 
2026-09-02 01:12:49.606330 | TASK [upload-logs : Upload logs to log server]
2026-09-02 01:12:50.076248 | localhost | Output suppressed because no_log was given
2026-09-02 01:12:50.116683 | 
2026-09-02 01:12:50.116874 | LOOP [upload-logs : Compress console log and json output]
2026-09-02 01:12:50.161180 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.168736 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.177042 | 
2026-09-02 01:12:50.177184 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-02 01:12:50.216053 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.216281 | 
2026-09-02 01:12:50.220508 | localhost | skipping: Conditional result was False
2026-09-02 01:12:50.227854 | 
2026-09-02 01:12:50.227926 | LOOP [upload-logs : Upload console log and json output]