2026-09-10 01:51:02.549141 | Job console starting
2026-09-10 01:51:02.603823 | Updating git repos
2026-09-10 01:51:10.510394 | Cloning repos into workspace
2026-09-10 01:51:27.971529 | Restoring repo states
2026-09-10 01:51:33.930140 | Merging changes
2026-09-10 01:51:37.798539 | Checking out repos
2026-09-10 01:51:53.754671 | Preparing playbooks
2026-09-10 01:52:09.623242 | Running Ansible setup
2026-09-10 01:52:13.388167 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-10 01:52:13.958643 | 
2026-09-10 01:52:13.964033 | PLAY [localhost]
2026-09-10 01:52:13.973896 | 
2026-09-10 01:52:13.974023 | TASK [Gathering Facts]
2026-09-10 01:52:15.142768 | localhost | ok
2026-09-10 01:52:15.149536 | 
2026-09-10 01:52:15.149635 | TASK [Setup log path fact]
2026-09-10 01:52:15.166156 | localhost | ok
2026-09-10 01:52:15.176159 | 
2026-09-10 01:52:15.176239 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-10 01:52:15.215132 | localhost | ok
2026-09-10 01:52:15.222464 | 
2026-09-10 01:52:15.222558 | TASK [emit-job-header : Print job information]
2026-09-10 01:52:15.292027 | # Job Information
2026-09-10 01:52:15.292197 | Ansible Version: 2.16.18
2026-09-10 01:52:15.292242 | Job: atmosphere-molecule-csi-rbd
2026-09-10 01:52:15.292272 | Pipeline: check
2026-09-10 01:52:15.292300 | Executor: 2d72f0692154
2026-09-10 01:52:15.292325 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4095
2026-09-10 01:52:15.292356 | Event ID: 7ab9cc00-acb9-11f1-8140-6b9d3ae21b07
2026-09-10 01:52:15.300284 | 
2026-09-10 01:52:15.300410 | LOOP [emit-job-header : Print node information]
2026-09-10 01:52:15.408333 | localhost | ok:
2026-09-10 01:52:15.410320 | localhost | # Node Information
2026-09-10 01:52:15.410362 | localhost | Inventory Hostname: instance
2026-09-10 01:52:15.410388 | localhost | Hostname: np0000192465
2026-09-10 01:52:15.410413 | localhost | Username: zuul
2026-09-10 01:52:15.410440 | localhost | Distro: Ubuntu 22.04
2026-09-10 01:52:15.410464 | localhost | Provider: yul1
2026-09-10 01:52:15.410485 | localhost | Region: ca-ymq-1
2026-09-10 01:52:15.410508 | localhost | Label: ubuntu-jammy
2026-09-10 01:52:15.410532 | localhost | Product Name: OpenStack Nova
2026-09-10 01:52:15.410554 | localhost | Interface IP: 162.253.55.38
2026-09-10 01:52:15.418788 | 
2026-09-10 01:52:15.418909 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-10 01:52:15.934351 | localhost -> localhost | changed
2026-09-10 01:52:15.940622 | 
2026-09-10 01:52:15.940701 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-10 01:52:16.894090 | localhost -> localhost | changed
2026-09-10 01:52:16.907609 | 
2026-09-10 01:52:16.907760 | PLAY [all]
2026-09-10 01:52:16.927813 | 
2026-09-10 01:52:16.928047 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-10 01:52:17.188437 | instance -> localhost | ok
2026-09-10 01:52:17.206415 | 
2026-09-10 01:52:17.206649 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-10 01:52:17.243730 | instance | ok
2026-09-10 01:52:17.270037 | instance | included: /var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-10 01:52:17.280550 | 
2026-09-10 01:52:17.280687 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-10 01:52:18.367259 | instance -> localhost | Generating public/private rsa key pair.
2026-09-10 01:52:18.367528 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/work/f21fe3d1a1404402be6e85636fa531ca_id_rsa
2026-09-10 01:52:18.367603 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/work/f21fe3d1a1404402be6e85636fa531ca_id_rsa.pub
2026-09-10 01:52:18.367648 | instance -> localhost | The key fingerprint is:
2026-09-10 01:52:18.367690 | instance -> localhost | SHA256:7Lb5OLJtbZV8lU2BHEXy4Z/cRTjbGCx0CzNBL68o3yA zuul-build-sshkey
2026-09-10 01:52:18.367749 | instance -> localhost | The key's randomart image is:
2026-09-10 01:52:18.367791 | instance -> localhost | +---[RSA 3072]----+
2026-09-10 01:52:18.367838 | instance -> localhost | |           oO=**o|
2026-09-10 01:52:18.367879 | instance -> localhost | |            .OOoo|
2026-09-10 01:52:18.367917 | instance -> localhost | |            ..oX+|
2026-09-10 01:52:18.367956 | instance -> localhost | |       .     o+.O|
2026-09-10 01:52:18.367994 | instance -> localhost | |        S  . ..+o|
2026-09-10 01:52:18.368032 | instance -> localhost | |       .   .+..  |
2026-09-10 01:52:18.368068 | instance -> localhost | |        E.o...   |
2026-09-10 01:52:18.368105 | instance -> localhost | |      .ooBoo     |
2026-09-10 01:52:18.368147 | instance -> localhost | |      .+=+o .    |
2026-09-10 01:52:18.368185 | instance -> localhost | +----[SHA256]-----+
2026-09-10 01:52:18.368466 | instance -> localhost | ok: Runtime: 0:00:00.613693
2026-09-10 01:52:18.381239 | 
2026-09-10 01:52:18.381327 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-10 01:52:18.413376 | instance | ok
2026-09-10 01:52:18.429010 | instance | included: /var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-10 01:52:18.438300 | 
2026-09-10 01:52:18.438382 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-10 01:52:18.473253 | instance | skipping: Conditional result was False
2026-09-10 01:52:18.489041 | 
2026-09-10 01:52:18.489216 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-10 01:52:18.970708 | instance | changed
2026-09-10 01:52:19.005310 | 
2026-09-10 01:52:19.005597 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-10 01:52:19.208843 | instance | ok
2026-09-10 01:52:19.215548 | 
2026-09-10 01:52:19.215754 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-10 01:52:19.768288 | instance | changed
2026-09-10 01:52:19.774808 | 
2026-09-10 01:52:19.774898 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-10 01:52:20.275830 | instance | changed
2026-09-10 01:52:20.288732 | 
2026-09-10 01:52:20.288932 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-10 01:52:20.316268 | instance | skipping: Conditional result was False
2026-09-10 01:52:20.324335 | 
2026-09-10 01:52:20.324479 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-10 01:52:20.796791 | instance -> localhost | changed
2026-09-10 01:52:20.819048 | 
2026-09-10 01:52:20.819187 | TASK [add-build-sshkey : Add back temp key]
2026-09-10 01:52:21.178258 | instance -> localhost | Identity added: /var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/work/f21fe3d1a1404402be6e85636fa531ca_id_rsa (zuul-build-sshkey)
2026-09-10 01:52:21.178510 | instance -> localhost | ok: Runtime: 0:00:00.016603
2026-09-10 01:52:21.186205 | 
2026-09-10 01:52:21.186308 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-10 01:52:21.517374 | instance | ok
2026-09-10 01:52:21.526073 | 
2026-09-10 01:52:21.526220 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-10 01:52:21.551229 | instance | skipping: Conditional result was False
2026-09-10 01:52:21.570208 | 
2026-09-10 01:52:21.570347 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-10 01:52:21.927339 | instance | ok
2026-09-10 01:52:21.935703 | 
2026-09-10 01:52:21.935830 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-10 01:52:26.733686 | instance | Output suppressed because no_log was given
2026-09-10 01:52:26.760453 | 
2026-09-10 01:52:26.760759 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-10 01:52:26.978975 | instance | ok: "logs"
2026-09-10 01:52:26.979326 | instance | ok: All items complete
2026-09-10 01:52:26.979381 | 
2026-09-10 01:52:27.127582 | instance | ok: "artifacts"
2026-09-10 01:52:27.277956 | instance | ok: "docs"
2026-09-10 01:52:27.291274 | 
2026-09-10 01:52:27.291510 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-10 01:52:27.479284 | instance | changed: "logs"
2026-09-10 01:52:27.615464 | instance | changed: "artifacts"
2026-09-10 01:52:27.759273 | instance | changed: "docs"
2026-09-10 01:52:27.781450 | 
2026-09-10 01:52:27.781615 | PLAY RECAP
2026-09-10 01:52:27.781717 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-10 01:52:27.781795 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 01:52:27.781856 | 
2026-09-10 01:52:27.974347 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-10 01:52:27.979533 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-10 01:52:28.672752 | 
2026-09-10 01:52:28.672941 | PLAY [all]
2026-09-10 01:52:28.684803 | 
2026-09-10 01:52:28.685013 | TASK [setup-uv : Extract archive]
2026-09-10 01:52:31.105146 | instance | changed
2026-09-10 01:52:31.117013 | 
2026-09-10 01:52:31.117207 | TASK [setup-uv : Print version]
2026-09-10 01:52:31.500540 | instance | uv 0.8.13
2026-09-10 01:52:31.664335 | instance | ok: Runtime: 0:00:00.013881
2026-09-10 01:52:31.685621 | 
2026-09-10 01:52:31.685792 | TASK [Install Ansible collection siblings]
2026-09-10 01:52:41.110017 | instance | changed:
2026-09-10 01:52:41.110331 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-10 01:52:41.110365 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-10 01:52:41.110426 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-10 01:52:41.110484 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-10 01:52:41.110533 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-10 01:52:41.110565 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-10 01:52:41.110627 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-10 01:52:41.110682 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-10 01:52:41.110703 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-10 01:52:41.110751 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-10 01:52:41.110772 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-10 01:52:41.110813 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-10 01:52:41.110834 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-10 01:52:41.110880 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-10 01:52:41.110901 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-10 01:52:41.110949 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-10 01:52:41.110968 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-10 01:52:41.111015 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-10 01:52:41.111035 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-10 01:52:41.111078 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-10 01:52:41.111099 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-10 01:52:41.111143 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-10 01:52:41.111166 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-10 01:52:41.111230 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-10 01:52:41.111254 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-10 01:52:41.111297 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-10 01:52:41.111317 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-10 01:52:41.111362 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-10 01:52:41.111385 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-10 01:52:41.111439 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-10 01:52:41.111497 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-10 01:52:41.111521 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-10 01:52:41.111570 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-10 01:52:41.111604 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-10 01:52:41.111657 | 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-10 01:52:41.111708 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.111732 | instance | Process install dependency map
2026-09-10 01:52:41.111781 | instance | Starting collection install process
2026-09-10 01:52:41.111803 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-10 01:52:41.111849 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-10 01:52:41.111868 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-10 01:52:41.111914 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-10 01:52:41.111933 | instance | Creating virtual environment at: .venv
2026-09-10 01:52:41.111977 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-10 01:52:41.111999 | instance | Downloading setuptools (1.1MiB)
2026-09-10 01:52:41.112062 | instance | Downloading rjsonnet (1.2MiB)
2026-09-10 01:52:41.112131 | instance | Downloading ansible-core (2.1MiB)
2026-09-10 01:52:41.112179 | instance | Downloading openstacksdk (1.7MiB)
2026-09-10 01:52:41.112202 | instance | Downloading pygments (1.2MiB)
2026-09-10 01:52:41.112254 | instance | Downloading cryptography (4.2MiB)
2026-09-10 01:52:41.112276 | instance | Downloading netaddr (2.2MiB)
2026-09-10 01:52:41.112323 | instance | Downloading kubernetes (1.9MiB)
2026-09-10 01:52:41.112342 | instance |    Building pyperclip==1.9.0
2026-09-10 01:52:41.112390 | instance |  Downloading rjsonnet
2026-09-10 01:52:41.112419 | instance |  Downloading pygments
2026-09-10 01:52:41.112465 | instance |  Downloading netaddr
2026-09-10 01:52:41.112484 | instance |  Downloading cryptography
2026-09-10 01:52:41.112548 | instance |  Downloading setuptools
2026-09-10 01:52:41.112566 | instance |  Downloading kubernetes
2026-09-10 01:52:41.112625 | instance |  Downloading ansible-core
2026-09-10 01:52:41.112649 | instance |  Downloading openstacksdk
2026-09-10 01:52:41.112692 | instance |       Built pyperclip==1.9.0
2026-09-10 01:52:41.112711 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-10 01:52:41.112759 | instance | Installed 79 packages in 62ms
2026-09-10 01:52:41.112780 | instance | Checked out ansible.posix to 1.6.0
2026-09-10 01:52:41.112820 | 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-10 01:52:41.112843 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.112929 | instance | Process install dependency map
2026-09-10 01:52:41.113056 | instance | Starting collection install process
2026-09-10 01:52:41.113112 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-10 01:52:41.113134 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-10 01:52:41.113175 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-10 01:52:41.113195 | instance | Checked out ansible.utils to v6.1.0
2026-09-10 01:52:41.113242 | 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-10 01:52:41.113262 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.113308 | instance | Process install dependency map
2026-09-10 01:52:41.113326 | instance | Starting collection install process
2026-09-10 01:52:41.113365 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-10 01:52:41.113387 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-10 01:52:41.113452 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-10 01:52:41.113478 | instance | Checked out atmosphere.common to v0.6.0
2026-09-10 01:52:41.113522 | 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-10 01:52:41.113544 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.113587 | instance | Process install dependency map
2026-09-10 01:52:41.114251 | instance | Starting collection install process
2026-09-10 01:52:41.114749 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-10 01:52:41.114926 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-10 01:52:41.115014 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-10 01:52:41.115150 | instance | Checked out community.crypto to 2.2.3
2026-09-10 01:52:41.115271 | 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-10 01:52:41.115409 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.115534 | instance | Process install dependency map
2026-09-10 01:52:41.115678 | instance | Starting collection install process
2026-09-10 01:52:41.115805 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-10 01:52:41.115851 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-10 01:52:41.115873 | instance | community.crypto:2.2.3 was installed successfully
2026-09-10 01:52:41.115889 | instance | Checked out community.general to 7.3.0
2026-09-10 01:52:41.115933 | 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-10 01:52:41.115951 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.115991 | instance | Process install dependency map
2026-09-10 01:52:41.116011 | instance | Starting collection install process
2026-09-10 01:52:41.116048 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-10 01:52:41.116068 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-10 01:52:41.116106 | instance | community.general:7.3.0 was installed successfully
2026-09-10 01:52:41.116188 | instance | Checked out community.mysql to 3.6.0
2026-09-10 01:52:41.116232 | 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-10 01:52:41.116249 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.116291 | instance | Process install dependency map
2026-09-10 01:52:41.116310 | instance | Starting collection install process
2026-09-10 01:52:41.116346 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-10 01:52:41.116366 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-10 01:52:41.116382 | instance | community.mysql:3.6.0 was installed successfully
2026-09-10 01:52:41.116427 | instance | Checked out kubernetes.core to 2.4.0
2026-09-10 01:52:41.116469 | 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-10 01:52:41.116515 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.116559 | instance | Process install dependency map
2026-09-10 01:52:41.116585 | instance | Starting collection install process
2026-09-10 01:52:41.116617 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-10 01:52:41.116633 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-10 01:52:41.116649 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-10 01:52:41.116665 | instance | Checked out openstack.cloud to 1.7.0
2026-09-10 01:52:41.116681 | 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-10 01:52:41.116699 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.116716 | instance | Process install dependency map
2026-09-10 01:52:41.116764 | instance | Starting collection install process
2026-09-10 01:52:41.116784 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-10 01:52:41.116826 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-10 01:52:41.116846 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-10 01:52:41.116862 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-10 01:52:41.116896 | 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-10 01:52:41.116918 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.116934 | instance | Process install dependency map
2026-09-10 01:52:41.116977 | instance | Starting collection install process
2026-09-10 01:52:41.116995 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-10 01:52:41.117031 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-10 01:52:41.117050 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-10 01:52:41.117066 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-10 01:52:41.117109 | 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-10 01:52:41.117127 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.117170 | instance | Process install dependency map
2026-09-10 01:52:41.117188 | instance | Starting collection install process
2026-09-10 01:52:41.117232 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-10 01:52:41.117249 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-10 01:52:41.117290 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-10 01:52:41.117310 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-10 01:52:41.117344 | 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-10 01:52:41.117364 | instance | Starting galaxy collection install process
2026-09-10 01:52:41.117381 | instance | Process install dependency map
2026-09-10 01:52:41.117423 | instance | Starting collection install process
2026-09-10 01:52:41.117441 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-10 01:52:41.117475 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-10 01:52:41.117501 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-10 01:52:41.126207 | 
2026-09-10 01:52:41.126300 | PLAY RECAP
2026-09-10 01:52:41.126372 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 01:52:41.126419 | 
2026-09-10 01:52:41.363697 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-10 01:52:41.365784 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-10 01:52:41.953144 | 
2026-09-10 01:52:41.953274 | PLAY [all]
2026-09-10 01:52:41.963519 | 
2026-09-10 01:52:41.963603 | TASK [Install "jq" for log collection]
2026-09-10 01:52:51.488922 | instance | changed
2026-09-10 01:52:51.491942 | 
2026-09-10 01:52:51.492057 | PLAY RECAP
2026-09-10 01:52:51.492154 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 01:52:51.492250 | 
2026-09-10 01:52:51.631110 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-10 01:52:51.635569 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-10 01:52:52.231943 | 
2026-09-10 01:52:52.232119 | PLAY [all]
2026-09-10 01:52:52.243331 | 
2026-09-10 01:52:52.243431 | LOOP [Check inventory directories]
2026-09-10 01:52:52.607622 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-10 01:52:52.788619 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-10 01:52:52.973878 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-10 01:52:52.987220 | 
2026-09-10 01:52:52.987465 | LOOP [Copy inventory file for Zuul]
2026-09-10 01:52:53.871082 | instance | changed:
2026-09-10 01:52:53.871374 | instance | {
2026-09-10 01:52:53.871530 | instance |   "ansible_loop_var": "item",
2026-09-10 01:52:53.871576 | instance |   "changed": false,
2026-09-10 01:52:53.871789 | instance |   "failed": false,
2026-09-10 01:52:53.871869 | instance |   "invocation": {
2026-09-10 01:52:53.871931 | instance |     "module_args": {
2026-09-10 01:52:53.871971 | instance |       "checksum_algorithm": "sha1",
2026-09-10 01:52:53.872005 | instance |       "follow": true,
2026-09-10 01:52:53.872038 | instance |       "get_attributes": true,
2026-09-10 01:52:53.872075 | instance |       "get_checksum": true,
2026-09-10 01:52:53.872108 | instance |       "get_mime": true,
2026-09-10 01:52:53.872141 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-10 01:52:53.872282 | instance |     }
2026-09-10 01:52:53.872807 | instance |   },
2026-09-10 01:52:53.873102 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-10 01:52:53.873158 | instance |   "stat": {
2026-09-10 01:52:53.873194 | instance |     "atime": 1789005154.1021986,
2026-09-10 01:52:53.873230 | instance |     "attr_flags": "e",
2026-09-10 01:52:53.873652 | instance |     "attributes": [
2026-09-10 01:52:53.873727 | instance |       "extents"
2026-09-10 01:52:53.873765 | instance |     ],
2026-09-10 01:52:53.873803 | instance |     "block_size": 4096,
2026-09-10 01:52:53.873836 | instance |     "blocks": 8,
2026-09-10 01:52:53.873869 | instance |     "charset": "unknown",
2026-09-10 01:52:53.873906 | instance |     "ctime": 1789005152.5780149,
2026-09-10 01:52:53.873941 | instance |     "dev": 64513,
2026-09-10 01:52:53.873990 | instance |     "device_type": 0,
2026-09-10 01:52:53.874037 | instance |     "executable": true,
2026-09-10 01:52:53.874072 | instance |     "exists": true,
2026-09-10 01:52:53.874105 | instance |     "gid": 1000,
2026-09-10 01:52:53.874136 | instance |     "gr_name": "zuul",
2026-09-10 01:52:53.874169 | instance |     "inode": 1048496,
2026-09-10 01:52:53.874201 | instance |     "isblk": false,
2026-09-10 01:52:53.874235 | instance |     "ischr": false,
2026-09-10 01:52:53.874267 | instance |     "isdir": true,
2026-09-10 01:52:53.874301 | instance |     "isfifo": false,
2026-09-10 01:52:53.874349 | instance |     "isgid": false,
2026-09-10 01:52:53.874395 | instance |     "islnk": false,
2026-09-10 01:52:53.874428 | instance |     "isreg": false,
2026-09-10 01:52:53.874461 | instance |     "issock": false,
2026-09-10 01:52:53.874492 | instance |     "isuid": false,
2026-09-10 01:52:53.874659 | instance |     "mimetype": "unknown",
2026-09-10 01:52:53.874862 | instance |     "mode": "0755",
2026-09-10 01:52:53.874910 | instance |     "mtime": 1789005152.5780149,
2026-09-10 01:52:53.874944 | instance |     "nlink": 21,
2026-09-10 01:52:53.874977 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-10 01:52:53.875022 | instance |     "pw_name": "zuul",
2026-09-10 01:52:53.875074 | instance |     "readable": true,
2026-09-10 01:52:53.875109 | instance |     "rgrp": true,
2026-09-10 01:52:53.875810 | instance |     "roth": true,
2026-09-10 01:52:53.875856 | instance |     "rusr": true,
2026-09-10 01:52:53.875893 | instance |     "size": 4096,
2026-09-10 01:52:53.875927 | instance |     "uid": 1000,
2026-09-10 01:52:53.875961 | instance |     "version": "2421158748",
2026-09-10 01:52:53.875998 | instance |     "wgrp": false,
2026-09-10 01:52:53.876041 | instance |     "woth": false,
2026-09-10 01:52:53.876095 | instance |     "writeable": true,
2026-09-10 01:52:53.876140 | instance |     "wusr": true,
2026-09-10 01:52:53.876173 | instance |     "xgrp": true,
2026-09-10 01:52:53.876206 | instance |     "xoth": true,
2026-09-10 01:52:53.876238 | instance |     "xusr": true
2026-09-10 01:52:53.876270 | instance |   }
2026-09-10 01:52:53.876301 | instance | }
2026-09-10 01:52:53.876366 | instance | ok: All items complete
2026-09-10 01:52:53.876426 | 
2026-09-10 01:52:53.879400 | instance | skipping: Conditional result was False
2026-09-10 01:52:53.881069 | instance | skipping: Conditional result was False
2026-09-10 01:52:53.895895 | 
2026-09-10 01:52:53.896037 | LOOP [Switch "ansible_host" to private IP]
2026-09-10 01:52:54.280008 | instance | changed: 1 replacements made
2026-09-10 01:52:54.282932 | instance | skipping: Conditional result was False
2026-09-10 01:52:54.293952 | instance | skipping: Conditional result was False
2026-09-10 01:52:54.315667 | 
2026-09-10 01:52:54.315879 | TASK [Run Molecule scenario]
2026-09-10 01:52:55.253821 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-10 01:52:55.642054 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-10 01:52:55.642170 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-10 01:53:06.502020 | instance | INFO     [csi > dependency] Executing
2026-09-10 01:53:06.502115 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-10 01:53:06.502273 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-10 01:53:06.502809 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-10 01:53:06.518769 | instance | INFO     [csi > cleanup] Executing
2026-09-10 01:53:06.519439 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-10 01:53:06.531965 | instance | INFO     [csi > destroy] Executing
2026-09-10 01:53:06.532064 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-10 01:53:06.532230 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-10 01:53:06.541040 | instance | INFO     [csi > syntax] Executing
2026-09-10 01:53:07.984413 | instance |
2026-09-10 01:53:07.984676 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-10 01:53:08.038603 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-10 01:53:08.060548 | instance | INFO     [csi > create] Executing
2026-09-10 01:53:08.064434 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-10 01:53:08.081721 | instance | INFO     [csi > prepare] Executing
2026-09-10 01:53:08.836877 | instance |
2026-09-10 01:53:08.837149 | instance | PLAY [Prepare] *****************************************************************
2026-09-10 01:53:08.837412 | instance |
2026-09-10 01:53:08.837671 | instance | TASK [Gathering Facts] *********************************************************
2026-09-10 01:53:08.837915 | instance | Thursday 10 September 2026  01:53:08 +0000 (0:00:00.023)       0:00:00.023 ****
2026-09-10 01:53:10.076141 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-10 01:53:10.076384 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-10 01:53:10.076641 | instance | interpreter could change the meaning of that path. See
2026-09-10 01:53:10.076944 | instance | https://docs.ansible.com/ansible-
2026-09-10 01:53:10.077287 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-10 01:53:10.086912 | instance | ok: [instance]
2026-09-10 01:53:10.087328 | instance |
2026-09-10 01:53:10.087757 | instance | TASK [Configure short hostname] ************************************************
2026-09-10 01:53:10.088143 | instance | Thursday 10 September 2026  01:53:10 +0000 (0:00:01.250)       0:00:01.273 ****
2026-09-10 01:53:10.839587 | instance | changed: [instance]
2026-09-10 01:53:10.839791 | instance |
2026-09-10 01:53:10.840054 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-10 01:53:10.840352 | instance | Thursday 10 September 2026  01:53:10 +0000 (0:00:00.752)       0:00:02.026 ****
2026-09-10 01:53:11.152893 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-10 01:53:11.152952 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-10 01:53:11.152968 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-10 01:53:11.162967 | instance | changed: [instance]
2026-09-10 01:53:11.163199 | instance |
2026-09-10 01:53:11.163467 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-10 01:53:11.163696 | instance | Thursday 10 September 2026  01:53:11 +0000 (0:00:00.323)       0:00:02.349 ****
2026-09-10 01:53:11.967495 | instance | ok: [instance]
2026-09-10 01:53:11.967698 | instance |
2026-09-10 01:53:11.967932 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-10 01:53:11.968144 | instance |
2026-09-10 01:53:11.968392 | instance | TASK [Gathering Facts] *********************************************************
2026-09-10 01:53:11.968623 | instance | Thursday 10 September 2026  01:53:11 +0000 (0:00:00.804)       0:00:03.154 ****
2026-09-10 01:53:12.734583 | instance | ok: [instance]
2026-09-10 01:53:12.734735 | instance |
2026-09-10 01:53:12.734942 | instance | TASK [Install depedencies] *****************************************************
2026-09-10 01:53:12.735124 | instance | Thursday 10 September 2026  01:53:12 +0000 (0:00:00.767)       0:00:03.921 ****
2026-09-10 01:53:35.656075 | instance | changed: [instance]
2026-09-10 01:53:35.656140 | instance |
2026-09-10 01:53:35.656152 | instance | TASK [Start up service] ********************************************************
2026-09-10 01:53:35.656163 | instance | Thursday 10 September 2026  01:53:35 +0000 (0:00:22.921)       0:00:26.842 ****
2026-09-10 01:53:36.205531 | instance | ok: [instance]
2026-09-10 01:53:36.206250 | instance |
2026-09-10 01:53:36.206305 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-10 01:53:36.206319 | instance | Thursday 10 September 2026  01:53:36 +0000 (0:00:00.549)       0:00:27.392 ****
2026-09-10 01:53:36.519599 | instance | ok: [instance]
2026-09-10 01:53:36.520277 | instance |
2026-09-10 01:53:36.520320 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-10 01:53:36.520332 | instance | Thursday 10 September 2026  01:53:36 +0000 (0:00:00.314)       0:00:27.706 ****
2026-09-10 01:53:37.217645 | instance | changed: [instance]
2026-09-10 01:53:37.218264 | instance |
2026-09-10 01:53:37.218298 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-10 01:53:37.218310 | instance | Thursday 10 September 2026  01:53:37 +0000 (0:00:00.697)       0:00:28.404 ****
2026-09-10 01:53:37.425960 | instance | ok: [instance]
2026-09-10 01:53:37.426756 | instance |
2026-09-10 01:53:37.426794 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-10 01:53:37.426809 | instance | Thursday 10 September 2026  01:53:37 +0000 (0:00:00.208)       0:00:28.613 ****
2026-09-10 01:53:37.450082 | instance | skipping: [instance]
2026-09-10 01:53:37.450302 | instance |
2026-09-10 01:53:37.450802 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-10 01:53:37.450833 | instance | Thursday 10 September 2026  01:53:37 +0000 (0:00:00.024)       0:00:28.637 ****
2026-09-10 01:53:37.999550 | instance | changed: [instance] => (item=osd0)
2026-09-10 01:53:37.999618 | instance | changed: [instance] => (item=osd1)
2026-09-10 01:53:37.999632 | instance | changed: [instance] => (item=osd2)
2026-09-10 01:53:37.999645 | instance |
2026-09-10 01:53:37.999657 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-10 01:53:37.999670 | instance | Thursday 10 September 2026  01:53:37 +0000 (0:00:00.547)       0:00:29.184 ****
2026-09-10 01:53:38.654273 | instance | changed: [instance] => (item=osd0)
2026-09-10 01:53:38.654331 | instance | changed: [instance] => (item=osd1)
2026-09-10 01:53:38.654770 | instance | changed: [instance] => (item=osd2)
2026-09-10 01:53:38.654784 | instance |
2026-09-10 01:53:38.654789 | instance | TASK [Start loop devices] ******************************************************
2026-09-10 01:53:38.654794 | instance | Thursday 10 September 2026  01:53:38 +0000 (0:00:00.657)       0:00:29.842 ****
2026-09-10 01:53:39.394604 | instance | changed: [instance] => (item=osd0)
2026-09-10 01:53:39.395787 | instance | changed: [instance] => (item=osd1)
2026-09-10 01:53:39.396020 | instance | changed: [instance] => (item=osd2)
2026-09-10 01:53:39.396033 | instance |
2026-09-10 01:53:39.396043 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-10 01:53:39.396052 | instance | Thursday 10 September 2026  01:53:39 +0000 (0:00:00.739)       0:00:30.581 ****
2026-09-10 01:53:42.596404 | instance | changed: [instance] => (item=osd0)
2026-09-10 01:53:42.596961 | instance | changed: [instance] => (item=osd1)
2026-09-10 01:53:42.597066 | instance | changed: [instance] => (item=osd2)
2026-09-10 01:53:42.597089 | instance |
2026-09-10 01:53:42.597692 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-10 01:53:42.597758 | instance | Thursday 10 September 2026  01:53:42 +0000 (0:00:03.201)       0:00:33.783 ****
2026-09-10 01:53:44.552897 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-10 01:53:44.552956 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-10 01:53:44.552983 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-10 01:53:44.552992 | instance |
2026-09-10 01:53:44.553007 | instance | PLAY RECAP *********************************************************************
2026-09-10 01:53:44.555187 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-10 01:53:44.555236 | instance |
2026-09-10 01:53:44.555248 | instance | Thursday 10 September 2026  01:53:44 +0000 (0:00:01.956)       0:00:35.739 ****
2026-09-10 01:53:44.555258 | instance | ===============================================================================
2026-09-10 01:53:44.555267 | instance | Install depedencies ---------------------------------------------------- 22.92s
2026-09-10 01:53:44.555275 | instance | Create a volume group for each loop device ------------------------------ 3.20s
2026-09-10 01:53:44.555284 | instance | Create a logical volume for each loop device ---------------------------- 1.96s
2026-09-10 01:53:44.555294 | instance | Gathering Facts --------------------------------------------------------- 1.25s
2026-09-10 01:53:44.555302 | instance | Purge "snapd" package --------------------------------------------------- 0.80s
2026-09-10 01:53:44.555373 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-10 01:53:44.555689 | instance | Configure short hostname ------------------------------------------------ 0.75s
2026-09-10 01:53:44.555980 | instance | Start loop devices ------------------------------------------------------ 0.74s
2026-09-10 01:53:44.556268 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.70s
2026-09-10 01:53:44.556583 | instance | Set permissions on loopback devices ------------------------------------- 0.66s
2026-09-10 01:53:44.556972 | instance | Start up service -------------------------------------------------------- 0.55s
2026-09-10 01:53:44.557279 | instance | Create devices for Ceph ------------------------------------------------- 0.55s
2026-09-10 01:53:44.557599 | instance | Ensure hostname inside hosts file --------------------------------------- 0.32s
2026-09-10 01:53:44.557890 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s
2026-09-10 01:53:44.558175 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-10 01:53:44.558486 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-10 01:53:44.635620 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-10 01:53:44.650374 | instance | INFO     [csi > converge] Executing
2026-09-10 01:53:46.070047 | instance |
2026-09-10 01:53:46.070428 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-10 01:53:46.070704 | instance |
2026-09-10 01:53:46.071073 | instance | TASK [Gathering Facts] *********************************************************
2026-09-10 01:53:46.071513 | instance | Thursday 10 September 2026  01:53:46 +0000 (0:00:00.007)       0:00:00.007 ****
2026-09-10 01:53:47.126606 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-10 01:53:47.126985 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-10 01:53:47.127289 | instance | interpreter could change the meaning of that path. See
2026-09-10 01:53:47.127610 | instance | https://docs.ansible.com/ansible-
2026-09-10 01:53:47.127908 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-10 01:53:47.139603 | instance | ok: [instance]
2026-09-10 01:53:47.139916 | instance |
2026-09-10 01:53:47.140221 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-10 01:53:47.140551 | instance | Thursday 10 September 2026  01:53:47 +0000 (0:00:01.069)       0:00:01.076 ****
2026-09-10 01:53:47.196526 | instance | ok: [instance] => {
2026-09-10 01:53:47.196899 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-10 01:53:47.197205 | instance | }
2026-09-10 01:53:47.197522 | instance |
2026-09-10 01:53:47.197819 | instance | PLAY [all] *********************************************************************
2026-09-10 01:53:47.198098 | instance |
2026-09-10 01:53:47.198421 | instance | TASK [Gathering Facts] *********************************************************
2026-09-10 01:53:47.198749 | instance | Thursday 10 September 2026  01:53:47 +0000 (0:00:00.057)       0:00:01.133 ****
2026-09-10 01:53:48.158070 | instance | ok: [instance]
2026-09-10 01:53:48.158458 | instance |
2026-09-10 01:53:48.158785 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-10 01:53:48.159093 | instance | Thursday 10 September 2026  01:53:48 +0000 (0:00:00.961)       0:00:02.095 ****
2026-09-10 01:53:48.358042 | instance | ok: [instance]
2026-09-10 01:53:48.358459 | instance |
2026-09-10 01:53:48.358774 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-10 01:53:48.359060 | instance |
2026-09-10 01:53:48.359383 | instance | TASK [Gathering Facts] *********************************************************
2026-09-10 01:53:48.359684 | instance | Thursday 10 September 2026  01:53:48 +0000 (0:00:00.199)       0:00:02.294 ****
2026-09-10 01:53:49.329604 | instance | ok: [instance]
2026-09-10 01:53:49.329959 | instance |
2026-09-10 01:53:49.330274 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-10 01:53:49.330612 | instance | Thursday 10 September 2026  01:53:49 +0000 (0:00:00.970)       0:00:03.264 ****
2026-09-10 01:53:49.645485 | instance | ok: [instance]
2026-09-10 01:53:49.645660 | instance |
2026-09-10 01:53:49.645953 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-10 01:53:49.646230 | instance | Thursday 10 September 2026  01:53:49 +0000 (0:00:00.316)       0:00:03.581 ****
2026-09-10 01:53:49.701458 | instance | skipping: [instance]
2026-09-10 01:53:49.701677 | instance |
2026-09-10 01:53:49.701968 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-10 01:53:49.702226 | instance | Thursday 10 September 2026  01:53:49 +0000 (0:00:00.054)       0:00:03.635 ****
2026-09-10 01:53:50.085045 | instance | changed: [instance]
2026-09-10 01:53:50.085374 | instance |
2026-09-10 01:53:50.085733 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-10 01:53:50.086073 | instance | Thursday 10 September 2026  01:53:50 +0000 (0:00:00.386)       0:00:04.022 ****
2026-09-10 01:53:50.183388 | instance | ok: [instance] => {
2026-09-10 01:53:50.183713 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-10 01:53:50.183947 | instance | }
2026-09-10 01:53:50.184164 | instance |
2026-09-10 01:53:50.184451 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-10 01:53:50.184776 | instance | Thursday 10 September 2026  01:53:50 +0000 (0:00:00.098)       0:00:04.120 ****
2026-09-10 01:53:50.933333 | instance | changed: [instance]
2026-09-10 01:53:50.933539 | instance |
2026-09-10 01:53:50.933817 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-10 01:53:50.934052 | instance | Thursday 10 September 2026  01:53:50 +0000 (0:00:00.749)       0:00:04.869 ****
2026-09-10 01:53:51.004201 | instance | skipping: [instance]
2026-09-10 01:53:51.004281 | instance |
2026-09-10 01:53:51.004460 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-10 01:53:51.004717 | instance | Thursday 10 September 2026  01:53:50 +0000 (0:00:00.071)       0:00:04.941 ****
2026-09-10 01:53:51.054762 | instance | skipping: [instance]
2026-09-10 01:53:51.054955 | instance |
2026-09-10 01:53:51.055184 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-10 01:53:51.055454 | instance | Thursday 10 September 2026  01:53:51 +0000 (0:00:00.051)       0:00:04.992 ****
2026-09-10 01:53:51.309661 | instance | ok: [instance]
2026-09-10 01:53:51.309745 | instance |
2026-09-10 01:53:51.309762 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-10 01:53:51.309938 | instance | Thursday 10 September 2026  01:53:51 +0000 (0:00:00.253)       0:00:05.245 ****
2026-09-10 01:53:52.483908 | instance | ok: [instance]
2026-09-10 01:53:52.484000 | instance |
2026-09-10 01:53:52.484129 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-10 01:53:52.484263 | instance | Thursday 10 September 2026  01:53:52 +0000 (0:00:01.175)       0:00:06.421 ****
2026-09-10 01:53:52.574442 | instance | ok: [instance] => {
2026-09-10 01:53:52.574770 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-10 01:53:52.575143 | instance | }
2026-09-10 01:53:52.575509 | instance |
2026-09-10 01:53:52.575860 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-10 01:53:52.576181 | instance | Thursday 10 September 2026  01:53:52 +0000 (0:00:00.089)       0:00:06.511 ****
2026-09-10 01:53:53.305726 | instance | changed: [instance]
2026-09-10 01:53:53.305785 | instance |
2026-09-10 01:53:53.305909 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-10 01:53:53.306013 | instance | Thursday 10 September 2026  01:53:53 +0000 (0:00:00.731)       0:00:07.243 ****
2026-09-10 01:53:56.203846 | instance | changed: [instance]
2026-09-10 01:53:56.204133 | instance |
2026-09-10 01:53:56.204398 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-10 01:53:56.204638 | instance | Thursday 10 September 2026  01:53:56 +0000 (0:00:02.897)       0:00:10.140 ****
2026-09-10 01:53:56.249336 | instance | skipping: [instance]
2026-09-10 01:53:56.249672 | instance |
2026-09-10 01:53:56.249989 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-10 01:53:56.250333 | instance | Thursday 10 September 2026  01:53:56 +0000 (0:00:00.046)       0:00:10.186 ****
2026-09-10 01:53:56.293442 | instance | skipping: [instance]
2026-09-10 01:53:56.293645 | instance |
2026-09-10 01:53:56.294503 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-10 01:53:56.294837 | instance | Thursday 10 September 2026  01:53:56 +0000 (0:00:00.042)       0:00:10.229 ****
2026-09-10 01:53:56.333606 | instance | skipping: [instance]
2026-09-10 01:53:56.333777 | instance |
2026-09-10 01:53:56.334036 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-10 01:53:56.334231 | instance | Thursday 10 September 2026  01:53:56 +0000 (0:00:00.040)       0:00:10.269 ****
2026-09-10 01:54:02.826470 | instance | changed: [instance]
2026-09-10 01:54:02.826532 | instance |
2026-09-10 01:54:02.826547 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-10 01:54:02.826561 | instance | Thursday 10 September 2026  01:54:02 +0000 (0:00:06.492)       0:00:16.762 ****
2026-09-10 01:54:03.472112 | instance | changed: [instance]
2026-09-10 01:54:03.472880 | instance |
2026-09-10 01:54:03.472937 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-10 01:54:03.473162 | instance | Thursday 10 September 2026  01:54:03 +0000 (0:00:00.646)       0:00:17.408 ****
2026-09-10 01:54:04.436904 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-10 01:54:04.436966 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-10 01:54:04.436982 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-10 01:54:04.437281 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-10 01:54:04.437518 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-10 01:54:04.437730 | instance |
2026-09-10 01:54:04.437971 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-10 01:54:04.438217 | instance | Thursday 10 September 2026  01:54:04 +0000 (0:00:00.964)       0:00:18.373 ****
2026-09-10 01:54:05.057056 | instance | changed: [instance]
2026-09-10 01:54:05.057431 | instance |
2026-09-10 01:54:05.057797 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-10 01:54:05.058081 | instance | Thursday 10 September 2026  01:54:05 +0000 (0:00:00.593)       0:00:18.967 ****
2026-09-10 01:54:05.058371 | instance |
2026-09-10 01:54:05.058701 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-10 01:54:05.058954 | instance | Thursday 10 September 2026  01:54:05 +0000 (0:00:00.027)       0:00:18.994 ****
2026-09-10 01:54:05.986290 | instance | ok: [instance]
2026-09-10 01:54:05.986404 | instance |
2026-09-10 01:54:05.986552 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-10 01:54:05.986699 | instance | Thursday 10 September 2026  01:54:05 +0000 (0:00:00.929)       0:00:19.923 ****
2026-09-10 01:54:06.469905 | instance | changed: [instance]
2026-09-10 01:54:06.470113 | instance |
2026-09-10 01:54:06.470400 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-10 01:54:06.470639 | instance | Thursday 10 September 2026  01:54:06 +0000 (0:00:00.483)       0:00:20.406 ****
2026-09-10 01:54:07.126088 | instance | changed: [instance]
2026-09-10 01:54:07.126146 | instance |
2026-09-10 01:54:07.126204 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-10 01:54:07.126218 | instance | Thursday 10 September 2026  01:54:07 +0000 (0:00:00.655)       0:00:21.061 ****
2026-09-10 01:54:07.366023 | instance | ok: [instance]
2026-09-10 01:54:07.366087 | instance |
2026-09-10 01:54:07.366345 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-10 01:54:07.366386 | instance | Thursday 10 September 2026  01:54:07 +0000 (0:00:00.241)       0:00:21.303 ****
2026-09-10 01:54:07.463392 | instance | ok: [instance] => {
2026-09-10 01:54:07.464772 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-10 01:54:07.464854 | instance | }
2026-09-10 01:54:07.464865 | instance |
2026-09-10 01:54:07.464875 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-10 01:54:07.464885 | instance | Thursday 10 September 2026  01:54:07 +0000 (0:00:00.095)       0:00:21.399 ****
2026-09-10 01:54:09.357931 | instance | changed: [instance]
2026-09-10 01:54:09.357989 | instance |
2026-09-10 01:54:09.358001 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-10 01:54:09.358012 | instance | Thursday 10 September 2026  01:54:09 +0000 (0:00:01.894)       0:00:23.294 ****
2026-09-10 01:54:13.774968 | instance | changed: [instance]
2026-09-10 01:54:13.775074 | instance |
2026-09-10 01:54:13.775443 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-10 01:54:13.775632 | instance | Thursday 10 September 2026  01:54:13 +0000 (0:00:04.418)       0:00:27.712 ****
2026-09-10 01:54:14.852617 | instance | ok: [instance]
2026-09-10 01:54:14.852712 | instance |
2026-09-10 01:54:14.853216 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-10 01:54:14.853273 | instance | Thursday 10 September 2026  01:54:14 +0000 (0:00:01.076)       0:00:28.789 ****
2026-09-10 01:54:15.213695 | instance | changed: [instance]
2026-09-10 01:54:15.213751 | instance |
2026-09-10 01:54:15.213762 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-10 01:54:15.213772 | instance | Thursday 10 September 2026  01:54:15 +0000 (0:00:00.360)       0:00:29.150 ****
2026-09-10 01:54:15.699259 | instance | changed: [instance]
2026-09-10 01:54:15.699978 | instance |
2026-09-10 01:54:15.700028 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-10 01:54:15.700040 | instance | Thursday 10 September 2026  01:54:15 +0000 (0:00:00.486)       0:00:29.636 ****
2026-09-10 01:54:16.313579 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-10 01:54:16.313795 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-10 01:54:16.314030 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-10 01:54:16.314325 | instance |
2026-09-10 01:54:16.314640 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-10 01:54:16.314902 | instance | Thursday 10 September 2026  01:54:16 +0000 (0:00:00.614)       0:00:30.250 ****
2026-09-10 01:54:16.801040 | instance | changed: [instance]
2026-09-10 01:54:16.801290 | instance |
2026-09-10 01:54:16.801554 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-10 01:54:16.801817 | instance | Thursday 10 September 2026  01:54:16 +0000 (0:00:00.487)       0:00:30.738 ****
2026-09-10 01:54:17.283770 | instance | changed: [instance]
2026-09-10 01:54:17.283842 | instance |
2026-09-10 01:54:17.283912 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-10 01:54:17.284016 | instance | Thursday 10 September 2026  01:54:17 +0000 (0:00:00.469)       0:00:31.207 ****
2026-09-10 01:54:17.284128 | instance |
2026-09-10 01:54:17.284232 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-10 01:54:17.284334 | instance | Thursday 10 September 2026  01:54:17 +0000 (0:00:00.013)       0:00:31.221 ****
2026-09-10 01:54:18.036826 | instance | ok: [instance]
2026-09-10 01:54:18.037043 | instance |
2026-09-10 01:54:18.037338 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-10 01:54:18.037592 | instance | Thursday 10 September 2026  01:54:18 +0000 (0:00:00.752)       0:00:31.973 ****
2026-09-10 01:54:19.032754 | instance | changed: [instance]
2026-09-10 01:54:19.032974 | instance |
2026-09-10 01:54:19.033238 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-10 01:54:19.033471 | instance | Thursday 10 September 2026  01:54:19 +0000 (0:00:00.996)       0:00:32.969 ****
2026-09-10 01:54:19.635563 | instance | changed: [instance]
2026-09-10 01:54:19.635622 | instance |
2026-09-10 01:54:19.635633 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-10 01:54:19.635643 | instance | Thursday 10 September 2026  01:54:19 +0000 (0:00:00.602)       0:00:33.572 ****
2026-09-10 01:54:19.704472 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-10 01:54:19.704522 | instance |
2026-09-10 01:54:19.704718 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-10 01:54:19.704733 | instance | Thursday 10 September 2026  01:54:19 +0000 (0:00:00.069)       0:00:33.642 ****
2026-09-10 01:54:25.384269 | instance | changed: [instance]
2026-09-10 01:54:25.384343 | instance |
2026-09-10 01:54:25.384692 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-10 01:54:25.385013 | instance | Thursday 10 September 2026  01:54:25 +0000 (0:00:05.670)       0:00:39.312 ****
2026-09-10 01:54:26.082446 | instance | ok: [instance] => (item=chronyd)
2026-09-10 01:54:26.082524 | instance | ok: [instance] => (item=sshd)
2026-09-10 01:54:26.082536 | instance |
2026-09-10 01:54:26.082545 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-10 01:54:26.082556 | instance | Thursday 10 September 2026  01:54:26 +0000 (0:00:00.705)       0:00:40.018 ****
2026-09-10 01:54:37.767355 | instance | changed: [instance]
2026-09-10 01:54:37.768096 | instance |
2026-09-10 01:54:37.768388 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-10 01:54:37.768403 | instance | Thursday 10 September 2026  01:54:37 +0000 (0:00:11.686)       0:00:51.704 ****
2026-09-10 01:54:37.995507 | instance | ok: [instance]
2026-09-10 01:54:37.995612 | instance |
2026-09-10 01:54:37.995992 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-10 01:54:37.996041 | instance | Thursday 10 September 2026  01:54:37 +0000 (0:00:00.228)       0:00:51.932 ****
2026-09-10 01:54:38.486613 | instance | changed: [instance]
2026-09-10 01:54:38.487122 | instance |
2026-09-10 01:54:38.487188 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-10 01:54:38.487198 | instance | Thursday 10 September 2026  01:54:38 +0000 (0:00:00.491)       0:00:52.423 ****
2026-09-10 01:54:38.857920 | instance | changed: [instance]
2026-09-10 01:54:38.858003 | instance |
2026-09-10 01:54:38.858340 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-10 01:54:38.858400 | instance | Thursday 10 September 2026  01:54:38 +0000 (0:00:00.370)       0:00:52.794 ****
2026-09-10 01:54:38.994150 | instance | ok: [instance]
2026-09-10 01:54:38.994846 | instance |
2026-09-10 01:54:38.994916 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-10 01:54:38.994929 | instance | Thursday 10 September 2026  01:54:38 +0000 (0:00:00.135)       0:00:52.930 ****
2026-09-10 01:54:39.256287 | instance | ok: [instance] => (item=instance)
2026-09-10 01:54:39.256939 | instance |
2026-09-10 01:54:39.257036 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-10 01:54:39.257059 | instance | Thursday 10 September 2026  01:54:39 +0000 (0:00:00.262)       0:00:53.193 ****
2026-09-10 01:54:39.306207 | instance | ok: [instance]
2026-09-10 01:54:39.306303 | instance |
2026-09-10 01:54:39.306592 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-10 01:54:39.306675 | instance | Thursday 10 September 2026  01:54:39 +0000 (0:00:00.050)       0:00:53.243 ****
2026-09-10 01:54:39.406970 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-10 01:54:39.407043 | instance |
2026-09-10 01:54:39.407393 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-10 01:54:39.407457 | instance | Thursday 10 September 2026  01:54:39 +0000 (0:00:00.100)       0:00:53.343 ****
2026-09-10 01:54:39.748920 | instance | changed: [instance]
2026-09-10 01:54:39.748985 | instance |
2026-09-10 01:54:39.749037 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-10 01:54:39.749269 | instance | Thursday 10 September 2026  01:54:39 +0000 (0:00:00.341)       0:00:53.685 ****
2026-09-10 01:54:40.514208 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-10 01:54:40.514439 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-10 01:54:40.514699 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-10 01:54:40.515016 | instance |
2026-09-10 01:54:40.515250 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-10 01:54:40.515493 | instance | Thursday 10 September 2026  01:54:40 +0000 (0:00:00.765)       0:00:54.450 ****
2026-09-10 01:56:13.554376 | instance | ok: [instance]
2026-09-10 01:56:13.554430 | instance |
2026-09-10 01:56:13.554442 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-10 01:56:13.554452 | instance | Thursday 10 September 2026  01:56:13 +0000 (0:01:33.039)       0:02:27.490 ****
2026-09-10 01:56:13.831504 | instance | changed: [instance]
2026-09-10 01:56:13.831740 | instance |
2026-09-10 01:56:13.831996 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-10 01:56:13.832260 | instance | Thursday 10 September 2026  01:56:13 +0000 (0:00:00.277)       0:02:27.768 ****
2026-09-10 01:56:13.957712 | instance | ok: [instance]
2026-09-10 01:56:13.957752 | instance |
2026-09-10 01:56:13.957982 | instance | TASK [Install Ceph host] *******************************************************
2026-09-10 01:56:13.958028 | instance | Thursday 10 September 2026  01:56:13 +0000 (0:00:00.127)       0:02:27.895 ****
2026-09-10 01:56:14.058611 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-10 01:56:14.058689 | instance |
2026-09-10 01:56:14.058870 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-10 01:56:14.058894 | instance | Thursday 10 September 2026  01:56:14 +0000 (0:00:00.101)       0:02:27.996 ****
2026-09-10 01:56:15.807250 | instance | ok: [instance]
2026-09-10 01:56:15.807303 | instance |
2026-09-10 01:56:15.807315 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-10 01:56:15.807326 | instance | Thursday 10 September 2026  01:56:15 +0000 (0:00:01.747)       0:02:29.743 ****
2026-09-10 01:56:15.884815 | instance | ok: [instance] => (item=instance)
2026-09-10 01:56:15.884850 | instance |
2026-09-10 01:56:15.884861 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-10 01:56:15.884870 | instance | Thursday 10 September 2026  01:56:15 +0000 (0:00:00.077)       0:02:29.821 ****
2026-09-10 01:56:16.273893 | instance | ok: [instance]
2026-09-10 01:56:16.273999 | instance |
2026-09-10 01:56:16.274343 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-10 01:56:16.274389 | instance | Thursday 10 September 2026  01:56:16 +0000 (0:00:00.389)       0:02:30.211 ****
2026-09-10 01:56:18.498270 | instance | ok: [instance]
2026-09-10 01:56:18.498382 | instance |
2026-09-10 01:56:18.498710 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-10 01:56:18.498753 | instance | Thursday 10 September 2026  01:56:18 +0000 (0:00:02.224)       0:02:32.435 ****
2026-09-10 01:57:13.253937 | 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-10 01:57:13.254388 | 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_mbh7cj4_/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_mbh7cj4_/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_mbh7cj4_/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_mbh7cj4_/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-10T01:57:13.087+0000 7f5a12241640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAWDqJqvabQHyAANQ9FPu0zBUBxMN98Bx17P5dZUTR5GboY7PO79eDI7cI=: Malformed input [buffer:3]\n2026-09-10T01:57:13.087+0000 7f5a12241640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-10T01:57:13.091+0000 7f5a12241640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAWDqJqvabQHyAANQ9FPu0zBUBxMN98Bx17P5dZUTR5GboY7PO79eDI7cI=: Malformed input [buffer:3]\n2026-09-10T01:57:13.091+0000 7f5a12241640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-10T01:57:13.091+0000 7f5a12241640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAWDqJqvabQHyAANQ9FPu0zBUBxMN98Bx17P5dZUTR5GboY7PO79eDI7cI=: Malformed input [buffer:3]\n2026-09-10T01:57:13.091+0000 7f5a12241640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-10T01:57:13.091+0000 7f5a12241640 -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-10 01:57:13.256777 | instance |
2026-09-10 01:57:13.257484 | instance | PLAY RECAP *********************************************************************
2026-09-10 01:57:13.257503 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-10 01:57:13.257516 | instance |
2026-09-10 01:57:13.257744 | instance | Thursday 10 September 2026  01:57:13 +0000 (0:00:54.759)       0:03:27.194 ****
2026-09-10 01:57:13.257960 | instance | ===============================================================================
2026-09-10 01:57:13.258182 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 93.04s
2026-09-10 01:57:13.258430 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 54.76s
2026-09-10 01:57:13.258653 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 11.69s
2026-09-10 01:57:13.258879 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.49s
2026-09-10 01:57:13.259096 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.67s
2026-09-10 01:57:13.259314 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.42s
2026-09-10 01:57:13.259560 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.90s
2026-09-10 01:57:13.259778 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.22s
2026-09-10 01:57:13.259997 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.89s
2026-09-10 01:57:13.260209 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.75s
2026-09-10 01:57:13.260445 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.68s
2026-09-10 01:57:13.260665 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.18s
2026-09-10 01:57:13.260917 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.08s
2026-09-10 01:57:13.261139 | instance | Gathering Facts --------------------------------------------------------- 1.07s
2026-09-10 01:57:13.261378 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.00s
2026-09-10 01:57:13.261672 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-10 01:57:13.261907 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.96s
2026-09-10 01:57:13.262129 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-09-10 01:57:13.262391 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.77s
2026-09-10 01:57:13.262655 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.75s
2026-09-10 01:57:13.356951 | 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-10 01:57:13.357090 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-10 01:57:13.357218 | 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-10 01:57:13.700891 | instance | ERROR
2026-09-10 01:57:13.701238 | instance | {
2026-09-10 01:57:13.701304 | instance |   "delta": "0:04:18.725772",
2026-09-10 01:57:13.701346 | instance |   "end": "2026-09-10 01:57:13.421173",
2026-09-10 01:57:13.701383 | instance |   "msg": "non-zero return code",
2026-09-10 01:57:13.701418 | instance |   "rc": 2,
2026-09-10 01:57:13.701452 | instance |   "start": "2026-09-10 01:52:54.695401"
2026-09-10 01:57:13.701485 | instance | }
failure
2026-09-10 01:57:13.710573 | 
2026-09-10 01:57:13.710675 | PLAY RECAP
2026-09-10 01:57:13.710791 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-10 01:57:13.710835 | 
2026-09-10 01:57:13.869209 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-10 01:57:13.874612 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-10 01:57:14.549239 | 
2026-09-10 01:57:14.549364 | PLAY [all]
2026-09-10 01:57:14.562605 | 
2026-09-10 01:57:14.562703 | TASK [gather-host-logs : creating directory for system status]
2026-09-10 01:57:14.920156 | instance | changed
2026-09-10 01:57:14.929749 | 
2026-09-10 01:57:14.929911 | TASK [gather-host-logs : Get logs for each host]
2026-09-10 01:57:15.310557 | instance | + systemd-cgls --full --all --no-pager
2026-09-10 01:57:15.320254 | instance | + ip addr
2026-09-10 01:57:15.323025 | instance | + ip route
2026-09-10 01:57:15.324189 | instance | + lsblk
2026-09-10 01:57:15.329451 | instance | + mount
2026-09-10 01:57:15.332110 | instance | + docker images
2026-09-10 01:57:15.355203 | instance | + brctl show
2026-09-10 01:57:15.355668 | instance | /bin/bash: line 8: brctl: command not found
2026-09-10 01:57:15.355901 | instance | + ps aux --sort=-%mem
2026-09-10 01:57:15.375446 | instance | + dpkg -l
2026-09-10 01:57:15.386333 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-10 01:57:15.386831 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-10 01:57:15.406748 | instance | + '[' '!' -z '' ']'
2026-09-10 01:57:15.474906 | instance | ok: Runtime: 0:00:00.100234
2026-09-10 01:57:15.480219 | 
2026-09-10 01:57:15.480284 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-10 01:57:16.177596 | instance | changed:
2026-09-10 01:57:16.177800 | instance | created directory /var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/work/logs/instance
2026-09-10 01:57:16.177835 | instance | cd+++++++++ system/
2026-09-10 01:57:16.177863 | instance | >f+++++++++ system/brctl-show.txt
2026-09-10 01:57:16.177888 | instance | >f+++++++++ system/docker-images.txt
2026-09-10 01:57:16.177913 | instance | >f+++++++++ system/ip-addr.txt
2026-09-10 01:57:16.177943 | instance | >f+++++++++ system/ip-route.txt
2026-09-10 01:57:16.177970 | instance | >f+++++++++ system/lsblk.txt
2026-09-10 01:57:16.177996 | instance | >f+++++++++ system/mount.txt
2026-09-10 01:57:16.178021 | instance | >f+++++++++ system/packages.txt
2026-09-10 01:57:16.178045 | instance | >f+++++++++ system/ps.txt
2026-09-10 01:57:16.178072 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-10 01:57:16.188631 | 
2026-09-10 01:57:16.188732 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-10 01:57:16.402360 | instance | changed: "values"
2026-09-10 01:57:16.584129 | instance | changed: "releases"
2026-09-10 01:57:16.590465 | 
2026-09-10 01:57:16.591349 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-10 01:57:16.830918 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-10 01:57:17.123834 | instance | ok: Runtime: 0:00:00.005444
2026-09-10 01:57:17.133782 | 
2026-09-10 01:57:17.133919 | TASK [helm-release-status : Downloads logs to executor]
2026-09-10 01:57:17.664477 | instance | changed:
2026-09-10 01:57:17.664760 | instance | cd+++++++++ helm/
2026-09-10 01:57:17.664816 | instance | cd+++++++++ helm/releases/
2026-09-10 01:57:17.664858 | instance | cd+++++++++ helm/values/
2026-09-10 01:57:17.681991 | 
2026-09-10 01:57:17.682114 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-10 01:57:17.908187 | instance | changed
2026-09-10 01:57:17.919346 | 
2026-09-10 01:57:17.919501 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-10 01:57:18.224745 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 01:57:18.225700 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 01:57:18.231544 | instance | environment: line 1: kubectl: command not found
2026-09-10 01:57:18.232899 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 01:57:18.233890 | instance | environment: line 1: kubectl: command not found
2026-09-10 01:57:18.235576 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 01:57:18.237007 | instance | environment: line 1: kubectl: command not found
2026-09-10 01:57:18.238707 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 01:57:18.238916 | instance | environment: line 1: kubectl: command not found
2026-09-10 01:57:18.240620 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 01:57:18.241696 | instance | environment: line 1: kubectl: command not found
2026-09-10 01:57:18.243119 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 01:57:18.468417 | instance | ok: Runtime: 0:00:00.026460
2026-09-10 01:57:18.477548 | 
2026-09-10 01:57:18.477676 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-10 01:57:18.703972 | instance | changed
2026-09-10 01:57:18.713727 | 
2026-09-10 01:57:18.713866 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-10 01:57:18.976142 | instance | environment: line 5: kubectl: command not found
2026-09-10 01:57:18.977034 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 01:57:18.977162 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 01:57:18.977763 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 01:57:19.265880 | instance | ok: Runtime: 0:00:00.010555
2026-09-10 01:57:19.276292 | 
2026-09-10 01:57:19.276421 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-10 01:57:19.827960 | instance | changed:
2026-09-10 01:57:19.828227 | instance | cd+++++++++ objects/
2026-09-10 01:57:19.828285 | instance | cd+++++++++ objects/cluster/
2026-09-10 01:57:19.828328 | instance | cd+++++++++ objects/namespaced/
2026-09-10 01:57:19.845790 | 
2026-09-10 01:57:19.845959 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-10 01:57:20.076330 | instance | changed
2026-09-10 01:57:20.086615 | 
2026-09-10 01:57:20.086772 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-10 01:57:20.307734 | instance | changed
2026-09-10 01:57:20.405062 | 
2026-09-10 01:57:20.405318 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-10 01:57:20.665901 | instance | environment: line 3: kubectl: command not found
2026-09-10 01:57:20.964389 | instance | ok: Runtime: 0:00:00.009144
2026-09-10 01:57:20.974221 | 
2026-09-10 01:57:20.974383 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-10 01:57:21.525880 | instance | changed:
2026-09-10 01:57:21.526162 | instance | cd+++++++++ pod-logs/
2026-09-10 01:57:21.526227 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-10 01:57:21.545392 | 
2026-09-10 01:57:21.545522 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-10 01:57:21.783289 | instance | changed
2026-09-10 01:57:21.793030 | 
2026-09-10 01:57:21.793166 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-10 01:57:22.099789 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-10 01:57:22.342316 | instance | ok: Runtime: 0:00:00.049904
2026-09-10 01:57:22.352398 | 
2026-09-10 01:57:22.352719 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-10 01:57:22.613331 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-10 01:57:22.660146 | instance | ceph-mgr endpoints:
2026-09-10 01:57:22.904322 | instance | ok: Runtime: 0:00:00.053443
2026-09-10 01:57:22.914294 | 
2026-09-10 01:57:22.914441 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-10 01:57:23.170966 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-10 01:57:23.463563 | instance | ok: Runtime: 0:00:00.052955
2026-09-10 01:57:23.473458 | 
2026-09-10 01:57:23.473588 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-10 01:57:24.004432 | instance | changed: cd+++++++++ prometheus/
2026-09-10 01:57:24.021665 | 
2026-09-10 01:57:24.021807 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-10 01:57:24.239126 | instance | changed
2026-09-10 01:57:24.248012 | 
2026-09-10 01:57:24.248138 | TASK [gather-selenium-data : Get selenium data]
2026-09-10 01:57:24.550657 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-10 01:57:24.552144 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-10 01:57:24.794469 | instance | ERROR
2026-09-10 01:57:24.794781 | instance | {
2026-09-10 01:57:24.794844 | instance |   "delta": "0:00:00.006809",
2026-09-10 01:57:24.794885 | instance |   "end": "2026-09-10 01:57:24.552556",
2026-09-10 01:57:24.794925 | instance |   "msg": "non-zero return code",
2026-09-10 01:57:24.794962 | instance |   "rc": 1,
2026-09-10 01:57:24.794996 | instance |   "start": "2026-09-10 01:57:24.545747"
2026-09-10 01:57:24.795031 | instance | }
2026-09-10 01:57:24.795075 | instance | ERROR: Ignoring Errors
2026-09-10 01:57:24.804156 | 
2026-09-10 01:57:24.804284 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-10 01:57:25.335022 | instance | changed: cd+++++++++ selenium/
2026-09-10 01:57:25.345755 | 
2026-09-10 01:57:25.345849 | PLAY RECAP
2026-09-10 01:57:25.345933 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-10 01:57:25.345976 | 
2026-09-10 01:57:25.475156 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-10 01:57:25.479493 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-10 01:57:26.030920 | 
2026-09-10 01:57:26.031044 | PLAY [all]
2026-09-10 01:57:26.041811 | 
2026-09-10 01:57:26.041894 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-10 01:57:26.106169 | instance | skipping: Conditional result was False
2026-09-10 01:57:26.113148 | 
2026-09-10 01:57:26.113260 | TASK [fetch-output : Set log path for single node]
2026-09-10 01:57:26.166761 | instance | ok
2026-09-10 01:57:26.176189 | 
2026-09-10 01:57:26.176330 | LOOP [fetch-output : Ensure local output dirs]
2026-09-10 01:57:26.592469 | instance -> localhost | ok: "/var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/work/logs"
2026-09-10 01:57:26.820541 | instance -> localhost | changed: "/var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/work/artifacts"
2026-09-10 01:57:27.051275 | instance -> localhost | changed: "/var/lib/zuul/builds/f21fe3d1a1404402be6e85636fa531ca/work/docs"
2026-09-10 01:57:27.068857 | 
2026-09-10 01:57:27.069000 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-10 01:57:27.787778 | instance | changed: .d..t...... ./
2026-09-10 01:57:27.788091 | instance | changed: All items complete
2026-09-10 01:57:27.788143 | 
2026-09-10 01:57:28.292993 | instance | changed: .d..t...... ./
2026-09-10 01:57:28.801415 | instance | changed: .d..t...... ./
2026-09-10 01:57:28.822991 | 
2026-09-10 01:57:28.823200 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-10 01:57:29.308550 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007207
2026-09-10 01:57:29.571239 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007638
2026-09-10 01:57:29.590987 | 
2026-09-10 01:57:29.591157 | PLAY [all]
2026-09-10 01:57:29.602530 | 
2026-09-10 01:57:29.602658 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-10 01:57:30.003703 | instance | changed
2026-09-10 01:57:30.014307 | 
2026-09-10 01:57:30.014411 | PLAY RECAP
2026-09-10 01:57:30.014490 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 01:57:30.014533 | 
2026-09-10 01:57:30.137931 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-10 01:57:30.141710 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-10 01:57:30.738560 | 
2026-09-10 01:57:30.738731 | PLAY [localhost]
2026-09-10 01:57:30.748824 | 
2026-09-10 01:57:30.748899 | TASK [Generate Zuul manifest]
2026-09-10 01:57:30.767565 | localhost | ok
2026-09-10 01:57:30.781959 | 
2026-09-10 01:57:30.782038 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-10 01:57:31.208594 | localhost | changed
2026-09-10 01:57:31.228255 | 
2026-09-10 01:57:31.228406 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-10 01:57:31.271020 | localhost | ok
2026-09-10 01:57:31.283874 | 
2026-09-10 01:57:31.283994 | TASK [Upload logs]
2026-09-10 01:57:31.307448 | localhost | ok
2026-09-10 01:57:31.372072 | 
2026-09-10 01:57:31.372188 | TASK [Set zuul-log-path fact]
2026-09-10 01:57:31.390140 | localhost | ok
2026-09-10 01:57:31.400623 | 
2026-09-10 01:57:31.400708 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-10 01:57:31.437470 | localhost | ok
2026-09-10 01:57:31.445543 | 
2026-09-10 01:57:31.445626 | TASK [upload-logs : Create log directories]
2026-09-10 01:57:31.883938 | localhost | changed
2026-09-10 01:57:31.892580 | 
2026-09-10 01:57:31.892743 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-10 01:57:32.308365 | localhost -> localhost | ok: Runtime: 0:00:00.005839
2026-09-10 01:57:32.317169 | 
2026-09-10 01:57:32.317299 | TASK [upload-logs : Upload logs to log server]
2026-09-10 01:57:32.836334 | localhost | Output suppressed because no_log was given
2026-09-10 01:57:32.876361 | 
2026-09-10 01:57:32.876490 | LOOP [upload-logs : Compress console log and json output]
2026-09-10 01:57:32.944247 | localhost | skipping: Conditional result was False
2026-09-10 01:57:32.953463 | localhost | skipping: Conditional result was False
2026-09-10 01:57:32.969662 | 
2026-09-10 01:57:32.969878 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-10 01:57:33.018354 | localhost | skipping: Conditional result was False
2026-09-10 01:57:33.018742 | 
2026-09-10 01:57:33.022386 | localhost | skipping: Conditional result was False
2026-09-10 01:57:33.034477 | 
2026-09-10 01:57:33.034670 | LOOP [upload-logs : Upload console log and json output]