2026-09-07 10:46:05.309988 | Job console starting
2026-09-07 10:46:05.323272 | Updating git repos
2026-09-07 10:46:08.039202 | Cloning repos into workspace
2026-09-07 10:46:23.432433 | Restoring repo states
2026-09-07 10:46:38.027320 | Merging changes
2026-09-07 10:46:39.251435 | Checking out repos
2026-09-07 10:46:40.669266 | Preparing playbooks
2026-09-07 10:46:42.448633 | Running Ansible setup
2026-09-07 10:46:46.519793 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 10:46:47.125740 | 
2026-09-07 10:46:47.126196 | PLAY [localhost]
2026-09-07 10:46:47.133729 | 
2026-09-07 10:46:47.133804 | TASK [Gathering Facts]
2026-09-07 10:46:48.124199 | localhost | ok
2026-09-07 10:46:48.139329 | 
2026-09-07 10:46:48.139530 | TASK [Setup log path fact]
2026-09-07 10:46:48.164482 | localhost | ok
2026-09-07 10:46:48.187868 | 
2026-09-07 10:46:48.188131 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 10:46:48.230275 | localhost | ok
2026-09-07 10:46:48.241746 | 
2026-09-07 10:46:48.241929 | TASK [emit-job-header : Print job information]
2026-09-07 10:46:48.293429 | # Job Information
2026-09-07 10:46:48.293702 | Ansible Version: 2.16.18
2026-09-07 10:46:48.293768 | Job: atmosphere-molecule-csi-rbd
2026-09-07 10:46:48.293811 | Pipeline: check
2026-09-07 10:46:48.293850 | Executor: 2d72f0692154
2026-09-07 10:46:48.293908 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-09-07 10:46:48.293957 | Event ID: 52f05ae0-aaa7-11f1-9b87-582763a12ff6
2026-09-07 10:46:48.299324 | 
2026-09-07 10:46:48.299469 | LOOP [emit-job-header : Print node information]
2026-09-07 10:46:48.408626 | localhost | ok:
2026-09-07 10:46:48.408793 | localhost | # Node Information
2026-09-07 10:46:48.408820 | localhost | Inventory Hostname: instance
2026-09-07 10:46:48.408839 | localhost | Hostname: np0000192135
2026-09-07 10:46:48.408866 | localhost | Username: zuul
2026-09-07 10:46:48.408898 | localhost | Distro: Ubuntu 22.04
2026-09-07 10:46:48.408917 | localhost | Provider: yul1
2026-09-07 10:46:48.408943 | localhost | Region: ca-ymq-1
2026-09-07 10:46:48.408959 | localhost | Label: ubuntu-jammy
2026-09-07 10:46:48.408975 | localhost | Product Name: OpenStack Nova
2026-09-07 10:46:48.408990 | localhost | Interface IP: 162.253.55.165
2026-09-07 10:46:48.421501 | 
2026-09-07 10:46:48.421722 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 10:46:48.834416 | localhost -> localhost | changed
2026-09-07 10:46:48.841192 | 
2026-09-07 10:46:48.841290 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 10:46:49.981600 | localhost -> localhost | changed
2026-09-07 10:46:49.992713 | 
2026-09-07 10:46:49.992813 | PLAY [all]
2026-09-07 10:46:50.008843 | 
2026-09-07 10:46:50.009010 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 10:46:50.257207 | instance -> localhost | ok
2026-09-07 10:46:50.268665 | 
2026-09-07 10:46:50.268797 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 10:46:50.302304 | instance | ok
2026-09-07 10:46:50.328656 | instance | included: /var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 10:46:50.335635 | 
2026-09-07 10:46:50.335711 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 10:46:51.619187 | instance -> localhost | Generating public/private rsa key pair.
2026-09-07 10:46:51.619392 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/work/0df0da236e174cff91bcbd4bd1223b83_id_rsa
2026-09-07 10:46:51.619432 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/work/0df0da236e174cff91bcbd4bd1223b83_id_rsa.pub
2026-09-07 10:46:51.619462 | instance -> localhost | The key fingerprint is:
2026-09-07 10:46:51.619488 | instance -> localhost | SHA256:5zMZxdnXq1xz/4Yo2QaJE9UpCnG2OxyB/rYwgGzS4iQ zuul-build-sshkey
2026-09-07 10:46:51.619529 | instance -> localhost | The key's randomart image is:
2026-09-07 10:46:51.619556 | instance -> localhost | +---[RSA 3072]----+
2026-09-07 10:46:51.619593 | instance -> localhost | |      .o+  . .   |
2026-09-07 10:46:51.619619 | instance -> localhost | |      oo oo.oo  .|
2026-09-07 10:46:51.619643 | instance -> localhost | | o . . .oo .+ . o|
2026-09-07 10:46:51.619666 | instance -> localhost | |E.= . ..oo .   ..|
2026-09-07 10:46:51.619690 | instance -> localhost | |++   . .Soo.   +.|
2026-09-07 10:46:51.619712 | instance -> localhost | | .    o =+oo. o +|
2026-09-07 10:46:51.619735 | instance -> localhost | |       + o=+ + ..|
2026-09-07 10:46:51.619848 | instance -> localhost | |        . oo+ . o|
2026-09-07 10:46:51.619914 | instance -> localhost | |           o   ..|
2026-09-07 10:46:51.619946 | instance -> localhost | +----[SHA256]-----+
2026-09-07 10:46:51.620014 | instance -> localhost | ok: Runtime: 0:00:00.754512
2026-09-07 10:46:51.629760 | 
2026-09-07 10:46:51.629854 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 10:46:51.665327 | instance | ok
2026-09-07 10:46:51.681006 | instance | included: /var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 10:46:51.691454 | 
2026-09-07 10:46:51.691553 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 10:46:51.718300 | instance | skipping: Conditional result was False
2026-09-07 10:46:51.726651 | 
2026-09-07 10:46:51.726741 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 10:46:52.199746 | instance | changed
2026-09-07 10:46:52.211117 | 
2026-09-07 10:46:52.211320 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 10:46:52.406450 | instance | ok
2026-09-07 10:46:52.416077 | 
2026-09-07 10:46:52.416211 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 10:46:52.976469 | instance | changed
2026-09-07 10:46:52.983151 | 
2026-09-07 10:46:52.983244 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 10:46:53.453389 | instance | changed
2026-09-07 10:46:53.465386 | 
2026-09-07 10:46:53.465566 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 10:46:53.491327 | instance | skipping: Conditional result was False
2026-09-07 10:46:53.502171 | 
2026-09-07 10:46:53.502336 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 10:46:53.891915 | instance -> localhost | changed
2026-09-07 10:46:53.900693 | 
2026-09-07 10:46:53.900776 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 10:46:54.181636 | instance -> localhost | Identity added: /var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/work/0df0da236e174cff91bcbd4bd1223b83_id_rsa (zuul-build-sshkey)
2026-09-07 10:46:54.181866 | instance -> localhost | ok: Runtime: 0:00:00.014457
2026-09-07 10:46:54.186810 | 
2026-09-07 10:46:54.186871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 10:46:54.515270 | instance | ok
2026-09-07 10:46:54.524876 | 
2026-09-07 10:46:54.524982 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 10:46:54.549755 | instance | skipping: Conditional result was False
2026-09-07 10:46:54.568367 | 
2026-09-07 10:46:54.568548 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 10:46:54.910961 | instance | ok
2026-09-07 10:46:54.916518 | 
2026-09-07 10:46:54.916606 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 10:46:58.628797 | instance | Output suppressed because no_log was given
2026-09-07 10:46:58.646211 | 
2026-09-07 10:46:58.646339 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 10:46:58.862222 | instance | ok: "logs"
2026-09-07 10:46:58.862780 | instance | ok: All items complete
2026-09-07 10:46:58.862852 | 
2026-09-07 10:46:59.035588 | instance | ok: "artifacts"
2026-09-07 10:46:59.211048 | instance | ok: "docs"
2026-09-07 10:46:59.223999 | 
2026-09-07 10:46:59.224770 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 10:46:59.430794 | instance | changed: "logs"
2026-09-07 10:46:59.624860 | instance | changed: "artifacts"
2026-09-07 10:46:59.778682 | instance | changed: "docs"
2026-09-07 10:46:59.800657 | 
2026-09-07 10:46:59.800843 | PLAY RECAP
2026-09-07 10:46:59.800961 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 10:46:59.801020 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:46:59.801063 | 
2026-09-07 10:46:59.952303 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 10:46:59.954752 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 10:47:00.537231 | 
2026-09-07 10:47:00.537345 | PLAY [all]
2026-09-07 10:47:00.547544 | 
2026-09-07 10:47:00.547637 | TASK [setup-uv : Extract archive]
2026-09-07 10:47:02.897256 | instance | changed
2026-09-07 10:47:02.914489 | 
2026-09-07 10:47:02.914728 | TASK [setup-uv : Print version]
2026-09-07 10:47:03.292444 | instance | uv 0.8.13
2026-09-07 10:47:03.465790 | instance | ok: Runtime: 0:00:00.010207
2026-09-07 10:47:03.485301 | 
2026-09-07 10:47:03.485531 | TASK [Install Ansible collection siblings]
2026-09-07 10:47:12.961690 | instance | changed:
2026-09-07 10:47:12.961838 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-07 10:47:12.961865 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-07 10:47:12.961884 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-07 10:47:12.961915 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-07 10:47:12.961933 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-07 10:47:12.961952 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-07 10:47:12.961970 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-07 10:47:12.961988 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-07 10:47:12.962005 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-07 10:47:12.962022 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-07 10:47:12.962038 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-07 10:47:12.962054 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-07 10:47:12.962069 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-07 10:47:12.962085 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-07 10:47:12.962101 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-07 10:47:12.962118 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-07 10:47:12.962137 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-07 10:47:12.962155 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-07 10:47:12.962171 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-07 10:47:12.962187 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-07 10:47:12.962203 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-07 10:47:12.962219 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-07 10:47:12.962242 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-07 10:47:12.962267 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-07 10:47:12.962284 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-07 10:47:12.962300 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-07 10:47:12.962315 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-07 10:47:12.962331 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-07 10:47:12.962346 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-07 10:47:12.962362 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-07 10:47:12.962377 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-07 10:47:12.962393 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-07 10:47:12.962409 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-07 10:47:12.962424 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-07 10:47:12.962440 | 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-07 10:47:12.962461 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.962477 | instance | Process install dependency map
2026-09-07 10:47:12.962493 | instance | Starting collection install process
2026-09-07 10:47:12.962685 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-07 10:47:12.962700 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-07 10:47:12.962716 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-07 10:47:12.962731 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-07 10:47:12.962746 | instance | Creating virtual environment at: .venv
2026-09-07 10:47:12.962762 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-07 10:47:12.962777 | instance | Downloading setuptools (1.1MiB)
2026-09-07 10:47:12.962797 | instance | Downloading ansible-core (2.1MiB)
2026-09-07 10:47:12.962813 | instance | Downloading kubernetes (1.9MiB)
2026-09-07 10:47:12.962828 | instance | Downloading pygments (1.2MiB)
2026-09-07 10:47:12.962844 | instance | Downloading cryptography (4.2MiB)
2026-09-07 10:47:12.962860 | instance | Downloading rjsonnet (1.2MiB)
2026-09-07 10:47:12.962876 | instance | Downloading netaddr (2.2MiB)
2026-09-07 10:47:12.962891 | instance | Downloading openstacksdk (1.7MiB)
2026-09-07 10:47:12.962923 | instance |    Building pyperclip==1.9.0
2026-09-07 10:47:12.962941 | instance |  Downloading rjsonnet
2026-09-07 10:47:12.962957 | instance |  Downloading netaddr
2026-09-07 10:47:12.963025 | instance |  Downloading pygments
2026-09-07 10:47:12.963044 | instance |  Downloading cryptography
2026-09-07 10:47:12.963060 | instance |  Downloading setuptools
2026-09-07 10:47:12.963076 | instance |  Downloading kubernetes
2026-09-07 10:47:12.963092 | instance |  Downloading ansible-core
2026-09-07 10:47:12.963108 | instance |  Downloading openstacksdk
2026-09-07 10:47:12.963123 | instance |       Built pyperclip==1.9.0
2026-09-07 10:47:12.963139 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-07 10:47:12.963155 | instance | Installed 79 packages in 72ms
2026-09-07 10:47:12.963170 | instance | Checked out ansible.posix to 1.6.0
2026-09-07 10:47:12.963186 | 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-07 10:47:12.963202 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.963218 | instance | Process install dependency map
2026-09-07 10:47:12.963238 | instance | Starting collection install process
2026-09-07 10:47:12.963254 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 10:47:12.963270 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-07 10:47:12.963285 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-07 10:47:12.963301 | instance | Checked out ansible.utils to v6.1.0
2026-09-07 10:47:12.963316 | 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-07 10:47:12.963332 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.963346 | instance | Process install dependency map
2026-09-07 10:47:12.963362 | instance | Starting collection install process
2026-09-07 10:47:12.963377 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-07 10:47:12.963392 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-07 10:47:12.963407 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-07 10:47:12.963423 | instance | Checked out atmosphere.common to v0.6.0
2026-09-07 10:47:12.963439 | 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-07 10:47:12.963455 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.963471 | instance | Process install dependency map
2026-09-07 10:47:12.963486 | instance | Starting collection install process
2026-09-07 10:47:12.963501 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-07 10:47:12.963517 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-07 10:47:12.963649 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-07 10:47:12.963664 | instance | Checked out community.crypto to 2.2.3
2026-09-07 10:47:12.963679 | 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-07 10:47:12.963695 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.963710 | instance | Process install dependency map
2026-09-07 10:47:12.963724 | instance | Starting collection install process
2026-09-07 10:47:12.963739 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-07 10:47:12.963754 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-07 10:47:12.963769 | instance | community.crypto:2.2.3 was installed successfully
2026-09-07 10:47:12.963784 | instance | Checked out community.general to 7.3.0
2026-09-07 10:47:12.963799 | 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-07 10:47:12.963814 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.963829 | instance | Process install dependency map
2026-09-07 10:47:12.963844 | instance | Starting collection install process
2026-09-07 10:47:12.963859 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 10:47:12.963874 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-07 10:47:12.963889 | instance | community.general:7.3.0 was installed successfully
2026-09-07 10:47:12.963921 | instance | Checked out community.mysql to 3.6.0
2026-09-07 10:47:12.963938 | 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-07 10:47:12.963953 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.963969 | instance | Process install dependency map
2026-09-07 10:47:12.963984 | instance | Starting collection install process
2026-09-07 10:47:12.963999 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-07 10:47:12.964015 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-07 10:47:12.964030 | instance | community.mysql:3.6.0 was installed successfully
2026-09-07 10:47:12.964045 | instance | Checked out kubernetes.core to 2.4.0
2026-09-07 10:47:12.964061 | 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-07 10:47:12.964076 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.964092 | instance | Process install dependency map
2026-09-07 10:47:12.964109 | instance | Starting collection install process
2026-09-07 10:47:12.964125 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-07 10:47:12.964140 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-07 10:47:12.964156 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-07 10:47:12.964173 | instance | Checked out openstack.cloud to 1.7.0
2026-09-07 10:47:12.964189 | 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-07 10:47:12.964205 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.964220 | instance | Process install dependency map
2026-09-07 10:47:12.964236 | instance | Starting collection install process
2026-09-07 10:47:12.964252 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-07 10:47:12.964267 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-07 10:47:12.964283 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-07 10:47:12.964298 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-07 10:47:12.964314 | 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-07 10:47:12.964329 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.964345 | instance | Process install dependency map
2026-09-07 10:47:12.964360 | instance | Starting collection install process
2026-09-07 10:47:12.964376 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-07 10:47:12.964391 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-07 10:47:12.964406 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-07 10:47:12.964421 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-07 10:47:12.964436 | 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-07 10:47:12.964451 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.964470 | instance | Process install dependency map
2026-09-07 10:47:12.964486 | instance | Starting collection install process
2026-09-07 10:47:12.964520 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-07 10:47:12.964536 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-07 10:47:12.964551 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-07 10:47:12.964567 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-07 10:47:12.964582 | 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-07 10:47:12.964597 | instance | Starting galaxy collection install process
2026-09-07 10:47:12.964612 | instance | Process install dependency map
2026-09-07 10:47:12.964627 | instance | Starting collection install process
2026-09-07 10:47:12.964642 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-07 10:47:12.964657 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-07 10:47:12.964672 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-07 10:47:12.969125 | 
2026-09-07 10:47:12.969165 | PLAY RECAP
2026-09-07 10:47:12.969207 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:47:12.969226 | 
2026-09-07 10:47:13.140854 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 10:47:13.143741 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-07 10:47:13.757754 | 
2026-09-07 10:47:13.757952 | PLAY [all]
2026-09-07 10:47:13.770450 | 
2026-09-07 10:47:13.770609 | TASK [Install "jq" for log collection]
2026-09-07 10:47:23.381800 | instance | changed
2026-09-07 10:47:23.384044 | 
2026-09-07 10:47:23.384115 | PLAY RECAP
2026-09-07 10:47:23.384180 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:47:23.384245 | 
2026-09-07 10:47:23.496974 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-07 10:47:23.500535 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 10:47:24.088167 | 
2026-09-07 10:47:24.088325 | PLAY [all]
2026-09-07 10:47:24.100653 | 
2026-09-07 10:47:24.100780 | LOOP [Check inventory directories]
2026-09-07 10:47:24.516233 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-07 10:47:24.685216 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-07 10:47:24.873831 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-07 10:47:24.893416 | 
2026-09-07 10:47:24.893729 | LOOP [Copy inventory file for Zuul]
2026-09-07 10:47:25.758466 | instance | changed:
2026-09-07 10:47:25.758814 | instance | {
2026-09-07 10:47:25.758937 | instance |   "ansible_loop_var": "item",
2026-09-07 10:47:25.758996 | instance |   "changed": false,
2026-09-07 10:47:25.759039 | instance |   "failed": false,
2026-09-07 10:47:25.759090 | instance |   "invocation": {
2026-09-07 10:47:25.759128 | instance |     "module_args": {
2026-09-07 10:47:25.759165 | instance |       "checksum_algorithm": "sha1",
2026-09-07 10:47:25.759205 | instance |       "follow": true,
2026-09-07 10:47:25.759241 | instance |       "get_attributes": true,
2026-09-07 10:47:25.759290 | instance |       "get_checksum": true,
2026-09-07 10:47:25.759340 | instance |       "get_mime": true,
2026-09-07 10:47:25.759376 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-07 10:47:25.759434 | instance |     }
2026-09-07 10:47:25.759472 | instance |   },
2026-09-07 10:47:25.759509 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-07 10:47:25.759546 | instance |   "stat": {
2026-09-07 10:47:25.759581 | instance |     "atime": 1788778025.6070015,
2026-09-07 10:47:25.759618 | instance |     "attr_flags": "e",
2026-09-07 10:47:25.759653 | instance |     "attributes": [
2026-09-07 10:47:25.759687 | instance |       "extents"
2026-09-07 10:47:25.759722 | instance |     ],
2026-09-07 10:47:25.759758 | instance |     "block_size": 4096,
2026-09-07 10:47:25.759796 | instance |     "blocks": 8,
2026-09-07 10:47:25.759891 | instance |     "charset": "unknown",
2026-09-07 10:47:25.759986 | instance |     "ctime": 1788778024.418977,
2026-09-07 10:47:25.760030 | instance |     "dev": 64513,
2026-09-07 10:47:25.760068 | instance |     "device_type": 0,
2026-09-07 10:47:25.760104 | instance |     "executable": true,
2026-09-07 10:47:25.760141 | instance |     "exists": true,
2026-09-07 10:47:25.760176 | instance |     "gid": 1000,
2026-09-07 10:47:25.760211 | instance |     "gr_name": "zuul",
2026-09-07 10:47:25.760247 | instance |     "inode": 1048747,
2026-09-07 10:47:25.760283 | instance |     "isblk": false,
2026-09-07 10:47:25.760319 | instance |     "ischr": false,
2026-09-07 10:47:25.760354 | instance |     "isdir": true,
2026-09-07 10:47:25.760388 | instance |     "isfifo": false,
2026-09-07 10:47:25.760422 | instance |     "isgid": false,
2026-09-07 10:47:25.760458 | instance |     "islnk": false,
2026-09-07 10:47:25.760560 | instance |     "isreg": false,
2026-09-07 10:47:25.760651 | instance |     "issock": false,
2026-09-07 10:47:25.760716 | instance |     "isuid": false,
2026-09-07 10:47:25.760777 | instance |     "mimetype": "unknown",
2026-09-07 10:47:25.760844 | instance |     "mode": "0755",
2026-09-07 10:47:25.760891 | instance |     "mtime": 1788778024.418977,
2026-09-07 10:47:25.760963 | instance |     "nlink": 22,
2026-09-07 10:47:25.761002 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-07 10:47:25.761038 | instance |     "pw_name": "zuul",
2026-09-07 10:47:25.761080 | instance |     "readable": true,
2026-09-07 10:47:25.761117 | instance |     "rgrp": true,
2026-09-07 10:47:25.761154 | instance |     "roth": true,
2026-09-07 10:47:25.761190 | instance |     "rusr": true,
2026-09-07 10:47:25.761225 | instance |     "size": 4096,
2026-09-07 10:47:25.761261 | instance |     "uid": 1000,
2026-09-07 10:47:25.761296 | instance |     "version": "3733541041",
2026-09-07 10:47:25.761332 | instance |     "wgrp": false,
2026-09-07 10:47:25.761372 | instance |     "woth": false,
2026-09-07 10:47:25.761408 | instance |     "writeable": true,
2026-09-07 10:47:25.761444 | instance |     "wusr": true,
2026-09-07 10:47:25.761478 | instance |     "xgrp": true,
2026-09-07 10:47:25.761511 | instance |     "xoth": true,
2026-09-07 10:47:25.761545 | instance |     "xusr": true
2026-09-07 10:47:25.761580 | instance |   }
2026-09-07 10:47:25.761615 | instance | }
2026-09-07 10:47:25.761692 | instance | ok: All items complete
2026-09-07 10:47:25.761738 | 
2026-09-07 10:47:25.764327 | instance | skipping: Conditional result was False
2026-09-07 10:47:25.765797 | instance | skipping: Conditional result was False
2026-09-07 10:47:25.788404 | 
2026-09-07 10:47:25.788604 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 10:47:26.210784 | instance | changed: 1 replacements made
2026-09-07 10:47:26.213930 | instance | skipping: Conditional result was False
2026-09-07 10:47:26.221812 | instance | skipping: Conditional result was False
2026-09-07 10:47:26.243128 | 
2026-09-07 10:47:26.243438 | TASK [Run Molecule scenario]
2026-09-07 10:47:27.269505 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-07 10:47:27.691347 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-07 10:47:27.691448 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-07 10:47:39.037706 | instance | INFO     [csi > dependency] Executing
2026-09-07 10:47:39.038170 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-07 10:47:39.038607 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-07 10:47:39.038842 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-07 10:47:39.056224 | instance | INFO     [csi > cleanup] Executing
2026-09-07 10:47:39.056792 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-07 10:47:39.067306 | instance | INFO     [csi > destroy] Executing
2026-09-07 10:47:39.067459 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-07 10:47:39.067571 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-07 10:47:39.076591 | instance | INFO     [csi > syntax] Executing
2026-09-07 10:47:40.669231 | instance |
2026-09-07 10:47:40.669482 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-07 10:47:40.736292 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-07 10:47:40.755733 | instance | INFO     [csi > create] Executing
2026-09-07 10:47:40.768627 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-07 10:47:40.792371 | instance | INFO     [csi > prepare] Executing
2026-09-07 10:47:41.631744 | instance |
2026-09-07 10:47:41.632030 | instance | PLAY [Prepare] *****************************************************************
2026-09-07 10:47:41.632281 | instance |
2026-09-07 10:47:41.632619 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:47:41.632869 | instance | Monday 07 September 2026  10:47:41 +0000 (0:00:00.025)       0:00:00.025 ******
2026-09-07 10:47:42.905517 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-07 10:47:42.905737 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-07 10:47:42.906072 | instance | interpreter could change the meaning of that path. See
2026-09-07 10:47:42.906295 | instance | https://docs.ansible.com/ansible-
2026-09-07 10:47:42.906583 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-07 10:47:42.915845 | instance | ok: [instance]
2026-09-07 10:47:42.916131 | instance |
2026-09-07 10:47:42.916345 | instance | TASK [Configure short hostname] ************************************************
2026-09-07 10:47:42.916625 | instance | Monday 07 September 2026  10:47:42 +0000 (0:00:01.285)       0:00:01.310 ******
2026-09-07 10:47:43.712416 | instance | changed: [instance]
2026-09-07 10:47:43.712607 | instance |
2026-09-07 10:47:43.712845 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-07 10:47:43.713116 | instance | Monday 07 September 2026  10:47:43 +0000 (0:00:00.795)       0:00:02.106 ******
2026-09-07 10:47:44.005102 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-07 10:47:44.005312 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-07 10:47:44.005550 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-07 10:47:44.014804 | instance | changed: [instance]
2026-09-07 10:47:44.015104 | instance |
2026-09-07 10:47:44.015295 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-07 10:47:44.015572 | instance | Monday 07 September 2026  10:47:44 +0000 (0:00:00.302)       0:00:02.409 ******
2026-09-07 10:47:44.906562 | instance | ok: [instance]
2026-09-07 10:47:44.906665 | instance |
2026-09-07 10:47:44.906780 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-07 10:47:44.906900 | instance |
2026-09-07 10:47:44.907036 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:47:44.907180 | instance | Monday 07 September 2026  10:47:44 +0000 (0:00:00.891)       0:00:03.301 ******
2026-09-07 10:47:45.710915 | instance | ok: [instance]
2026-09-07 10:47:45.711136 | instance |
2026-09-07 10:47:45.711386 | instance | TASK [Install depedencies] *****************************************************
2026-09-07 10:47:45.711633 | instance | Monday 07 September 2026  10:47:45 +0000 (0:00:00.804)       0:00:04.105 ******
2026-09-07 10:48:09.568971 | instance | changed: [instance]
2026-09-07 10:48:09.569174 | instance |
2026-09-07 10:48:09.569187 | instance | TASK [Start up service] ********************************************************
2026-09-07 10:48:09.569199 | instance | Monday 07 September 2026  10:48:09 +0000 (0:00:23.856)       0:00:27.962 ******
2026-09-07 10:48:10.074050 | instance | ok: [instance]
2026-09-07 10:48:10.074308 | instance |
2026-09-07 10:48:10.074590 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-07 10:48:10.074837 | instance | Monday 07 September 2026  10:48:10 +0000 (0:00:00.506)       0:00:28.468 ******
2026-09-07 10:48:10.398508 | instance | ok: [instance]
2026-09-07 10:48:10.398691 | instance |
2026-09-07 10:48:10.398948 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-07 10:48:10.399227 | instance | Monday 07 September 2026  10:48:10 +0000 (0:00:00.324)       0:00:28.792 ******
2026-09-07 10:48:11.143775 | instance | changed: [instance]
2026-09-07 10:48:11.143837 | instance |
2026-09-07 10:48:11.143850 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-07 10:48:11.143860 | instance | Monday 07 September 2026  10:48:11 +0000 (0:00:00.744)       0:00:29.537 ******
2026-09-07 10:48:11.360815 | instance | ok: [instance]
2026-09-07 10:48:11.361895 | instance |
2026-09-07 10:48:11.361962 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-07 10:48:11.361974 | instance | Monday 07 September 2026  10:48:11 +0000 (0:00:00.217)       0:00:29.754 ******
2026-09-07 10:48:11.382663 | instance | skipping: [instance]
2026-09-07 10:48:11.382787 | instance |
2026-09-07 10:48:11.383011 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-07 10:48:11.383207 | instance | Monday 07 September 2026  10:48:11 +0000 (0:00:00.022)       0:00:29.777 ******
2026-09-07 10:48:11.979928 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:48:11.980153 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:48:11.980504 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:48:11.980783 | instance |
2026-09-07 10:48:11.981077 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-07 10:48:11.981360 | instance | Monday 07 September 2026  10:48:11 +0000 (0:00:00.597)       0:00:30.374 ******
2026-09-07 10:48:12.676207 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:48:12.676445 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:48:12.676799 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:48:12.677174 | instance |
2026-09-07 10:48:12.677583 | instance | TASK [Start loop devices] ******************************************************
2026-09-07 10:48:12.677918 | instance | Monday 07 September 2026  10:48:12 +0000 (0:00:00.696)       0:00:31.070 ******
2026-09-07 10:48:13.471797 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:48:13.471868 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:48:13.472526 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:48:13.472601 | instance |
2026-09-07 10:48:13.472609 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-07 10:48:13.472617 | instance | Monday 07 September 2026  10:48:13 +0000 (0:00:00.795)       0:00:31.866 ******
2026-09-07 10:48:16.739230 | instance | changed: [instance] => (item=osd0)
2026-09-07 10:48:16.740627 | instance | changed: [instance] => (item=osd1)
2026-09-07 10:48:16.740661 | instance | changed: [instance] => (item=osd2)
2026-09-07 10:48:16.740672 | instance |
2026-09-07 10:48:16.740682 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-07 10:48:16.740691 | instance | Monday 07 September 2026  10:48:16 +0000 (0:00:03.266)       0:00:35.132 ******
2026-09-07 10:48:18.846110 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-07 10:48:18.846187 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-07 10:48:18.846218 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-07 10:48:18.846226 | instance |
2026-09-07 10:48:18.846424 | instance | PLAY RECAP *********************************************************************
2026-09-07 10:48:18.846845 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-07 10:48:18.847225 | instance |
2026-09-07 10:48:18.847709 | instance | Monday 07 September 2026  10:48:18 +0000 (0:00:02.106)       0:00:37.239 ******
2026-09-07 10:48:18.848051 | instance | ===============================================================================
2026-09-07 10:48:18.848514 | instance | Install depedencies ---------------------------------------------------- 23.86s
2026-09-07 10:48:18.848877 | instance | Create a volume group for each loop device ------------------------------ 3.27s
2026-09-07 10:48:18.849373 | instance | Create a logical volume for each loop device ---------------------------- 2.11s
2026-09-07 10:48:18.849671 | instance | Gathering Facts --------------------------------------------------------- 1.29s
2026-09-07 10:48:18.850353 | instance | Purge "snapd" package --------------------------------------------------- 0.89s
2026-09-07 10:48:18.850450 | instance | Gathering Facts --------------------------------------------------------- 0.80s
2026-09-07 10:48:18.850738 | instance | Configure short hostname ------------------------------------------------ 0.80s
2026-09-07 10:48:18.851128 | instance | Start loop devices ------------------------------------------------------ 0.80s
2026-09-07 10:48:18.851560 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.74s
2026-09-07 10:48:18.851960 | instance | Set permissions on loopback devices ------------------------------------- 0.70s
2026-09-07 10:48:18.852318 | instance | Create devices for Ceph ------------------------------------------------- 0.60s
2026-09-07 10:48:18.852706 | instance | Start up service -------------------------------------------------------- 0.51s
2026-09-07 10:48:18.853085 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s
2026-09-07 10:48:18.853508 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-09-07 10:48:18.853863 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-07 10:48:18.854245 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-07 10:48:18.922866 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-07 10:48:18.937489 | instance | INFO     [csi > converge] Executing
2026-09-07 10:48:20.247038 | instance |
2026-09-07 10:48:20.247243 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-07 10:48:20.247503 | instance |
2026-09-07 10:48:20.247766 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:48:20.248089 | instance | Monday 07 September 2026  10:48:20 +0000 (0:00:00.007)       0:00:00.007 ******
2026-09-07 10:48:21.402974 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-07 10:48:21.403194 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-07 10:48:21.403468 | instance | interpreter could change the meaning of that path. See
2026-09-07 10:48:21.403724 | instance | https://docs.ansible.com/ansible-
2026-09-07 10:48:21.404001 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-07 10:48:21.419242 | instance | ok: [instance]
2026-09-07 10:48:21.419545 | instance |
2026-09-07 10:48:21.419805 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-07 10:48:21.420051 | instance | Monday 07 September 2026  10:48:21 +0000 (0:00:01.172)       0:00:01.179 ******
2026-09-07 10:48:21.491084 | instance | ok: [instance] => {
2026-09-07 10:48:21.491293 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-07 10:48:21.491561 | instance | }
2026-09-07 10:48:21.491861 | instance |
2026-09-07 10:48:21.492388 | instance | PLAY [all] *********************************************************************
2026-09-07 10:48:21.492679 | instance |
2026-09-07 10:48:21.492971 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:48:21.493297 | instance | Monday 07 September 2026  10:48:21 +0000 (0:00:00.071)       0:00:01.250 ******
2026-09-07 10:48:22.472138 | instance | ok: [instance]
2026-09-07 10:48:22.472368 | instance |
2026-09-07 10:48:22.472612 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-07 10:48:22.472843 | instance | Monday 07 September 2026  10:48:22 +0000 (0:00:00.981)       0:00:02.231 ******
2026-09-07 10:48:22.671871 | instance | ok: [instance]
2026-09-07 10:48:22.672076 | instance |
2026-09-07 10:48:22.672407 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-07 10:48:22.672689 | instance |
2026-09-07 10:48:22.672932 | instance | TASK [Gathering Facts] *********************************************************
2026-09-07 10:48:22.673168 | instance | Monday 07 September 2026  10:48:22 +0000 (0:00:00.200)       0:00:02.432 ******
2026-09-07 10:48:23.692354 | instance | ok: [instance]
2026-09-07 10:48:23.692434 | instance |
2026-09-07 10:48:23.692442 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-07 10:48:23.692458 | instance | Monday 07 September 2026  10:48:23 +0000 (0:00:01.019)       0:00:03.451 ******
2026-09-07 10:48:24.026160 | instance | ok: [instance]
2026-09-07 10:48:24.027088 | instance |
2026-09-07 10:48:24.027370 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-07 10:48:24.027387 | instance | Monday 07 September 2026  10:48:24 +0000 (0:00:00.334)       0:00:03.785 ******
2026-09-07 10:48:24.088853 | instance | skipping: [instance]
2026-09-07 10:48:24.088932 | instance |
2026-09-07 10:48:24.089382 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-07 10:48:24.089445 | instance | Monday 07 September 2026  10:48:24 +0000 (0:00:00.063)       0:00:03.849 ******
2026-09-07 10:48:24.531280 | instance | changed: [instance]
2026-09-07 10:48:24.531795 | instance |
2026-09-07 10:48:24.531847 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-07 10:48:24.531855 | instance | Monday 07 September 2026  10:48:24 +0000 (0:00:00.442)       0:00:04.291 ******
2026-09-07 10:48:24.627819 | instance | ok: [instance] => {
2026-09-07 10:48:24.629043 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-07 10:48:24.629093 | instance | }
2026-09-07 10:48:24.629104 | instance |
2026-09-07 10:48:24.629114 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-07 10:48:24.629124 | instance | Monday 07 September 2026  10:48:24 +0000 (0:00:00.095)       0:00:04.387 ******
2026-09-07 10:48:25.566747 | instance | changed: [instance]
2026-09-07 10:48:25.566962 | instance |
2026-09-07 10:48:25.567361 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-07 10:48:25.567748 | instance | Monday 07 September 2026  10:48:25 +0000 (0:00:00.938)       0:00:05.326 ******
2026-09-07 10:48:25.625543 | instance | skipping: [instance]
2026-09-07 10:48:25.625662 | instance |
2026-09-07 10:48:25.625872 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-07 10:48:25.626159 | instance | Monday 07 September 2026  10:48:25 +0000 (0:00:00.059)       0:00:05.386 ******
2026-09-07 10:48:25.698874 | instance | skipping: [instance]
2026-09-07 10:48:25.698954 | instance |
2026-09-07 10:48:25.699138 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-07 10:48:25.699443 | instance | Monday 07 September 2026  10:48:25 +0000 (0:00:00.071)       0:00:05.458 ******
2026-09-07 10:48:25.931544 | instance | ok: [instance]
2026-09-07 10:48:25.931597 | instance |
2026-09-07 10:48:25.931607 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-07 10:48:25.931617 | instance | Monday 07 September 2026  10:48:25 +0000 (0:00:00.233)       0:00:05.691 ******
2026-09-07 10:48:27.297073 | instance | ok: [instance]
2026-09-07 10:48:27.297189 | instance |
2026-09-07 10:48:27.297473 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-07 10:48:27.297540 | instance | Monday 07 September 2026  10:48:27 +0000 (0:00:01.365)       0:00:07.057 ******
2026-09-07 10:48:27.376966 | instance | ok: [instance] => {
2026-09-07 10:48:27.378197 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-07 10:48:27.378434 | instance | }
2026-09-07 10:48:27.378447 | instance |
2026-09-07 10:48:27.378490 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-07 10:48:27.378924 | instance | Monday 07 September 2026  10:48:27 +0000 (0:00:00.078)       0:00:07.136 ******
2026-09-07 10:48:28.137336 | instance | changed: [instance]
2026-09-07 10:48:28.137406 | instance |
2026-09-07 10:48:28.137421 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-07 10:48:28.137426 | instance | Monday 07 September 2026  10:48:28 +0000 (0:00:00.761)       0:00:07.897 ******
2026-09-07 10:48:31.080064 | instance | changed: [instance]
2026-09-07 10:48:31.080230 | instance |
2026-09-07 10:48:31.080631 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-07 10:48:31.080694 | instance | Monday 07 September 2026  10:48:31 +0000 (0:00:02.942)       0:00:10.840 ******
2026-09-07 10:48:31.123822 | instance | skipping: [instance]
2026-09-07 10:48:31.124521 | instance |
2026-09-07 10:48:31.124600 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-07 10:48:31.124612 | instance | Monday 07 September 2026  10:48:31 +0000 (0:00:00.043)       0:00:10.883 ******
2026-09-07 10:48:31.167158 | instance | skipping: [instance]
2026-09-07 10:48:31.168398 | instance |
2026-09-07 10:48:31.168459 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-07 10:48:31.168471 | instance | Monday 07 September 2026  10:48:31 +0000 (0:00:00.043)       0:00:10.927 ******
2026-09-07 10:48:31.207384 | instance | skipping: [instance]
2026-09-07 10:48:31.207570 | instance |
2026-09-07 10:48:31.207908 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-07 10:48:31.208231 | instance | Monday 07 September 2026  10:48:31 +0000 (0:00:00.040)       0:00:10.967 ******
2026-09-07 10:48:37.075101 | instance | changed: [instance]
2026-09-07 10:48:37.075185 | instance |
2026-09-07 10:48:37.075535 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-07 10:48:37.075590 | instance | Monday 07 September 2026  10:48:37 +0000 (0:00:05.867)       0:00:16.835 ******
2026-09-07 10:48:37.713936 | instance | changed: [instance]
2026-09-07 10:48:37.714045 | instance |
2026-09-07 10:48:37.714057 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-07 10:48:37.714066 | instance | Monday 07 September 2026  10:48:37 +0000 (0:00:00.637)       0:00:17.472 ******
2026-09-07 10:48:38.713034 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-07 10:48:38.713149 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-07 10:48:38.714111 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-07 10:48:38.714329 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-07 10:48:38.714345 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-07 10:48:38.714356 | instance |
2026-09-07 10:48:38.714380 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-07 10:48:38.714399 | instance | Monday 07 September 2026  10:48:38 +0000 (0:00:01.000)       0:00:18.473 ******
2026-09-07 10:48:39.360506 | instance | changed: [instance]
2026-09-07 10:48:39.360582 | instance |
2026-09-07 10:48:39.361397 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-07 10:48:39.361445 | instance | Monday 07 September 2026  10:48:39 +0000 (0:00:00.617)       0:00:19.091 ******
2026-09-07 10:48:39.361453 | instance |
2026-09-07 10:48:39.361459 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-07 10:48:39.361465 | instance | Monday 07 September 2026  10:48:39 +0000 (0:00:00.029)       0:00:19.121 ******
2026-09-07 10:48:40.338779 | instance | ok: [instance]
2026-09-07 10:48:40.338840 | instance |
2026-09-07 10:48:40.338849 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-07 10:48:40.338856 | instance | Monday 07 September 2026  10:48:40 +0000 (0:00:00.977)       0:00:20.098 ******
2026-09-07 10:48:40.836491 | instance | changed: [instance]
2026-09-07 10:48:40.836577 | instance |
2026-09-07 10:48:40.836588 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-07 10:48:40.836598 | instance | Monday 07 September 2026  10:48:40 +0000 (0:00:00.497)       0:00:20.595 ******
2026-09-07 10:48:41.432989 | instance | changed: [instance]
2026-09-07 10:48:41.433743 | instance |
2026-09-07 10:48:41.433775 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-07 10:48:41.433786 | instance | Monday 07 September 2026  10:48:41 +0000 (0:00:00.597)       0:00:21.193 ******
2026-09-07 10:48:41.664249 | instance | ok: [instance]
2026-09-07 10:48:41.664984 | instance |
2026-09-07 10:48:41.665020 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-07 10:48:41.665035 | instance | Monday 07 September 2026  10:48:41 +0000 (0:00:00.231)       0:00:21.424 ******
2026-09-07 10:48:41.732724 | instance | ok: [instance] => {
2026-09-07 10:48:41.732784 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-07 10:48:41.733226 | instance | }
2026-09-07 10:48:41.733240 | instance |
2026-09-07 10:48:41.733245 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-07 10:48:41.733249 | instance | Monday 07 September 2026  10:48:41 +0000 (0:00:00.069)       0:00:21.493 ******
2026-09-07 10:49:49.501686 | instance | FAILED - RETRYING: [instance]: Download item (4 retries left).
2026-09-07 10:49:49.502178 | instance | FAILED - RETRYING: [instance]: Download item (3 retries left).
2026-09-07 10:49:49.503671 | instance | changed: [instance]
2026-09-07 10:49:49.503765 | instance |
2026-09-07 10:49:49.503782 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-07 10:49:49.503796 | instance | Monday 07 September 2026  10:49:49 +0000 (0:01:07.766)       0:01:29.260 ******
2026-09-07 10:49:53.926732 | instance | changed: [instance]
2026-09-07 10:49:53.927556 | instance |
2026-09-07 10:49:53.927609 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-07 10:49:53.927621 | instance | Monday 07 September 2026  10:49:53 +0000 (0:00:04.426)       0:01:33.687 ******
2026-09-07 10:49:55.207103 | instance | ok: [instance]
2026-09-07 10:49:55.207191 | instance |
2026-09-07 10:49:55.207203 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-07 10:49:55.207214 | instance | Monday 07 September 2026  10:49:55 +0000 (0:00:01.278)       0:01:34.965 ******
2026-09-07 10:49:55.593316 | instance | changed: [instance]
2026-09-07 10:49:55.593385 | instance |
2026-09-07 10:49:55.593562 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-07 10:49:55.593580 | instance | Monday 07 September 2026  10:49:55 +0000 (0:00:00.388)       0:01:35.354 ******
2026-09-07 10:49:56.060911 | instance | changed: [instance]
2026-09-07 10:49:56.061623 | instance |
2026-09-07 10:49:56.061684 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-07 10:49:56.061695 | instance | Monday 07 September 2026  10:49:56 +0000 (0:00:00.466)       0:01:35.821 ******
2026-09-07 10:49:56.673121 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-07 10:49:56.674170 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-07 10:49:56.674271 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-07 10:49:56.674284 | instance |
2026-09-07 10:49:56.674306 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-07 10:49:56.674636 | instance | Monday 07 September 2026  10:49:56 +0000 (0:00:00.611)       0:01:36.433 ******
2026-09-07 10:49:57.170670 | instance | changed: [instance]
2026-09-07 10:49:57.170757 | instance |
2026-09-07 10:49:57.170999 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-07 10:49:57.171057 | instance | Monday 07 September 2026  10:49:57 +0000 (0:00:00.498)       0:01:36.931 ******
2026-09-07 10:49:57.626923 | instance | changed: [instance]
2026-09-07 10:49:57.626992 | instance |
2026-09-07 10:49:57.627550 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-07 10:49:57.627600 | instance | Monday 07 September 2026  10:49:57 +0000 (0:00:00.441)       0:01:37.373 ******
2026-09-07 10:49:57.627619 | instance |
2026-09-07 10:49:57.627626 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-07 10:49:57.627632 | instance | Monday 07 September 2026  10:49:57 +0000 (0:00:00.014)       0:01:37.387 ******
2026-09-07 10:49:58.428263 | instance | ok: [instance]
2026-09-07 10:49:58.429005 | instance |
2026-09-07 10:49:58.429067 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-07 10:49:58.429082 | instance | Monday 07 September 2026  10:49:58 +0000 (0:00:00.800)       0:01:38.188 ******
2026-09-07 10:49:59.382666 | instance | changed: [instance]
2026-09-07 10:49:59.383381 | instance |
2026-09-07 10:49:59.383415 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-07 10:49:59.383427 | instance | Monday 07 September 2026  10:49:59 +0000 (0:00:00.954)       0:01:39.142 ******
2026-09-07 10:49:59.981737 | instance | changed: [instance]
2026-09-07 10:49:59.981818 | instance |
2026-09-07 10:49:59.982104 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-07 10:49:59.982371 | instance | Monday 07 September 2026  10:49:59 +0000 (0:00:00.599)       0:01:39.741 ******
2026-09-07 10:50:00.053421 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-07 10:50:00.053492 | instance |
2026-09-07 10:50:00.053504 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-07 10:50:00.053514 | instance | Monday 07 September 2026  10:50:00 +0000 (0:00:00.071)       0:01:39.813 ******
2026-09-07 10:50:09.534927 | instance | changed: [instance]
2026-09-07 10:50:09.535115 | instance |
2026-09-07 10:50:09.535123 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-07 10:50:09.535129 | instance | Monday 07 September 2026  10:50:09 +0000 (0:00:09.481)       0:01:49.294 ******
2026-09-07 10:50:10.220583 | instance | ok: [instance] => (item=chronyd)
2026-09-07 10:50:10.220644 | instance | ok: [instance] => (item=sshd)
2026-09-07 10:50:10.220656 | instance |
2026-09-07 10:50:10.220667 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-07 10:50:10.220677 | instance | Monday 07 September 2026  10:50:10 +0000 (0:00:00.684)       0:01:49.979 ******
2026-09-07 10:50:22.349494 | instance | changed: [instance]
2026-09-07 10:50:22.349586 | instance |
2026-09-07 10:50:22.349599 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-07 10:50:22.349609 | instance | Monday 07 September 2026  10:50:22 +0000 (0:00:12.128)       0:02:02.107 ******
2026-09-07 10:50:22.607409 | instance | ok: [instance]
2026-09-07 10:50:22.607458 | instance |
2026-09-07 10:50:22.607470 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-07 10:50:22.607479 | instance | Monday 07 September 2026  10:50:22 +0000 (0:00:00.258)       0:02:02.366 ******
2026-09-07 10:50:23.077192 | instance | changed: [instance]
2026-09-07 10:50:23.077239 | instance |
2026-09-07 10:50:23.077246 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-07 10:50:23.077253 | instance | Monday 07 September 2026  10:50:23 +0000 (0:00:00.470)       0:02:02.837 ******
2026-09-07 10:50:23.447687 | instance | changed: [instance]
2026-09-07 10:50:23.447725 | instance |
2026-09-07 10:50:23.447731 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-07 10:50:23.447735 | instance | Monday 07 September 2026  10:50:23 +0000 (0:00:00.370)       0:02:03.208 ******
2026-09-07 10:50:23.582155 | instance | ok: [instance]
2026-09-07 10:50:23.582236 | instance |
2026-09-07 10:50:23.582256 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-07 10:50:23.582452 | instance | Monday 07 September 2026  10:50:23 +0000 (0:00:00.133)       0:02:03.341 ******
2026-09-07 10:50:23.859671 | instance | ok: [instance] => (item=instance)
2026-09-07 10:50:23.859782 | instance |
2026-09-07 10:50:23.860134 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-07 10:50:23.860186 | instance | Monday 07 September 2026  10:50:23 +0000 (0:00:00.277)       0:02:03.619 ******
2026-09-07 10:50:23.932009 | instance | ok: [instance]
2026-09-07 10:50:23.932082 | instance |
2026-09-07 10:50:23.932429 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-07 10:50:23.932475 | instance | Monday 07 September 2026  10:50:23 +0000 (0:00:00.073)       0:02:03.692 ******
2026-09-07 10:50:24.008978 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-07 10:50:24.009035 | instance |
2026-09-07 10:50:24.009303 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-07 10:50:24.009361 | instance | Monday 07 September 2026  10:50:24 +0000 (0:00:00.076)       0:02:03.769 ******
2026-09-07 10:50:24.344665 | instance | changed: [instance]
2026-09-07 10:50:24.345401 | instance |
2026-09-07 10:50:24.345459 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-07 10:50:24.345472 | instance | Monday 07 September 2026  10:50:24 +0000 (0:00:00.335)       0:02:04.104 ******
2026-09-07 10:50:25.124463 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-07 10:50:25.124519 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-07 10:50:25.124530 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-07 10:50:25.124540 | instance |
2026-09-07 10:50:25.124549 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-07 10:50:25.124558 | instance | Monday 07 September 2026  10:50:25 +0000 (0:00:00.778)       0:02:04.883 ******
2026-09-07 10:51:58.185511 | instance | ok: [instance]
2026-09-07 10:51:58.185570 | instance |
2026-09-07 10:51:58.185581 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-07 10:51:58.185613 | instance | Monday 07 September 2026  10:51:58 +0000 (0:01:33.061)       0:03:37.944 ******
2026-09-07 10:51:58.454404 | instance | changed: [instance]
2026-09-07 10:51:58.454541 | instance |
2026-09-07 10:51:58.454757 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-07 10:51:58.454965 | instance | Monday 07 September 2026  10:51:58 +0000 (0:00:00.269)       0:03:38.214 ******
2026-09-07 10:51:58.561975 | instance | ok: [instance]
2026-09-07 10:51:58.562052 | instance |
2026-09-07 10:51:58.562350 | instance | TASK [Install Ceph host] *******************************************************
2026-09-07 10:51:58.562393 | instance | Monday 07 September 2026  10:51:58 +0000 (0:00:00.107)       0:03:38.322 ******
2026-09-07 10:51:58.655031 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-07 10:51:58.655073 | instance |
2026-09-07 10:51:58.655407 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-07 10:51:58.655425 | instance | Monday 07 September 2026  10:51:58 +0000 (0:00:00.093)       0:03:38.415 ******
2026-09-07 10:52:00.256850 | instance | ok: [instance]
2026-09-07 10:52:00.257067 | instance |
2026-09-07 10:52:00.257348 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-07 10:52:00.257638 | instance | Monday 07 September 2026  10:52:00 +0000 (0:00:01.601)       0:03:40.017 ******
2026-09-07 10:52:00.332718 | instance | ok: [instance] => (item=instance)
2026-09-07 10:52:00.332898 | instance |
2026-09-07 10:52:00.333149 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-07 10:52:00.333376 | instance | Monday 07 September 2026  10:52:00 +0000 (0:00:00.076)       0:03:40.093 ******
2026-09-07 10:52:00.703884 | instance | ok: [instance]
2026-09-07 10:52:00.703949 | instance |
2026-09-07 10:52:00.704181 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-07 10:52:00.704214 | instance | Monday 07 September 2026  10:52:00 +0000 (0:00:00.371)       0:03:40.464 ******
2026-09-07 10:52:03.016656 | instance | ok: [instance]
2026-09-07 10:52:03.016739 | instance |
2026-09-07 10:52:03.017046 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-07 10:52:03.017092 | instance | Monday 07 September 2026  10:52:03 +0000 (0:00:02.312)       0:03:42.777 ******
2026-09-07 10:52:52.188094 | 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-07 10:52:52.188904 | 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_mjp0gik0/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_mjp0gik0/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_mjp0gik0/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_mjp0gik0/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-07T10:52:51.920+0000 7f29150f7640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAml55q7jtkIyAAxOs+evpxCzjlgiU/v6pOq0iJbMe49N1J0SD2u/SyT3g=: Malformed input [buffer:3]\n2026-09-07T10:52:51.920+0000 7f29150f7640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-07T10:52:51.920+0000 7f29150f7640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAml55q7jtkIyAAxOs+evpxCzjlgiU/v6pOq0iJbMe49N1J0SD2u/SyT3g=: Malformed input [buffer:3]\n2026-09-07T10:52:51.920+0000 7f29150f7640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-07T10:52:51.920+0000 7f29150f7640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAml55q7jtkIyAAxOs+evpxCzjlgiU/v6pOq0iJbMe49N1J0SD2u/SyT3g=: Malformed input [buffer:3]\n2026-09-07T10:52:51.920+0000 7f29150f7640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-07T10:52:51.920+0000 7f29150f7640 -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-07 10:52:52.191532 | instance |
2026-09-07 10:52:52.191790 | instance | PLAY RECAP *********************************************************************
2026-09-07 10:52:52.194135 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-07 10:52:52.194209 | instance |
2026-09-07 10:52:52.194221 | instance | Monday 07 September 2026  10:52:52 +0000 (0:00:49.175)       0:04:31.952 ******
2026-09-07 10:52:52.194231 | instance | ===============================================================================
2026-09-07 10:52:52.194240 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 93.06s
2026-09-07 10:52:52.194249 | instance | vexxhost.containers.download_artifact : Download item ------------------ 67.77s
2026-09-07 10:52:52.194257 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 49.18s
2026-09-07 10:52:52.194266 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 12.13s
2026-09-07 10:52:52.194275 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 9.48s
2026-09-07 10:52:52.194292 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.87s
2026-09-07 10:52:52.197366 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.43s
2026-09-07 10:52:52.197417 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.94s
2026-09-07 10:52:52.197429 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.31s
2026-09-07 10:52:52.197438 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.78s
2026-09-07 10:52:52.197447 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.60s
2026-09-07 10:52:52.197456 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.37s
2026-09-07 10:52:52.197465 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.28s
2026-09-07 10:52:52.197473 | instance | Gathering Facts --------------------------------------------------------- 1.17s
2026-09-07 10:52:52.197482 | instance | Gathering Facts --------------------------------------------------------- 1.02s
2026-09-07 10:52:52.197491 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.00s
2026-09-07 10:52:52.197499 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-07 10:52:52.197530 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.95s
2026-09-07 10:52:52.197540 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.94s
2026-09-07 10:52:52.197549 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.78s
2026-09-07 10:52:52.286343 | 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-07 10:52:52.286632 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-07 10:52:52.286807 | 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-07 10:52:52.713722 | instance | ERROR
2026-09-07 10:52:52.713981 | instance | {
2026-09-07 10:52:52.714027 | instance |   "delta": "0:05:25.672039",
2026-09-07 10:52:52.714053 | instance |   "end": "2026-09-07 10:52:52.351480",
2026-09-07 10:52:52.714072 | instance |   "msg": "non-zero return code",
2026-09-07 10:52:52.714089 | instance |   "rc": 2,
2026-09-07 10:52:52.714105 | instance |   "start": "2026-09-07 10:47:26.679441"
2026-09-07 10:52:52.714121 | instance | }
failure
2026-09-07 10:52:52.718060 | 
2026-09-07 10:52:52.718109 | PLAY RECAP
2026-09-07 10:52:52.718149 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 10:52:52.718171 | 
2026-09-07 10:52:52.863528 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 10:52:52.865373 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-07 10:52:53.627170 | 
2026-09-07 10:52:53.627312 | PLAY [all]
2026-09-07 10:52:53.645385 | 
2026-09-07 10:52:53.645507 | TASK [gather-host-logs : creating directory for system status]
2026-09-07 10:52:53.983944 | instance | changed
2026-09-07 10:52:53.990899 | 
2026-09-07 10:52:53.990972 | TASK [gather-host-logs : Get logs for each host]
2026-09-07 10:52:54.400404 | instance | + systemd-cgls --full --all --no-pager
2026-09-07 10:52:54.420005 | instance | + ip addr
2026-09-07 10:52:54.422938 | instance | + ip route
2026-09-07 10:52:54.425073 | instance | + lsblk
2026-09-07 10:52:54.430089 | instance | + mount
2026-09-07 10:52:54.431944 | instance | + docker images
2026-09-07 10:52:54.454183 | instance | + brctl show
2026-09-07 10:52:54.454722 | instance | /bin/bash: line 8: brctl: command not found
2026-09-07 10:52:54.455032 | instance | + ps aux --sort=-%mem
2026-09-07 10:52:54.477286 | instance | + dpkg -l
2026-09-07 10:52:54.488614 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-07 10:52:54.489060 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-07 10:52:54.511034 | instance | + '[' '!' -z '' ']'
2026-09-07 10:52:54.562023 | instance | ok: Runtime: 0:00:00.116636
2026-09-07 10:52:54.572862 | 
2026-09-07 10:52:54.573007 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-07 10:52:55.216011 | instance | changed:
2026-09-07 10:52:55.216250 | instance | created directory /var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/work/logs/instance
2026-09-07 10:52:55.216312 | instance | cd+++++++++ system/
2026-09-07 10:52:55.216359 | instance | >f+++++++++ system/brctl-show.txt
2026-09-07 10:52:55.216409 | instance | >f+++++++++ system/docker-images.txt
2026-09-07 10:52:55.216450 | instance | >f+++++++++ system/ip-addr.txt
2026-09-07 10:52:55.216558 | instance | >f+++++++++ system/ip-route.txt
2026-09-07 10:52:55.216613 | instance | >f+++++++++ system/lsblk.txt
2026-09-07 10:52:55.216694 | instance | >f+++++++++ system/mount.txt
2026-09-07 10:52:55.216754 | instance | >f+++++++++ system/packages.txt
2026-09-07 10:52:55.216797 | instance | >f+++++++++ system/ps.txt
2026-09-07 10:52:55.216843 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-07 10:52:55.235886 | 
2026-09-07 10:52:55.236015 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-07 10:52:55.442432 | instance | changed: "values"
2026-09-07 10:52:55.616627 | instance | changed: "releases"
2026-09-07 10:52:55.633570 | 
2026-09-07 10:52:55.633788 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-07 10:52:55.887702 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-07 10:52:56.180917 | instance | ok: Runtime: 0:00:00.005408
2026-09-07 10:52:56.190801 | 
2026-09-07 10:52:56.190940 | TASK [helm-release-status : Downloads logs to executor]
2026-09-07 10:52:56.669086 | instance | changed:
2026-09-07 10:52:56.669301 | instance | cd+++++++++ helm/
2026-09-07 10:52:56.669355 | instance | cd+++++++++ helm/releases/
2026-09-07 10:52:56.669397 | instance | cd+++++++++ helm/values/
2026-09-07 10:52:56.686024 | 
2026-09-07 10:52:56.686143 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-07 10:52:56.888740 | instance | changed
2026-09-07 10:52:56.896695 | 
2026-09-07 10:52:56.896798 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-07 10:52:57.174239 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:52:57.174351 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:52:57.180637 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:52:57.181713 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:52:57.182441 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:52:57.183740 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:52:57.185527 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:52:57.187079 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:52:57.187962 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:52:57.189042 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:52:57.190987 | instance | environment: line 1: kubectl: command not found
2026-09-07 10:52:57.192133 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 10:52:57.433544 | instance | ok: Runtime: 0:00:00.026310
2026-09-07 10:52:57.439207 | 
2026-09-07 10:52:57.439326 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-07 10:52:57.654593 | instance | changed
2026-09-07 10:52:57.663725 | 
2026-09-07 10:52:57.663885 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-07 10:52:57.921145 | instance | environment: line 5: kubectl: command not found
2026-09-07 10:52:57.921876 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:52:57.922309 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:52:57.922581 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 10:52:58.212112 | instance | ok: Runtime: 0:00:00.008416
2026-09-07 10:52:58.222062 | 
2026-09-07 10:52:58.222187 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-07 10:52:58.783941 | instance | changed:
2026-09-07 10:52:58.784188 | instance | cd+++++++++ objects/
2026-09-07 10:52:58.784247 | instance | cd+++++++++ objects/cluster/
2026-09-07 10:52:58.784295 | instance | cd+++++++++ objects/namespaced/
2026-09-07 10:52:58.801163 | 
2026-09-07 10:52:58.801289 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-07 10:52:59.016844 | instance | changed
2026-09-07 10:52:59.026270 | 
2026-09-07 10:52:59.026394 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-07 10:52:59.260232 | instance | changed
2026-09-07 10:52:59.267157 | 
2026-09-07 10:52:59.267250 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-07 10:52:59.487608 | instance | environment: line 3: kubectl: command not found
2026-09-07 10:52:59.799630 | instance | ok: Runtime: 0:00:00.006791
2026-09-07 10:52:59.809956 | 
2026-09-07 10:52:59.810090 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-07 10:53:00.258115 | instance | changed:
2026-09-07 10:53:00.258365 | instance | cd+++++++++ pod-logs/
2026-09-07 10:53:00.258424 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-07 10:53:00.276987 | 
2026-09-07 10:53:00.277119 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-07 10:53:00.494027 | instance | changed
2026-09-07 10:53:00.504005 | 
2026-09-07 10:53:00.504141 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-07 10:53:00.751235 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-07 10:53:01.058730 | instance | ok: Runtime: 0:00:00.048180
2026-09-07 10:53:01.067979 | 
2026-09-07 10:53:01.068805 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-07 10:53:01.332897 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-07 10:53:01.377588 | instance | ceph-mgr endpoints:
2026-09-07 10:53:01.617999 | instance | ok: Runtime: 0:00:00.051058
2026-09-07 10:53:01.628697 | 
2026-09-07 10:53:01.628827 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-07 10:53:01.868058 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-07 10:53:02.174192 | instance | ok: Runtime: 0:00:00.058809
2026-09-07 10:53:02.184306 | 
2026-09-07 10:53:02.184431 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-07 10:53:02.714230 | instance | changed: cd+++++++++ prometheus/
2026-09-07 10:53:02.727033 | 
2026-09-07 10:53:02.727125 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-07 10:53:02.950575 | instance | changed
2026-09-07 10:53:02.956923 | 
2026-09-07 10:53:02.957001 | TASK [gather-selenium-data : Get selenium data]
2026-09-07 10:53:03.227689 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-07 10:53:03.229092 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-07 10:53:03.491547 | instance | ERROR
2026-09-07 10:53:03.491783 | instance | {
2026-09-07 10:53:03.491849 | instance |   "delta": "0:00:00.005828",
2026-09-07 10:53:03.491905 | instance |   "end": "2026-09-07 10:53:03.229425",
2026-09-07 10:53:03.491961 | instance |   "msg": "non-zero return code",
2026-09-07 10:53:03.491999 | instance |   "rc": 1,
2026-09-07 10:53:03.492035 | instance |   "start": "2026-09-07 10:53:03.223597"
2026-09-07 10:53:03.492080 | instance | }
2026-09-07 10:53:03.492141 | instance | ERROR: Ignoring Errors
2026-09-07 10:53:03.502236 | 
2026-09-07 10:53:03.502361 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-07 10:53:04.017099 | instance | changed: cd+++++++++ selenium/
2026-09-07 10:53:04.021983 | 
2026-09-07 10:53:04.022026 | PLAY RECAP
2026-09-07 10:53:04.022066 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-07 10:53:04.022085 | 
2026-09-07 10:53:04.128082 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-07 10:53:04.131025 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 10:53:04.749034 | 
2026-09-07 10:53:04.749199 | PLAY [all]
2026-09-07 10:53:04.760653 | 
2026-09-07 10:53:04.760736 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 10:53:04.815292 | instance | skipping: Conditional result was False
2026-09-07 10:53:04.822100 | 
2026-09-07 10:53:04.822222 | TASK [fetch-output : Set log path for single node]
2026-09-07 10:53:04.874764 | instance | ok
2026-09-07 10:53:04.883751 | 
2026-09-07 10:53:04.883882 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 10:53:05.284712 | instance -> localhost | ok: "/var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/work/logs"
2026-09-07 10:53:05.516709 | instance -> localhost | changed: "/var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/work/artifacts"
2026-09-07 10:53:05.768881 | instance -> localhost | changed: "/var/lib/zuul/builds/0df0da236e174cff91bcbd4bd1223b83/work/docs"
2026-09-07 10:53:05.782448 | 
2026-09-07 10:53:05.782627 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 10:53:06.462333 | instance | changed: .d..t...... ./
2026-09-07 10:53:06.462640 | instance | changed: All items complete
2026-09-07 10:53:06.462723 | 
2026-09-07 10:53:07.050797 | instance | changed: .d..t...... ./
2026-09-07 10:53:07.552415 | instance | changed: .d..t...... ./
2026-09-07 10:53:07.581041 | 
2026-09-07 10:53:07.581226 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 10:53:08.108843 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007137
2026-09-07 10:53:08.371680 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009037
2026-09-07 10:53:08.387550 | 
2026-09-07 10:53:08.387756 | PLAY [all]
2026-09-07 10:53:08.398931 | 
2026-09-07 10:53:08.399058 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 10:53:08.834575 | instance | changed
2026-09-07 10:53:08.844053 | 
2026-09-07 10:53:08.844140 | PLAY RECAP
2026-09-07 10:53:08.844216 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 10:53:08.844259 | 
2026-09-07 10:53:08.949675 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 10:53:08.952464 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 10:53:09.537142 | 
2026-09-07 10:53:09.537575 | PLAY [localhost]
2026-09-07 10:53:09.546715 | 
2026-09-07 10:53:09.546793 | TASK [Generate Zuul manifest]
2026-09-07 10:53:09.563210 | localhost | ok
2026-09-07 10:53:09.577483 | 
2026-09-07 10:53:09.577577 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 10:53:09.950097 | localhost | changed
2026-09-07 10:53:09.960408 | 
2026-09-07 10:53:09.960527 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 10:53:09.987619 | localhost | ok
2026-09-07 10:53:09.993867 | 
2026-09-07 10:53:09.993929 | TASK [Upload logs]
2026-09-07 10:53:10.011403 | localhost | ok
2026-09-07 10:53:10.061401 | 
2026-09-07 10:53:10.061541 | TASK [Set zuul-log-path fact]
2026-09-07 10:53:10.079132 | localhost | ok
2026-09-07 10:53:10.091538 | 
2026-09-07 10:53:10.091622 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 10:53:10.129237 | localhost | ok
2026-09-07 10:53:10.137689 | 
2026-09-07 10:53:10.137804 | TASK [upload-logs : Create log directories]
2026-09-07 10:53:10.574467 | localhost | changed
2026-09-07 10:53:10.579996 | 
2026-09-07 10:53:10.580059 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 10:53:10.983115 | localhost -> localhost | ok: Runtime: 0:00:00.005084
2026-09-07 10:53:10.989202 | 
2026-09-07 10:53:10.989515 | TASK [upload-logs : Upload logs to log server]
2026-09-07 10:53:11.423054 | localhost | Output suppressed because no_log was given
2026-09-07 10:53:11.456842 | 
2026-09-07 10:53:11.456983 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 10:53:11.512732 | localhost | skipping: Conditional result was False
2026-09-07 10:53:11.522607 | localhost | skipping: Conditional result was False
2026-09-07 10:53:11.534156 | 
2026-09-07 10:53:11.534310 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 10:53:11.575995 | localhost | skipping: Conditional result was False
2026-09-07 10:53:11.576330 | 
2026-09-07 10:53:11.580035 | localhost | skipping: Conditional result was False
2026-09-07 10:53:11.599554 | 
2026-09-07 10:53:11.599804 | LOOP [upload-logs : Upload console log and json output]