2026-08-21 02:26:30.096103 | Job console starting
2026-08-21 02:26:30.136262 | Updating git repos
2026-08-21 02:26:32.534351 | Cloning repos into workspace
2026-08-21 02:26:47.880243 | Restoring repo states
2026-08-21 02:27:00.898016 | Merging changes
2026-08-21 02:27:02.100776 | Checking out repos
2026-08-21 02:27:11.442238 | Preparing playbooks
2026-08-21 02:27:23.138104 | Running Ansible setup
2026-08-21 02:27:27.028135 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-21 02:27:27.692516 | 
2026-08-21 02:27:27.748765 | PLAY [localhost]
2026-08-21 02:27:27.764280 | 
2026-08-21 02:27:27.764466 | TASK [Gathering Facts]
2026-08-21 02:27:28.748530 | localhost | ok
2026-08-21 02:27:28.759099 | 
2026-08-21 02:27:28.759223 | TASK [Setup log path fact]
2026-08-21 02:27:28.781519 | localhost | ok
2026-08-21 02:27:28.796507 | 
2026-08-21 02:27:28.796584 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-21 02:27:28.837735 | localhost | ok
2026-08-21 02:27:28.846076 | 
2026-08-21 02:27:28.846210 | TASK [emit-job-header : Print job information]
2026-08-21 02:27:28.888332 | # Job Information
2026-08-21 02:27:28.888525 | Ansible Version: 2.16.18
2026-08-21 02:27:28.888574 | Job: atmosphere-molecule-csi-rbd
2026-08-21 02:27:28.888605 | Pipeline: check
2026-08-21 02:27:28.888633 | Executor: 2d72f0692154
2026-08-21 02:27:28.888660 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4095
2026-08-21 02:27:28.888694 | Event ID: b33d2710-9d06-11f1-909b-350a4cb2cb44
2026-08-21 02:27:28.893483 | 
2026-08-21 02:27:28.893587 | LOOP [emit-job-header : Print node information]
2026-08-21 02:27:29.007514 | localhost | ok:
2026-08-21 02:27:29.007772 | localhost | # Node Information
2026-08-21 02:27:29.007816 | localhost | Inventory Hostname: instance
2026-08-21 02:27:29.007850 | localhost | Hostname: np0000190388
2026-08-21 02:27:29.007880 | localhost | Username: zuul
2026-08-21 02:27:29.007916 | localhost | Distro: Ubuntu 22.04
2026-08-21 02:27:29.007947 | localhost | Provider: yul1
2026-08-21 02:27:29.007977 | localhost | Region: ca-ymq-1
2026-08-21 02:27:29.008007 | localhost | Label: ubuntu-jammy
2026-08-21 02:27:29.008035 | localhost | Product Name: OpenStack Nova
2026-08-21 02:27:29.008064 | localhost | Interface IP: 199.204.45.156
2026-08-21 02:27:29.022166 | 
2026-08-21 02:27:29.022354 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-21 02:27:29.474567 | localhost -> localhost | changed
2026-08-21 02:27:29.482166 | 
2026-08-21 02:27:29.482304 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-21 02:27:30.581075 | localhost -> localhost | changed
2026-08-21 02:27:30.590899 | 
2026-08-21 02:27:30.591005 | PLAY [all]
2026-08-21 02:27:30.601061 | 
2026-08-21 02:27:30.601137 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-21 02:27:30.860185 | instance -> localhost | ok
2026-08-21 02:27:30.879072 | 
2026-08-21 02:27:30.879309 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-21 02:27:30.918507 | instance | ok
2026-08-21 02:27:30.945041 | instance | included: /var/lib/zuul/builds/360581e935254354b0cb57058962b37a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-21 02:27:30.953803 | 
2026-08-21 02:27:30.953956 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-21 02:27:31.938308 | instance -> localhost | Generating public/private rsa key pair.
2026-08-21 02:27:31.938526 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/360581e935254354b0cb57058962b37a/work/360581e935254354b0cb57058962b37a_id_rsa
2026-08-21 02:27:31.938567 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/360581e935254354b0cb57058962b37a/work/360581e935254354b0cb57058962b37a_id_rsa.pub
2026-08-21 02:27:31.938600 | instance -> localhost | The key fingerprint is:
2026-08-21 02:27:31.938631 | instance -> localhost | SHA256:yioOJPX7Qc0+rH2UHNGOgzpIbtEAxlSyJHn+fP9sgq0 zuul-build-sshkey
2026-08-21 02:27:31.938679 | instance -> localhost | The key's randomart image is:
2026-08-21 02:27:31.938710 | instance -> localhost | +---[RSA 3072]----+
2026-08-21 02:27:31.938856 | instance -> localhost | |.+*o.      .     |
2026-08-21 02:27:31.938893 | instance -> localhost | |.+oo.     . .    |
2026-08-21 02:27:31.938925 | instance -> localhost | | oo  o   . +     |
2026-08-21 02:27:31.938956 | instance -> localhost | | ...o .o. + .    |
2026-08-21 02:27:31.938986 | instance -> localhost | |.. =.o..S. +     |
2026-08-21 02:27:31.939015 | instance -> localhost | |o   *+++  +      |
2026-08-21 02:27:31.939044 | instance -> localhost | |.  ...+=+.       |
2026-08-21 02:27:31.939073 | instance -> localhost | | ..  o.++oo      |
2026-08-21 02:27:31.939105 | instance -> localhost | | .... E..=o      |
2026-08-21 02:27:31.939135 | instance -> localhost | +----[SHA256]-----+
2026-08-21 02:27:31.939210 | instance -> localhost | ok: Runtime: 0:00:00.478800
2026-08-21 02:27:31.948772 | 
2026-08-21 02:27:31.948990 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-21 02:27:31.987791 | instance | ok
2026-08-21 02:27:31.996591 | instance | included: /var/lib/zuul/builds/360581e935254354b0cb57058962b37a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-21 02:27:32.006119 | 
2026-08-21 02:27:32.006186 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-21 02:27:32.030117 | instance | skipping: Conditional result was False
2026-08-21 02:27:32.037864 | 
2026-08-21 02:27:32.037962 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-21 02:27:32.518372 | instance | changed
2026-08-21 02:27:32.528309 | 
2026-08-21 02:27:32.528446 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-21 02:27:32.732262 | instance | ok
2026-08-21 02:27:32.802609 | 
2026-08-21 02:27:32.802777 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-21 02:27:33.297183 | instance | changed
2026-08-21 02:27:33.305674 | 
2026-08-21 02:27:33.305817 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-21 02:27:33.756802 | instance | changed
2026-08-21 02:27:33.765421 | 
2026-08-21 02:27:33.765524 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-21 02:27:33.790919 | instance | skipping: Conditional result was False
2026-08-21 02:27:33.802403 | 
2026-08-21 02:27:33.802496 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-21 02:27:34.269105 | instance -> localhost | changed
2026-08-21 02:27:34.288643 | 
2026-08-21 02:27:34.288787 | TASK [add-build-sshkey : Add back temp key]
2026-08-21 02:27:34.616267 | instance -> localhost | Identity added: /var/lib/zuul/builds/360581e935254354b0cb57058962b37a/work/360581e935254354b0cb57058962b37a_id_rsa (zuul-build-sshkey)
2026-08-21 02:27:34.616493 | instance -> localhost | ok: Runtime: 0:00:00.013024
2026-08-21 02:27:34.623628 | 
2026-08-21 02:27:34.623719 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-21 02:27:35.004426 | instance | ok
2026-08-21 02:27:35.012238 | 
2026-08-21 02:27:35.012363 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-21 02:27:35.036799 | instance | skipping: Conditional result was False
2026-08-21 02:27:35.051992 | 
2026-08-21 02:27:35.052164 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-21 02:27:35.392677 | instance | ok
2026-08-21 02:27:35.404004 | 
2026-08-21 02:27:35.404097 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-21 02:27:39.949482 | instance | Output suppressed because no_log was given
2026-08-21 02:27:39.979067 | 
2026-08-21 02:27:39.979243 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-21 02:27:40.171347 | instance | ok: "logs"
2026-08-21 02:27:40.171786 | instance | ok: All items complete
2026-08-21 02:27:40.171840 | 
2026-08-21 02:27:40.363500 | instance | ok: "artifacts"
2026-08-21 02:27:40.527452 | instance | ok: "docs"
2026-08-21 02:27:40.541874 | 
2026-08-21 02:27:40.541992 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-21 02:27:40.737468 | instance | changed: "logs"
2026-08-21 02:27:40.908410 | instance | changed: "artifacts"
2026-08-21 02:27:41.070006 | instance | changed: "docs"
2026-08-21 02:27:41.085903 | 
2026-08-21 02:27:41.086032 | PLAY RECAP
2026-08-21 02:27:41.086086 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-21 02:27:41.086116 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-21 02:27:41.086138 | 
2026-08-21 02:27:41.288906 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-21 02:27:41.295686 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-21 02:27:42.057469 | 
2026-08-21 02:27:42.057600 | PLAY [all]
2026-08-21 02:27:42.071699 | 
2026-08-21 02:27:42.071856 | TASK [setup-uv : Extract archive]
2026-08-21 02:27:44.321362 | instance | changed
2026-08-21 02:27:44.330236 | 
2026-08-21 02:27:44.330416 | TASK [setup-uv : Print version]
2026-08-21 02:27:44.695150 | instance | uv 0.8.13
2026-08-21 02:27:44.864997 | instance | ok: Runtime: 0:00:00.010176
2026-08-21 02:27:44.876035 | 
2026-08-21 02:27:44.876117 | TASK [Install Ansible collection siblings]
2026-08-21 02:27:54.489022 | instance | changed:
2026-08-21 02:27:54.489194 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-21 02:27:54.489226 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-21 02:27:54.489250 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-21 02:27:54.489271 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-21 02:27:54.489291 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-21 02:27:54.489315 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-21 02:27:54.489336 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-21 02:27:54.489363 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-21 02:27:54.489387 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-21 02:27:54.489408 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-21 02:27:54.489427 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-21 02:27:54.489446 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-21 02:27:54.489464 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-08-21 02:27:54.489482 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-21 02:27:54.489501 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-08-21 02:27:54.489520 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-21 02:27:54.489539 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-21 02:27:54.489558 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-21 02:27:54.489576 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-21 02:27:54.489599 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-08-21 02:27:54.489620 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-08-21 02:27:54.489642 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-08-21 02:27:54.489663 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-21 02:27:54.489694 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-21 02:27:54.489719 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-21 02:27:54.489763 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-21 02:27:54.489786 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-21 02:27:54.489823 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-21 02:27:54.489856 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-21 02:27:54.489878 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-21 02:27:54.489896 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-21 02:27:54.489916 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-21 02:27:54.489934 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-21 02:27:54.489952 | instance | Checked out ansible.netcommon to v8.6.2
2026-08-21 02:27:54.489972 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-21 02:27:54.489996 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.490017 | instance | Process install dependency map
2026-08-21 02:27:54.490041 | instance | Starting collection install process
2026-08-21 02:27:54.490066 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-21 02:27:54.490085 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-21 02:27:54.490104 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-08-21 02:27:54.490122 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-21 02:27:54.490139 | instance | Creating virtual environment at: .venv
2026-08-21 02:27:54.490156 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-21 02:27:54.490174 | instance | Downloading cryptography (4.2MiB)
2026-08-21 02:27:54.490197 | instance | Downloading netaddr (2.2MiB)
2026-08-21 02:27:54.490216 | instance | Downloading kubernetes (1.9MiB)
2026-08-21 02:27:54.490234 | instance | Downloading pygments (1.2MiB)
2026-08-21 02:27:54.490255 | instance | Downloading setuptools (1.1MiB)
2026-08-21 02:27:54.490277 | instance | Downloading openstacksdk (1.7MiB)
2026-08-21 02:27:54.490298 | instance | Downloading rjsonnet (1.2MiB)
2026-08-21 02:27:54.490317 | instance | Downloading ansible-core (2.1MiB)
2026-08-21 02:27:54.490335 | instance |    Building pyperclip==1.9.0
2026-08-21 02:27:54.490356 | instance |  Downloading rjsonnet
2026-08-21 02:27:54.490375 | instance |  Downloading pygments
2026-08-21 02:27:54.490393 | instance |  Downloading netaddr
2026-08-21 02:27:54.490412 | instance |  Downloading cryptography
2026-08-21 02:27:54.490430 | instance |  Downloading setuptools
2026-08-21 02:27:54.490447 | instance |  Downloading kubernetes
2026-08-21 02:27:54.490465 | instance |  Downloading ansible-core
2026-08-21 02:27:54.490488 | instance |  Downloading openstacksdk
2026-08-21 02:27:54.490509 | instance |       Built pyperclip==1.9.0
2026-08-21 02:27:54.490530 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-21 02:27:54.490548 | instance | Installed 79 packages in 41ms
2026-08-21 02:27:54.490567 | instance | Checked out ansible.posix to 1.6.0
2026-08-21 02:27:54.490585 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-21 02:27:54.490603 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.490621 | instance | Process install dependency map
2026-08-21 02:27:54.490645 | instance | Starting collection install process
2026-08-21 02:27:54.490665 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-21 02:27:54.490683 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-21 02:27:54.490702 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-21 02:27:54.490719 | instance | Checked out ansible.utils to v6.1.0
2026-08-21 02:27:54.490738 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-21 02:27:54.490756 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.490795 | instance | Process install dependency map
2026-08-21 02:27:54.490815 | instance | Starting collection install process
2026-08-21 02:27:54.490832 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-21 02:27:54.490850 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-21 02:27:54.490868 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-21 02:27:54.490886 | instance | Checked out atmosphere.common to v0.6.0
2026-08-21 02:27:54.490904 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-21 02:27:54.490922 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.490940 | instance | Process install dependency map
2026-08-21 02:27:54.490957 | instance | Starting collection install process
2026-08-21 02:27:54.491004 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-21 02:27:54.491027 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-21 02:27:54.491049 | instance | atmosphere.common:0.6.0 was installed successfully
2026-08-21 02:27:54.491070 | instance | Checked out community.crypto to 2.2.3
2026-08-21 02:27:54.491088 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-21 02:27:54.491106 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.491415 | instance | Process install dependency map
2026-08-21 02:27:54.491447 | instance | Starting collection install process
2026-08-21 02:27:54.491468 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-21 02:27:54.491487 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-21 02:27:54.491505 | instance | community.crypto:2.2.3 was installed successfully
2026-08-21 02:27:54.491528 | instance | Checked out community.general to 7.3.0
2026-08-21 02:27:54.491548 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-08-21 02:27:54.491570 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.491588 | instance | Process install dependency map
2026-08-21 02:27:54.491607 | instance | Starting collection install process
2026-08-21 02:27:54.491625 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-21 02:27:54.491643 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-21 02:27:54.491660 | instance | community.general:7.3.0 was installed successfully
2026-08-21 02:27:54.491684 | instance | Checked out community.mysql to 3.6.0
2026-08-21 02:27:54.491703 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-21 02:27:54.491723 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.491744 | instance | Process install dependency map
2026-08-21 02:27:54.491765 | instance | Starting collection install process
2026-08-21 02:27:54.491785 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-21 02:27:54.491804 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-21 02:27:54.491822 | instance | community.mysql:3.6.0 was installed successfully
2026-08-21 02:27:54.491844 | instance | Checked out kubernetes.core to 2.4.0
2026-08-21 02:27:54.491864 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-21 02:27:54.491886 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.491904 | instance | Process install dependency map
2026-08-21 02:27:54.491925 | instance | Starting collection install process
2026-08-21 02:27:54.491944 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-21 02:27:54.491962 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-21 02:27:54.491980 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-21 02:27:54.492002 | instance | Checked out openstack.cloud to 1.7.0
2026-08-21 02:27:54.492022 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-21 02:27:54.492045 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.492063 | instance | Process install dependency map
2026-08-21 02:27:54.492081 | instance | Starting collection install process
2026-08-21 02:27:54.492101 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-21 02:27:54.492122 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-21 02:27:54.492144 | instance | openstack.cloud:1.7.0 was installed successfully
2026-08-21 02:27:54.492163 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-21 02:27:54.492182 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-21 02:27:54.492200 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.492218 | instance | Process install dependency map
2026-08-21 02:27:54.492236 | instance | Starting collection install process
2026-08-21 02:27:54.492255 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-21 02:27:54.492276 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-21 02:27:54.492296 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-21 02:27:54.492318 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-21 02:27:54.492337 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-21 02:27:54.492355 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.492378 | instance | Process install dependency map
2026-08-21 02:27:54.492399 | instance | Starting collection install process
2026-08-21 02:27:54.492420 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-21 02:27:54.492439 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-21 02:27:54.492460 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-21 02:27:54.492478 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-21 02:27:54.492496 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-21 02:27:54.492514 | instance | Starting galaxy collection install process
2026-08-21 02:27:54.492535 | instance | Process install dependency map
2026-08-21 02:27:54.492580 | instance | Starting collection install process
2026-08-21 02:27:54.492611 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-21 02:27:54.492636 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-21 02:27:54.492656 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-21 02:27:54.498706 | 
2026-08-21 02:27:54.498756 | PLAY RECAP
2026-08-21 02:27:54.498802 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-21 02:27:54.498827 | 
2026-08-21 02:27:54.687293 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-21 02:27:54.691125 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-21 02:27:55.374753 | 
2026-08-21 02:27:55.374874 | PLAY [all]
2026-08-21 02:27:55.392346 | 
2026-08-21 02:27:55.392472 | TASK [Install "jq" for log collection]
2026-08-21 02:28:05.291520 | instance | changed
2026-08-21 02:28:05.293337 | 
2026-08-21 02:28:05.293398 | PLAY RECAP
2026-08-21 02:28:05.293449 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-21 02:28:05.293691 | 
2026-08-21 02:28:05.408062 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-21 02:28:05.413736 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-21 02:28:06.149655 | 
2026-08-21 02:28:06.149904 | PLAY [all]
2026-08-21 02:28:06.164499 | 
2026-08-21 02:28:06.164683 | LOOP [Check inventory directories]
2026-08-21 02:28:06.515745 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-21 02:28:06.670363 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-21 02:28:06.828427 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-21 02:28:06.848619 | 
2026-08-21 02:28:06.848874 | LOOP [Copy inventory file for Zuul]
2026-08-21 02:28:07.694697 | instance | changed:
2026-08-21 02:28:07.694904 | instance | {
2026-08-21 02:28:07.694946 | instance |   "ansible_loop_var": "item",
2026-08-21 02:28:07.694980 | instance |   "changed": false,
2026-08-21 02:28:07.695009 | instance |   "failed": false,
2026-08-21 02:28:07.695046 | instance |   "invocation": {
2026-08-21 02:28:07.695075 | instance |     "module_args": {
2026-08-21 02:28:07.695102 | instance |       "checksum_algorithm": "sha1",
2026-08-21 02:28:07.695128 | instance |       "follow": true,
2026-08-21 02:28:07.695154 | instance |       "get_attributes": true,
2026-08-21 02:28:07.695182 | instance |       "get_checksum": true,
2026-08-21 02:28:07.695208 | instance |       "get_mime": true,
2026-08-21 02:28:07.695234 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-21 02:28:07.695275 | instance |     }
2026-08-21 02:28:07.695302 | instance |   },
2026-08-21 02:28:07.695329 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-21 02:28:07.695355 | instance |   "stat": {
2026-08-21 02:28:07.695381 | instance |     "atime": 1787279266.8435605,
2026-08-21 02:28:07.695408 | instance |     "attr_flags": "e",
2026-08-21 02:28:07.695434 | instance |     "attributes": [
2026-08-21 02:28:07.695460 | instance |       "extents"
2026-08-21 02:28:07.695486 | instance |     ],
2026-08-21 02:28:07.695512 | instance |     "block_size": 4096,
2026-08-21 02:28:07.695538 | instance |     "blocks": 8,
2026-08-21 02:28:07.695564 | instance |     "charset": "unknown",
2026-08-21 02:28:07.695592 | instance |     "ctime": 1787279265.6954453,
2026-08-21 02:28:07.695620 | instance |     "dev": 64513,
2026-08-21 02:28:07.695647 | instance |     "device_type": 0,
2026-08-21 02:28:07.695672 | instance |     "executable": true,
2026-08-21 02:28:07.695698 | instance |     "exists": true,
2026-08-21 02:28:07.695723 | instance |     "gid": 1000,
2026-08-21 02:28:07.695749 | instance |     "gr_name": "zuul",
2026-08-21 02:28:07.695775 | instance |     "inode": 1047475,
2026-08-21 02:28:07.695800 | instance |     "isblk": false,
2026-08-21 02:28:07.695826 | instance |     "ischr": false,
2026-08-21 02:28:07.695853 | instance |     "isdir": true,
2026-08-21 02:28:07.695879 | instance |     "isfifo": false,
2026-08-21 02:28:07.695905 | instance |     "isgid": false,
2026-08-21 02:28:07.695931 | instance |     "islnk": false,
2026-08-21 02:28:07.695957 | instance |     "isreg": false,
2026-08-21 02:28:07.695983 | instance |     "issock": false,
2026-08-21 02:28:07.696009 | instance |     "isuid": false,
2026-08-21 02:28:07.696048 | instance |     "mimetype": "unknown",
2026-08-21 02:28:07.696111 | instance |     "mode": "0755",
2026-08-21 02:28:07.696153 | instance |     "mtime": 1787279265.6954453,
2026-08-21 02:28:07.696190 | instance |     "nlink": 21,
2026-08-21 02:28:07.696225 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-21 02:28:07.696260 | instance |     "pw_name": "zuul",
2026-08-21 02:28:07.696300 | instance |     "readable": true,
2026-08-21 02:28:07.696337 | instance |     "rgrp": true,
2026-08-21 02:28:07.696373 | instance |     "roth": true,
2026-08-21 02:28:07.696411 | instance |     "rusr": true,
2026-08-21 02:28:07.696449 | instance |     "size": 4096,
2026-08-21 02:28:07.696481 | instance |     "uid": 1000,
2026-08-21 02:28:07.696507 | instance |     "version": "1062327720",
2026-08-21 02:28:07.696533 | instance |     "wgrp": false,
2026-08-21 02:28:07.696562 | instance |     "woth": false,
2026-08-21 02:28:07.696588 | instance |     "writeable": true,
2026-08-21 02:28:07.696614 | instance |     "wusr": true,
2026-08-21 02:28:07.696640 | instance |     "xgrp": true,
2026-08-21 02:28:07.696665 | instance |     "xoth": true,
2026-08-21 02:28:07.696690 | instance |     "xusr": true
2026-08-21 02:28:07.696715 | instance |   }
2026-08-21 02:28:07.696741 | instance | }
2026-08-21 02:28:07.696790 | instance | ok: All items complete
2026-08-21 02:28:07.696821 | 
2026-08-21 02:28:07.698906 | instance | skipping: Conditional result was False
2026-08-21 02:28:07.699997 | instance | skipping: Conditional result was False
2026-08-21 02:28:07.716026 | 
2026-08-21 02:28:07.716180 | LOOP [Switch "ansible_host" to private IP]
2026-08-21 02:28:08.073779 | instance | changed: 1 replacements made
2026-08-21 02:28:08.148227 | instance | skipping: Conditional result was False
2026-08-21 02:28:08.150154 | instance | skipping: Conditional result was False
2026-08-21 02:28:08.169021 | 
2026-08-21 02:28:08.169141 | TASK [Run Molecule scenario]
2026-08-21 02:28:09.174632 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-21 02:28:09.580040 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-21 02:28:09.580122 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-21 02:28:21.315361 | instance | INFO     [csi > dependency] Executing
2026-08-21 02:28:21.315653 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-21 02:28:21.315983 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-21 02:28:21.316116 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-21 02:28:21.325171 | instance | INFO     [csi > cleanup] Executing
2026-08-21 02:28:21.325614 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-21 02:28:21.335914 | instance | INFO     [csi > destroy] Executing
2026-08-21 02:28:21.336014 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-21 02:28:21.336117 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-21 02:28:21.345955 | instance | INFO     [csi > syntax] Executing
2026-08-21 02:28:23.039625 | instance |
2026-08-21 02:28:23.040027 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-21 02:28:23.108787 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-21 02:28:23.122460 | instance | INFO     [csi > create] Executing
2026-08-21 02:28:23.124659 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-21 02:28:23.132970 | instance | INFO     [csi > prepare] Executing
2026-08-21 02:28:23.995813 | instance |
2026-08-21 02:28:23.995993 | instance | PLAY [Prepare] *****************************************************************
2026-08-21 02:28:23.996132 | instance |
2026-08-21 02:28:23.996292 | instance | TASK [Gathering Facts] *********************************************************
2026-08-21 02:28:23.996466 | instance | Friday 21 August 2026  02:28:23 +0000 (0:00:00.026)       0:00:00.026 *********
2026-08-21 02:28:25.156818 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-21 02:28:25.157080 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-21 02:28:25.157381 | instance | interpreter could change the meaning of that path. See
2026-08-21 02:28:25.157739 | instance | https://docs.ansible.com/ansible-
2026-08-21 02:28:25.158034 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-21 02:28:25.164792 | instance | ok: [instance]
2026-08-21 02:28:25.165032 | instance |
2026-08-21 02:28:25.165302 | instance | TASK [Configure short hostname] ************************************************
2026-08-21 02:28:25.165624 | instance | Friday 21 August 2026  02:28:25 +0000 (0:00:01.169)       0:00:01.196 *********
2026-08-21 02:28:25.864709 | instance | changed: [instance]
2026-08-21 02:28:25.864961 | instance |
2026-08-21 02:28:25.865327 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-21 02:28:25.865599 | instance | Friday 21 August 2026  02:28:25 +0000 (0:00:00.699)       0:00:01.896 *********
2026-08-21 02:28:26.131139 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-21 02:28:26.131399 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-21 02:28:26.131618 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-21 02:28:26.137514 | instance | changed: [instance]
2026-08-21 02:28:26.137747 | instance |
2026-08-21 02:28:26.138009 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-21 02:28:26.138290 | instance | Friday 21 August 2026  02:28:26 +0000 (0:00:00.273)       0:00:02.169 *********
2026-08-21 02:28:26.970586 | instance | ok: [instance]
2026-08-21 02:28:26.970802 | instance |
2026-08-21 02:28:26.971085 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-21 02:28:26.971344 | instance |
2026-08-21 02:28:26.971613 | instance | TASK [Gathering Facts] *********************************************************
2026-08-21 02:28:26.971887 | instance | Friday 21 August 2026  02:28:26 +0000 (0:00:00.832)       0:00:03.001 *********
2026-08-21 02:28:27.699214 | instance | ok: [instance]
2026-08-21 02:28:27.699452 | instance |
2026-08-21 02:28:27.699777 | instance | TASK [Install depedencies] *****************************************************
2026-08-21 02:28:27.700194 | instance | Friday 21 August 2026  02:28:27 +0000 (0:00:00.728)       0:00:03.730 *********
2026-08-21 02:28:47.114057 | instance | changed: [instance]
2026-08-21 02:28:47.114303 | instance |
2026-08-21 02:28:47.114601 | instance | TASK [Start up service] ********************************************************
2026-08-21 02:28:47.114946 | instance | Friday 21 August 2026  02:28:47 +0000 (0:00:19.414)       0:00:23.145 *********
2026-08-21 02:28:47.674081 | instance | ok: [instance]
2026-08-21 02:28:47.674294 | instance |
2026-08-21 02:28:47.674577 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-21 02:28:47.674875 | instance | Friday 21 August 2026  02:28:47 +0000 (0:00:00.559)       0:00:23.705 *********
2026-08-21 02:28:47.968273 | instance | ok: [instance]
2026-08-21 02:28:47.968532 | instance |
2026-08-21 02:28:47.968820 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-21 02:28:47.969123 | instance | Friday 21 August 2026  02:28:47 +0000 (0:00:00.294)       0:00:23.999 *********
2026-08-21 02:28:48.626575 | instance | changed: [instance]
2026-08-21 02:28:48.626919 | instance |
2026-08-21 02:28:48.627244 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-21 02:28:48.627519 | instance | Friday 21 August 2026  02:28:48 +0000 (0:00:00.658)       0:00:24.657 *********
2026-08-21 02:28:48.839275 | instance | ok: [instance]
2026-08-21 02:28:48.839334 | instance |
2026-08-21 02:28:48.839347 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-21 02:28:48.839539 | instance | Friday 21 August 2026  02:28:48 +0000 (0:00:00.212)       0:00:24.869 *********
2026-08-21 02:28:48.861870 | instance | skipping: [instance]
2026-08-21 02:28:48.861986 | instance |
2026-08-21 02:28:48.862161 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-21 02:28:48.862340 | instance | Friday 21 August 2026  02:28:48 +0000 (0:00:00.023)       0:00:24.893 *********
2026-08-21 02:28:49.451733 | instance | changed: [instance] => (item=osd0)
2026-08-21 02:28:49.451839 | instance | changed: [instance] => (item=osd1)
2026-08-21 02:28:49.451978 | instance | changed: [instance] => (item=osd2)
2026-08-21 02:28:49.452178 | instance |
2026-08-21 02:28:49.452362 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-21 02:28:49.452513 | instance | Friday 21 August 2026  02:28:49 +0000 (0:00:00.590)       0:00:25.483 *********
2026-08-21 02:28:50.095191 | instance | changed: [instance] => (item=osd0)
2026-08-21 02:28:50.095252 | instance | changed: [instance] => (item=osd1)
2026-08-21 02:28:50.095266 | instance | changed: [instance] => (item=osd2)
2026-08-21 02:28:50.095279 | instance |
2026-08-21 02:28:50.095293 | instance | TASK [Start loop devices] ******************************************************
2026-08-21 02:28:50.095307 | instance | Friday 21 August 2026  02:28:50 +0000 (0:00:00.641)       0:00:26.124 *********
2026-08-21 02:28:50.832888 | instance | changed: [instance] => (item=osd0)
2026-08-21 02:28:50.833843 | instance | changed: [instance] => (item=osd1)
2026-08-21 02:28:50.833888 | instance | changed: [instance] => (item=osd2)
2026-08-21 02:28:50.833896 | instance |
2026-08-21 02:28:50.833903 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-21 02:28:50.833909 | instance | Friday 21 August 2026  02:28:50 +0000 (0:00:00.740)       0:00:26.864 *********
2026-08-21 02:28:54.045398 | instance | changed: [instance] => (item=osd0)
2026-08-21 02:28:54.045515 | instance | changed: [instance] => (item=osd1)
2026-08-21 02:28:54.046242 | instance | changed: [instance] => (item=osd2)
2026-08-21 02:28:54.046298 | instance |
2026-08-21 02:28:54.046306 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-21 02:28:54.046313 | instance | Friday 21 August 2026  02:28:54 +0000 (0:00:03.212)       0:00:30.077 *********
2026-08-21 02:28:55.946671 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-21 02:28:55.946721 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-21 02:28:55.946935 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-21 02:28:55.947236 | instance |
2026-08-21 02:28:55.947547 | instance | PLAY RECAP *********************************************************************
2026-08-21 02:28:55.947865 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-21 02:28:55.948141 | instance |
2026-08-21 02:28:55.948503 | instance | Friday 21 August 2026  02:28:55 +0000 (0:00:01.901)       0:00:31.978 *********
2026-08-21 02:28:55.948796 | instance | ===============================================================================
2026-08-21 02:28:55.949079 | instance | Install depedencies ---------------------------------------------------- 19.41s
2026-08-21 02:28:55.949365 | instance | Create a volume group for each loop device ------------------------------ 3.21s
2026-08-21 02:28:55.949651 | instance | Create a logical volume for each loop device ---------------------------- 1.90s
2026-08-21 02:28:55.949941 | instance | Gathering Facts --------------------------------------------------------- 1.17s
2026-08-21 02:28:55.950223 | instance | Purge "snapd" package --------------------------------------------------- 0.83s
2026-08-21 02:28:55.950506 | instance | Start loop devices ------------------------------------------------------ 0.74s
2026-08-21 02:28:55.950786 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-08-21 02:28:55.951068 | instance | Configure short hostname ------------------------------------------------ 0.70s
2026-08-21 02:28:55.951426 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.66s
2026-08-21 02:28:55.951717 | instance | Set permissions on loopback devices ------------------------------------- 0.64s
2026-08-21 02:28:55.951978 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-08-21 02:28:55.952102 | instance | Start up service -------------------------------------------------------- 0.56s
2026-08-21 02:28:55.952221 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s
2026-08-21 02:28:55.952370 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s
2026-08-21 02:28:55.952511 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-08-21 02:28:55.952632 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-21 02:28:56.018359 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-21 02:28:56.033774 | instance | INFO     [csi > converge] Executing
2026-08-21 02:28:57.571620 | instance |
2026-08-21 02:28:57.571943 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-21 02:28:57.572212 | instance |
2026-08-21 02:28:57.572545 | instance | TASK [Gathering Facts] *********************************************************
2026-08-21 02:28:57.572870 | instance | Friday 21 August 2026  02:28:57 +0000 (0:00:00.007)       0:00:00.007 *********
2026-08-21 02:28:58.530110 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-21 02:28:58.530238 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-21 02:28:58.530394 | instance | interpreter could change the meaning of that path. See
2026-08-21 02:28:58.530573 | instance | https://docs.ansible.com/ansible-
2026-08-21 02:28:58.530799 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-21 02:28:58.540854 | instance | ok: [instance]
2026-08-21 02:28:58.541015 | instance |
2026-08-21 02:28:58.541244 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-21 02:28:58.541442 | instance | Friday 21 August 2026  02:28:58 +0000 (0:00:00.970)       0:00:00.978 *********
2026-08-21 02:28:58.588648 | instance | ok: [instance] => {
2026-08-21 02:28:58.588772 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-21 02:28:58.589148 | instance | }
2026-08-21 02:28:58.589399 | instance |
2026-08-21 02:28:58.589733 | instance | PLAY [all] *********************************************************************
2026-08-21 02:28:58.590071 | instance |
2026-08-21 02:28:58.590354 | instance | TASK [Gathering Facts] *********************************************************
2026-08-21 02:28:58.590725 | instance | Friday 21 August 2026  02:28:58 +0000 (0:00:00.047)       0:00:01.025 *********
2026-08-21 02:28:59.423244 | instance | ok: [instance]
2026-08-21 02:28:59.423499 | instance |
2026-08-21 02:28:59.424025 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-21 02:28:59.424141 | instance | Friday 21 August 2026  02:28:59 +0000 (0:00:00.834)       0:00:01.860 *********
2026-08-21 02:28:59.606875 | instance | ok: [instance]
2026-08-21 02:28:59.607147 | instance |
2026-08-21 02:28:59.607378 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-21 02:28:59.607589 | instance |
2026-08-21 02:28:59.607809 | instance | TASK [Gathering Facts] *********************************************************
2026-08-21 02:28:59.608032 | instance | Friday 21 August 2026  02:28:59 +0000 (0:00:00.184)       0:00:02.044 *********
2026-08-21 02:29:00.528885 | instance | ok: [instance]
2026-08-21 02:29:00.528956 | instance |
2026-08-21 02:29:00.529101 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-21 02:29:00.529297 | instance | Friday 21 August 2026  02:29:00 +0000 (0:00:00.919)       0:00:02.964 *********
2026-08-21 02:29:00.815242 | instance | ok: [instance]
2026-08-21 02:29:00.815275 | instance |
2026-08-21 02:29:00.815281 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-21 02:29:00.815285 | instance | Friday 21 August 2026  02:29:00 +0000 (0:00:00.288)       0:00:03.252 *********
2026-08-21 02:29:00.859438 | instance | skipping: [instance]
2026-08-21 02:29:00.859474 | instance |
2026-08-21 02:29:00.859479 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-21 02:29:00.859484 | instance | Friday 21 August 2026  02:29:00 +0000 (0:00:00.043)       0:00:03.296 *********
2026-08-21 02:29:01.192437 | instance | changed: [instance]
2026-08-21 02:29:01.192504 | instance |
2026-08-21 02:29:01.192789 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-21 02:29:01.192824 | instance | Friday 21 August 2026  02:29:01 +0000 (0:00:00.333)       0:00:03.630 *********
2026-08-21 02:29:01.261830 | instance | ok: [instance] => {
2026-08-21 02:29:01.262577 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-21 02:29:01.262616 | instance | }
2026-08-21 02:29:01.262622 | instance |
2026-08-21 02:29:01.262627 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-21 02:29:01.262632 | instance | Friday 21 August 2026  02:29:01 +0000 (0:00:00.069)       0:00:03.699 *********
2026-08-21 02:29:01.858989 | instance | changed: [instance]
2026-08-21 02:29:01.859471 | instance |
2026-08-21 02:29:01.859505 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-21 02:29:01.859511 | instance | Friday 21 August 2026  02:29:01 +0000 (0:00:00.596)       0:00:04.296 *********
2026-08-21 02:29:01.912252 | instance | skipping: [instance]
2026-08-21 02:29:01.912360 | instance |
2026-08-21 02:29:01.912650 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-21 02:29:01.912689 | instance | Friday 21 August 2026  02:29:01 +0000 (0:00:00.053)       0:00:04.349 *********
2026-08-21 02:29:01.965374 | instance | skipping: [instance]
2026-08-21 02:29:01.965453 | instance |
2026-08-21 02:29:01.965705 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-21 02:29:01.965744 | instance | Friday 21 August 2026  02:29:01 +0000 (0:00:00.053)       0:00:04.403 *********
2026-08-21 02:29:02.185574 | instance | ok: [instance]
2026-08-21 02:29:02.185635 | instance |
2026-08-21 02:29:02.185834 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-21 02:29:02.186039 | instance | Friday 21 August 2026  02:29:02 +0000 (0:00:00.220)       0:00:04.623 *********
2026-08-21 02:29:03.416520 | instance | ok: [instance]
2026-08-21 02:29:03.416591 | instance |
2026-08-21 02:29:03.416739 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-21 02:29:03.417011 | instance | Friday 21 August 2026  02:29:03 +0000 (0:00:01.230)       0:00:05.854 *********
2026-08-21 02:29:03.481768 | instance | ok: [instance] => {
2026-08-21 02:29:03.481912 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-21 02:29:03.482169 | instance | }
2026-08-21 02:29:03.482375 | instance |
2026-08-21 02:29:03.482592 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-21 02:29:03.482805 | instance | Friday 21 August 2026  02:29:03 +0000 (0:00:00.065)       0:00:05.919 *********
2026-08-21 02:29:04.153171 | instance | changed: [instance]
2026-08-21 02:29:04.153695 | instance |
2026-08-21 02:29:04.153741 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-21 02:29:04.153749 | instance | Friday 21 August 2026  02:29:04 +0000 (0:00:00.671)       0:00:06.590 *********
2026-08-21 02:29:07.005911 | instance | changed: [instance]
2026-08-21 02:29:07.005995 | instance |
2026-08-21 02:29:07.007066 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-21 02:29:07.007118 | instance | Friday 21 August 2026  02:29:06 +0000 (0:00:02.852)       0:00:09.443 *********
2026-08-21 02:29:07.043095 | instance | skipping: [instance]
2026-08-21 02:29:07.043203 | instance |
2026-08-21 02:29:07.043266 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-21 02:29:07.043432 | instance | Friday 21 August 2026  02:29:07 +0000 (0:00:00.037)       0:00:09.480 *********
2026-08-21 02:29:07.075186 | instance | skipping: [instance]
2026-08-21 02:29:07.076072 | instance |
2026-08-21 02:29:07.076125 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-21 02:29:07.076133 | instance | Friday 21 August 2026  02:29:07 +0000 (0:00:00.032)       0:00:09.512 *********
2026-08-21 02:29:07.111653 | instance | skipping: [instance]
2026-08-21 02:29:07.111812 | instance |
2026-08-21 02:29:07.112196 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-21 02:29:07.112450 | instance | Friday 21 August 2026  02:29:07 +0000 (0:00:00.036)       0:00:09.548 *********
2026-08-21 02:29:12.490205 | instance | changed: [instance]
2026-08-21 02:29:12.490237 | instance |
2026-08-21 02:29:12.490244 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-21 02:29:12.490249 | instance | Friday 21 August 2026  02:29:12 +0000 (0:00:05.378)       0:00:14.927 *********
2026-08-21 02:29:13.068386 | instance | changed: [instance]
2026-08-21 02:29:13.068460 | instance |
2026-08-21 02:29:13.068791 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-21 02:29:13.068841 | instance | Friday 21 August 2026  02:29:13 +0000 (0:00:00.578)       0:00:15.505 *********
2026-08-21 02:29:13.987900 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-21 02:29:13.987981 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-21 02:29:13.988414 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-21 02:29:13.988459 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-21 02:29:13.988945 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-21 02:29:13.988958 | instance |
2026-08-21 02:29:13.988967 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-21 02:29:13.988972 | instance | Friday 21 August 2026  02:29:13 +0000 (0:00:00.919)       0:00:16.425 *********
2026-08-21 02:29:14.585965 | instance | changed: [instance]
2026-08-21 02:29:14.586044 | instance |
2026-08-21 02:29:14.586677 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-21 02:29:14.586714 | instance | Friday 21 August 2026  02:29:14 +0000 (0:00:00.581)       0:00:17.006 *********
2026-08-21 02:29:14.586719 | instance |
2026-08-21 02:29:14.586724 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-21 02:29:14.586728 | instance | Friday 21 August 2026  02:29:14 +0000 (0:00:00.017)       0:00:17.023 *********
2026-08-21 02:29:15.456237 | instance | ok: [instance]
2026-08-21 02:29:15.456311 | instance |
2026-08-21 02:29:15.456668 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-21 02:29:15.456704 | instance | Friday 21 August 2026  02:29:15 +0000 (0:00:00.870)       0:00:17.893 *********
2026-08-21 02:29:15.900243 | instance | changed: [instance]
2026-08-21 02:29:15.900383 | instance |
2026-08-21 02:29:15.900645 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-21 02:29:15.900685 | instance | Friday 21 August 2026  02:29:15 +0000 (0:00:00.444)       0:00:18.338 *********
2026-08-21 02:29:16.439311 | instance | changed: [instance]
2026-08-21 02:29:16.439353 | instance |
2026-08-21 02:29:16.439360 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-21 02:29:16.439367 | instance | Friday 21 August 2026  02:29:16 +0000 (0:00:00.538)       0:00:18.876 *********
2026-08-21 02:29:16.643880 | instance | ok: [instance]
2026-08-21 02:29:16.644044 | instance |
2026-08-21 02:29:16.644072 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-21 02:29:16.644246 | instance | Friday 21 August 2026  02:29:16 +0000 (0:00:00.205)       0:00:19.081 *********
2026-08-21 02:29:16.698855 | instance | ok: [instance] => {
2026-08-21 02:29:16.699464 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-21 02:29:16.699508 | instance | }
2026-08-21 02:29:16.699514 | instance |
2026-08-21 02:29:16.699519 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-21 02:29:16.699524 | instance | Friday 21 August 2026  02:29:16 +0000 (0:00:00.054)       0:00:19.136 *********
2026-08-21 02:29:17.377837 | instance | changed: [instance]
2026-08-21 02:29:17.377934 | instance |
2026-08-21 02:29:17.378233 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-21 02:29:17.378278 | instance | Friday 21 August 2026  02:29:17 +0000 (0:00:00.678)       0:00:19.815 *********
2026-08-21 02:29:21.752270 | instance | changed: [instance]
2026-08-21 02:29:21.752895 | instance |
2026-08-21 02:29:21.752944 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-21 02:29:21.752952 | instance | Friday 21 August 2026  02:29:21 +0000 (0:00:04.373)       0:00:24.189 *********
2026-08-21 02:29:22.844107 | instance | ok: [instance]
2026-08-21 02:29:22.844202 | instance |
2026-08-21 02:29:22.844540 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-21 02:29:22.844611 | instance | Friday 21 August 2026  02:29:22 +0000 (0:00:01.092)       0:00:25.281 *********
2026-08-21 02:29:23.192767 | instance | changed: [instance]
2026-08-21 02:29:23.192871 | instance |
2026-08-21 02:29:23.192991 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-21 02:29:23.193148 | instance | Friday 21 August 2026  02:29:23 +0000 (0:00:00.348)       0:00:25.630 *********
2026-08-21 02:29:23.651242 | instance | changed: [instance]
2026-08-21 02:29:23.651307 | instance |
2026-08-21 02:29:23.651432 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-21 02:29:23.651557 | instance | Friday 21 August 2026  02:29:23 +0000 (0:00:00.458)       0:00:26.088 *********
2026-08-21 02:29:24.208525 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-21 02:29:24.208877 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-21 02:29:24.209227 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-21 02:29:24.209551 | instance |
2026-08-21 02:29:24.209905 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-21 02:29:24.210256 | instance | Friday 21 August 2026  02:29:24 +0000 (0:00:00.556)       0:00:26.645 *********
2026-08-21 02:29:24.663365 | instance | changed: [instance]
2026-08-21 02:29:24.663507 | instance |
2026-08-21 02:29:24.663864 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-21 02:29:24.663996 | instance | Friday 21 August 2026  02:29:24 +0000 (0:00:00.455)       0:00:27.100 *********
2026-08-21 02:29:25.108931 | instance | changed: [instance]
2026-08-21 02:29:25.109179 | instance |
2026-08-21 02:29:25.109463 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-21 02:29:25.109738 | instance | Friday 21 August 2026  02:29:25 +0000 (0:00:00.428)       0:00:27.529 *********
2026-08-21 02:29:25.109986 | instance |
2026-08-21 02:29:25.110259 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-21 02:29:25.110530 | instance | Friday 21 August 2026  02:29:25 +0000 (0:00:00.016)       0:00:27.546 *********
2026-08-21 02:29:25.823419 | instance | ok: [instance]
2026-08-21 02:29:25.823476 | instance |
2026-08-21 02:29:25.823488 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-21 02:29:25.823508 | instance | Friday 21 August 2026  02:29:25 +0000 (0:00:00.713)       0:00:28.259 *********
2026-08-21 02:29:26.634623 | instance | changed: [instance]
2026-08-21 02:29:26.634736 | instance |
2026-08-21 02:29:26.634997 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-21 02:29:26.635176 | instance | Friday 21 August 2026  02:29:26 +0000 (0:00:00.812)       0:00:29.072 *********
2026-08-21 02:29:27.199983 | instance | changed: [instance]
2026-08-21 02:29:27.200046 | instance |
2026-08-21 02:29:27.200490 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-21 02:29:27.200552 | instance | Friday 21 August 2026  02:29:27 +0000 (0:00:00.565)       0:00:29.637 *********
2026-08-21 02:29:27.257191 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-21 02:29:27.257252 | instance |
2026-08-21 02:29:27.257522 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-21 02:29:27.257555 | instance | Friday 21 August 2026  02:29:27 +0000 (0:00:00.057)       0:00:29.694 *********
2026-08-21 02:29:31.758191 | instance | changed: [instance]
2026-08-21 02:29:31.758264 | instance |
2026-08-21 02:29:31.758525 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-21 02:29:31.758565 | instance | Friday 21 August 2026  02:29:31 +0000 (0:00:04.500)       0:00:34.195 *********
2026-08-21 02:29:32.454442 | instance | ok: [instance] => (item=chronyd)
2026-08-21 02:29:32.454516 | instance | ok: [instance] => (item=sshd)
2026-08-21 02:29:32.454905 | instance |
2026-08-21 02:29:32.454945 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-21 02:29:32.454951 | instance | Friday 21 August 2026  02:29:32 +0000 (0:00:00.696)       0:00:34.892 *********
2026-08-21 02:29:33.435895 | instance | changed: [instance]
2026-08-21 02:29:33.436103 | instance |
2026-08-21 02:29:33.436117 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-21 02:29:33.436176 | instance | Friday 21 August 2026  02:29:33 +0000 (0:00:00.980)       0:00:35.873 *********
2026-08-21 02:29:33.621524 | instance | ok: [instance]
2026-08-21 02:29:33.621592 | instance |
2026-08-21 02:29:33.621872 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-21 02:29:33.621913 | instance | Friday 21 August 2026  02:29:33 +0000 (0:00:00.186)       0:00:36.059 *********
2026-08-21 02:29:34.074369 | instance | changed: [instance]
2026-08-21 02:29:34.074453 | instance |
2026-08-21 02:29:34.074740 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-21 02:29:34.074786 | instance | Friday 21 August 2026  02:29:34 +0000 (0:00:00.452)       0:00:36.511 *********
2026-08-21 02:29:34.414725 | instance | changed: [instance]
2026-08-21 02:29:34.415284 | instance |
2026-08-21 02:29:34.415325 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-21 02:29:34.415331 | instance | Friday 21 August 2026  02:29:34 +0000 (0:00:00.340)       0:00:36.852 *********
2026-08-21 02:29:34.526358 | instance | ok: [instance]
2026-08-21 02:29:34.526439 | instance |
2026-08-21 02:29:34.526676 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-21 02:29:34.526715 | instance | Friday 21 August 2026  02:29:34 +0000 (0:00:00.111)       0:00:36.963 *********
2026-08-21 02:29:34.749718 | instance | ok: [instance] => (item=instance)
2026-08-21 02:29:34.749794 | instance |
2026-08-21 02:29:34.750077 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-21 02:29:34.750116 | instance | Friday 21 August 2026  02:29:34 +0000 (0:00:00.223)       0:00:37.187 *********
2026-08-21 02:29:34.798477 | instance | ok: [instance]
2026-08-21 02:29:34.798849 | instance |
2026-08-21 02:29:34.798886 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-21 02:29:34.798892 | instance | Friday 21 August 2026  02:29:34 +0000 (0:00:00.048)       0:00:37.235 *********
2026-08-21 02:29:34.863327 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-21 02:29:34.863419 | instance |
2026-08-21 02:29:34.863678 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-21 02:29:34.863810 | instance | Friday 21 August 2026  02:29:34 +0000 (0:00:00.064)       0:00:37.300 *********
2026-08-21 02:29:35.163103 | instance | changed: [instance]
2026-08-21 02:29:35.163174 | instance |
2026-08-21 02:29:35.163493 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-21 02:29:35.163529 | instance | Friday 21 August 2026  02:29:35 +0000 (0:00:00.299)       0:00:37.600 *********
2026-08-21 02:29:35.834375 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-21 02:29:35.834492 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-21 02:29:35.835099 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-21 02:29:35.835141 | instance |
2026-08-21 02:29:35.835147 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-21 02:29:35.835152 | instance | Friday 21 August 2026  02:29:35 +0000 (0:00:00.671)       0:00:38.271 *********
2026-08-21 02:31:00.911012 | instance | ok: [instance]
2026-08-21 02:31:00.911063 | instance |
2026-08-21 02:31:00.911071 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-21 02:31:00.911078 | instance | Friday 21 August 2026  02:31:00 +0000 (0:01:25.075)       0:02:03.347 *********
2026-08-21 02:31:01.125208 | instance | changed: [instance]
2026-08-21 02:31:01.125295 | instance |
2026-08-21 02:31:01.125414 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-21 02:31:01.125542 | instance | Friday 21 August 2026  02:31:01 +0000 (0:00:00.214)       0:02:03.562 *********
2026-08-21 02:31:01.201329 | instance | ok: [instance]
2026-08-21 02:31:01.201775 | instance |
2026-08-21 02:31:01.201815 | instance | TASK [Install Ceph host] *******************************************************
2026-08-21 02:31:01.201821 | instance | Friday 21 August 2026  02:31:01 +0000 (0:00:00.076)       0:02:03.638 *********
2026-08-21 02:31:01.298395 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-21 02:31:01.298467 | instance |
2026-08-21 02:31:01.298763 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-21 02:31:01.298807 | instance | Friday 21 August 2026  02:31:01 +0000 (0:00:00.097)       0:02:03.736 *********
2026-08-21 02:31:02.849659 | instance | ok: [instance]
2026-08-21 02:31:02.849739 | instance |
2026-08-21 02:31:02.849864 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-21 02:31:02.849991 | instance | Friday 21 August 2026  02:31:02 +0000 (0:00:01.551)       0:02:05.287 *********
2026-08-21 02:31:02.915334 | instance | ok: [instance] => (item=instance)
2026-08-21 02:31:02.915457 | instance |
2026-08-21 02:31:02.915638 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-21 02:31:02.915853 | instance | Friday 21 August 2026  02:31:02 +0000 (0:00:00.065)       0:02:05.352 *********
2026-08-21 02:31:03.262718 | instance | ok: [instance]
2026-08-21 02:31:03.263152 | instance |
2026-08-21 02:31:03.263633 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-21 02:31:03.264029 | instance | Friday 21 August 2026  02:31:03 +0000 (0:00:00.347)       0:02:05.699 *********
2026-08-21 02:31:05.572275 | instance | ok: [instance]
2026-08-21 02:31:05.572478 | instance |
2026-08-21 02:31:05.572707 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-21 02:31:05.572871 | instance | Friday 21 August 2026  02:31:05 +0000 (0:00:02.310)       0:02:08.009 *********
2026-08-21 02:31:50.859323 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-21 02:31:50.859659 | 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_zziybg41/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_zziybg41/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_zziybg41/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_zziybg41/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-08-21T02:31:50.702+0000 7fa40db3b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA7uIdqn8EbKyAA2dtXxcUn3/ZQwRGqpaiMbLoeKSsFB1BhQ/fneUm0fX0=: Malformed input [buffer:3]\n2026-08-21T02:31:50.702+0000 7fa40db3b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-21T02:31:50.706+0000 7fa40db3b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA7uIdqn8EbKyAA2dtXxcUn3/ZQwRGqpaiMbLoeKSsFB1BhQ/fneUm0fX0=: Malformed input [buffer:3]\n2026-08-21T02:31:50.706+0000 7fa40db3b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-21T02:31:50.706+0000 7fa40db3b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA7uIdqn8EbKyAA2dtXxcUn3/ZQwRGqpaiMbLoeKSsFB1BhQ/fneUm0fX0=: Malformed input [buffer:3]\n2026-08-21T02:31:50.706+0000 7fa40db3b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-21T02:31:50.706+0000 7fa40db3b640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-21 02:31:50.860946 | instance |
2026-08-21 02:31:50.861214 | instance | PLAY RECAP *********************************************************************
2026-08-21 02:31:50.861554 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-21 02:31:50.861813 | instance |
2026-08-21 02:31:50.862086 | instance | Friday 21 August 2026  02:31:50 +0000 (0:00:45.289)       0:02:53.299 *********
2026-08-21 02:31:50.862394 | instance | ===============================================================================
2026-08-21 02:31:50.862635 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 85.08s
2026-08-21 02:31:50.863048 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 45.29s
2026-08-21 02:31:50.863427 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.38s
2026-08-21 02:31:50.863766 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.50s
2026-08-21 02:31:50.864186 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.37s
2026-08-21 02:31:50.864557 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.85s
2026-08-21 02:31:50.864961 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.31s
2026-08-21 02:31:50.865303 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.58s
2026-08-21 02:31:50.865687 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.55s
2026-08-21 02:31:50.866040 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.23s
2026-08-21 02:31:50.866367 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.09s
2026-08-21 02:31:50.866709 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 0.98s
2026-08-21 02:31:50.867105 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-08-21 02:31:50.867487 | instance | Gathering Facts --------------------------------------------------------- 0.92s
2026-08-21 02:31:50.867785 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.92s
2026-08-21 02:31:50.868023 | instance | Gathering Facts --------------------------------------------------------- 0.83s
2026-08-21 02:31:50.868185 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.81s
2026-08-21 02:31:50.868400 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.70s
2026-08-21 02:31:50.868608 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.68s
2026-08-21 02:31:50.868775 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.67s
2026-08-21 02:31:50.959245 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-21 02:31:50.959445 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-21 02:31:50.959588 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-21 02:31:51.528974 | instance | ERROR
2026-08-21 02:31:51.529311 | instance | {
2026-08-21 02:31:51.529357 | instance |   "delta": "0:03:42.436615",
2026-08-21 02:31:51.529388 | instance |   "end": "2026-08-21 02:31:51.015431",
2026-08-21 02:31:51.529417 | instance |   "msg": "non-zero return code",
2026-08-21 02:31:51.529445 | instance |   "rc": 2,
2026-08-21 02:31:51.529471 | instance |   "start": "2026-08-21 02:28:08.578816"
2026-08-21 02:31:51.529497 | instance | }
failure
2026-08-21 02:31:51.535786 | 
2026-08-21 02:31:51.535861 | PLAY RECAP
2026-08-21 02:31:51.535930 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-21 02:31:51.535964 | 
2026-08-21 02:31:51.681965 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-21 02:31:51.684049 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-21 02:31:52.467964 | 
2026-08-21 02:31:52.468179 | PLAY [all]
2026-08-21 02:31:52.487165 | 
2026-08-21 02:31:52.487372 | TASK [gather-host-logs : creating directory for system status]
2026-08-21 02:31:52.890265 | instance | changed
2026-08-21 02:31:52.897753 | 
2026-08-21 02:31:52.897860 | TASK [gather-host-logs : Get logs for each host]
2026-08-21 02:31:53.263417 | instance | + systemd-cgls --full --all --no-pager
2026-08-21 02:31:53.277462 | instance | + ip addr
2026-08-21 02:31:53.280597 | instance | + ip route
2026-08-21 02:31:53.282617 | instance | + lsblk
2026-08-21 02:31:53.287621 | instance | + mount
2026-08-21 02:31:53.290810 | instance | + docker images
2026-08-21 02:31:53.309747 | instance | + brctl show
2026-08-21 02:31:53.310355 | instance | /bin/bash: line 8: brctl: command not found
2026-08-21 02:31:53.310673 | instance | + ps aux --sort=-%mem
2026-08-21 02:31:53.324789 | instance | + dpkg -l
2026-08-21 02:31:53.337344 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-21 02:31:53.338038 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-21 02:31:53.357691 | instance | + '[' '!' -z '' ']'
2026-08-21 02:31:53.440475 | instance | ok: Runtime: 0:00:00.101510
2026-08-21 02:31:53.450695 | 
2026-08-21 02:31:53.450882 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-21 02:31:54.093159 | instance | changed:
2026-08-21 02:31:54.093343 | instance | created directory /var/lib/zuul/builds/360581e935254354b0cb57058962b37a/work/logs/instance
2026-08-21 02:31:54.093384 | instance | cd+++++++++ system/
2026-08-21 02:31:54.093417 | instance | >f+++++++++ system/brctl-show.txt
2026-08-21 02:31:54.093448 | instance | >f+++++++++ system/docker-images.txt
2026-08-21 02:31:54.093477 | instance | >f+++++++++ system/ip-addr.txt
2026-08-21 02:31:54.093510 | instance | >f+++++++++ system/ip-route.txt
2026-08-21 02:31:54.093541 | instance | >f+++++++++ system/lsblk.txt
2026-08-21 02:31:54.093570 | instance | >f+++++++++ system/mount.txt
2026-08-21 02:31:54.093599 | instance | >f+++++++++ system/packages.txt
2026-08-21 02:31:54.093627 | instance | >f+++++++++ system/ps.txt
2026-08-21 02:31:54.093658 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-21 02:31:54.106225 | 
2026-08-21 02:31:54.106316 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-21 02:31:54.315151 | instance | changed: "values"
2026-08-21 02:31:54.483253 | instance | changed: "releases"
2026-08-21 02:31:54.498665 | 
2026-08-21 02:31:54.498878 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-21 02:31:54.713494 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-21 02:31:55.045117 | instance | ok: Runtime: 0:00:00.006139
2026-08-21 02:31:55.053796 | 
2026-08-21 02:31:55.053903 | TASK [helm-release-status : Downloads logs to executor]
2026-08-21 02:31:55.567159 | instance | changed:
2026-08-21 02:31:55.567320 | instance | cd+++++++++ helm/
2026-08-21 02:31:55.567372 | instance | cd+++++++++ helm/releases/
2026-08-21 02:31:55.567408 | instance | cd+++++++++ helm/values/
2026-08-21 02:31:55.582912 | 
2026-08-21 02:31:55.583118 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-21 02:31:55.783740 | instance | changed
2026-08-21 02:31:55.791862 | 
2026-08-21 02:31:55.792021 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-21 02:31:56.069068 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-21 02:31:56.069563 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-21 02:31:56.076811 | instance | environment: line 1: kubectl: command not found
2026-08-21 02:31:56.078411 | instance | environment: line 1: kubectl: command not found
2026-08-21 02:31:56.079010 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-21 02:31:56.079710 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-21 02:31:56.082443 | instance | environment: line 1: kubectl: command not found
2026-08-21 02:31:56.083262 | instance | environment: line 1: kubectl: command not found
2026-08-21 02:31:56.083355 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-21 02:31:56.084117 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-21 02:31:56.086183 | instance | environment: line 1: kubectl: command not found
2026-08-21 02:31:56.087427 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-21 02:31:56.328388 | instance | ok: Runtime: 0:00:00.026902
2026-08-21 02:31:56.335101 | 
2026-08-21 02:31:56.335181 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-21 02:31:56.534208 | instance | changed
2026-08-21 02:31:56.544565 | 
2026-08-21 02:31:56.544924 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-21 02:31:56.762075 | instance | environment: line 5: kubectl: command not found
2026-08-21 02:31:56.763106 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-21 02:31:56.763180 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-21 02:31:56.763965 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-21 02:31:57.090436 | instance | ok: Runtime: 0:00:00.009382
2026-08-21 02:31:57.097410 | 
2026-08-21 02:31:57.097504 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-21 02:31:57.833065 | instance | changed:
2026-08-21 02:31:57.833329 | instance | cd+++++++++ objects/
2026-08-21 02:31:57.833400 | instance | cd+++++++++ objects/cluster/
2026-08-21 02:31:57.833456 | instance | cd+++++++++ objects/namespaced/
2026-08-21 02:31:57.845682 | 
2026-08-21 02:31:57.845822 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-21 02:31:58.091446 | instance | changed
2026-08-21 02:31:58.102933 | 
2026-08-21 02:31:58.103028 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-21 02:31:58.333509 | instance | changed
2026-08-21 02:31:58.340646 | 
2026-08-21 02:31:58.340812 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-21 02:31:58.562846 | instance | environment: line 3: kubectl: command not found
2026-08-21 02:31:58.877570 | instance | ok: Runtime: 0:00:00.007338
2026-08-21 02:31:58.887854 | 
2026-08-21 02:31:58.888000 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-21 02:31:59.412244 | instance | changed:
2026-08-21 02:31:59.412495 | instance | cd+++++++++ pod-logs/
2026-08-21 02:31:59.412557 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-21 02:31:59.425979 | 
2026-08-21 02:31:59.426104 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-21 02:31:59.622752 | instance | changed
2026-08-21 02:31:59.629808 | 
2026-08-21 02:31:59.629914 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-21 02:31:59.844874 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-21 02:32:00.169034 | instance | ok: Runtime: 0:00:00.036334
2026-08-21 02:32:00.177805 | 
2026-08-21 02:32:00.178010 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-21 02:32:00.420673 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-21 02:32:00.447758 | instance | ceph-mgr endpoints:
2026-08-21 02:32:00.727003 | instance | ok: Runtime: 0:00:00.033426
2026-08-21 02:32:00.735686 | 
2026-08-21 02:32:00.735853 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-21 02:32:00.953962 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-21 02:32:01.285968 | instance | ok: Runtime: 0:00:00.039128
2026-08-21 02:32:01.297153 | 
2026-08-21 02:32:01.297391 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-21 02:32:01.813799 | instance | changed: cd+++++++++ prometheus/
2026-08-21 02:32:01.829842 | 
2026-08-21 02:32:01.830208 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-21 02:32:02.044893 | instance | changed
2026-08-21 02:32:02.051352 | 
2026-08-21 02:32:02.051527 | TASK [gather-selenium-data : Get selenium data]
2026-08-21 02:32:02.352201 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-21 02:32:02.353751 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-21 02:32:02.591784 | instance | ERROR
2026-08-21 02:32:02.592072 | instance | {
2026-08-21 02:32:02.592162 | instance |   "delta": "0:00:00.005681",
2026-08-21 02:32:02.592195 | instance |   "end": "2026-08-21 02:32:02.354136",
2026-08-21 02:32:02.592227 | instance |   "msg": "non-zero return code",
2026-08-21 02:32:02.592256 | instance |   "rc": 1,
2026-08-21 02:32:02.592283 | instance |   "start": "2026-08-21 02:32:02.348455"
2026-08-21 02:32:02.592310 | instance | }
2026-08-21 02:32:02.592344 | instance | ERROR: Ignoring Errors
2026-08-21 02:32:02.600595 | 
2026-08-21 02:32:02.600800 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-21 02:32:03.125663 | instance | changed: cd+++++++++ selenium/
2026-08-21 02:32:03.137120 | 
2026-08-21 02:32:03.137247 | PLAY RECAP
2026-08-21 02:32:03.137326 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-21 02:32:03.137367 | 
2026-08-21 02:32:03.410415 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-21 02:32:03.414050 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-21 02:32:04.418981 | 
2026-08-21 02:32:04.555574 | PLAY [all]
2026-08-21 02:32:04.578840 | 
2026-08-21 02:32:04.579001 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-21 02:32:04.616046 | instance | skipping: Conditional result was False
2026-08-21 02:32:04.631239 | 
2026-08-21 02:32:04.631396 | TASK [fetch-output : Set log path for single node]
2026-08-21 02:32:04.675940 | instance | ok
2026-08-21 02:32:04.680927 | 
2026-08-21 02:32:04.681045 | LOOP [fetch-output : Ensure local output dirs]
2026-08-21 02:32:05.250282 | instance -> localhost | ok: "/var/lib/zuul/builds/360581e935254354b0cb57058962b37a/work/logs"
2026-08-21 02:32:05.477414 | instance -> localhost | changed: "/var/lib/zuul/builds/360581e935254354b0cb57058962b37a/work/artifacts"
2026-08-21 02:32:05.733162 | instance -> localhost | changed: "/var/lib/zuul/builds/360581e935254354b0cb57058962b37a/work/docs"
2026-08-21 02:32:05.749692 | 
2026-08-21 02:32:05.749950 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-21 02:32:06.450223 | instance | changed: .d..t...... ./
2026-08-21 02:32:06.450444 | instance | changed: All items complete
2026-08-21 02:32:06.450484 | 
2026-08-21 02:32:06.943355 | instance | changed: .d..t...... ./
2026-08-21 02:32:07.460649 | instance | changed: .d..t...... ./
2026-08-21 02:32:07.475347 | 
2026-08-21 02:32:07.475465 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-21 02:32:08.094261 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007650
2026-08-21 02:32:08.363934 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006898
2026-08-21 02:32:08.384514 | 
2026-08-21 02:32:08.384672 | PLAY [all]
2026-08-21 02:32:08.391470 | 
2026-08-21 02:32:08.391547 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-21 02:32:08.897339 | instance | changed
2026-08-21 02:32:08.905907 | 
2026-08-21 02:32:08.906018 | PLAY RECAP
2026-08-21 02:32:08.906068 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-21 02:32:08.906092 | 
2026-08-21 02:32:09.047066 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-21 02:32:09.076379 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-21 02:32:10.021289 | 
2026-08-21 02:32:10.021457 | PLAY [localhost]
2026-08-21 02:32:10.032652 | 
2026-08-21 02:32:10.033346 | TASK [Generate Zuul manifest]
2026-08-21 02:32:10.053709 | localhost | ok
2026-08-21 02:32:10.101975 | 
2026-08-21 02:32:10.102146 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-21 02:32:10.527361 | localhost | changed
2026-08-21 02:32:10.544584 | 
2026-08-21 02:32:10.544744 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-21 02:32:10.689937 | localhost | ok
2026-08-21 02:32:10.698623 | 
2026-08-21 02:32:10.698731 | TASK [Upload logs]
2026-08-21 02:32:10.722565 | localhost | ok
2026-08-21 02:32:10.841969 | 
2026-08-21 02:32:10.842095 | TASK [Set zuul-log-path fact]
2026-08-21 02:32:10.863111 | localhost | ok
2026-08-21 02:32:10.887022 | 
2026-08-21 02:32:10.887272 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-21 02:32:10.922539 | localhost | ok
2026-08-21 02:32:10.971682 | 
2026-08-21 02:32:10.971878 | TASK [upload-logs : Create log directories]
2026-08-21 02:32:11.471755 | localhost | changed
2026-08-21 02:32:11.481604 | 
2026-08-21 02:32:11.481864 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-21 02:32:12.004649 | localhost -> localhost | ok: Runtime: 0:00:00.006585
2026-08-21 02:32:12.011687 | 
2026-08-21 02:32:12.011783 | TASK [upload-logs : Upload logs to log server]
2026-08-21 02:32:12.646758 | localhost | Output suppressed because no_log was given
2026-08-21 02:32:12.714923 | 
2026-08-21 02:32:12.715116 | LOOP [upload-logs : Compress console log and json output]
2026-08-21 02:32:12.772122 | localhost | skipping: Conditional result was False
2026-08-21 02:32:12.782890 | localhost | skipping: Conditional result was False
2026-08-21 02:32:12.799335 | 
2026-08-21 02:32:12.799709 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-21 02:32:12.847925 | localhost | skipping: Conditional result was False
2026-08-21 02:32:12.848247 | 
2026-08-21 02:32:12.853168 | localhost | skipping: Conditional result was False
2026-08-21 02:32:12.862681 | 
2026-08-21 02:32:12.862844 | LOOP [upload-logs : Upload console log and json output]