2026-09-16 21:32:41.189060 | Job console starting
2026-09-16 21:32:41.202200 | Updating git repos
2026-09-16 21:32:43.599139 | Cloning repos into workspace
2026-09-16 21:33:00.805069 | Restoring repo states
2026-09-16 21:33:15.401299 | Merging changes
2026-09-16 21:33:16.823601 | Checking out repos
2026-09-16 21:33:18.400197 | Preparing playbooks
2026-09-16 21:33:20.568934 | Running Ansible setup
2026-09-16 21:33:24.315917 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 21:33:24.907850 | 
2026-09-16 21:33:24.911119 | PLAY [localhost]
2026-09-16 21:33:24.919027 | 
2026-09-16 21:33:24.919125 | TASK [Gathering Facts]
2026-09-16 21:33:25.836408 | localhost | ok
2026-09-16 21:33:25.849990 | 
2026-09-16 21:33:25.850171 | TASK [Setup log path fact]
2026-09-16 21:33:25.870250 | localhost | ok
2026-09-16 21:33:25.888005 | 
2026-09-16 21:33:25.888124 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 21:33:25.929189 | localhost | ok
2026-09-16 21:33:25.941659 | 
2026-09-16 21:33:25.941791 | TASK [emit-job-header : Print job information]
2026-09-16 21:33:25.984928 | # Job Information
2026-09-16 21:33:25.985237 | Ansible Version: 2.16.18
2026-09-16 21:33:25.985310 | Job: atmosphere-molecule-csi-rbd
2026-09-16 21:33:25.985357 | Pipeline: check
2026-09-16 21:33:25.985398 | Executor: 2d72f0692154
2026-09-16 21:33:25.985440 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-09-16 21:33:25.985488 | Event ID: 9e4b2c20-b215-11f1-94c6-76a85fa6ea1b
2026-09-16 21:33:25.989681 | 
2026-09-16 21:33:25.989770 | LOOP [emit-job-header : Print node information]
2026-09-16 21:33:26.093935 | localhost | ok:
2026-09-16 21:33:26.094204 | localhost | # Node Information
2026-09-16 21:33:26.094261 | localhost | Inventory Hostname: instance
2026-09-16 21:33:26.094305 | localhost | Hostname: np0000192924
2026-09-16 21:33:26.094344 | localhost | Username: zuul
2026-09-16 21:33:26.094387 | localhost | Distro: Ubuntu 22.04
2026-09-16 21:33:26.094425 | localhost | Provider: yul1
2026-09-16 21:33:26.094461 | localhost | Region: ca-ymq-1
2026-09-16 21:33:26.094496 | localhost | Label: ubuntu-jammy
2026-09-16 21:33:26.094531 | localhost | Product Name: OpenStack Nova
2026-09-16 21:33:26.094566 | localhost | Interface IP: 162.253.55.76
2026-09-16 21:33:26.112711 | 
2026-09-16 21:33:26.112957 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-16 21:33:26.516319 | localhost -> localhost | changed
2026-09-16 21:33:26.526371 | 
2026-09-16 21:33:26.526547 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-16 21:33:27.706848 | localhost -> localhost | changed
2026-09-16 21:33:27.713420 | 
2026-09-16 21:33:27.713508 | PLAY [all]
2026-09-16 21:33:27.721852 | 
2026-09-16 21:33:27.722013 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-16 21:33:28.027959 | instance -> localhost | ok
2026-09-16 21:33:28.039793 | 
2026-09-16 21:33:28.040000 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-16 21:33:28.079518 | instance | ok
2026-09-16 21:33:28.114560 | instance | included: /var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-16 21:33:28.122487 | 
2026-09-16 21:33:28.122584 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-16 21:33:29.388933 | instance -> localhost | Generating public/private rsa key pair.
2026-09-16 21:33:29.389159 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/work/3fac56937e094cdea045e540c9f96216_id_rsa
2026-09-16 21:33:29.389200 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/work/3fac56937e094cdea045e540c9f96216_id_rsa.pub
2026-09-16 21:33:29.389232 | instance -> localhost | The key fingerprint is:
2026-09-16 21:33:29.389262 | instance -> localhost | SHA256:CwbauAchPqVKsWPlAX4vfxhK0sGDBFRdWd0KF/dJefQ zuul-build-sshkey
2026-09-16 21:33:29.389306 | instance -> localhost | The key's randomart image is:
2026-09-16 21:33:29.389337 | instance -> localhost | +---[RSA 3072]----+
2026-09-16 21:33:29.389376 | instance -> localhost | |++... ..o...o. oo|
2026-09-16 21:33:29.389407 | instance -> localhost | |o +  . . . o..o.+|
2026-09-16 21:33:29.389435 | instance -> localhost | |.+.O.     o .  oE|
2026-09-16 21:33:29.389463 | instance -> localhost | |..X==.     .     |
2026-09-16 21:33:29.389490 | instance -> localhost | | X+=.oo S        |
2026-09-16 21:33:29.389517 | instance -> localhost | |+ =o+.o. .       |
2026-09-16 21:33:29.389544 | instance -> localhost | |. ...o ..        |
2026-09-16 21:33:29.389570 | instance -> localhost | |   .  .          |
2026-09-16 21:33:29.389601 | instance -> localhost | |                 |
2026-09-16 21:33:29.389629 | instance -> localhost | +----[SHA256]-----+
2026-09-16 21:33:29.389689 | instance -> localhost | ok: Runtime: 0:00:00.807958
2026-09-16 21:33:29.397467 | 
2026-09-16 21:33:29.397552 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-16 21:33:29.436263 | instance | ok
2026-09-16 21:33:29.452526 | instance | included: /var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-16 21:33:29.464690 | 
2026-09-16 21:33:29.464812 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-16 21:33:29.490305 | instance | skipping: Conditional result was False
2026-09-16 21:33:29.498852 | 
2026-09-16 21:33:29.499028 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-16 21:33:30.001137 | instance | changed
2026-09-16 21:33:30.013013 | 
2026-09-16 21:33:30.013215 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-16 21:33:30.229003 | instance | ok
2026-09-16 21:33:30.284268 | 
2026-09-16 21:33:30.284439 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-16 21:33:30.763074 | instance | changed
2026-09-16 21:33:30.770437 | 
2026-09-16 21:33:30.770578 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-16 21:33:31.301696 | instance | changed
2026-09-16 21:33:31.314614 | 
2026-09-16 21:33:31.314829 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-16 21:33:31.340103 | instance | skipping: Conditional result was False
2026-09-16 21:33:31.351209 | 
2026-09-16 21:33:31.351372 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-16 21:33:31.795955 | instance -> localhost | changed
2026-09-16 21:33:31.810900 | 
2026-09-16 21:33:31.811020 | TASK [add-build-sshkey : Add back temp key]
2026-09-16 21:33:32.099032 | instance -> localhost | Identity added: /var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/work/3fac56937e094cdea045e540c9f96216_id_rsa (zuul-build-sshkey)
2026-09-16 21:33:32.099411 | instance -> localhost | ok: Runtime: 0:00:00.011409
2026-09-16 21:33:32.107989 | 
2026-09-16 21:33:32.108093 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-16 21:33:32.443188 | instance | ok
2026-09-16 21:33:32.447653 | 
2026-09-16 21:33:32.447753 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-16 21:33:32.474177 | instance | skipping: Conditional result was False
2026-09-16 21:33:32.483559 | 
2026-09-16 21:33:32.483665 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-16 21:33:32.829985 | instance | ok
2026-09-16 21:33:32.837237 | 
2026-09-16 21:33:32.837330 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-16 21:33:36.857948 | instance | Output suppressed because no_log was given
2026-09-16 21:33:36.878711 | 
2026-09-16 21:33:36.878815 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-16 21:33:37.117272 | instance | ok: "logs"
2026-09-16 21:33:37.117685 | instance | ok: All items complete
2026-09-16 21:33:37.117750 | 
2026-09-16 21:33:37.260034 | instance | ok: "artifacts"
2026-09-16 21:33:37.438545 | instance | ok: "docs"
2026-09-16 21:33:37.451205 | 
2026-09-16 21:33:37.451406 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-16 21:33:37.659725 | instance | changed: "logs"
2026-09-16 21:33:37.809296 | instance | changed: "artifacts"
2026-09-16 21:33:37.982092 | instance | changed: "docs"
2026-09-16 21:33:38.004428 | 
2026-09-16 21:33:38.004636 | PLAY RECAP
2026-09-16 21:33:38.004720 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-16 21:33:38.004777 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:33:38.004820 | 
2026-09-16 21:33:38.183969 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 21:33:38.188610 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 21:33:38.871914 | 
2026-09-16 21:33:38.996380 | PLAY [all]
2026-09-16 21:33:39.020955 | 
2026-09-16 21:33:39.021186 | TASK [setup-uv : Extract archive]
2026-09-16 21:33:41.705743 | instance | changed
2026-09-16 21:33:41.712751 | 
2026-09-16 21:33:41.712856 | TASK [setup-uv : Print version]
2026-09-16 21:33:42.129003 | instance | uv 0.8.13
2026-09-16 21:33:42.250892 | instance | ok: Runtime: 0:00:00.020205
2026-09-16 21:33:42.267278 | 
2026-09-16 21:33:42.267413 | TASK [Install Ansible collection siblings]
2026-09-16 21:34:05.361737 | instance | changed:
2026-09-16 21:34:05.362042 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 21:34:05.362120 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 21:34:05.362164 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 21:34:05.362203 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 21:34:05.362252 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 21:34:05.362297 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 21:34:05.362337 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 21:34:05.362389 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 21:34:05.362427 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 21:34:05.362464 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 21:34:05.362500 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 21:34:05.362536 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 21:34:05.362581 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-16 21:34:05.362617 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-16 21:34:05.362663 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-16 21:34:05.362700 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-16 21:34:05.362736 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-16 21:34:05.362772 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-16 21:34:05.362808 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-16 21:34:05.362843 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-16 21:34:05.362879 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-16 21:34:05.362924 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-16 21:34:05.362964 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 21:34:05.363024 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-16 21:34:05.363081 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 21:34:05.363118 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 21:34:05.363153 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-16 21:34:05.363188 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-16 21:34:05.363222 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 21:34:05.363257 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-16 21:34:05.363291 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-16 21:34:05.363328 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 21:34:05.363377 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 21:34:05.363413 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-16 21:34:05.363449 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 21:34:05.363495 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.363531 | instance | Process install dependency map
2026-09-16 21:34:05.363566 | instance | Starting collection install process
2026-09-16 21:34:05.363621 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-16 21:34:05.363657 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-16 21:34:05.363692 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-16 21:34:05.363727 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-16 21:34:05.363761 | instance | Creating virtual environment at: .venv
2026-09-16 21:34:05.363795 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 21:34:05.363830 | instance | Downloading cryptography (4.2MiB)
2026-09-16 21:34:05.363865 | instance | Downloading openstacksdk (1.7MiB)
2026-09-16 21:34:05.363900 | instance | Downloading kubernetes (1.9MiB)
2026-09-16 21:34:05.363934 | instance | Downloading pygments (1.2MiB)
2026-09-16 21:34:05.363969 | instance | Downloading setuptools (1.1MiB)
2026-09-16 21:34:05.364004 | instance | Downloading rjsonnet (1.2MiB)
2026-09-16 21:34:05.364080 | instance | Downloading netaddr (2.2MiB)
2026-09-16 21:34:05.364137 | instance | Downloading ansible-core (2.1MiB)
2026-09-16 21:34:05.364175 | instance |  Downloading rjsonnet
2026-09-16 21:34:05.364214 | instance |    Building pyperclip==1.9.0
2026-09-16 21:34:05.364249 | instance |  Downloading netaddr
2026-09-16 21:34:05.364304 | instance |  Downloading cryptography
2026-09-16 21:34:05.364413 | instance |  Downloading pygments
2026-09-16 21:34:05.364533 | instance |  Downloading setuptools
2026-09-16 21:34:05.364596 | instance |  Downloading kubernetes
2026-09-16 21:34:05.364648 | instance |  Downloading ansible-core
2026-09-16 21:34:05.364701 | instance |  Downloading openstacksdk
2026-09-16 21:34:05.364767 | instance |       Built pyperclip==1.9.0
2026-09-16 21:34:05.364831 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 21:34:05.364900 | instance | Installed 79 packages in 51ms
2026-09-16 21:34:05.364953 | instance | Checked out ansible.posix to 1.6.0
2026-09-16 21:34:05.364992 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 21:34:05.365041 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.365098 | instance | Process install dependency map
2026-09-16 21:34:05.365146 | instance | Starting collection install process
2026-09-16 21:34:05.365183 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-16 21:34:05.365219 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-16 21:34:05.365273 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-16 21:34:05.365318 | instance | Checked out ansible.utils to v6.1.0
2026-09-16 21:34:05.365353 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 21:34:05.365389 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.365423 | instance | Process install dependency map
2026-09-16 21:34:05.365475 | instance | Starting collection install process
2026-09-16 21:34:05.365511 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-16 21:34:05.365546 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-16 21:34:05.365580 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-16 21:34:05.365615 | instance | Checked out atmosphere.common to v0.6.0
2026-09-16 21:34:05.365650 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 21:34:05.365696 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.365732 | instance | Process install dependency map
2026-09-16 21:34:05.365766 | instance | Starting collection install process
2026-09-16 21:34:05.365801 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-16 21:34:05.365837 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-16 21:34:05.365872 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-16 21:34:05.365934 | instance | Checked out community.crypto to 2.2.3
2026-09-16 21:34:05.365970 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 21:34:05.366005 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.366041 | instance | Process install dependency map
2026-09-16 21:34:05.366117 | instance | Starting collection install process
2026-09-16 21:34:05.366167 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-16 21:34:05.366208 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-16 21:34:05.366244 | instance | community.crypto:2.2.3 was installed successfully
2026-09-16 21:34:05.366279 | instance | Checked out community.general to 7.3.0
2026-09-16 21:34:05.366324 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 21:34:05.366361 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.366395 | instance | Process install dependency map
2026-09-16 21:34:05.366440 | instance | Starting collection install process
2026-09-16 21:34:05.366476 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-16 21:34:05.366511 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-16 21:34:05.366545 | instance | community.general:7.3.0 was installed successfully
2026-09-16 21:34:05.366590 | instance | Checked out community.mysql to 3.6.0
2026-09-16 21:34:05.366626 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 21:34:05.366661 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.366695 | instance | Process install dependency map
2026-09-16 21:34:05.366730 | instance | Starting collection install process
2026-09-16 21:34:05.366764 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-16 21:34:05.366798 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-16 21:34:05.366832 | instance | community.mysql:3.6.0 was installed successfully
2026-09-16 21:34:05.366885 | instance | Checked out kubernetes.core to 2.4.0
2026-09-16 21:34:05.366939 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 21:34:05.366974 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.367009 | instance | Process install dependency map
2026-09-16 21:34:05.367064 | instance | Starting collection install process
2026-09-16 21:34:05.367102 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-16 21:34:05.367137 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-16 21:34:05.367171 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-16 21:34:05.367206 | instance | Checked out openstack.cloud to 1.7.0
2026-09-16 21:34:05.367250 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 21:34:05.367287 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.367321 | instance | Process install dependency map
2026-09-16 21:34:05.367356 | instance | Starting collection install process
2026-09-16 21:34:05.367390 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-16 21:34:05.367425 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-16 21:34:05.367459 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-16 21:34:05.367507 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-16 21:34:05.367543 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 21:34:05.367578 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.367612 | instance | Process install dependency map
2026-09-16 21:34:05.367646 | instance | Starting collection install process
2026-09-16 21:34:05.367693 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-16 21:34:05.367747 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-16 21:34:05.367792 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-16 21:34:05.367830 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-16 21:34:05.367882 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 21:34:05.367927 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.367972 | instance | Process install dependency map
2026-09-16 21:34:05.368008 | instance | Starting collection install process
2026-09-16 21:34:05.368104 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-16 21:34:05.368152 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-16 21:34:05.368202 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-16 21:34:05.368269 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-16 21:34:05.368309 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 21:34:05.368346 | instance | Starting galaxy collection install process
2026-09-16 21:34:05.368384 | instance | Process install dependency map
2026-09-16 21:34:05.368422 | instance | Starting collection install process
2026-09-16 21:34:05.368461 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-16 21:34:05.368530 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-16 21:34:05.368573 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-16 21:34:05.379014 | 
2026-09-16 21:34:05.379123 | PLAY RECAP
2026-09-16 21:34:05.379203 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:34:05.379256 | 
2026-09-16 21:34:05.502168 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 21:34:05.505861 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 21:34:06.060483 | 
2026-09-16 21:34:06.060638 | PLAY [all]
2026-09-16 21:34:06.071320 | 
2026-09-16 21:34:06.071423 | TASK [Install "jq" for log collection]
2026-09-16 21:34:18.807298 | instance | changed
2026-09-16 21:34:18.810518 | 
2026-09-16 21:34:18.810642 | PLAY RECAP
2026-09-16 21:34:18.810755 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:34:18.810864 | 
2026-09-16 21:34:18.962157 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 21:34:18.966152 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 21:34:19.551949 | 
2026-09-16 21:34:19.552088 | PLAY [all]
2026-09-16 21:34:19.562679 | 
2026-09-16 21:34:19.562769 | LOOP [Check inventory directories]
2026-09-16 21:34:19.961796 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-16 21:34:20.140867 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-16 21:34:20.323908 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-16 21:34:20.348225 | 
2026-09-16 21:34:20.348547 | LOOP [Copy inventory file for Zuul]
2026-09-16 21:34:21.231083 | instance | changed:
2026-09-16 21:34:21.231384 | instance | {
2026-09-16 21:34:21.231440 | instance |   "ansible_loop_var": "item",
2026-09-16 21:34:21.231484 | instance |   "changed": false,
2026-09-16 21:34:21.231523 | instance |   "failed": false,
2026-09-16 21:34:21.231565 | instance |   "invocation": {
2026-09-16 21:34:21.231601 | instance |     "module_args": {
2026-09-16 21:34:21.231635 | instance |       "checksum_algorithm": "sha1",
2026-09-16 21:34:21.231669 | instance |       "follow": true,
2026-09-16 21:34:21.231702 | instance |       "get_attributes": true,
2026-09-16 21:34:21.231747 | instance |       "get_checksum": true,
2026-09-16 21:34:21.231782 | instance |       "get_mime": true,
2026-09-16 21:34:21.231815 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-16 21:34:21.231849 | instance |     }
2026-09-16 21:34:21.231898 | instance |   },
2026-09-16 21:34:21.231945 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-16 21:34:21.231993 | instance |   "stat": {
2026-09-16 21:34:21.232028 | instance |     "atime": 1789594438.145413,
2026-09-16 21:34:21.232086 | instance |     "attr_flags": "e",
2026-09-16 21:34:21.232122 | instance |     "attributes": [
2026-09-16 21:34:21.232156 | instance |       "extents"
2026-09-16 21:34:21.232188 | instance |     ],
2026-09-16 21:34:21.232220 | instance |     "block_size": 4096,
2026-09-16 21:34:21.232252 | instance |     "blocks": 8,
2026-09-16 21:34:21.232285 | instance |     "charset": "unknown",
2026-09-16 21:34:21.232320 | instance |     "ctime": 1789594423.1410315,
2026-09-16 21:34:21.232354 | instance |     "dev": 64513,
2026-09-16 21:34:21.232387 | instance |     "device_type": 0,
2026-09-16 21:34:21.232418 | instance |     "executable": true,
2026-09-16 21:34:21.232450 | instance |     "exists": true,
2026-09-16 21:34:21.232482 | instance |     "gid": 1000,
2026-09-16 21:34:21.232550 | instance |     "gr_name": "zuul",
2026-09-16 21:34:21.232584 | instance |     "inode": 1049016,
2026-09-16 21:34:21.232617 | instance |     "isblk": false,
2026-09-16 21:34:21.232650 | instance |     "ischr": false,
2026-09-16 21:34:21.232682 | instance |     "isdir": true,
2026-09-16 21:34:21.232718 | instance |     "isfifo": false,
2026-09-16 21:34:21.232750 | instance |     "isgid": false,
2026-09-16 21:34:21.232782 | instance |     "islnk": false,
2026-09-16 21:34:21.232813 | instance |     "isreg": false,
2026-09-16 21:34:21.232844 | instance |     "issock": false,
2026-09-16 21:34:21.232875 | instance |     "isuid": false,
2026-09-16 21:34:21.232908 | instance |     "mimetype": "unknown",
2026-09-16 21:34:21.232955 | instance |     "mode": "0755",
2026-09-16 21:34:21.232999 | instance |     "mtime": 1789594423.1410315,
2026-09-16 21:34:21.233033 | instance |     "nlink": 22,
2026-09-16 21:34:21.233095 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-16 21:34:21.233129 | instance |     "pw_name": "zuul",
2026-09-16 21:34:21.233163 | instance |     "readable": true,
2026-09-16 21:34:21.233196 | instance |     "rgrp": true,
2026-09-16 21:34:21.233228 | instance |     "roth": true,
2026-09-16 21:34:21.233260 | instance |     "rusr": true,
2026-09-16 21:34:21.233292 | instance |     "size": 4096,
2026-09-16 21:34:21.233323 | instance |     "uid": 1000,
2026-09-16 21:34:21.233355 | instance |     "version": "247623894",
2026-09-16 21:34:21.233388 | instance |     "wgrp": false,
2026-09-16 21:34:21.233427 | instance |     "woth": false,
2026-09-16 21:34:21.233460 | instance |     "writeable": true,
2026-09-16 21:34:21.233493 | instance |     "wusr": true,
2026-09-16 21:34:21.233525 | instance |     "xgrp": true,
2026-09-16 21:34:21.233557 | instance |     "xoth": true,
2026-09-16 21:34:21.233589 | instance |     "xusr": true
2026-09-16 21:34:21.233621 | instance |   }
2026-09-16 21:34:21.233653 | instance | }
2026-09-16 21:34:21.233716 | instance | ok: All items complete
2026-09-16 21:34:21.233756 | 
2026-09-16 21:34:21.245491 | instance | skipping: Conditional result was False
2026-09-16 21:34:21.254472 | instance | skipping: Conditional result was False
2026-09-16 21:34:21.270916 | 
2026-09-16 21:34:21.271142 | LOOP [Switch "ansible_host" to private IP]
2026-09-16 21:34:21.644350 | instance | changed: 1 replacements made
2026-09-16 21:34:21.646885 | instance | skipping: Conditional result was False
2026-09-16 21:34:21.655295 | instance | skipping: Conditional result was False
2026-09-16 21:34:21.681096 | 
2026-09-16 21:34:21.681328 | TASK [Run Molecule scenario]
2026-09-16 21:34:22.621268 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-16 21:34:23.069253 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-16 21:34:23.069362 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-16 21:34:34.625438 | instance | INFO     [csi > dependency] Executing
2026-09-16 21:34:34.625570 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-16 21:34:34.625864 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-16 21:34:34.625933 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-16 21:34:34.634676 | instance | INFO     [csi > cleanup] Executing
2026-09-16 21:34:34.635411 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 21:34:34.648168 | instance | INFO     [csi > destroy] Executing
2026-09-16 21:34:34.648244 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-16 21:34:34.648262 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-16 21:34:34.659610 | instance | INFO     [csi > syntax] Executing
2026-09-16 21:34:36.134674 | instance |
2026-09-16 21:34:36.134811 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:34:36.224378 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-16 21:34:36.244364 | instance | INFO     [csi > create] Executing
2026-09-16 21:34:36.248134 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 21:34:36.264167 | instance | INFO     [csi > prepare] Executing
2026-09-16 21:34:37.158346 | instance |
2026-09-16 21:34:37.158442 | instance | PLAY [Prepare] *****************************************************************
2026-09-16 21:34:37.158638 | instance |
2026-09-16 21:34:37.158804 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:34:37.158950 | instance | Wednesday 16 September 2026  21:34:37 +0000 (0:00:00.026)       0:00:00.026 ***
2026-09-16 21:34:38.431525 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 21:34:38.431775 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 21:34:38.432017 | instance | interpreter could change the meaning of that path. See
2026-09-16 21:34:38.432338 | instance | https://docs.ansible.com/ansible-
2026-09-16 21:34:38.432738 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 21:34:38.443450 | instance | ok: [instance]
2026-09-16 21:34:38.443676 | instance |
2026-09-16 21:34:38.443973 | instance | TASK [Configure short hostname] ************************************************
2026-09-16 21:34:38.444187 | instance | Wednesday 16 September 2026  21:34:38 +0000 (0:00:01.286)       0:00:01.313 ***
2026-09-16 21:34:39.180048 | instance | changed: [instance]
2026-09-16 21:34:39.180249 | instance |
2026-09-16 21:34:39.180514 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-16 21:34:39.180792 | instance | Wednesday 16 September 2026  21:34:39 +0000 (0:00:00.736)       0:00:02.049 ***
2026-09-16 21:34:39.460673 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-16 21:34:39.460909 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-16 21:34:39.461156 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-16 21:34:39.470297 | instance | changed: [instance]
2026-09-16 21:34:39.470502 | instance |
2026-09-16 21:34:39.470784 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-16 21:34:39.471020 | instance | Wednesday 16 September 2026  21:34:39 +0000 (0:00:00.290)       0:00:02.340 ***
2026-09-16 21:34:40.442461 | instance | ok: [instance]
2026-09-16 21:34:40.442680 | instance |
2026-09-16 21:34:40.442982 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-16 21:34:40.443222 | instance |
2026-09-16 21:34:40.443533 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:34:40.443903 | instance | Wednesday 16 September 2026  21:34:40 +0000 (0:00:00.971)       0:00:03.312 ***
2026-09-16 21:34:41.220740 | instance | ok: [instance]
2026-09-16 21:34:41.221096 | instance |
2026-09-16 21:34:41.221400 | instance | TASK [Install depedencies] *****************************************************
2026-09-16 21:34:41.221729 | instance | Wednesday 16 September 2026  21:34:41 +0000 (0:00:00.778)       0:00:04.090 ***
2026-09-16 21:35:05.094868 | instance | changed: [instance]
2026-09-16 21:35:05.095075 | instance |
2026-09-16 21:35:05.095694 | instance | TASK [Start up service] ********************************************************
2026-09-16 21:35:05.095768 | instance | Wednesday 16 September 2026  21:35:05 +0000 (0:00:23.874)       0:00:27.964 ***
2026-09-16 21:35:05.732899 | instance | ok: [instance]
2026-09-16 21:35:05.733686 | instance |
2026-09-16 21:35:05.733748 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-16 21:35:05.733760 | instance | Wednesday 16 September 2026  21:35:05 +0000 (0:00:00.638)       0:00:28.602 ***
2026-09-16 21:35:06.059699 | instance | ok: [instance]
2026-09-16 21:35:06.059779 | instance |
2026-09-16 21:35:06.060306 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-16 21:35:06.060367 | instance | Wednesday 16 September 2026  21:35:06 +0000 (0:00:00.327)       0:00:28.929 ***
2026-09-16 21:35:06.802540 | instance | changed: [instance]
2026-09-16 21:35:06.802641 | instance |
2026-09-16 21:35:06.802992 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-16 21:35:06.803057 | instance | Wednesday 16 September 2026  21:35:06 +0000 (0:00:00.742)       0:00:29.672 ***
2026-09-16 21:35:07.033657 | instance | ok: [instance]
2026-09-16 21:35:07.034105 | instance |
2026-09-16 21:35:07.034562 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-16 21:35:07.035077 | instance | Wednesday 16 September 2026  21:35:07 +0000 (0:00:00.230)       0:00:29.903 ***
2026-09-16 21:35:07.059748 | instance | skipping: [instance]
2026-09-16 21:35:07.060087 | instance |
2026-09-16 21:35:07.060508 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-16 21:35:07.060935 | instance | Wednesday 16 September 2026  21:35:07 +0000 (0:00:00.026)       0:00:29.929 ***
2026-09-16 21:35:08.648922 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:35:08.649008 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:35:08.649164 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:35:08.649393 | instance |
2026-09-16 21:35:08.649573 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-16 21:35:08.649752 | instance | Wednesday 16 September 2026  21:35:08 +0000 (0:00:01.589)       0:00:31.519 ***
2026-09-16 21:35:09.370198 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:35:09.370507 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:35:09.371121 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:35:09.371510 | instance |
2026-09-16 21:35:09.371914 | instance | TASK [Start loop devices] ******************************************************
2026-09-16 21:35:09.372257 | instance | Wednesday 16 September 2026  21:35:09 +0000 (0:00:00.720)       0:00:32.239 ***
2026-09-16 21:35:10.152423 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:35:10.152496 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:35:10.152975 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:35:10.153035 | instance |
2026-09-16 21:35:10.153041 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-16 21:35:10.153047 | instance | Wednesday 16 September 2026  21:35:10 +0000 (0:00:00.782)       0:00:33.022 ***
2026-09-16 21:35:13.521212 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:35:13.522426 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:35:13.522492 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:35:13.522504 | instance |
2026-09-16 21:35:13.522514 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-16 21:35:13.522524 | instance | Wednesday 16 September 2026  21:35:13 +0000 (0:00:03.368)       0:00:36.390 ***
2026-09-16 21:35:15.650393 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-16 21:35:15.651390 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-16 21:35:15.651490 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-16 21:35:15.655605 | instance |
2026-09-16 21:35:15.655700 | instance | PLAY RECAP *********************************************************************
2026-09-16 21:35:15.655713 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-16 21:35:15.655724 | instance |
2026-09-16 21:35:15.655733 | instance | Wednesday 16 September 2026  21:35:15 +0000 (0:00:02.129)       0:00:38.520 ***
2026-09-16 21:35:15.655743 | instance | ===============================================================================
2026-09-16 21:35:15.655753 | instance | Install depedencies ---------------------------------------------------- 23.87s
2026-09-16 21:35:15.655763 | instance | Create a volume group for each loop device ------------------------------ 3.37s
2026-09-16 21:35:15.655772 | instance | Create a logical volume for each loop device ---------------------------- 2.13s
2026-09-16 21:35:15.655783 | instance | Create devices for Ceph ------------------------------------------------- 1.59s
2026-09-16 21:35:15.655793 | instance | Gathering Facts --------------------------------------------------------- 1.29s
2026-09-16 21:35:15.655812 | instance | Purge "snapd" package --------------------------------------------------- 0.97s
2026-09-16 21:35:15.656152 | instance | Start loop devices ------------------------------------------------------ 0.78s
2026-09-16 21:35:15.656574 | instance | Gathering Facts --------------------------------------------------------- 0.78s
2026-09-16 21:35:15.657002 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.74s
2026-09-16 21:35:15.657404 | instance | Configure short hostname ------------------------------------------------ 0.74s
2026-09-16 21:35:15.657805 | instance | Set permissions on loopback devices ------------------------------------- 0.72s
2026-09-16 21:35:15.658233 | instance | Start up service -------------------------------------------------------- 0.64s
2026-09-16 21:35:15.658632 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s
2026-09-16 21:35:15.659056 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-16 21:35:15.659507 | instance | Get list of all loopback devices ---------------------------------------- 0.23s
2026-09-16 21:35:15.659939 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-16 21:35:15.764973 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-16 21:35:15.784744 | instance | INFO     [csi > converge] Executing
2026-09-16 21:35:17.312202 | instance |
2026-09-16 21:35:17.312698 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-16 21:35:17.313123 | instance |
2026-09-16 21:35:17.313529 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:35:17.313991 | instance | Wednesday 16 September 2026  21:35:17 +0000 (0:00:00.008)       0:00:00.008 ***
2026-09-16 21:35:18.449785 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 21:35:18.450227 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 21:35:18.450788 | instance | interpreter could change the meaning of that path. See
2026-09-16 21:35:18.451194 | instance | https://docs.ansible.com/ansible-
2026-09-16 21:35:18.451834 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 21:35:18.464027 | instance | ok: [instance]
2026-09-16 21:35:18.464403 | instance |
2026-09-16 21:35:18.464732 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-16 21:35:18.465205 | instance | Wednesday 16 September 2026  21:35:18 +0000 (0:00:01.151)       0:00:01.160 ***
2026-09-16 21:35:18.522689 | instance | ok: [instance] => {
2026-09-16 21:35:18.523337 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-16 21:35:18.523753 | instance | }
2026-09-16 21:35:18.524110 | instance |
2026-09-16 21:35:18.524470 | instance | PLAY [all] *********************************************************************
2026-09-16 21:35:18.524818 | instance |
2026-09-16 21:35:18.525187 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:35:18.525570 | instance | Wednesday 16 September 2026  21:35:18 +0000 (0:00:00.058)       0:00:01.218 ***
2026-09-16 21:35:19.582318 | instance | ok: [instance]
2026-09-16 21:35:19.582699 | instance |
2026-09-16 21:35:19.583050 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-16 21:35:19.583423 | instance | Wednesday 16 September 2026  21:35:19 +0000 (0:00:01.058)       0:00:02.277 ***
2026-09-16 21:35:19.805448 | instance | ok: [instance]
2026-09-16 21:35:19.805890 | instance |
2026-09-16 21:35:19.806269 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-16 21:35:19.806569 | instance |
2026-09-16 21:35:19.806870 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:35:19.807358 | instance | Wednesday 16 September 2026  21:35:19 +0000 (0:00:00.221)       0:00:02.498 ***
2026-09-16 21:35:20.874186 | instance | ok: [instance]
2026-09-16 21:35:20.874268 | instance |
2026-09-16 21:35:20.874280 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:35:20.874305 | instance | Wednesday 16 September 2026  21:35:20 +0000 (0:00:01.070)       0:00:03.568 ***
2026-09-16 21:35:21.217236 | instance | ok: [instance]
2026-09-16 21:35:21.217390 | instance |
2026-09-16 21:35:21.218094 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:35:21.218171 | instance | Wednesday 16 September 2026  21:35:21 +0000 (0:00:00.344)       0:00:03.913 ***
2026-09-16 21:35:21.285499 | instance | skipping: [instance]
2026-09-16 21:35:21.285589 | instance |
2026-09-16 21:35:21.285935 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-16 21:35:21.285997 | instance | Wednesday 16 September 2026  21:35:21 +0000 (0:00:00.068)       0:00:03.981 ***
2026-09-16 21:35:21.606736 | instance | changed: [instance]
2026-09-16 21:35:21.607459 | instance |
2026-09-16 21:35:21.607688 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:35:21.607710 | instance | Wednesday 16 September 2026  21:35:21 +0000 (0:00:00.320)       0:00:04.302 ***
2026-09-16 21:35:21.704215 | instance | ok: [instance] => {
2026-09-16 21:35:21.704306 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-16 21:35:21.704531 | instance | }
2026-09-16 21:35:21.704797 | instance |
2026-09-16 21:35:21.705029 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:35:21.705244 | instance | Wednesday 16 September 2026  21:35:21 +0000 (0:00:00.097)       0:00:04.400 ***
2026-09-16 21:35:22.530490 | instance | changed: [instance]
2026-09-16 21:35:22.530552 | instance |
2026-09-16 21:35:22.530565 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:35:22.530576 | instance | Wednesday 16 September 2026  21:35:22 +0000 (0:00:00.824)       0:00:05.225 ***
2026-09-16 21:35:22.601985 | instance | skipping: [instance]
2026-09-16 21:35:22.602051 | instance |
2026-09-16 21:35:22.602063 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:35:22.602071 | instance | Wednesday 16 September 2026  21:35:22 +0000 (0:00:00.072)       0:00:05.297 ***
2026-09-16 21:35:22.649772 | instance | skipping: [instance]
2026-09-16 21:35:22.649832 | instance |
2026-09-16 21:35:22.649839 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:35:22.649846 | instance | Wednesday 16 September 2026  21:35:22 +0000 (0:00:00.047)       0:00:05.345 ***
2026-09-16 21:35:22.916422 | instance | ok: [instance]
2026-09-16 21:35:22.916547 | instance |
2026-09-16 21:35:22.916844 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:35:22.916905 | instance | Wednesday 16 September 2026  21:35:22 +0000 (0:00:00.267)       0:00:05.613 ***
2026-09-16 21:35:24.728632 | instance | ok: [instance]
2026-09-16 21:35:24.729403 | instance |
2026-09-16 21:35:24.729460 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:35:24.729468 | instance | Wednesday 16 September 2026  21:35:24 +0000 (0:00:01.811)       0:00:07.424 ***
2026-09-16 21:35:24.818490 | instance | ok: [instance] => {
2026-09-16 21:35:24.819337 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-16 21:35:24.819406 | instance | }
2026-09-16 21:35:24.819414 | instance |
2026-09-16 21:35:24.819740 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:35:24.819751 | instance | Wednesday 16 September 2026  21:35:24 +0000 (0:00:00.089)       0:00:07.514 ***
2026-09-16 21:35:25.793763 | instance | changed: [instance]
2026-09-16 21:35:25.793947 | instance |
2026-09-16 21:35:25.795854 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:35:25.795877 | instance | Wednesday 16 September 2026  21:35:25 +0000 (0:00:00.975)       0:00:08.490 ***
2026-09-16 21:35:28.695602 | instance | changed: [instance]
2026-09-16 21:35:28.695698 | instance |
2026-09-16 21:35:28.695890 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-16 21:35:28.696212 | instance | Wednesday 16 September 2026  21:35:28 +0000 (0:00:02.902)       0:00:11.392 ***
2026-09-16 21:35:28.736031 | instance | skipping: [instance]
2026-09-16 21:35:28.736255 | instance |
2026-09-16 21:35:28.736521 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-16 21:35:28.736789 | instance | Wednesday 16 September 2026  21:35:28 +0000 (0:00:00.040)       0:00:11.432 ***
2026-09-16 21:35:28.781189 | instance | skipping: [instance]
2026-09-16 21:35:28.781560 | instance |
2026-09-16 21:35:28.781821 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-16 21:35:28.782086 | instance | Wednesday 16 September 2026  21:35:28 +0000 (0:00:00.045)       0:00:11.477 ***
2026-09-16 21:35:28.820875 | instance | skipping: [instance]
2026-09-16 21:35:28.821127 | instance |
2026-09-16 21:35:28.821372 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-16 21:35:28.821608 | instance | Wednesday 16 September 2026  21:35:28 +0000 (0:00:00.039)       0:00:11.517 ***
2026-09-16 21:35:35.400681 | instance | changed: [instance]
2026-09-16 21:35:35.400860 | instance |
2026-09-16 21:35:35.401144 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-16 21:35:35.401389 | instance | Wednesday 16 September 2026  21:35:35 +0000 (0:00:06.579)       0:00:18.096 ***
2026-09-16 21:35:36.062095 | instance | changed: [instance]
2026-09-16 21:35:36.062333 | instance |
2026-09-16 21:35:36.062641 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-16 21:35:36.062838 | instance | Wednesday 16 September 2026  21:35:36 +0000 (0:00:00.661)       0:00:18.758 ***
2026-09-16 21:35:37.109863 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-16 21:35:37.110073 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-16 21:35:37.110081 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-16 21:35:37.110085 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-16 21:35:37.110095 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-16 21:35:37.110100 | instance |
2026-09-16 21:35:37.110110 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-16 21:35:37.110115 | instance | Wednesday 16 September 2026  21:35:37 +0000 (0:00:01.047)       0:00:19.805 ***
2026-09-16 21:35:37.770603 | instance | changed: [instance]
2026-09-16 21:35:37.770687 | instance |
2026-09-16 21:35:37.771426 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-16 21:35:37.771501 | instance | Wednesday 16 September 2026  21:35:37 +0000 (0:00:00.630)       0:00:20.436 ***
2026-09-16 21:35:37.771507 | instance |
2026-09-16 21:35:37.771512 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 21:35:37.771525 | instance | Wednesday 16 September 2026  21:35:37 +0000 (0:00:00.030)       0:00:20.467 ***
2026-09-16 21:35:38.754502 | instance | ok: [instance]
2026-09-16 21:35:38.754578 | instance |
2026-09-16 21:35:38.754869 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-16 21:35:38.754951 | instance | Wednesday 16 September 2026  21:35:38 +0000 (0:00:00.983)       0:00:21.451 ***
2026-09-16 21:35:39.294640 | instance | changed: [instance]
2026-09-16 21:35:39.294767 | instance |
2026-09-16 21:35:39.295059 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-16 21:35:39.295124 | instance | Wednesday 16 September 2026  21:35:39 +0000 (0:00:00.540)       0:00:21.991 ***
2026-09-16 21:35:39.931878 | instance | changed: [instance]
2026-09-16 21:35:39.932396 | instance |
2026-09-16 21:35:39.932417 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:35:39.932425 | instance | Wednesday 16 September 2026  21:35:39 +0000 (0:00:00.637)       0:00:22.628 ***
2026-09-16 21:35:40.192816 | instance | ok: [instance]
2026-09-16 21:35:40.192906 | instance |
2026-09-16 21:35:40.193302 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:35:40.193367 | instance | Wednesday 16 September 2026  21:35:40 +0000 (0:00:00.261)       0:00:22.889 ***
2026-09-16 21:35:40.270424 | instance | ok: [instance] => {
2026-09-16 21:35:40.270519 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-16 21:35:40.270540 | instance | }
2026-09-16 21:35:40.270552 | instance |
2026-09-16 21:35:40.270845 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:35:40.270906 | instance | Wednesday 16 September 2026  21:35:40 +0000 (0:00:00.077)       0:00:22.967 ***
2026-09-16 21:35:41.130597 | instance | changed: [instance]
2026-09-16 21:35:41.131406 | instance |
2026-09-16 21:35:41.131610 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:35:41.131628 | instance | Wednesday 16 September 2026  21:35:41 +0000 (0:00:00.859)       0:00:23.826 ***
2026-09-16 21:35:45.623169 | instance | changed: [instance]
2026-09-16 21:35:45.623314 | instance |
2026-09-16 21:35:45.623651 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-16 21:35:45.623707 | instance | Wednesday 16 September 2026  21:35:45 +0000 (0:00:04.493)       0:00:28.320 ***
2026-09-16 21:35:46.766165 | instance | ok: [instance]
2026-09-16 21:35:46.766962 | instance |
2026-09-16 21:35:46.767037 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-16 21:35:46.767051 | instance | Wednesday 16 September 2026  21:35:46 +0000 (0:00:01.142)       0:00:29.462 ***
2026-09-16 21:35:47.203639 | instance | changed: [instance]
2026-09-16 21:35:47.203756 | instance |
2026-09-16 21:35:47.204242 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-16 21:35:47.204311 | instance | Wednesday 16 September 2026  21:35:47 +0000 (0:00:00.438)       0:00:29.900 ***
2026-09-16 21:35:47.764544 | instance | changed: [instance]
2026-09-16 21:35:47.764597 | instance |
2026-09-16 21:35:47.764603 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-16 21:35:47.764609 | instance | Wednesday 16 September 2026  21:35:47 +0000 (0:00:00.560)       0:00:30.460 ***
2026-09-16 21:35:48.365134 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-16 21:35:48.365188 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-16 21:35:48.365194 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-16 21:35:48.365199 | instance |
2026-09-16 21:35:48.365204 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-16 21:35:48.365208 | instance | Wednesday 16 September 2026  21:35:48 +0000 (0:00:00.600)       0:00:31.061 ***
2026-09-16 21:35:48.865626 | instance | changed: [instance]
2026-09-16 21:35:48.865697 | instance |
2026-09-16 21:35:48.865709 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-16 21:35:48.865719 | instance | Wednesday 16 September 2026  21:35:48 +0000 (0:00:00.499)       0:00:31.560 ***
2026-09-16 21:35:49.410640 | instance | changed: [instance]
2026-09-16 21:35:49.410748 | instance |
2026-09-16 21:35:49.411493 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-16 21:35:49.411572 | instance | Wednesday 16 September 2026  21:35:49 +0000 (0:00:00.515)       0:00:32.075 ***
2026-09-16 21:35:49.411579 | instance |
2026-09-16 21:35:49.411595 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 21:35:49.411601 | instance | Wednesday 16 September 2026  21:35:49 +0000 (0:00:00.030)       0:00:32.106 ***
2026-09-16 21:35:50.192007 | instance | ok: [instance]
2026-09-16 21:35:50.192602 | instance |
2026-09-16 21:35:50.192654 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-16 21:35:50.192669 | instance | Wednesday 16 September 2026  21:35:50 +0000 (0:00:00.782)       0:00:32.888 ***
2026-09-16 21:35:51.002728 | instance | changed: [instance]
2026-09-16 21:35:51.002789 | instance |
2026-09-16 21:35:51.002796 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-16 21:35:51.002802 | instance | Wednesday 16 September 2026  21:35:50 +0000 (0:00:00.810)       0:00:33.699 ***
2026-09-16 21:35:51.634675 | instance | changed: [instance]
2026-09-16 21:35:51.635242 | instance |
2026-09-16 21:35:51.635311 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-16 21:35:51.635322 | instance | Wednesday 16 September 2026  21:35:51 +0000 (0:00:00.632)       0:00:34.331 ***
2026-09-16 21:35:51.714554 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-16 21:35:51.715410 | instance |
2026-09-16 21:35:51.715483 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-16 21:35:51.715496 | instance | Wednesday 16 September 2026  21:35:51 +0000 (0:00:00.079)       0:00:34.410 ***
2026-09-16 21:35:58.237830 | instance | changed: [instance]
2026-09-16 21:35:58.237959 | instance |
2026-09-16 21:35:58.238304 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-16 21:35:58.238369 | instance | Wednesday 16 September 2026  21:35:58 +0000 (0:00:06.523)       0:00:40.934 ***
2026-09-16 21:35:58.948664 | instance | ok: [instance] => (item=chronyd)
2026-09-16 21:35:58.948740 | instance | ok: [instance] => (item=sshd)
2026-09-16 21:35:58.949122 | instance |
2026-09-16 21:35:58.949188 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-16 21:35:58.949197 | instance | Wednesday 16 September 2026  21:35:58 +0000 (0:00:00.710)       0:00:41.645 ***
2026-09-16 21:36:11.916653 | instance | changed: [instance]
2026-09-16 21:36:11.916733 | instance |
2026-09-16 21:36:11.916740 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-16 21:36:11.916777 | instance | Wednesday 16 September 2026  21:36:11 +0000 (0:00:12.967)       0:00:54.612 ***
2026-09-16 21:36:12.124985 | instance | ok: [instance]
2026-09-16 21:36:12.125095 | instance |
2026-09-16 21:36:12.125126 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-16 21:36:12.125285 | instance | Wednesday 16 September 2026  21:36:12 +0000 (0:00:00.209)       0:00:54.822 ***
2026-09-16 21:36:12.821525 | instance | changed: [instance]
2026-09-16 21:36:12.821675 | instance |
2026-09-16 21:36:12.822256 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-16 21:36:12.822323 | instance | Wednesday 16 September 2026  21:36:12 +0000 (0:00:00.695)       0:00:55.517 ***
2026-09-16 21:36:13.208869 | instance | changed: [instance]
2026-09-16 21:36:13.209097 | instance |
2026-09-16 21:36:13.209169 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-16 21:36:13.209353 | instance | Wednesday 16 September 2026  21:36:13 +0000 (0:00:00.388)       0:00:55.905 ***
2026-09-16 21:36:13.319574 | instance | ok: [instance]
2026-09-16 21:36:13.319636 | instance |
2026-09-16 21:36:13.319903 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-16 21:36:13.319920 | instance | Wednesday 16 September 2026  21:36:13 +0000 (0:00:00.111)       0:00:56.016 ***
2026-09-16 21:36:13.593997 | instance | ok: [instance] => (item=instance)
2026-09-16 21:36:13.594136 | instance |
2026-09-16 21:36:13.594151 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-16 21:36:13.594438 | instance | Wednesday 16 September 2026  21:36:13 +0000 (0:00:00.273)       0:00:56.290 ***
2026-09-16 21:36:13.641606 | instance | ok: [instance]
2026-09-16 21:36:13.641699 | instance |
2026-09-16 21:36:13.642045 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-16 21:36:13.642104 | instance | Wednesday 16 September 2026  21:36:13 +0000 (0:00:00.048)       0:00:56.338 ***
2026-09-16 21:36:13.728480 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-16 21:36:13.728571 | instance |
2026-09-16 21:36:13.728592 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-16 21:36:13.728759 | instance | Wednesday 16 September 2026  21:36:13 +0000 (0:00:00.087)       0:00:56.425 ***
2026-09-16 21:36:14.118650 | instance | changed: [instance]
2026-09-16 21:36:14.119402 | instance |
2026-09-16 21:36:14.119436 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-16 21:36:14.119642 | instance | Wednesday 16 September 2026  21:36:14 +0000 (0:00:00.388)       0:00:56.814 ***
2026-09-16 21:36:14.944979 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-16 21:36:14.945096 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-16 21:36:14.945273 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-16 21:36:14.945528 | instance |
2026-09-16 21:36:14.945643 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-16 21:36:14.945801 | instance | Wednesday 16 September 2026  21:36:14 +0000 (0:00:00.826)       0:00:57.641 ***
2026-09-16 21:37:44.269264 | instance | ok: [instance]
2026-09-16 21:37:44.269791 | instance |
2026-09-16 21:37:44.269842 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-16 21:37:44.269850 | instance | Wednesday 16 September 2026  21:37:44 +0000 (0:01:29.324)       0:02:26.965 ***
2026-09-16 21:37:44.526944 | instance | changed: [instance]
2026-09-16 21:37:44.527029 | instance |
2026-09-16 21:37:44.527168 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-16 21:37:44.527397 | instance | Wednesday 16 September 2026  21:37:44 +0000 (0:00:00.257)       0:02:27.223 ***
2026-09-16 21:37:44.569993 | instance | ok: [instance]
2026-09-16 21:37:44.570073 | instance |
2026-09-16 21:37:44.570228 | instance | TASK [Install Ceph host] *******************************************************
2026-09-16 21:37:44.570383 | instance | Wednesday 16 September 2026  21:37:44 +0000 (0:00:00.042)       0:02:27.266 ***
2026-09-16 21:37:44.649348 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-16 21:37:44.649492 | instance |
2026-09-16 21:37:44.649533 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-16 21:37:44.649690 | instance | Wednesday 16 September 2026  21:37:44 +0000 (0:00:00.079)       0:02:27.346 ***
2026-09-16 21:37:46.286337 | instance | ok: [instance]
2026-09-16 21:37:46.286462 | instance |
2026-09-16 21:37:46.286640 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-16 21:37:46.286797 | instance | Wednesday 16 September 2026  21:37:46 +0000 (0:00:01.636)       0:02:28.983 ***
2026-09-16 21:37:46.334679 | instance | ok: [instance] => (item=instance)
2026-09-16 21:37:46.334884 | instance |
2026-09-16 21:37:46.335135 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-16 21:37:46.335476 | instance | Wednesday 16 September 2026  21:37:46 +0000 (0:00:00.048)       0:02:29.031 ***
2026-09-16 21:37:46.740156 | instance | ok: [instance]
2026-09-16 21:37:46.740467 | instance |
2026-09-16 21:37:46.740796 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-16 21:37:46.741150 | instance | Wednesday 16 September 2026  21:37:46 +0000 (0:00:00.405)       0:02:29.437 ***
2026-09-16 21:37:49.330191 | instance | ok: [instance]
2026-09-16 21:37:49.330272 | instance |
2026-09-16 21:37:49.330287 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-16 21:37:49.330299 | instance | Wednesday 16 September 2026  21:37:49 +0000 (0:00:02.588)       0:02:32.025 ***
2026-09-16 21:38:29.749395 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-16 21:38:29.749468 | 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_66f_2hqt/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_66f_2hqt/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_66f_2hqt/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_66f_2hqt/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-16T21:38:29.570+0000 7fcd885a3640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD+C6tq+n9aCSAAjE1J1Uq+z/PVT1yBWj71viOYP6Id9CjjO9tIIqxGOek=: Malformed input [buffer:3]\n2026-09-16T21:38:29.570+0000 7fcd885a3640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:38:29.574+0000 7fcd885a3640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD+C6tq+n9aCSAAjE1J1Uq+z/PVT1yBWj71viOYP6Id9CjjO9tIIqxGOek=: Malformed input [buffer:3]\n2026-09-16T21:38:29.574+0000 7fcd885a3640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:38:29.574+0000 7fcd885a3640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD+C6tq+n9aCSAAjE1J1Uq+z/PVT1yBWj71viOYP6Id9CjjO9tIIqxGOek=: Malformed input [buffer:3]\n2026-09-16T21:38:29.574+0000 7fcd885a3640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:38:29.574+0000 7fcd885a3640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-16 21:38:29.750125 | instance |
2026-09-16 21:38:29.750315 | instance | PLAY RECAP *********************************************************************
2026-09-16 21:38:29.750641 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-16 21:38:29.751312 | instance |
2026-09-16 21:38:29.751326 | instance | Wednesday 16 September 2026  21:38:29 +0000 (0:00:40.422)       0:03:12.447 ***
2026-09-16 21:38:29.751331 | instance | ===============================================================================
2026-09-16 21:38:29.751335 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 89.32s
2026-09-16 21:38:29.752054 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 40.42s
2026-09-16 21:38:29.752107 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 12.97s
2026-09-16 21:38:29.752118 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.58s
2026-09-16 21:38:29.752127 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.52s
2026-09-16 21:38:29.752142 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.49s
2026-09-16 21:38:29.754916 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.90s
2026-09-16 21:38:29.754949 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.59s
2026-09-16 21:38:29.754970 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.81s
2026-09-16 21:38:29.754979 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.77s
2026-09-16 21:38:29.754988 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.64s
2026-09-16 21:38:29.754995 | instance | Gathering Facts --------------------------------------------------------- 1.15s
2026-09-16 21:38:29.755003 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.14s
2026-09-16 21:38:29.755010 | instance | Gathering Facts --------------------------------------------------------- 1.07s
2026-09-16 21:38:29.755018 | instance | Gathering Facts --------------------------------------------------------- 1.06s
2026-09-16 21:38:29.755025 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.05s
2026-09-16 21:38:29.755033 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.98s
2026-09-16 21:38:29.755040 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.86s
2026-09-16 21:38:29.755048 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.83s
2026-09-16 21:38:29.755055 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.82s
2026-09-16 21:38:29.854683 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:38:29.854917 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-16 21:38:29.855060 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:38:30.052981 | instance | ERROR
2026-09-16 21:38:30.053321 | instance | {
2026-09-16 21:38:30.053392 | instance |   "delta": "0:04:07.889044",
2026-09-16 21:38:30.053440 | instance |   "end": "2026-09-16 21:38:29.922182",
2026-09-16 21:38:30.053490 | instance |   "msg": "non-zero return code",
2026-09-16 21:38:30.053540 | instance |   "rc": 2,
2026-09-16 21:38:30.053589 | instance |   "start": "2026-09-16 21:34:22.033138"
2026-09-16 21:38:30.053634 | instance | }
failure
2026-09-16 21:38:30.062450 | 
2026-09-16 21:38:30.062559 | PLAY RECAP
2026-09-16 21:38:30.062646 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:38:30.062691 | 
2026-09-16 21:38:30.215991 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 21:38:30.222463 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 21:38:30.928196 | 
2026-09-16 21:38:30.928382 | PLAY [all]
2026-09-16 21:38:30.944414 | 
2026-09-16 21:38:30.944584 | TASK [gather-host-logs : creating directory for system status]
2026-09-16 21:38:31.313030 | instance | changed
2026-09-16 21:38:31.324024 | 
2026-09-16 21:38:31.324235 | TASK [gather-host-logs : Get logs for each host]
2026-09-16 21:38:31.707020 | instance | + systemd-cgls --full --all --no-pager
2026-09-16 21:38:31.716882 | instance | + ip addr
2026-09-16 21:38:31.720152 | instance | + ip route
2026-09-16 21:38:31.721845 | instance | + lsblk
2026-09-16 21:38:31.727634 | instance | + mount
2026-09-16 21:38:31.730700 | instance | + docker images
2026-09-16 21:38:31.758437 | instance | + brctl show
2026-09-16 21:38:31.758864 | instance | /bin/bash: line 8: brctl: command not found
2026-09-16 21:38:31.759107 | instance | + ip neigh
2026-09-16 21:38:31.760658 | instance | + bridge fdb show
2026-09-16 21:38:31.764124 | instance | + ps aux --sort=-%mem
2026-09-16 21:38:31.777085 | instance | + dpkg -l
2026-09-16 21:38:31.791447 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-16 21:38:31.791887 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-16 21:38:31.811256 | instance | + '[' '!' -z '' ']'
2026-09-16 21:38:31.874117 | instance | ok: Runtime: 0:00:00.109908
2026-09-16 21:38:31.885362 | 
2026-09-16 21:38:31.885499 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-16 21:38:32.621612 | instance | changed:
2026-09-16 21:38:32.622035 | instance | created directory /var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/work/logs/instance
2026-09-16 21:38:32.622391 | instance | cd+++++++++ system/
2026-09-16 21:38:32.622450 | instance | >f+++++++++ system/brctl-show.txt
2026-09-16 21:38:32.622493 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-16 21:38:32.622531 | instance | >f+++++++++ system/docker-images.txt
2026-09-16 21:38:32.622572 | instance | >f+++++++++ system/ip-addr.txt
2026-09-16 21:38:32.622613 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-16 21:38:32.622651 | instance | >f+++++++++ system/ip-route.txt
2026-09-16 21:38:32.622693 | instance | >f+++++++++ system/lsblk.txt
2026-09-16 21:38:32.622730 | instance | >f+++++++++ system/mount.txt
2026-09-16 21:38:32.622766 | instance | >f+++++++++ system/packages.txt
2026-09-16 21:38:32.622803 | instance | >f+++++++++ system/ps.txt
2026-09-16 21:38:32.622839 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-16 21:38:32.642030 | 
2026-09-16 21:38:32.642208 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-16 21:38:32.885894 | instance | changed: "values"
2026-09-16 21:38:33.061596 | instance | changed: "releases"
2026-09-16 21:38:33.079977 | 
2026-09-16 21:38:33.080234 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-16 21:38:33.342446 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-16 21:38:33.630976 | instance | ok: Runtime: 0:00:00.005875
2026-09-16 21:38:33.640531 | 
2026-09-16 21:38:33.640696 | TASK [helm-release-status : Downloads logs to executor]
2026-09-16 21:38:34.186204 | instance | changed:
2026-09-16 21:38:34.186368 | instance | cd+++++++++ helm/
2026-09-16 21:38:34.186393 | instance | cd+++++++++ helm/releases/
2026-09-16 21:38:34.186413 | instance | cd+++++++++ helm/values/
2026-09-16 21:38:34.194239 | 
2026-09-16 21:38:34.194308 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-16 21:38:34.417830 | instance | changed
2026-09-16 21:38:34.427665 | 
2026-09-16 21:38:34.427938 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-16 21:38:34.733799 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:38:34.734390 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:38:34.740566 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:38:34.741592 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:38:34.742062 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:38:34.743640 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:38:34.745036 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:38:34.746508 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:38:34.746566 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:38:34.748483 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:38:34.749594 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:38:34.750730 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:38:34.971885 | instance | ok: Runtime: 0:00:00.025101
2026-09-16 21:38:34.981411 | 
2026-09-16 21:38:34.981545 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-16 21:38:35.198145 | instance | changed
2026-09-16 21:38:35.207684 | 
2026-09-16 21:38:35.207868 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-16 21:38:35.478491 | instance | environment: line 5: kubectl: command not found
2026-09-16 21:38:35.478654 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:38:35.479077 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:38:35.479507 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:38:35.756064 | instance | ok: Runtime: 0:00:00.008998
2026-09-16 21:38:35.764270 | 
2026-09-16 21:38:35.764480 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-16 21:38:36.388543 | instance | changed:
2026-09-16 21:38:36.388801 | instance | cd+++++++++ objects/
2026-09-16 21:38:36.388856 | instance | cd+++++++++ objects/cluster/
2026-09-16 21:38:36.388900 | instance | cd+++++++++ objects/namespaced/
2026-09-16 21:38:36.405984 | 
2026-09-16 21:38:36.406162 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-16 21:38:36.652545 | instance | changed
2026-09-16 21:38:36.663251 | 
2026-09-16 21:38:36.663436 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-16 21:38:36.899610 | instance | changed
2026-09-16 21:38:36.909457 | 
2026-09-16 21:38:36.909622 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-16 21:38:37.184950 | instance | environment: line 3: kubectl: command not found
2026-09-16 21:38:37.472442 | instance | ok: Runtime: 0:00:00.007284
2026-09-16 21:38:37.481664 | 
2026-09-16 21:38:37.481801 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-16 21:38:38.061283 | instance | changed:
2026-09-16 21:38:38.061508 | instance | cd+++++++++ pod-logs/
2026-09-16 21:38:38.061549 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-16 21:38:38.073622 | 
2026-09-16 21:38:38.073709 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-16 21:38:38.299791 | instance | changed
2026-09-16 21:38:38.309651 | 
2026-09-16 21:38:38.309829 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-16 21:38:38.555959 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 21:38:38.855287 | instance | ok: Runtime: 0:00:00.051367
2026-09-16 21:38:38.861822 | 
2026-09-16 21:38:38.861948 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-16 21:38:39.103786 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 21:38:39.150145 | instance | ceph-mgr endpoints:
2026-09-16 21:38:39.403530 | instance | ok: Runtime: 0:00:00.053135
2026-09-16 21:38:39.413858 | 
2026-09-16 21:38:39.413998 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-16 21:38:39.651460 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-16 21:38:39.960187 | instance | ok: Runtime: 0:00:00.041498
2026-09-16 21:38:39.972693 | 
2026-09-16 21:38:39.972861 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-16 21:38:40.503949 | instance | changed: cd+++++++++ prometheus/
2026-09-16 21:38:40.517241 | 
2026-09-16 21:38:40.517341 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-16 21:38:40.726543 | instance | changed
2026-09-16 21:38:40.735879 | 
2026-09-16 21:38:40.736022 | TASK [gather-selenium-data : Get selenium data]
2026-09-16 21:38:41.035217 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-16 21:38:41.036931 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-16 21:38:41.281937 | instance | ERROR
2026-09-16 21:38:41.282248 | instance | {
2026-09-16 21:38:41.282309 | instance |   "delta": "0:00:00.007317",
2026-09-16 21:38:41.282350 | instance |   "end": "2026-09-16 21:38:41.037382",
2026-09-16 21:38:41.282388 | instance |   "msg": "non-zero return code",
2026-09-16 21:38:41.282436 | instance |   "rc": 1,
2026-09-16 21:38:41.282491 | instance |   "start": "2026-09-16 21:38:41.030065"
2026-09-16 21:38:41.282536 | instance | }
2026-09-16 21:38:41.282583 | instance | ERROR: Ignoring Errors
2026-09-16 21:38:41.293574 | 
2026-09-16 21:38:41.293749 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-16 21:38:41.812916 | instance | changed: cd+++++++++ selenium/
2026-09-16 21:38:41.829337 | 
2026-09-16 21:38:41.829506 | PLAY RECAP
2026-09-16 21:38:41.829600 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-16 21:38:41.829645 | 
2026-09-16 21:38:42.026479 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 21:38:42.030513 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 21:38:42.734186 | 
2026-09-16 21:38:42.734362 | PLAY [all]
2026-09-16 21:38:42.747179 | 
2026-09-16 21:38:42.747355 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-16 21:38:42.792742 | instance | skipping: Conditional result was False
2026-09-16 21:38:42.799813 | 
2026-09-16 21:38:42.800007 | TASK [fetch-output : Set log path for single node]
2026-09-16 21:38:42.853867 | instance | ok
2026-09-16 21:38:42.863386 | 
2026-09-16 21:38:42.863537 | LOOP [fetch-output : Ensure local output dirs]
2026-09-16 21:38:43.284978 | instance -> localhost | ok: "/var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/work/logs"
2026-09-16 21:38:43.509983 | instance -> localhost | changed: "/var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/work/artifacts"
2026-09-16 21:38:43.744808 | instance -> localhost | changed: "/var/lib/zuul/builds/3fac56937e094cdea045e540c9f96216/work/docs"
2026-09-16 21:38:43.758257 | 
2026-09-16 21:38:43.758446 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-16 21:38:44.507859 | instance | changed: .d..t...... ./
2026-09-16 21:38:44.508207 | instance | changed: All items complete
2026-09-16 21:38:44.508262 | 
2026-09-16 21:38:44.996586 | instance | changed: .d..t...... ./
2026-09-16 21:38:45.520044 | instance | changed: .d..t...... ./
2026-09-16 21:38:45.544851 | 
2026-09-16 21:38:45.545063 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-16 21:38:46.045372 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007332
2026-09-16 21:38:46.293511 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006867
2026-09-16 21:38:46.309973 | 
2026-09-16 21:38:46.310118 | PLAY [all]
2026-09-16 21:38:46.318247 | 
2026-09-16 21:38:46.318413 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-16 21:38:46.778184 | instance | changed
2026-09-16 21:38:46.788396 | 
2026-09-16 21:38:46.788577 | PLAY RECAP
2026-09-16 21:38:46.788715 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-16 21:38:46.788768 | 
2026-09-16 21:38:46.970602 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 21:38:46.972729 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-16 21:38:47.667680 | 
2026-09-16 21:38:47.694854 | PLAY [localhost]
2026-09-16 21:38:47.708440 | 
2026-09-16 21:38:47.708679 | TASK [Generate Zuul manifest]
2026-09-16 21:38:47.727110 | localhost | ok
2026-09-16 21:38:47.761566 | 
2026-09-16 21:38:47.761814 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-16 21:38:48.319424 | localhost | changed
2026-09-16 21:38:48.340887 | 
2026-09-16 21:38:48.341160 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-16 21:38:48.369639 | localhost | ok
2026-09-16 21:38:48.378877 | 
2026-09-16 21:38:48.379037 | TASK [Upload logs]
2026-09-16 21:38:48.399783 | localhost | ok
2026-09-16 21:38:48.494494 | 
2026-09-16 21:38:48.494662 | TASK [Set zuul-log-path fact]
2026-09-16 21:38:48.514262 | localhost | ok
2026-09-16 21:38:48.531014 | 
2026-09-16 21:38:48.531218 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 21:38:48.561005 | localhost | ok
2026-09-16 21:38:48.569905 | 
2026-09-16 21:38:48.570047 | TASK [upload-logs : Create log directories]
2026-09-16 21:38:49.038776 | localhost | changed
2026-09-16 21:38:49.045632 | 
2026-09-16 21:38:49.045801 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-16 21:38:49.510725 | localhost -> localhost | ok: Runtime: 0:00:00.005605
2026-09-16 21:38:49.517792 | 
2026-09-16 21:38:49.521047 | TASK [upload-logs : Upload logs to log server]
2026-09-16 21:38:50.032897 | localhost | Output suppressed because no_log was given
2026-09-16 21:38:50.091761 | 
2026-09-16 21:38:50.091899 | LOOP [upload-logs : Compress console log and json output]
2026-09-16 21:38:50.153413 | localhost | skipping: Conditional result was False
2026-09-16 21:38:50.160274 | localhost | skipping: Conditional result was False
2026-09-16 21:38:50.174539 | 
2026-09-16 21:38:50.174731 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-16 21:38:50.224293 | localhost | skipping: Conditional result was False
2026-09-16 21:38:50.224713 | 
2026-09-16 21:38:50.227917 | localhost | skipping: Conditional result was False
2026-09-16 21:38:50.238870 | 
2026-09-16 21:38:50.239120 | LOOP [upload-logs : Upload console log and json output]