2026-08-20 20:21:53.155933 | Job console starting
2026-08-20 20:21:53.166781 | Updating git repos
2026-08-20 20:22:01.256871 | Cloning repos into workspace
2026-08-20 20:22:19.907627 | Restoring repo states
2026-08-20 20:22:35.831088 | Merging changes
2026-08-20 20:22:38.664126 | Checking out repos
2026-08-20 20:22:43.828675 | Preparing playbooks
2026-08-20 20:22:48.763216 | Running Ansible setup
2026-08-20 20:22:52.890329 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-20 20:22:53.636114 | 
2026-08-20 20:22:53.745502 | PLAY [localhost]
2026-08-20 20:22:53.760146 | 
2026-08-20 20:22:53.760312 | TASK [Gathering Facts]
2026-08-20 20:22:54.811564 | localhost | ok
2026-08-20 20:22:54.822533 | 
2026-08-20 20:22:54.822695 | TASK [Setup log path fact]
2026-08-20 20:22:54.841536 | localhost | ok
2026-08-20 20:22:54.861701 | 
2026-08-20 20:22:54.861921 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-20 20:22:54.891200 | localhost | ok
2026-08-20 20:22:54.899985 | 
2026-08-20 20:22:54.900108 | TASK [emit-job-header : Print job information]
2026-08-20 20:22:54.939940 | # Job Information
2026-08-20 20:22:54.940118 | Ansible Version: 2.16.18
2026-08-20 20:22:54.940167 | Job: atmosphere-molecule-csi-rbd
2026-08-20 20:22:54.940199 | Pipeline: check
2026-08-20 20:22:54.940228 | Executor: 2d72f0692154
2026-08-20 20:22:54.940258 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4308
2026-08-20 20:22:54.940291 | Event ID: a9aa5160-9cd3-11f1-8e97-dcb7a0cf5b52
2026-08-20 20:22:54.944475 | 
2026-08-20 20:22:54.944570 | LOOP [emit-job-header : Print node information]
2026-08-20 20:22:55.042798 | localhost | ok:
2026-08-20 20:22:55.043071 | localhost | # Node Information
2026-08-20 20:22:55.043150 | localhost | Inventory Hostname: instance
2026-08-20 20:22:55.043211 | localhost | Hostname: np0000190251
2026-08-20 20:22:55.043266 | localhost | Username: zuul
2026-08-20 20:22:55.043328 | localhost | Distro: Ubuntu 22.04
2026-08-20 20:22:55.043385 | localhost | Provider: yul1
2026-08-20 20:22:55.043439 | localhost | Region: ca-ymq-1
2026-08-20 20:22:55.043492 | localhost | Label: ubuntu-jammy
2026-08-20 20:22:55.043543 | localhost | Product Name: OpenStack Nova
2026-08-20 20:22:55.043595 | localhost | Interface IP: 199.19.213.118
2026-08-20 20:22:55.052643 | 
2026-08-20 20:22:55.052823 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-20 20:22:55.648979 | localhost -> localhost | changed
2026-08-20 20:22:55.653822 | 
2026-08-20 20:22:55.653892 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-20 20:22:56.623120 | localhost -> localhost | changed
2026-08-20 20:22:56.635133 | 
2026-08-20 20:22:56.635224 | PLAY [all]
2026-08-20 20:22:56.644225 | 
2026-08-20 20:22:56.644339 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-20 20:22:56.877885 | instance -> localhost | ok
2026-08-20 20:22:56.885712 | 
2026-08-20 20:22:58.780212 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-20 20:22:58.814538 | instance | ok
2026-08-20 20:22:58.843959 | instance | included: /var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-20 20:22:58.855661 | 
2026-08-20 20:22:58.855820 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-20 20:23:00.121835 | instance -> localhost | Generating public/private rsa key pair.
2026-08-20 20:23:00.122035 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/work/b906fcbfef7c4282bc9bae8b7fe12012_id_rsa
2026-08-20 20:23:00.122081 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/work/b906fcbfef7c4282bc9bae8b7fe12012_id_rsa.pub
2026-08-20 20:23:00.122117 | instance -> localhost | The key fingerprint is:
2026-08-20 20:23:00.122150 | instance -> localhost | SHA256:mCT1pYowa3m49XUDtpINOKxz/f8QxwP3nws+ctjgPgo zuul-build-sshkey
2026-08-20 20:23:00.122193 | instance -> localhost | The key's randomart image is:
2026-08-20 20:23:00.122227 | instance -> localhost | +---[RSA 3072]----+
2026-08-20 20:23:00.122265 | instance -> localhost | |      .   .      |
2026-08-20 20:23:00.122299 | instance -> localhost | |   . o . o       |
2026-08-20 20:23:00.122331 | instance -> localhost | |  o = o = . .    |
2026-08-20 20:23:00.122362 | instance -> localhost | |   B * O o + .   |
2026-08-20 20:23:00.122401 | instance -> localhost | |  B = B S + + .  |
2026-08-20 20:23:00.122435 | instance -> localhost | | . * . + . = . ..|
2026-08-20 20:23:00.122466 | instance -> localhost | |  .   .E. o +. ..|
2026-08-20 20:23:00.122498 | instance -> localhost | |        .. *.+. .|
2026-08-20 20:23:00.122639 | instance -> localhost | |         .+o=... |
2026-08-20 20:23:00.122680 | instance -> localhost | +----[SHA256]-----+
2026-08-20 20:23:00.122761 | instance -> localhost | ok: Runtime: 0:00:00.811240
2026-08-20 20:23:00.132050 | 
2026-08-20 20:23:00.132159 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-20 20:23:00.168170 | instance | ok
2026-08-20 20:23:00.184886 | instance | included: /var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-20 20:23:00.196238 | 
2026-08-20 20:23:00.196356 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-20 20:23:00.220898 | instance | skipping: Conditional result was False
2026-08-20 20:23:00.230588 | 
2026-08-20 20:23:00.230699 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-20 20:23:00.704174 | instance | changed
2026-08-20 20:23:00.723474 | 
2026-08-20 20:23:00.723675 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-20 20:23:00.926951 | instance | ok
2026-08-20 20:23:00.934724 | 
2026-08-20 20:23:00.934807 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-20 20:23:01.431526 | instance | changed
2026-08-20 20:23:01.436996 | 
2026-08-20 20:23:01.437072 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-20 20:23:01.928140 | instance | changed
2026-08-20 20:23:02.089241 | 
2026-08-20 20:23:02.089379 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-20 20:23:02.114161 | instance | skipping: Conditional result was False
2026-08-20 20:23:02.122631 | 
2026-08-20 20:23:02.122726 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-20 20:23:02.547327 | instance -> localhost | changed
2026-08-20 20:23:02.575887 | 
2026-08-20 20:23:02.576152 | TASK [add-build-sshkey : Add back temp key]
2026-08-20 20:23:03.091576 | instance -> localhost | Identity added: /var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/work/b906fcbfef7c4282bc9bae8b7fe12012_id_rsa (zuul-build-sshkey)
2026-08-20 20:23:03.091775 | instance -> localhost | ok: Runtime: 0:00:00.011300
2026-08-20 20:23:03.098592 | 
2026-08-20 20:23:03.098714 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-20 20:23:03.413652 | instance | ok
2026-08-20 20:23:03.418510 | 
2026-08-20 20:23:03.418585 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-20 20:23:03.444207 | instance | skipping: Conditional result was False
2026-08-20 20:23:03.458468 | 
2026-08-20 20:23:03.458605 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-20 20:23:03.834535 | instance | ok
2026-08-20 20:23:03.839969 | 
2026-08-20 20:23:03.840036 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-20 20:23:14.654725 | instance | Output suppressed because no_log was given
2026-08-20 20:23:14.785087 | 
2026-08-20 20:23:14.785216 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-20 20:23:14.983688 | instance | ok: "logs"
2026-08-20 20:23:14.984051 | instance | ok: All items complete
2026-08-20 20:23:14.984206 | 
2026-08-20 20:23:15.132711 | instance | ok: "artifacts"
2026-08-20 20:23:15.289588 | instance | ok: "docs"
2026-08-20 20:23:15.306317 | 
2026-08-20 20:23:15.306478 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-20 20:23:15.505569 | instance | changed: "logs"
2026-08-20 20:23:15.677017 | instance | changed: "artifacts"
2026-08-20 20:23:15.843884 | instance | changed: "docs"
2026-08-20 20:23:15.861895 | 
2026-08-20 20:23:15.862003 | PLAY RECAP
2026-08-20 20:23:15.862100 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-20 20:23:15.862164 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-20 20:23:15.862191 | 
2026-08-20 20:23:16.105685 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-20 20:23:16.107696 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-20 20:23:16.792273 | 
2026-08-20 20:23:16.792401 | PLAY [all]
2026-08-20 20:23:16.803105 | 
2026-08-20 20:23:16.803189 | TASK [setup-uv : Extract archive]
2026-08-20 20:23:19.041021 | instance | changed
2026-08-20 20:23:19.050425 | 
2026-08-20 20:23:19.050534 | TASK [setup-uv : Print version]
2026-08-20 20:23:19.420174 | instance | uv 0.8.13
2026-08-20 20:23:19.588450 | instance | ok: Runtime: 0:00:00.011730
2026-08-20 20:23:19.601549 | 
2026-08-20 20:23:19.601657 | TASK [Install Ansible collection siblings]
2026-08-20 20:23:29.110413 | instance | changed:
2026-08-20 20:23:29.110577 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-20 20:23:29.110610 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-20 20:23:29.110633 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-20 20:23:29.110653 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-20 20:23:29.110673 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-20 20:23:29.110697 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-20 20:23:29.110718 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-20 20:23:29.110741 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-20 20:23:29.110783 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-20 20:23:29.110821 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-20 20:23:29.110844 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-20 20:23:29.110865 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-20 20:23:29.110885 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-08-20 20:23:29.110905 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-20 20:23:29.110925 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-08-20 20:23:29.110946 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-20 20:23:29.110967 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-20 20:23:29.110987 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-20 20:23:29.111013 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-20 20:23:29.111060 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-08-20 20:23:29.111085 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-08-20 20:23:29.111106 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-08-20 20:23:29.111128 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-20 20:23:29.111158 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-20 20:23:29.111180 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-20 20:23:29.111200 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-20 20:23:29.111220 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-20 20:23:29.111239 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-20 20:23:29.111258 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-20 20:23:29.111277 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-20 20:23:29.111296 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-20 20:23:29.111316 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-20 20:23:29.111336 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-20 20:23:29.111355 | instance | Checked out ansible.netcommon to v8.6.2
2026-08-20 20:23:29.111375 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-20 20:23:29.111401 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.111421 | instance | Process install dependency map
2026-08-20 20:23:29.111441 | instance | Starting collection install process
2026-08-20 20:23:29.111460 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-20 20:23:29.111480 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-20 20:23:29.111498 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-08-20 20:23:29.111517 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-20 20:23:29.111536 | instance | Creating virtual environment at: .venv
2026-08-20 20:23:29.111554 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-20 20:23:29.111573 | instance | Downloading netaddr (2.2MiB)
2026-08-20 20:23:29.111597 | instance | Downloading pygments (1.2MiB)
2026-08-20 20:23:29.111616 | instance | Downloading rjsonnet (1.2MiB)
2026-08-20 20:23:29.111635 | instance | Downloading openstacksdk (1.7MiB)
2026-08-20 20:23:29.111654 | instance | Downloading ansible-core (2.1MiB)
2026-08-20 20:23:29.112159 | instance | Downloading cryptography (4.2MiB)
2026-08-20 20:23:29.112191 | instance | Downloading kubernetes (1.9MiB)
2026-08-20 20:23:29.112619 | instance | Downloading setuptools (1.1MiB)
2026-08-20 20:23:29.112653 | instance |    Building pyperclip==1.9.0
2026-08-20 20:23:29.112696 | instance |  Downloading rjsonnet
2026-08-20 20:23:29.112720 | instance |  Downloading netaddr
2026-08-20 20:23:29.112740 | instance |  Downloading pygments
2026-08-20 20:23:29.112759 | instance |  Downloading cryptography
2026-08-20 20:23:29.112778 | instance |  Downloading setuptools
2026-08-20 20:23:29.112797 | instance |  Downloading kubernetes
2026-08-20 20:23:29.112815 | instance |  Downloading ansible-core
2026-08-20 20:23:29.112835 | instance |  Downloading openstacksdk
2026-08-20 20:23:29.112853 | instance |       Built pyperclip==1.9.0
2026-08-20 20:23:29.112873 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-20 20:23:29.112893 | instance | Installed 79 packages in 43ms
2026-08-20 20:23:29.112913 | instance | Checked out ansible.posix to 1.6.0
2026-08-20 20:23:29.112932 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-20 20:23:29.112968 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.112990 | instance | Process install dependency map
2026-08-20 20:23:29.113016 | instance | Starting collection install process
2026-08-20 20:23:29.113036 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-20 20:23:29.113054 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-20 20:23:29.113073 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-20 20:23:29.113091 | instance | Checked out ansible.utils to v6.1.0
2026-08-20 20:23:29.113111 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-20 20:23:29.113188 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.113252 | instance | Process install dependency map
2026-08-20 20:23:29.113276 | instance | Starting collection install process
2026-08-20 20:23:29.113296 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-20 20:23:29.113316 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-20 20:23:29.113335 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-20 20:23:29.113354 | instance | Checked out atmosphere.common to v0.6.0
2026-08-20 20:23:29.113468 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-20 20:23:29.113610 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.113640 | instance | Process install dependency map
2026-08-20 20:23:29.113661 | instance | Starting collection install process
2026-08-20 20:23:29.113681 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-20 20:23:29.113699 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-20 20:23:29.113719 | instance | atmosphere.common:0.6.0 was installed successfully
2026-08-20 20:23:29.113737 | instance | Checked out community.crypto to 2.2.3
2026-08-20 20:23:29.113756 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-20 20:23:29.113775 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.113792 | instance | Process install dependency map
2026-08-20 20:23:29.113811 | instance | Starting collection install process
2026-08-20 20:23:29.113830 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-20 20:23:29.113848 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-20 20:23:29.113866 | instance | community.crypto:2.2.3 was installed successfully
2026-08-20 20:23:29.113884 | instance | Checked out community.general to 7.3.0
2026-08-20 20:23:29.113902 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-08-20 20:23:29.113920 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.113938 | instance | Process install dependency map
2026-08-20 20:23:29.113956 | instance | Starting collection install process
2026-08-20 20:23:29.113974 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-20 20:23:29.113991 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-20 20:23:29.114010 | instance | community.general:7.3.0 was installed successfully
2026-08-20 20:23:29.114035 | instance | Checked out community.mysql to 3.6.0
2026-08-20 20:23:29.114055 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-20 20:23:29.114089 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.114111 | instance | Process install dependency map
2026-08-20 20:23:29.114130 | instance | Starting collection install process
2026-08-20 20:23:29.114149 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-20 20:23:29.114167 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-20 20:23:29.114186 | instance | community.mysql:3.6.0 was installed successfully
2026-08-20 20:23:29.114204 | instance | Checked out kubernetes.core to 2.4.0
2026-08-20 20:23:29.114265 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-20 20:23:29.114440 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.114501 | instance | Process install dependency map
2026-08-20 20:23:29.114525 | instance | Starting collection install process
2026-08-20 20:23:29.114546 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-20 20:23:29.114576 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-20 20:23:29.114597 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-20 20:23:29.114616 | instance | Checked out openstack.cloud to 1.7.0
2026-08-20 20:23:29.114635 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-20 20:23:29.114655 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.114673 | instance | Process install dependency map
2026-08-20 20:23:29.114692 | instance | Starting collection install process
2026-08-20 20:23:29.114710 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-20 20:23:29.114730 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-20 20:23:29.114749 | instance | openstack.cloud:1.7.0 was installed successfully
2026-08-20 20:23:29.114768 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-20 20:23:29.114788 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-20 20:23:29.114807 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.114839 | instance | Process install dependency map
2026-08-20 20:23:29.114859 | instance | Starting collection install process
2026-08-20 20:23:29.114878 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-20 20:23:29.114897 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-20 20:23:29.114915 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-20 20:23:29.114934 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-20 20:23:29.114953 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-20 20:23:29.114983 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.115009 | instance | Process install dependency map
2026-08-20 20:23:29.115029 | instance | Starting collection install process
2026-08-20 20:23:29.115048 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-20 20:23:29.115067 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-20 20:23:29.115087 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-20 20:23:29.115106 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-20 20:23:29.115125 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-20 20:23:29.115144 | instance | Starting galaxy collection install process
2026-08-20 20:23:29.115164 | instance | Process install dependency map
2026-08-20 20:23:29.115183 | instance | Starting collection install process
2026-08-20 20:23:29.115202 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-20 20:23:29.115221 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-20 20:23:29.115240 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-20 20:23:29.122150 | 
2026-08-20 20:23:29.122207 | PLAY RECAP
2026-08-20 20:23:29.122253 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-20 20:23:29.122275 | 
2026-08-20 20:23:29.269752 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-20 20:23:29.273412 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-20 20:23:30.007311 | 
2026-08-20 20:23:30.007438 | PLAY [all]
2026-08-20 20:23:30.019783 | 
2026-08-20 20:23:30.019887 | TASK [Install "jq" for log collection]
2026-08-20 20:23:40.005597 | instance | changed
2026-08-20 20:23:40.008743 | 
2026-08-20 20:23:40.008892 | PLAY RECAP
2026-08-20 20:23:40.009039 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-20 20:23:40.009102 | 
2026-08-20 20:23:40.185218 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-20 20:23:40.187259 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-20 20:23:40.969171 | 
2026-08-20 20:23:41.888476 | PLAY [all]
2026-08-20 20:23:41.915923 | 
2026-08-20 20:23:41.916141 | LOOP [Check inventory directories]
2026-08-20 20:23:42.583856 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-20 20:23:42.770266 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-20 20:23:42.939863 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-20 20:23:42.954999 | 
2026-08-20 20:23:42.955206 | LOOP [Copy inventory file for Zuul]
2026-08-20 20:23:45.654811 | instance | changed:
2026-08-20 20:23:45.655650 | instance | {
2026-08-20 20:23:45.655748 | instance |   "ansible_loop_var": "item",
2026-08-20 20:23:45.655782 | instance |   "changed": false,
2026-08-20 20:23:45.655808 | instance |   "failed": false,
2026-08-20 20:23:45.655833 | instance |   "invocation": {
2026-08-20 20:23:45.655856 | instance |     "module_args": {
2026-08-20 20:23:45.655878 | instance |       "checksum_algorithm": "sha1",
2026-08-20 20:23:45.655900 | instance |       "follow": true,
2026-08-20 20:23:45.655920 | instance |       "get_attributes": true,
2026-08-20 20:23:45.656168 | instance |       "get_checksum": true,
2026-08-20 20:23:45.656223 | instance |       "get_mime": true,
2026-08-20 20:23:45.656281 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-20 20:23:45.656330 | instance |     }
2026-08-20 20:23:45.656364 | instance |   },
2026-08-20 20:23:45.656437 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-20 20:23:45.656467 | instance |   "stat": {
2026-08-20 20:23:45.656593 | instance |     "atime": 1787257401.5025299,
2026-08-20 20:23:45.656681 | instance |     "attr_flags": "e",
2026-08-20 20:23:45.656757 | instance |     "attributes": [
2026-08-20 20:23:45.656844 | instance |       "extents"
2026-08-20 20:23:45.656869 | instance |     ],
2026-08-20 20:23:45.656888 | instance |     "block_size": 4096,
2026-08-20 20:23:45.656930 | instance |     "blocks": 8,
2026-08-20 20:23:45.656994 | instance |     "charset": "unknown",
2026-08-20 20:23:45.657599 | instance |     "ctime": 1787257400.4625008,
2026-08-20 20:23:45.657668 | instance |     "dev": 64513,
2026-08-20 20:23:45.657697 | instance |     "device_type": 0,
2026-08-20 20:23:45.657775 | instance |     "executable": true,
2026-08-20 20:23:45.657809 | instance |     "exists": true,
2026-08-20 20:23:45.657903 | instance |     "gid": 1000,
2026-08-20 20:23:45.657939 | instance |     "gr_name": "zuul",
2026-08-20 20:23:45.657964 | instance |     "inode": 1048912,
2026-08-20 20:23:45.657987 | instance |     "isblk": false,
2026-08-20 20:23:45.658008 | instance |     "ischr": false,
2026-08-20 20:23:45.658028 | instance |     "isdir": true,
2026-08-20 20:23:45.658053 | instance |     "isfifo": false,
2026-08-20 20:23:45.658089 | instance |     "isgid": false,
2026-08-20 20:23:45.658114 | instance |     "islnk": false,
2026-08-20 20:23:45.658137 | instance |     "isreg": false,
2026-08-20 20:23:45.658160 | instance |     "issock": false,
2026-08-20 20:23:45.658185 | instance |     "isuid": false,
2026-08-20 20:23:45.661400 | instance |     "mimetype": "unknown",
2026-08-20 20:23:45.661496 | instance |     "mode": "0755",
2026-08-20 20:23:45.661522 | instance |     "mtime": 1787257400.4625008,
2026-08-20 20:23:45.661543 | instance |     "nlink": 22,
2026-08-20 20:23:45.661587 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-20 20:23:45.661755 | instance |     "pw_name": "zuul",
2026-08-20 20:23:45.661801 | instance |     "readable": true,
2026-08-20 20:23:45.661864 | instance |     "rgrp": true,
2026-08-20 20:23:45.661892 | instance |     "roth": true,
2026-08-20 20:23:45.661943 | instance |     "rusr": true,
2026-08-20 20:23:45.661970 | instance |     "size": 4096,
2026-08-20 20:23:45.661997 | instance |     "uid": 1000,
2026-08-20 20:23:45.662023 | instance |     "version": "2755245677",
2026-08-20 20:23:45.662076 | instance |     "wgrp": false,
2026-08-20 20:23:45.662110 | instance |     "woth": false,
2026-08-20 20:23:45.662134 | instance |     "writeable": true,
2026-08-20 20:23:45.662155 | instance |     "wusr": true,
2026-08-20 20:23:45.662173 | instance |     "xgrp": true,
2026-08-20 20:23:45.662191 | instance |     "xoth": true,
2026-08-20 20:23:45.662209 | instance |     "xusr": true
2026-08-20 20:23:45.662228 | instance |   }
2026-08-20 20:23:45.662246 | instance | }
2026-08-20 20:23:45.662313 | instance | ok: All items complete
2026-08-20 20:23:45.662561 | 
2026-08-20 20:23:45.664249 | instance | skipping: Conditional result was False
2026-08-20 20:23:45.665234 | instance | skipping: Conditional result was False
2026-08-20 20:23:45.680625 | 
2026-08-20 20:23:45.680769 | LOOP [Switch "ansible_host" to private IP]
2026-08-20 20:23:47.445801 | instance | changed: 1 replacements made
2026-08-20 20:23:47.473045 | instance | skipping: Conditional result was False
2026-08-20 20:23:47.474138 | instance | skipping: Conditional result was False
2026-08-20 20:23:47.490577 | 
2026-08-20 20:23:47.490739 | TASK [Run Molecule scenario]
2026-08-20 20:23:48.621152 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-20 20:23:49.033522 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-20 20:23:49.033612 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-20 20:24:00.964215 | instance | INFO     [csi > dependency] Executing
2026-08-20 20:24:00.964544 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-20 20:24:00.964898 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-20 20:24:00.965101 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-20 20:24:00.973879 | instance | INFO     [csi > cleanup] Executing
2026-08-20 20:24:00.974241 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-20 20:24:00.983101 | instance | INFO     [csi > destroy] Executing
2026-08-20 20:24:00.983182 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-20 20:24:00.983320 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-20 20:24:00.991889 | instance | INFO     [csi > syntax] Executing
2026-08-20 20:24:02.851917 | instance |
2026-08-20 20:24:02.852275 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-20 20:24:02.944567 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-20 20:24:02.959241 | instance | INFO     [csi > create] Executing
2026-08-20 20:24:02.961859 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-20 20:24:02.971896 | instance | INFO     [csi > prepare] Executing
2026-08-20 20:24:03.931167 | instance |
2026-08-20 20:24:03.931487 | instance | PLAY [Prepare] *****************************************************************
2026-08-20 20:24:03.931899 | instance |
2026-08-20 20:24:03.932293 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 20:24:03.932859 | instance | Thursday 20 August 2026  20:24:03 +0000 (0:00:00.031)       0:00:00.031 *******
2026-08-20 20:24:05.183881 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-20 20:24:05.365337 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-20 20:24:05.365401 | instance | interpreter could change the meaning of that path. See
2026-08-20 20:24:05.365413 | instance | https://docs.ansible.com/ansible-
2026-08-20 20:24:05.365423 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-20 20:24:05.365433 | instance | ok: [instance]
2026-08-20 20:24:05.365443 | instance |
2026-08-20 20:24:05.365453 | instance | TASK [Configure short hostname] ************************************************
2026-08-20 20:24:05.365463 | instance | Thursday 20 August 2026  20:24:05 +0000 (0:00:01.264)       0:00:01.296 *******
2026-08-20 20:24:06.082152 | instance | changed: [instance]
2026-08-20 20:24:06.082442 | instance |
2026-08-20 20:24:06.082742 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-20 20:24:06.083024 | instance | Thursday 20 August 2026  20:24:06 +0000 (0:00:00.886)       0:00:02.182 *******
2026-08-20 20:24:06.343732 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-20 20:24:06.344112 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-20 20:24:06.344336 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-20 20:24:06.353990 | instance | changed: [instance]
2026-08-20 20:24:06.354196 | instance |
2026-08-20 20:24:06.354569 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-20 20:24:06.354752 | instance | Thursday 20 August 2026  20:24:06 +0000 (0:00:00.272)       0:00:02.454 *******
2026-08-20 20:24:07.145847 | instance | ok: [instance]
2026-08-20 20:24:07.146124 | instance |
2026-08-20 20:24:07.146486 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-20 20:24:07.146797 | instance |
2026-08-20 20:24:07.147141 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 20:24:07.147521 | instance | Thursday 20 August 2026  20:24:07 +0000 (0:00:00.791)       0:00:03.246 *******
2026-08-20 20:24:07.876927 | instance | ok: [instance]
2026-08-20 20:24:07.877006 | instance |
2026-08-20 20:24:07.877014 | instance | TASK [Install depedencies] *****************************************************
2026-08-20 20:24:07.877021 | instance | Thursday 20 August 2026  20:24:07 +0000 (0:00:00.731)       0:00:03.977 *******
2026-08-20 20:24:29.058534 | instance | changed: [instance]
2026-08-20 20:24:29.058576 | instance |
2026-08-20 20:24:29.058585 | instance | TASK [Start up service] ********************************************************
2026-08-20 20:24:29.058593 | instance | Thursday 20 August 2026  20:24:29 +0000 (0:00:21.181)       0:00:25.159 *******
2026-08-20 20:24:29.662033 | instance | ok: [instance]
2026-08-20 20:24:29.662120 | instance |
2026-08-20 20:24:29.662470 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-20 20:24:29.662516 | instance | Thursday 20 August 2026  20:24:29 +0000 (0:00:00.603)       0:00:25.763 *******
2026-08-20 20:24:29.959306 | instance | ok: [instance]
2026-08-20 20:24:29.959386 | instance |
2026-08-20 20:24:29.959636 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-20 20:24:29.959669 | instance | Thursday 20 August 2026  20:24:29 +0000 (0:00:00.297)       0:00:26.060 *******
2026-08-20 20:24:30.706732 | instance | changed: [instance]
2026-08-20 20:24:30.706812 | instance |
2026-08-20 20:24:30.707106 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-20 20:24:30.707144 | instance | Thursday 20 August 2026  20:24:30 +0000 (0:00:00.747)       0:00:26.807 *******
2026-08-20 20:24:30.927701 | instance | ok: [instance]
2026-08-20 20:24:30.928207 | instance |
2026-08-20 20:24:30.928246 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-20 20:24:30.928254 | instance | Thursday 20 August 2026  20:24:30 +0000 (0:00:00.221)       0:00:27.029 *******
2026-08-20 20:24:30.950306 | instance | skipping: [instance]
2026-08-20 20:24:30.950415 | instance |
2026-08-20 20:24:30.950696 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-20 20:24:30.950711 | instance | Thursday 20 August 2026  20:24:30 +0000 (0:00:00.022)       0:00:27.051 *******
2026-08-20 20:24:31.528389 | instance | changed: [instance] => (item=osd0)
2026-08-20 20:24:31.528532 | instance | changed: [instance] => (item=osd1)
2026-08-20 20:24:31.528762 | instance | changed: [instance] => (item=osd2)
2026-08-20 20:24:31.529021 | instance |
2026-08-20 20:24:31.529314 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-20 20:24:31.529558 | instance | Thursday 20 August 2026  20:24:31 +0000 (0:00:00.577)       0:00:27.629 *******
2026-08-20 20:24:32.137815 | instance | changed: [instance] => (item=osd0)
2026-08-20 20:24:32.137886 | instance | changed: [instance] => (item=osd1)
2026-08-20 20:24:32.138075 | instance | changed: [instance] => (item=osd2)
2026-08-20 20:24:32.138291 | instance |
2026-08-20 20:24:32.138486 | instance | TASK [Start loop devices] ******************************************************
2026-08-20 20:24:32.138654 | instance | Thursday 20 August 2026  20:24:32 +0000 (0:00:00.609)       0:00:28.239 *******
2026-08-20 20:24:32.845273 | instance | changed: [instance] => (item=osd0)
2026-08-20 20:24:32.845422 | instance | changed: [instance] => (item=osd1)
2026-08-20 20:24:32.845455 | instance | changed: [instance] => (item=osd2)
2026-08-20 20:24:32.845653 | instance |
2026-08-20 20:24:32.845907 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-20 20:24:32.846100 | instance | Thursday 20 August 2026  20:24:32 +0000 (0:00:00.707)       0:00:28.946 *******
2026-08-20 20:24:36.266164 | instance | changed: [instance] => (item=osd0)
2026-08-20 20:24:36.266627 | instance | changed: [instance] => (item=osd1)
2026-08-20 20:24:36.267020 | instance | changed: [instance] => (item=osd2)
2026-08-20 20:24:36.267387 | instance |
2026-08-20 20:24:36.267828 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-20 20:24:36.268229 | instance | Thursday 20 August 2026  20:24:36 +0000 (0:00:03.416)       0:00:32.363 *******
2026-08-20 20:24:38.407888 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-20 20:24:38.408134 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-20 20:24:38.408413 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-20 20:24:38.408707 | instance |
2026-08-20 20:24:38.409011 | instance | PLAY RECAP *********************************************************************
2026-08-20 20:24:38.409373 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-20 20:24:38.409663 | instance |
2026-08-20 20:24:38.409969 | instance | Thursday 20 August 2026  20:24:38 +0000 (0:00:02.146)       0:00:34.509 *******
2026-08-20 20:24:38.410292 | instance | ===============================================================================
2026-08-20 20:24:38.410593 | instance | Install depedencies ---------------------------------------------------- 21.18s
2026-08-20 20:24:38.410879 | instance | Create a volume group for each loop device ------------------------------ 3.42s
2026-08-20 20:24:38.411108 | instance | Create a logical volume for each loop device ---------------------------- 2.15s
2026-08-20 20:24:38.411351 | instance | Gathering Facts --------------------------------------------------------- 1.26s
2026-08-20 20:24:38.411579 | instance | Configure short hostname ------------------------------------------------ 0.89s
2026-08-20 20:24:38.411799 | instance | Purge "snapd" package --------------------------------------------------- 0.79s
2026-08-20 20:24:38.412015 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.75s
2026-08-20 20:24:38.412233 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-08-20 20:24:38.412452 | instance | Start loop devices ------------------------------------------------------ 0.71s
2026-08-20 20:24:38.412670 | instance | Set permissions on loopback devices ------------------------------------- 0.61s
2026-08-20 20:24:38.412886 | instance | Start up service -------------------------------------------------------- 0.60s
2026-08-20 20:24:38.413104 | instance | Create devices for Ceph ------------------------------------------------- 0.58s
2026-08-20 20:24:38.413397 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-08-20 20:24:38.413643 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s
2026-08-20 20:24:38.413872 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-08-20 20:24:38.414137 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-20 20:24:38.494502 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-20 20:24:38.506366 | instance | INFO     [csi > converge] Executing
2026-08-20 20:24:40.158801 | instance |
2026-08-20 20:24:40.159223 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-20 20:24:40.159552 | instance |
2026-08-20 20:24:40.159941 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 20:24:40.160286 | instance | Thursday 20 August 2026  20:24:40 +0000 (0:00:00.008)       0:00:00.008 *******
2026-08-20 20:24:41.136580 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-20 20:24:41.137018 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-20 20:24:41.137548 | instance | interpreter could change the meaning of that path. See
2026-08-20 20:24:41.137963 | instance | https://docs.ansible.com/ansible-
2026-08-20 20:24:41.138332 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-20 20:24:41.148788 | instance | ok: [instance]
2026-08-20 20:24:41.149033 | instance |
2026-08-20 20:24:41.149350 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-20 20:24:41.149725 | instance | Thursday 20 August 2026  20:24:41 +0000 (0:00:00.990)       0:00:00.998 *******
2026-08-20 20:24:41.193386 | instance | ok: [instance] => {
2026-08-20 20:24:41.193905 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-20 20:24:41.194423 | instance | }
2026-08-20 20:24:41.194897 | instance |
2026-08-20 20:24:41.195303 | instance | PLAY [all] *********************************************************************
2026-08-20 20:24:41.195648 | instance |
2026-08-20 20:24:41.196025 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 20:24:41.196509 | instance | Thursday 20 August 2026  20:24:41 +0000 (0:00:00.044)       0:00:01.043 *******
2026-08-20 20:24:42.078177 | instance | ok: [instance]
2026-08-20 20:24:42.078589 | instance |
2026-08-20 20:24:42.078968 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-20 20:24:42.079547 | instance | Thursday 20 August 2026  20:24:42 +0000 (0:00:00.884)       0:00:01.927 *******
2026-08-20 20:24:42.274925 | instance | ok: [instance]
2026-08-20 20:24:42.275429 | instance |
2026-08-20 20:24:42.275673 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-20 20:24:42.275890 | instance |
2026-08-20 20:24:42.276120 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 20:24:42.276408 | instance | Thursday 20 August 2026  20:24:42 +0000 (0:00:00.197)       0:00:02.124 *******
2026-08-20 20:24:43.164222 | instance | ok: [instance]
2026-08-20 20:24:43.164271 | instance |
2026-08-20 20:24:43.164279 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-20 20:24:43.164293 | instance | Thursday 20 August 2026  20:24:43 +0000 (0:00:00.888)       0:00:03.013 *******
2026-08-20 20:24:43.490804 | instance | ok: [instance]
2026-08-20 20:24:43.490841 | instance |
2026-08-20 20:24:43.490847 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-20 20:24:43.490852 | instance | Thursday 20 August 2026  20:24:43 +0000 (0:00:00.326)       0:00:03.340 *******
2026-08-20 20:24:43.541495 | instance | skipping: [instance]
2026-08-20 20:24:43.541603 | instance |
2026-08-20 20:24:43.541625 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-20 20:24:43.541636 | instance | Thursday 20 August 2026  20:24:43 +0000 (0:00:00.050)       0:00:03.390 *******
2026-08-20 20:24:43.890518 | instance | changed: [instance]
2026-08-20 20:24:43.890891 | instance |
2026-08-20 20:24:43.890907 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-20 20:24:43.890912 | instance | Thursday 20 August 2026  20:24:43 +0000 (0:00:00.349)       0:00:03.740 *******
2026-08-20 20:24:43.967855 | instance | ok: [instance] => {
2026-08-20 20:24:43.968001 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-20 20:24:43.968581 | instance | }
2026-08-20 20:24:43.968625 | instance |
2026-08-20 20:24:43.968631 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-20 20:24:43.968637 | instance | Thursday 20 August 2026  20:24:43 +0000 (0:00:00.077)       0:00:03.817 *******
2026-08-20 20:24:44.663655 | instance | changed: [instance]
2026-08-20 20:24:44.663749 | instance |
2026-08-20 20:24:44.664032 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-20 20:24:44.664062 | instance | Thursday 20 August 2026  20:24:44 +0000 (0:00:00.695)       0:00:04.513 *******
2026-08-20 20:24:44.719581 | instance | skipping: [instance]
2026-08-20 20:24:44.720062 | instance |
2026-08-20 20:24:44.720105 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-20 20:24:44.720113 | instance | Thursday 20 August 2026  20:24:44 +0000 (0:00:00.055)       0:00:04.569 *******
2026-08-20 20:24:44.773787 | instance | skipping: [instance]
2026-08-20 20:24:44.773924 | instance |
2026-08-20 20:24:44.774115 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-20 20:24:44.774275 | instance | Thursday 20 August 2026  20:24:44 +0000 (0:00:00.054)       0:00:04.623 *******
2026-08-20 20:24:44.990679 | instance | ok: [instance]
2026-08-20 20:24:44.990786 | instance |
2026-08-20 20:24:44.990915 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-20 20:24:44.991111 | instance | Thursday 20 August 2026  20:24:44 +0000 (0:00:00.216)       0:00:04.840 *******
2026-08-20 20:24:46.242048 | instance | ok: [instance]
2026-08-20 20:24:46.242219 | instance |
2026-08-20 20:24:46.242236 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-20 20:24:46.242381 | instance | Thursday 20 August 2026  20:24:46 +0000 (0:00:01.251)       0:00:06.091 *******
2026-08-20 20:24:46.309533 | instance | ok: [instance] => {
2026-08-20 20:24:46.309607 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-20 20:24:46.309746 | instance | }
2026-08-20 20:24:46.309920 | instance |
2026-08-20 20:24:46.310075 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-20 20:24:46.310228 | instance | Thursday 20 August 2026  20:24:46 +0000 (0:00:00.067)       0:00:06.159 *******
2026-08-20 20:24:47.085786 | instance | changed: [instance]
2026-08-20 20:24:47.085909 | instance |
2026-08-20 20:24:47.086026 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-20 20:24:47.086221 | instance | Thursday 20 August 2026  20:24:47 +0000 (0:00:00.775)       0:00:06.935 *******
2026-08-20 20:24:49.986734 | instance | changed: [instance]
2026-08-20 20:24:49.986806 | instance |
2026-08-20 20:24:49.986932 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-20 20:24:49.987138 | instance | Thursday 20 August 2026  20:24:49 +0000 (0:00:02.897)       0:00:09.833 *******
2026-08-20 20:24:50.017852 | instance | skipping: [instance]
2026-08-20 20:24:50.018008 | instance |
2026-08-20 20:24:50.018209 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-20 20:24:50.018382 | instance | Thursday 20 August 2026  20:24:50 +0000 (0:00:00.033)       0:00:09.866 *******
2026-08-20 20:24:50.046243 | instance | skipping: [instance]
2026-08-20 20:24:50.046669 | instance |
2026-08-20 20:24:50.047212 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-20 20:24:50.047542 | instance | Thursday 20 August 2026  20:24:50 +0000 (0:00:00.028)       0:00:09.895 *******
2026-08-20 20:24:50.082110 | instance | skipping: [instance]
2026-08-20 20:24:50.082493 | instance |
2026-08-20 20:24:50.082882 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-20 20:24:50.083240 | instance | Thursday 20 August 2026  20:24:50 +0000 (0:00:00.033)       0:00:09.929 *******
2026-08-20 20:24:57.248509 | instance | changed: [instance]
2026-08-20 20:24:57.248710 | instance |
2026-08-20 20:24:57.248890 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-20 20:24:57.249058 | instance | Thursday 20 August 2026  20:24:57 +0000 (0:00:07.166)       0:00:17.096 *******
2026-08-20 20:24:57.855273 | instance | changed: [instance]
2026-08-20 20:24:57.855340 | instance |
2026-08-20 20:24:57.855717 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-20 20:24:57.855766 | instance | Thursday 20 August 2026  20:24:57 +0000 (0:00:00.609)       0:00:17.705 *******
2026-08-20 20:24:58.768623 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-20 20:24:58.768698 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-20 20:24:58.769349 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-20 20:24:58.769418 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-20 20:24:58.769428 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-20 20:24:58.769436 | instance |
2026-08-20 20:24:58.769464 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-20 20:24:58.769474 | instance | Thursday 20 August 2026  20:24:58 +0000 (0:00:00.913)       0:00:18.618 *******
2026-08-20 20:24:59.341479 | instance | changed: [instance]
2026-08-20 20:24:59.341550 | instance |
2026-08-20 20:24:59.342172 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-20 20:24:59.342212 | instance | Thursday 20 August 2026  20:24:59 +0000 (0:00:00.556)       0:00:19.174 *******
2026-08-20 20:24:59.342218 | instance |
2026-08-20 20:24:59.342222 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-20 20:24:59.342226 | instance | Thursday 20 August 2026  20:24:59 +0000 (0:00:00.016)       0:00:19.191 *******
2026-08-20 20:25:00.271961 | instance | ok: [instance]
2026-08-20 20:25:00.272489 | instance |
2026-08-20 20:25:00.272529 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-20 20:25:00.272537 | instance | Thursday 20 August 2026  20:25:00 +0000 (0:00:00.930)       0:00:20.121 *******
2026-08-20 20:25:00.707798 | instance | changed: [instance]
2026-08-20 20:25:00.707859 | instance |
2026-08-20 20:25:00.707867 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-20 20:25:00.707874 | instance | Thursday 20 August 2026  20:25:00 +0000 (0:00:00.435)       0:00:20.557 *******
2026-08-20 20:25:01.267133 | instance | changed: [instance]
2026-08-20 20:25:01.267213 | instance |
2026-08-20 20:25:01.267524 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-20 20:25:01.267567 | instance | Thursday 20 August 2026  20:25:01 +0000 (0:00:00.559)       0:00:21.116 *******
2026-08-20 20:25:01.473774 | instance | ok: [instance]
2026-08-20 20:25:01.473874 | instance |
2026-08-20 20:25:01.474155 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-20 20:25:01.474192 | instance | Thursday 20 August 2026  20:25:01 +0000 (0:00:00.206)       0:00:21.323 *******
2026-08-20 20:25:01.536079 | instance | ok: [instance] => {
2026-08-20 20:25:01.536751 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-20 20:25:01.536792 | instance | }
2026-08-20 20:25:01.536797 | instance |
2026-08-20 20:25:01.536802 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-20 20:25:01.536806 | instance | Thursday 20 August 2026  20:25:01 +0000 (0:00:00.062)       0:00:21.385 *******
2026-08-20 20:25:02.464045 | instance | changed: [instance]
2026-08-20 20:25:02.464455 | instance |
2026-08-20 20:25:02.464494 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-20 20:25:02.464501 | instance | Thursday 20 August 2026  20:25:02 +0000 (0:00:00.928)       0:00:22.314 *******
2026-08-20 20:25:06.853915 | instance | changed: [instance]
2026-08-20 20:25:06.853987 | instance |
2026-08-20 20:25:06.854258 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-20 20:25:06.854295 | instance | Thursday 20 August 2026  20:25:06 +0000 (0:00:04.389)       0:00:26.703 *******
2026-08-20 20:25:07.936090 | instance | ok: [instance]
2026-08-20 20:25:07.936617 | instance |
2026-08-20 20:25:07.936788 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-20 20:25:07.936798 | instance | Thursday 20 August 2026  20:25:07 +0000 (0:00:01.081)       0:00:27.785 *******
2026-08-20 20:25:08.303708 | instance | changed: [instance]
2026-08-20 20:25:08.303810 | instance |
2026-08-20 20:25:08.304158 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-20 20:25:08.304326 | instance | Thursday 20 August 2026  20:25:08 +0000 (0:00:00.367)       0:00:28.153 *******
2026-08-20 20:25:08.788667 | instance | changed: [instance]
2026-08-20 20:25:08.789081 | instance |
2026-08-20 20:25:08.789119 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-20 20:25:08.789125 | instance | Thursday 20 August 2026  20:25:08 +0000 (0:00:00.485)       0:00:28.638 *******
2026-08-20 20:25:09.361775 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-20 20:25:09.361877 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-20 20:25:09.362441 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-20 20:25:09.362486 | instance |
2026-08-20 20:25:09.362504 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-20 20:25:09.362508 | instance | Thursday 20 August 2026  20:25:09 +0000 (0:00:00.573)       0:00:29.211 *******
2026-08-20 20:25:09.837796 | instance | changed: [instance]
2026-08-20 20:25:09.838417 | instance |
2026-08-20 20:25:09.838466 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-20 20:25:09.838474 | instance | Thursday 20 August 2026  20:25:09 +0000 (0:00:00.475)       0:00:29.687 *******
2026-08-20 20:25:10.341930 | instance | changed: [instance]
2026-08-20 20:25:10.342042 | instance |
2026-08-20 20:25:10.342049 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-20 20:25:10.342056 | instance | Thursday 20 August 2026  20:25:10 +0000 (0:00:00.483)       0:00:30.170 *******
2026-08-20 20:25:10.342061 | instance |
2026-08-20 20:25:10.342065 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-20 20:25:10.342070 | instance | Thursday 20 August 2026  20:25:10 +0000 (0:00:00.019)       0:00:30.190 *******
2026-08-20 20:25:11.073019 | instance | ok: [instance]
2026-08-20 20:25:11.073130 | instance |
2026-08-20 20:25:11.073309 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-20 20:25:11.073451 | instance | Thursday 20 August 2026  20:25:11 +0000 (0:00:00.732)       0:00:30.922 *******
2026-08-20 20:25:12.443514 | instance | changed: [instance]
2026-08-20 20:25:12.443652 | instance |
2026-08-20 20:25:12.443666 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-20 20:25:12.443811 | instance | Thursday 20 August 2026  20:25:12 +0000 (0:00:01.370)       0:00:32.293 *******
2026-08-20 20:25:12.999856 | instance | changed: [instance]
2026-08-20 20:25:12.999943 | instance |
2026-08-20 20:25:13.000187 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-20 20:25:13.000201 | instance | Thursday 20 August 2026  20:25:12 +0000 (0:00:00.556)       0:00:32.849 *******
2026-08-20 20:25:13.056073 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-20 20:25:13.056197 | instance |
2026-08-20 20:25:13.056577 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-20 20:25:13.056627 | instance | Thursday 20 August 2026  20:25:13 +0000 (0:00:00.055)       0:00:32.905 *******
2026-08-20 20:25:19.126166 | instance | changed: [instance]
2026-08-20 20:25:19.126310 | instance |
2026-08-20 20:25:19.126325 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-20 20:25:19.126478 | instance | Thursday 20 August 2026  20:25:19 +0000 (0:00:06.070)       0:00:38.975 *******
2026-08-20 20:25:19.924715 | instance | ok: [instance] => (item=chronyd)
2026-08-20 20:25:19.924799 | instance | ok: [instance] => (item=sshd)
2026-08-20 20:25:19.925175 | instance |
2026-08-20 20:25:19.925194 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-20 20:25:19.925201 | instance | Thursday 20 August 2026  20:25:19 +0000 (0:00:00.798)       0:00:39.774 *******
2026-08-20 20:25:21.535266 | instance | changed: [instance]
2026-08-20 20:25:21.535386 | instance |
2026-08-20 20:25:21.535399 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-20 20:25:21.535599 | instance | Thursday 20 August 2026  20:25:21 +0000 (0:00:01.611)       0:00:41.385 *******
2026-08-20 20:25:21.743769 | instance | ok: [instance]
2026-08-20 20:25:21.744186 | instance |
2026-08-20 20:25:21.744227 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-20 20:25:21.744234 | instance | Thursday 20 August 2026  20:25:21 +0000 (0:00:00.208)       0:00:41.593 *******
2026-08-20 20:25:22.239212 | instance | changed: [instance]
2026-08-20 20:25:22.239281 | instance |
2026-08-20 20:25:22.239603 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-20 20:25:22.239674 | instance | Thursday 20 August 2026  20:25:22 +0000 (0:00:00.495)       0:00:42.088 *******
2026-08-20 20:25:22.582481 | instance | changed: [instance]
2026-08-20 20:25:22.582596 | instance |
2026-08-20 20:25:22.582664 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-20 20:25:22.582818 | instance | Thursday 20 August 2026  20:25:22 +0000 (0:00:00.343)       0:00:42.432 *******
2026-08-20 20:25:22.699387 | instance | ok: [instance]
2026-08-20 20:25:22.699478 | instance |
2026-08-20 20:25:22.699771 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-20 20:25:22.699839 | instance | Thursday 20 August 2026  20:25:22 +0000 (0:00:00.116)       0:00:42.548 *******
2026-08-20 20:25:22.943586 | instance | ok: [instance] => (item=instance)
2026-08-20 20:25:22.943704 | instance |
2026-08-20 20:25:22.943720 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-20 20:25:22.943911 | instance | Thursday 20 August 2026  20:25:22 +0000 (0:00:00.244)       0:00:42.792 *******
2026-08-20 20:25:22.995259 | instance | ok: [instance]
2026-08-20 20:25:22.995709 | instance |
2026-08-20 20:25:22.995765 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-20 20:25:22.995773 | instance | Thursday 20 August 2026  20:25:22 +0000 (0:00:00.051)       0:00:42.844 *******
2026-08-20 20:25:23.088021 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-20 20:25:23.088115 | instance |
2026-08-20 20:25:23.088442 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-20 20:25:23.088487 | instance | Thursday 20 August 2026  20:25:23 +0000 (0:00:00.093)       0:00:42.937 *******
2026-08-20 20:25:23.392297 | instance | changed: [instance]
2026-08-20 20:25:23.392357 | instance |
2026-08-20 20:25:23.392728 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-20 20:25:23.392778 | instance | Thursday 20 August 2026  20:25:23 +0000 (0:00:00.304)       0:00:43.241 *******
2026-08-20 20:25:24.087566 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-20 20:25:24.088461 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-20 20:25:24.088484 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-20 20:25:24.088492 | instance |
2026-08-20 20:25:24.088498 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-20 20:25:24.088504 | instance | Thursday 20 August 2026  20:25:24 +0000 (0:00:00.695)       0:00:43.937 *******
2026-08-20 20:27:19.571699 | instance | ok: [instance]
2026-08-20 20:27:19.571753 | instance |
2026-08-20 20:27:19.571761 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-20 20:27:19.571768 | instance | Thursday 20 August 2026  20:27:19 +0000 (0:01:55.483)       0:02:39.420 *******
2026-08-20 20:27:19.789323 | instance | changed: [instance]
2026-08-20 20:27:19.789392 | instance |
2026-08-20 20:27:19.789405 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-20 20:27:19.789786 | instance | Thursday 20 August 2026  20:27:19 +0000 (0:00:00.217)       0:02:39.638 *******
2026-08-20 20:27:19.870612 | instance | ok: [instance]
2026-08-20 20:27:19.870682 | instance |
2026-08-20 20:27:19.870951 | instance | TASK [Install Ceph host] *******************************************************
2026-08-20 20:27:19.871003 | instance | Thursday 20 August 2026  20:27:19 +0000 (0:00:00.082)       0:02:39.720 *******
2026-08-20 20:27:19.955721 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-20 20:27:19.955782 | instance |
2026-08-20 20:27:19.956060 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-20 20:27:19.956097 | instance | Thursday 20 August 2026  20:27:19 +0000 (0:00:00.085)       0:02:39.805 *******
2026-08-20 20:27:21.639392 | instance | ok: [instance]
2026-08-20 20:27:21.639475 | instance |
2026-08-20 20:27:21.639658 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-20 20:27:21.639755 | instance | Thursday 20 August 2026  20:27:21 +0000 (0:00:01.683)       0:02:41.488 *******
2026-08-20 20:27:21.711507 | instance | ok: [instance] => (item=instance)
2026-08-20 20:27:21.711583 | instance |
2026-08-20 20:27:21.711724 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-20 20:27:21.711889 | instance | Thursday 20 August 2026  20:27:21 +0000 (0:00:00.071)       0:02:41.560 *******
2026-08-20 20:27:22.073685 | instance | ok: [instance]
2026-08-20 20:27:22.073756 | instance |
2026-08-20 20:27:22.074046 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-20 20:27:22.074087 | instance | Thursday 20 August 2026  20:27:22 +0000 (0:00:00.362)       0:02:41.923 *******
2026-08-20 20:27:25.649600 | instance | ok: [instance]
2026-08-20 20:27:25.649679 | instance |
2026-08-20 20:27:25.649963 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-20 20:27:25.650009 | instance | Thursday 20 August 2026  20:27:25 +0000 (0:00:03.575)       0:02:45.499 *******
2026-08-20 20:28:21.850495 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-20 20:28:21.851945 | 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_brm93c24/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_brm93c24/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_brm93c24/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_brm93c24/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-08-20T20:28:21.592+0000 7ff1564ea640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDwYodq3+JECyAADNidmOoH3f6GyJqWVSuLaufMNg6iAoj0LGyfP+84t5c=: Malformed input [buffer:3]\n2026-08-20T20:28:21.592+0000 7ff1564ea640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-20T20:28:21.592+0000 7ff1564ea640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDwYodq3+JECyAADNidmOoH3f6GyJqWVSuLaufMNg6iAoj0LGyfP+84t5c=: Malformed input [buffer:3]\n2026-08-20T20:28:21.592+0000 7ff1564ea640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-20T20:28:21.592+0000 7ff1564ea640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDwYodq3+JECyAADNidmOoH3f6GyJqWVSuLaufMNg6iAoj0LGyfP+84t5c=: Malformed input [buffer:3]\n2026-08-20T20:28:21.592+0000 7ff1564ea640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-20T20:28:21.592+0000 7ff1564ea640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-20 20:28:21.854657 | instance |
2026-08-20 20:28:21.854874 | instance | PLAY RECAP *********************************************************************
2026-08-20 20:28:21.857359 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-20 20:28:21.857420 | instance |
2026-08-20 20:28:21.857428 | instance | Thursday 20 August 2026  20:28:21 +0000 (0:00:56.205)       0:03:41.704 *******
2026-08-20 20:28:21.857434 | instance | ===============================================================================
2026-08-20 20:28:21.857440 | instance | vexxhost.ceph.mon : Run Bootstrap command ----------------------------- 115.48s
2026-08-20 20:28:21.857446 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 56.21s
2026-08-20 20:28:21.857451 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 7.17s
2026-08-20 20:28:21.857457 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.07s
2026-08-20 20:28:21.857462 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.39s
2026-08-20 20:28:21.857474 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 3.58s
2026-08-20 20:28:21.857587 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.90s
2026-08-20 20:28:21.857808 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.68s
2026-08-20 20:28:21.858050 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.66s
2026-08-20 20:28:21.858277 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.61s
2026-08-20 20:28:21.858503 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.37s
2026-08-20 20:28:21.858733 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.25s
2026-08-20 20:28:21.858962 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.08s
2026-08-20 20:28:21.859212 | instance | Gathering Facts --------------------------------------------------------- 0.99s
2026-08-20 20:28:21.859483 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.93s
2026-08-20 20:28:21.859717 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.91s
2026-08-20 20:28:21.859956 | instance | Gathering Facts --------------------------------------------------------- 0.89s
2026-08-20 20:28:21.860209 | instance | Gathering Facts --------------------------------------------------------- 0.88s
2026-08-20 20:28:21.860467 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.80s
2026-08-20 20:28:21.860732 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.78s
2026-08-20 20:28:21.981072 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-20 20:28:21.981160 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-20 20:28:21.981329 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-20 20:28:22.347986 | instance | ERROR
2026-08-20 20:28:22.348214 | instance | {
2026-08-20 20:28:22.348259 | instance |   "delta": "0:04:34.159472",
2026-08-20 20:28:22.348291 | instance |   "end": "2026-08-20 20:28:22.060661",
2026-08-20 20:28:22.348320 | instance |   "msg": "non-zero return code",
2026-08-20 20:28:22.348346 | instance |   "rc": 2,
2026-08-20 20:28:22.348496 | instance |   "start": "2026-08-20 20:23:47.901189"
2026-08-20 20:28:22.348535 | instance | }
failure
2026-08-20 20:28:22.357103 | 
2026-08-20 20:28:22.357263 | PLAY RECAP
2026-08-20 20:28:22.357334 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-20 20:28:22.357362 | 
2026-08-20 20:28:22.499384 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-20 20:28:22.537167 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-20 20:28:23.911435 | 
2026-08-20 20:28:28.587521 | PLAY [all]
2026-08-20 20:28:28.944567 | 
2026-08-20 20:28:28.944737 | TASK [gather-host-logs : creating directory for system status]
2026-08-20 20:28:29.328404 | instance | changed
2026-08-20 20:28:29.333645 | 
2026-08-20 20:28:29.333726 | TASK [gather-host-logs : Get logs for each host]
2026-08-20 20:28:29.691406 | instance | + systemd-cgls --full --all --no-pager
2026-08-20 20:28:29.738541 | instance | + ip addr
2026-08-20 20:28:29.741191 | instance | + ip route
2026-08-20 20:28:29.744025 | instance | + lsblk
2026-08-20 20:28:29.747635 | instance | + mount
2026-08-20 20:28:29.750516 | instance | + docker images
2026-08-20 20:28:29.771062 | instance | + brctl show
2026-08-20 20:28:29.771597 | instance | /bin/bash: line 8: brctl: command not found
2026-08-20 20:28:29.771901 | instance | + ps aux --sort=-%mem
2026-08-20 20:28:29.787023 | instance | + dpkg -l
2026-08-20 20:28:29.795562 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-20 20:28:29.796050 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-20 20:28:29.815284 | instance | + '[' '!' -z '' ']'
2026-08-20 20:28:29.872429 | instance | ok: Runtime: 0:00:00.128374
2026-08-20 20:28:29.879354 | 
2026-08-20 20:28:29.879440 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-20 20:28:30.778009 | instance | changed:
2026-08-20 20:28:30.778175 | instance | created directory /var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/work/logs/instance
2026-08-20 20:28:30.778205 | instance | cd+++++++++ system/
2026-08-20 20:28:30.778228 | instance | >f+++++++++ system/brctl-show.txt
2026-08-20 20:28:30.778249 | instance | >f+++++++++ system/docker-images.txt
2026-08-20 20:28:30.778269 | instance | >f+++++++++ system/ip-addr.txt
2026-08-20 20:28:30.778292 | instance | >f+++++++++ system/ip-route.txt
2026-08-20 20:28:30.778317 | instance | >f+++++++++ system/lsblk.txt
2026-08-20 20:28:30.778340 | instance | >f+++++++++ system/mount.txt
2026-08-20 20:28:30.778361 | instance | >f+++++++++ system/packages.txt
2026-08-20 20:28:30.778384 | instance | >f+++++++++ system/ps.txt
2026-08-20 20:28:30.778406 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-20 20:28:30.792925 | 
2026-08-20 20:28:30.793115 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-20 20:28:31.003086 | instance | changed: "values"
2026-08-20 20:28:31.168900 | instance | changed: "releases"
2026-08-20 20:28:31.185887 | 
2026-08-20 20:28:31.186016 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-20 20:28:31.402235 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-20 20:28:31.723743 | instance | ok: Runtime: 0:00:00.004852
2026-08-20 20:28:31.728845 | 
2026-08-20 20:28:31.728922 | TASK [helm-release-status : Downloads logs to executor]
2026-08-20 20:28:32.258113 | instance | changed:
2026-08-20 20:28:32.258428 | instance | cd+++++++++ helm/
2026-08-20 20:28:32.258468 | instance | cd+++++++++ helm/releases/
2026-08-20 20:28:32.258494 | instance | cd+++++++++ helm/values/
2026-08-20 20:28:32.268610 | 
2026-08-20 20:28:32.268686 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-20 20:28:32.465696 | instance | changed
2026-08-20 20:28:32.667576 | 
2026-08-20 20:28:32.667711 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-20 20:28:32.939274 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 20:28:32.939377 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 20:28:32.947315 | instance | environment: line 1: kubectl: command not found
2026-08-20 20:28:32.947705 | instance | environment: line 1: kubectl: command not found
2026-08-20 20:28:32.948480 | instance | xargs: xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines valuewarning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 20:28:32.948501 | instance |
2026-08-20 20:28:32.951840 | instance | environment: line 1: kubectl: command not found
2026-08-20 20:28:32.952305 | instance | environment: line 1: kubectl: command not found
2026-08-20 20:28:32.953221 | instance | xargs: xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 20:28:32.953292 | instance | warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 20:28:32.955647 | instance | environment: line 1: kubectl: command not found
2026-08-20 20:28:32.957002 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 20:28:33.213554 | instance | ok: Runtime: 0:00:00.026043
2026-08-20 20:28:33.221935 | 
2026-08-20 20:28:33.222041 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-20 20:28:33.417911 | instance | changed
2026-08-20 20:28:33.422795 | 
2026-08-20 20:28:33.422869 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-20 20:28:33.650698 | instance | environment: line 5: kubectl: command not found
2026-08-20 20:28:33.651887 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 20:28:33.652083 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 20:28:33.652327 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 20:28:33.961262 | instance | ok: Runtime: 0:00:00.011106
2026-08-20 20:28:33.970064 | 
2026-08-20 20:28:33.970623 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-20 20:28:35.293213 | instance | changed:
2026-08-20 20:28:35.293374 | instance | cd+++++++++ objects/
2026-08-20 20:28:35.293405 | instance | cd+++++++++ objects/cluster/
2026-08-20 20:28:35.293430 | instance | cd+++++++++ objects/namespaced/
2026-08-20 20:28:35.302796 | 
2026-08-20 20:28:35.302863 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-20 20:28:35.554288 | instance | changed
2026-08-20 20:28:35.561483 | 
2026-08-20 20:28:35.561600 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-20 20:28:35.766320 | instance | changed
2026-08-20 20:28:35.774977 | 
2026-08-20 20:28:35.775206 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-20 20:28:36.972041 | instance | environment: line 3: kubectl: command not found
2026-08-20 20:28:37.565371 | instance | ok: Runtime: 0:00:00.008277
2026-08-20 20:28:37.571769 | 
2026-08-20 20:28:37.571859 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-20 20:28:39.234408 | instance | changed:
2026-08-20 20:28:39.234600 | instance | cd+++++++++ pod-logs/
2026-08-20 20:28:39.234640 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-20 20:28:39.249857 | 
2026-08-20 20:28:39.250065 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-20 20:28:39.466208 | instance | changed
2026-08-20 20:28:39.559787 | 
2026-08-20 20:28:39.559928 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-20 20:28:39.769779 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-20 20:28:40.098093 | instance | ok: Runtime: 0:00:00.037663
2026-08-20 20:28:40.106453 | 
2026-08-20 20:28:40.106571 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-20 20:28:40.332022 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-20 20:28:40.366032 | instance | ceph-mgr endpoints:
2026-08-20 20:28:40.646794 | instance | ok: Runtime: 0:00:00.042226
2026-08-20 20:28:40.659153 | 
2026-08-20 20:28:40.939710 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-20 20:28:41.160057 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-20 20:28:41.487601 | instance | ok: Runtime: 0:00:00.035757
2026-08-20 20:28:41.493843 | 
2026-08-20 20:28:41.820196 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-20 20:28:42.363800 | instance | changed: cd+++++++++ prometheus/
2026-08-20 20:28:42.387822 | 
2026-08-20 20:28:42.388010 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-20 20:28:42.620577 | instance | changed
2026-08-20 20:28:42.628074 | 
2026-08-20 20:28:42.628150 | TASK [gather-selenium-data : Get selenium data]
2026-08-20 20:28:42.900891 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-20 20:28:42.901956 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-20 20:28:43.178030 | instance | ERROR
2026-08-20 20:28:43.178241 | instance | {
2026-08-20 20:28:43.178287 | instance |   "delta": "0:00:00.006998",
2026-08-20 20:28:43.178317 | instance |   "end": "2026-08-20 20:28:42.902307",
2026-08-20 20:28:43.178349 | instance |   "msg": "non-zero return code",
2026-08-20 20:28:43.178376 | instance |   "rc": 1,
2026-08-20 20:28:43.178402 | instance |   "start": "2026-08-20 20:28:42.895309"
2026-08-20 20:28:43.178427 | instance | }
2026-08-20 20:28:43.178461 | instance | ERROR: Ignoring Errors
2026-08-20 20:28:43.185542 | 
2026-08-20 20:28:43.185608 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-20 20:28:43.685720 | instance | changed: cd+++++++++ selenium/
2026-08-20 20:28:43.692142 | 
2026-08-20 20:28:43.692196 | PLAY RECAP
2026-08-20 20:28:43.692242 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-20 20:28:43.692264 | 
2026-08-20 20:28:43.862941 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-20 20:28:43.868073 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-20 20:28:44.912839 | 
2026-08-20 20:28:45.945309 | PLAY [all]
2026-08-20 20:28:45.964403 | 
2026-08-20 20:28:45.964550 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-20 20:28:46.009938 | instance | skipping: Conditional result was False
2026-08-20 20:28:46.018788 | 
2026-08-20 20:28:46.018906 | TASK [fetch-output : Set log path for single node]
2026-08-20 20:28:46.062418 | instance | ok
2026-08-20 20:28:46.068069 | 
2026-08-20 20:28:46.068149 | LOOP [fetch-output : Ensure local output dirs]
2026-08-20 20:28:46.609785 | instance -> localhost | ok: "/var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/work/logs"
2026-08-20 20:28:46.852711 | instance -> localhost | changed: "/var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/work/artifacts"
2026-08-20 20:28:47.242713 | instance -> localhost | changed: "/var/lib/zuul/builds/b906fcbfef7c4282bc9bae8b7fe12012/work/docs"
2026-08-20 20:28:47.259226 | 
2026-08-20 20:28:47.259368 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-20 20:28:48.072309 | instance | changed: .d..t...... ./
2026-08-20 20:28:48.072544 | instance | changed: All items complete
2026-08-20 20:28:48.072574 | 
2026-08-20 20:28:48.537783 | instance | changed: .d..t...... ./
2026-08-20 20:28:49.649469 | instance | changed: .d..t...... ./
2026-08-20 20:28:49.664995 | 
2026-08-20 20:28:49.665120 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-20 20:28:50.239022 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.090021
2026-08-20 20:28:50.525738 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006546
2026-08-20 20:28:50.564205 | 
2026-08-20 20:28:50.564304 | PLAY [all]
2026-08-20 20:28:50.573925 | 
2026-08-20 20:28:50.574029 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-20 20:28:51.182625 | instance | changed
2026-08-20 20:28:51.191514 | 
2026-08-20 20:28:51.191976 | PLAY RECAP
2026-08-20 20:28:51.192035 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-20 20:28:51.192058 | 
2026-08-20 20:28:51.424262 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-20 20:28:51.936845 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-20 20:28:52.605235 | 
2026-08-20 20:28:52.605370 | PLAY [localhost]
2026-08-20 20:28:52.616284 | 
2026-08-20 20:28:52.616386 | TASK [Generate Zuul manifest]
2026-08-20 20:28:52.639169 | localhost | ok
2026-08-20 20:28:52.668257 | 
2026-08-20 20:28:52.782457 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-20 20:28:53.213104 | localhost | changed
2026-08-20 20:28:53.329139 | 
2026-08-20 20:28:53.368777 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-20 20:28:53.421323 | localhost | ok
2026-08-20 20:28:53.433597 | 
2026-08-20 20:28:53.433930 | TASK [Upload logs]
2026-08-20 20:28:53.466537 | localhost | ok
2026-08-20 20:28:53.602990 | 
2026-08-20 20:28:53.603230 | TASK [Set zuul-log-path fact]
2026-08-20 20:28:53.625699 | localhost | ok
2026-08-20 20:28:53.676535 | 
2026-08-20 20:28:56.116516 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-20 20:28:56.147843 | localhost | ok
2026-08-20 20:28:56.155867 | 
2026-08-20 20:28:56.156012 | TASK [upload-logs : Create log directories]
2026-08-20 20:28:57.296986 | localhost | changed
2026-08-20 20:28:57.303867 | 
2026-08-20 20:28:57.303986 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-20 20:28:57.800831 | localhost -> localhost | ok: Runtime: 0:00:00.016121
2026-08-20 20:28:57.813355 | 
2026-08-20 20:28:57.813476 | TASK [upload-logs : Upload logs to log server]
2026-08-20 20:28:58.413499 | localhost | Output suppressed because no_log was given
2026-08-20 20:28:58.473313 | 
2026-08-20 20:28:58.473445 | LOOP [upload-logs : Compress console log and json output]
2026-08-20 20:28:58.526407 | localhost | skipping: Conditional result was False
2026-08-20 20:28:58.540567 | localhost | skipping: Conditional result was False
2026-08-20 20:28:58.558262 | 
2026-08-20 20:28:58.956801 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-20 20:28:59.007194 | localhost | skipping: Conditional result was False
2026-08-20 20:28:59.007536 | 
2026-08-20 20:28:59.011835 | localhost | skipping: Conditional result was False
2026-08-20 20:28:59.025032 | 
2026-08-20 20:28:59.025153 | LOOP [upload-logs : Upload console log and json output]