2026-08-28 02:08:31.912577 | Job console starting
2026-08-28 02:08:31.922209 | Updating git repos
2026-08-28 02:08:36.998124 | Cloning repos into workspace
2026-08-28 02:08:52.153123 | Restoring repo states
2026-08-28 02:09:08.690715 | Merging changes
2026-08-28 02:09:10.037976 | Checking out repos
2026-08-28 02:09:11.595757 | Preparing playbooks
2026-08-28 02:09:13.231158 | Running Ansible setup
2026-08-28 02:09:16.625862 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-28 02:09:17.225227 | 
2026-08-28 02:09:17.225344 | PLAY [localhost]
2026-08-28 02:09:17.232927 | 
2026-08-28 02:09:17.233025 | TASK [Gathering Facts]
2026-08-28 02:09:18.114966 | localhost | ok
2026-08-28 02:09:18.127056 | 
2026-08-28 02:09:18.127137 | TASK [Setup log path fact]
2026-08-28 02:09:18.149376 | localhost | ok
2026-08-28 02:09:18.164168 | 
2026-08-28 02:09:18.164239 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-28 02:09:18.195705 | localhost | ok
2026-08-28 02:09:18.203858 | 
2026-08-28 02:09:18.203932 | TASK [emit-job-header : Print job information]
2026-08-28 02:09:18.264296 | # Job Information
2026-08-28 02:09:18.264496 | Ansible Version: 2.16.18
2026-08-28 02:09:18.264529 | Job: atmosphere-molecule-csi-rbd
2026-08-28 02:09:18.264550 | Pipeline: check
2026-08-28 02:09:18.264570 | Executor: 2d72f0692154
2026-08-28 02:09:18.264588 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-08-28 02:09:18.264611 | Event ID: d243fd90-a284-11f1-81c9-640887afa4a9
2026-08-28 02:09:18.274170 | 
2026-08-28 02:09:18.274349 | LOOP [emit-job-header : Print node information]
2026-08-28 02:09:18.373752 | localhost | ok:
2026-08-28 02:09:18.374104 | localhost | # Node Information
2026-08-28 02:09:18.374180 | localhost | Inventory Hostname: instance
2026-08-28 02:09:18.374237 | localhost | Hostname: np0000191353
2026-08-28 02:09:18.374289 | localhost | Username: zuul
2026-08-28 02:09:18.374346 | localhost | Distro: Ubuntu 22.04
2026-08-28 02:09:18.374397 | localhost | Provider: yul1
2026-08-28 02:09:18.374446 | localhost | Region: ca-ymq-1
2026-08-28 02:09:18.374496 | localhost | Label: ubuntu-jammy
2026-08-28 02:09:18.374543 | localhost | Product Name: OpenStack Nova
2026-08-28 02:09:18.374591 | localhost | Interface IP: 162.253.55.239
2026-08-28 02:09:18.386864 | 
2026-08-28 02:09:18.387034 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-28 02:09:18.777635 | localhost -> localhost | changed
2026-08-28 02:09:18.786462 | 
2026-08-28 02:09:18.786535 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-28 02:09:19.623294 | localhost -> localhost | changed
2026-08-28 02:09:19.634563 | 
2026-08-28 02:09:19.693028 | PLAY [all]
2026-08-28 02:09:19.706880 | 
2026-08-28 02:09:19.706974 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-28 02:09:19.934400 | instance -> localhost | ok
2026-08-28 02:09:19.944665 | 
2026-08-28 02:09:20.030900 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-28 02:09:20.067527 | instance | ok
2026-08-28 02:09:20.082014 | instance | included: /var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-28 02:09:20.088141 | 
2026-08-28 02:09:20.088202 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-28 02:09:20.860596 | instance -> localhost | Generating public/private rsa key pair.
2026-08-28 02:09:20.860864 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/work/bfcf2188c7f643f991dd4d2506d7ff31_id_rsa
2026-08-28 02:09:20.860929 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/work/bfcf2188c7f643f991dd4d2506d7ff31_id_rsa.pub
2026-08-28 02:09:20.861022 | instance -> localhost | The key fingerprint is:
2026-08-28 02:09:20.861071 | instance -> localhost | SHA256:lj1JwINtSGibZ22e9ggkhH1cIMM7mGnndpzA8z7YLTI zuul-build-sshkey
2026-08-28 02:09:20.861136 | instance -> localhost | The key's randomart image is:
2026-08-28 02:09:20.861184 | instance -> localhost | +---[RSA 3072]----+
2026-08-28 02:09:20.861236 | instance -> localhost | |   +o+oBo        |
2026-08-28 02:09:20.861283 | instance -> localhost | |  . *o= =.       |
2026-08-28 02:09:20.861327 | instance -> localhost | |   B = o ..      |
2026-08-28 02:09:20.861372 | instance -> localhost | |  = @ + o+ .     |
2026-08-28 02:09:20.861415 | instance -> localhost | | . o @ +S.+      |
2026-08-28 02:09:20.861459 | instance -> localhost | |    o *.+  .     |
2026-08-28 02:09:20.861503 | instance -> localhost | |   . = + o       |
2026-08-28 02:09:20.861548 | instance -> localhost | |    E = o .      |
2026-08-28 02:09:20.861597 | instance -> localhost | |     o o         |
2026-08-28 02:09:20.861643 | instance -> localhost | +----[SHA256]-----+
2026-08-28 02:09:20.861736 | instance -> localhost | ok: Runtime: 0:00:00.329996
2026-08-28 02:09:20.868541 | 
2026-08-28 02:09:20.868604 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-28 02:09:20.902710 | instance | ok
2026-08-28 02:09:20.911111 | instance | included: /var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-28 02:09:20.918859 | 
2026-08-28 02:09:20.918921 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-28 02:09:20.944827 | instance | skipping: Conditional result was False
2026-08-28 02:09:20.954348 | 
2026-08-28 02:09:20.954441 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-28 02:09:21.401151 | instance | changed
2026-08-28 02:09:21.409469 | 
2026-08-28 02:09:21.409554 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-28 02:09:21.600631 | instance | ok
2026-08-28 02:09:21.606637 | 
2026-08-28 02:09:21.606707 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-28 02:09:22.096911 | instance | changed
2026-08-28 02:09:22.104028 | 
2026-08-28 02:09:22.104149 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-28 02:09:22.585260 | instance | changed
2026-08-28 02:09:22.591651 | 
2026-08-28 02:09:22.591725 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-28 02:09:22.618782 | instance | skipping: Conditional result was False
2026-08-28 02:09:22.629937 | 
2026-08-28 02:09:22.630018 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-28 02:09:22.989191 | instance -> localhost | changed
2026-08-28 02:09:23.005591 | 
2026-08-28 02:09:23.005691 | TASK [add-build-sshkey : Add back temp key]
2026-08-28 02:09:23.334659 | instance -> localhost | Identity added: /var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/work/bfcf2188c7f643f991dd4d2506d7ff31_id_rsa (zuul-build-sshkey)
2026-08-28 02:09:23.335001 | instance -> localhost | ok: Runtime: 0:00:00.014672
2026-08-28 02:09:23.341112 | 
2026-08-28 02:09:23.341184 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-28 02:09:23.680800 | instance | ok
2026-08-28 02:09:23.685456 | 
2026-08-28 02:09:23.685524 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-28 02:09:23.711028 | instance | skipping: Conditional result was False
2026-08-28 02:09:23.727260 | 
2026-08-28 02:09:23.727356 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-28 02:09:24.065990 | instance | ok
2026-08-28 02:09:24.074137 | 
2026-08-28 02:09:24.074223 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-28 02:09:27.498733 | instance | Output suppressed because no_log was given
2026-08-28 02:09:27.510081 | 
2026-08-28 02:09:27.510151 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-28 02:09:27.714142 | instance | ok: "logs"
2026-08-28 02:09:27.714450 | instance | ok: All items complete
2026-08-28 02:09:27.714479 | 
2026-08-28 02:09:27.850896 | instance | ok: "artifacts"
2026-08-28 02:09:28.025778 | instance | ok: "docs"
2026-08-28 02:09:28.047522 | 
2026-08-28 02:09:28.047685 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-28 02:09:28.232273 | instance | changed: "logs"
2026-08-28 02:09:28.404127 | instance | changed: "artifacts"
2026-08-28 02:09:28.555538 | instance | changed: "docs"
2026-08-28 02:09:28.569977 | 
2026-08-28 02:09:28.570105 | PLAY RECAP
2026-08-28 02:09:28.570178 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-28 02:09:28.570222 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-28 02:09:28.570250 | 
2026-08-28 02:09:28.743420 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-28 02:09:28.746888 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-28 02:09:29.388479 | 
2026-08-28 02:09:29.388623 | PLAY [all]
2026-08-28 02:09:29.399163 | 
2026-08-28 02:09:29.399251 | TASK [setup-uv : Extract archive]
2026-08-28 02:09:31.958002 | instance | changed
2026-08-28 02:09:31.964061 | 
2026-08-28 02:09:31.964138 | TASK [setup-uv : Print version]
2026-08-28 02:09:32.327076 | instance | uv 0.8.13
2026-08-28 02:09:32.502150 | instance | ok: Runtime: 0:00:00.007246
2026-08-28 02:09:32.511164 | 
2026-08-28 02:09:32.511236 | TASK [Install Ansible collection siblings]
2026-08-28 02:09:41.312365 | instance | changed:
2026-08-28 02:09:41.312597 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-28 02:09:41.312636 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-28 02:09:41.312665 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-28 02:09:41.312693 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-28 02:09:41.312720 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-28 02:09:41.312751 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-28 02:09:41.312779 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-28 02:09:41.312809 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-28 02:09:41.312845 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-28 02:09:41.312885 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-28 02:09:41.312915 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-28 02:09:41.312942 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-28 02:09:41.313013 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-08-28 02:09:41.313053 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-28 02:09:41.313093 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-08-28 02:09:41.313122 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-28 02:09:41.313148 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-28 02:09:41.313174 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-28 02:09:41.313198 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-28 02:09:41.313223 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-08-28 02:09:41.313248 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-08-28 02:09:41.313273 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-08-28 02:09:41.313300 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-28 02:09:41.313346 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-28 02:09:41.313373 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-28 02:09:41.313409 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-28 02:09:41.313443 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-28 02:09:41.313469 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-08-28 02:09:41.313494 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-28 02:09:41.313519 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-28 02:09:41.313544 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-28 02:09:41.313570 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-28 02:09:41.313612 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-28 02:09:41.313639 | instance | Checked out ansible.netcommon to v8.6.2
2026-08-28 02:09:41.313665 | 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-28 02:09:41.313699 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.313724 | instance | Process install dependency map
2026-08-28 02:09:41.313749 | instance | Starting collection install process
2026-08-28 02:09:41.313774 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-28 02:09:41.313799 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-28 02:09:41.313824 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-08-28 02:09:41.313848 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-28 02:09:41.313872 | instance | Creating virtual environment at: .venv
2026-08-28 02:09:41.313897 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-28 02:09:41.313924 | instance | Downloading kubernetes (1.9MiB)
2026-08-28 02:09:41.313967 | instance | Downloading cryptography (4.2MiB)
2026-08-28 02:09:41.314007 | instance | Downloading pygments (1.2MiB)
2026-08-28 02:09:41.314040 | instance | Downloading ansible-core (2.1MiB)
2026-08-28 02:09:41.314066 | instance | Downloading setuptools (1.1MiB)
2026-08-28 02:09:41.314091 | instance | Downloading openstacksdk (1.7MiB)
2026-08-28 02:09:41.314116 | instance | Downloading rjsonnet (1.2MiB)
2026-08-28 02:09:41.314141 | instance | Downloading netaddr (2.2MiB)
2026-08-28 02:09:41.314166 | instance |    Building pyperclip==1.9.0
2026-08-28 02:09:41.314193 | instance |  Downloading rjsonnet
2026-08-28 02:09:41.314219 | instance |  Downloading netaddr
2026-08-28 02:09:41.314245 | instance |  Downloading pygments
2026-08-28 02:09:41.314269 | instance |  Downloading cryptography
2026-08-28 02:09:41.314294 | instance |  Downloading setuptools
2026-08-28 02:09:41.314318 | instance |  Downloading kubernetes
2026-08-28 02:09:41.314342 | instance |  Downloading ansible-core
2026-08-28 02:09:41.314367 | instance |  Downloading openstacksdk
2026-08-28 02:09:41.314392 | instance |       Built pyperclip==1.9.0
2026-08-28 02:09:41.314417 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-28 02:09:41.314442 | instance | Installed 79 packages in 81ms
2026-08-28 02:09:41.314467 | instance | Checked out ansible.posix to 1.6.0
2026-08-28 02:09:41.314491 | 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-28 02:09:41.314516 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.314541 | instance | Process install dependency map
2026-08-28 02:09:41.314573 | instance | Starting collection install process
2026-08-28 02:09:41.314599 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-28 02:09:41.314624 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-28 02:09:41.314649 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-28 02:09:41.314673 | instance | Checked out ansible.utils to v6.1.0
2026-08-28 02:09:41.314698 | 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-28 02:09:41.314724 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.314748 | instance | Process install dependency map
2026-08-28 02:09:41.314773 | instance | Starting collection install process
2026-08-28 02:09:41.314797 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-28 02:09:41.314822 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-28 02:09:41.314846 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-28 02:09:41.314871 | instance | Checked out atmosphere.common to v0.6.0
2026-08-28 02:09:41.314896 | 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-28 02:09:41.314922 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.314947 | instance | Process install dependency map
2026-08-28 02:09:41.314972 | instance | Starting collection install process
2026-08-28 02:09:41.314997 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-28 02:09:41.315021 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-28 02:09:41.315046 | instance | atmosphere.common:0.6.0 was installed successfully
2026-08-28 02:09:41.315069 | instance | Checked out community.crypto to 2.2.3
2026-08-28 02:09:41.315094 | 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-28 02:09:41.315118 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.315142 | instance | Process install dependency map
2026-08-28 02:09:41.315165 | instance | Starting collection install process
2026-08-28 02:09:41.315189 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-28 02:09:41.315213 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-28 02:09:41.315237 | instance | community.crypto:2.2.3 was installed successfully
2026-08-28 02:09:41.315261 | instance | Checked out community.general to 7.3.0
2026-08-28 02:09:41.315286 | 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-28 02:09:41.315311 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.315335 | instance | Process install dependency map
2026-08-28 02:09:41.315359 | instance | Starting collection install process
2026-08-28 02:09:41.315383 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-28 02:09:41.315408 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-28 02:09:41.315432 | instance | community.general:7.3.0 was installed successfully
2026-08-28 02:09:41.315462 | instance | Checked out community.mysql to 3.6.0
2026-08-28 02:09:41.315487 | 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-28 02:09:41.315511 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.315536 | instance | Process install dependency map
2026-08-28 02:09:41.315560 | instance | Starting collection install process
2026-08-28 02:09:41.315584 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-28 02:09:41.315608 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-28 02:09:41.315633 | instance | community.mysql:3.6.0 was installed successfully
2026-08-28 02:09:41.315657 | instance | Checked out kubernetes.core to 2.4.0
2026-08-28 02:09:41.315682 | 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-28 02:09:41.315707 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.315731 | instance | Process install dependency map
2026-08-28 02:09:41.315758 | instance | Starting collection install process
2026-08-28 02:09:41.315783 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-28 02:09:41.315808 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-28 02:09:41.315832 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-28 02:09:41.315857 | instance | Checked out openstack.cloud to 1.7.0
2026-08-28 02:09:41.315882 | 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-28 02:09:41.315907 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.315932 | instance | Process install dependency map
2026-08-28 02:09:41.315956 | instance | Starting collection install process
2026-08-28 02:09:41.315981 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-28 02:09:41.316005 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-28 02:09:41.316030 | instance | openstack.cloud:1.7.0 was installed successfully
2026-08-28 02:09:41.316068 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-28 02:09:41.316107 | 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-28 02:09:41.316138 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.316163 | instance | Process install dependency map
2026-08-28 02:09:41.316188 | instance | Starting collection install process
2026-08-28 02:09:41.316214 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-28 02:09:41.316239 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-28 02:09:41.316264 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-28 02:09:41.316289 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-28 02:09:41.316313 | 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-28 02:09:41.316338 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.316370 | instance | Process install dependency map
2026-08-28 02:09:41.316395 | instance | Starting collection install process
2026-08-28 02:09:41.316420 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-28 02:09:41.316444 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-28 02:09:41.316469 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-28 02:09:41.316494 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-28 02:09:41.316519 | 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-28 02:09:41.316544 | instance | Starting galaxy collection install process
2026-08-28 02:09:41.316567 | instance | Process install dependency map
2026-08-28 02:09:41.316591 | instance | Starting collection install process
2026-08-28 02:09:41.316615 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-28 02:09:41.316639 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-28 02:09:41.316663 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-28 02:09:41.323189 | 
2026-08-28 02:09:41.323240 | PLAY RECAP
2026-08-28 02:09:41.323290 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-28 02:09:41.323311 | 
2026-08-28 02:09:41.457576 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-28 02:09:41.917504 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-28 02:09:42.508199 | 
2026-08-28 02:09:42.508338 | PLAY [all]
2026-08-28 02:09:42.519451 | 
2026-08-28 02:09:42.519530 | TASK [Install "jq" for log collection]
2026-08-28 02:09:51.959965 | instance | changed
2026-08-28 02:09:52.049547 | 
2026-08-28 02:09:52.049644 | PLAY RECAP
2026-08-28 02:09:52.049715 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-28 02:09:52.049782 | 
2026-08-28 02:09:52.244624 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-28 02:09:52.254213 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-28 02:09:52.860398 | 
2026-08-28 02:09:52.860536 | PLAY [all]
2026-08-28 02:09:52.871838 | 
2026-08-28 02:09:52.871917 | LOOP [Check inventory directories]
2026-08-28 02:09:53.210039 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-28 02:09:53.374652 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-28 02:09:53.845972 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-28 02:09:53.865443 | 
2026-08-28 02:09:53.865649 | LOOP [Copy inventory file for Zuul]
2026-08-28 02:09:54.680762 | instance | changed:
2026-08-28 02:09:54.681296 | instance | {
2026-08-28 02:09:54.681481 | instance |   "ansible_loop_var": "item",
2026-08-28 02:09:54.681520 | instance |   "changed": false,
2026-08-28 02:09:54.681714 | instance |   "failed": false,
2026-08-28 02:09:54.681754 | instance |   "invocation": {
2026-08-28 02:09:54.681776 | instance |     "module_args": {
2026-08-28 02:09:54.681944 | instance |       "checksum_algorithm": "sha1",
2026-08-28 02:09:54.681976 | instance |       "follow": true,
2026-08-28 02:09:54.681996 | instance |       "get_attributes": true,
2026-08-28 02:09:54.682193 | instance |       "get_checksum": true,
2026-08-28 02:09:54.682233 | instance |       "get_mime": true,
2026-08-28 02:09:54.682256 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-28 02:09:54.682297 | instance |     }
2026-08-28 02:09:54.682317 | instance |   },
2026-08-28 02:09:54.682337 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-28 02:09:54.682506 | instance |   "stat": {
2026-08-28 02:09:54.682536 | instance |     "atime": 1787882974.2905788,
2026-08-28 02:09:54.682557 | instance |     "attr_flags": "e",
2026-08-28 02:09:54.682576 | instance |     "attributes": [
2026-08-28 02:09:54.682595 | instance |       "extents"
2026-08-28 02:09:54.682816 | instance |     ],
2026-08-28 02:09:54.682853 | instance |     "block_size": 4096,
2026-08-28 02:09:54.682875 | instance |     "blocks": 8,
2026-08-28 02:09:54.682895 | instance |     "charset": "unknown",
2026-08-28 02:09:54.682917 | instance |     "ctime": 1787882973.3665757,
2026-08-28 02:09:54.682937 | instance |     "dev": 64513,
2026-08-28 02:09:54.682955 | instance |     "device_type": 0,
2026-08-28 02:09:54.682974 | instance |     "executable": true,
2026-08-28 02:09:54.683160 | instance |     "exists": true,
2026-08-28 02:09:54.683183 | instance |     "gid": 1000,
2026-08-28 02:09:54.683202 | instance |     "gr_name": "zuul",
2026-08-28 02:09:54.683221 | instance |     "inode": 1047247,
2026-08-28 02:09:54.683240 | instance |     "isblk": false,
2026-08-28 02:09:54.683432 | instance |     "ischr": false,
2026-08-28 02:09:54.683464 | instance |     "isdir": true,
2026-08-28 02:09:54.683485 | instance |     "isfifo": false,
2026-08-28 02:09:54.683504 | instance |     "isgid": false,
2026-08-28 02:09:54.683523 | instance |     "islnk": false,
2026-08-28 02:09:54.683541 | instance |     "isreg": false,
2026-08-28 02:09:54.683558 | instance |     "issock": false,
2026-08-28 02:09:54.683576 | instance |     "isuid": false,
2026-08-28 02:09:54.683594 | instance |     "mimetype": "unknown",
2026-08-28 02:09:54.683612 | instance |     "mode": "0755",
2026-08-28 02:09:54.683781 | instance |     "mtime": 1787882973.3665757,
2026-08-28 02:09:54.683809 | instance |     "nlink": 22,
2026-08-28 02:09:54.683829 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-28 02:09:54.683853 | instance |     "pw_name": "zuul",
2026-08-28 02:09:54.683877 | instance |     "readable": true,
2026-08-28 02:09:54.683897 | instance |     "rgrp": true,
2026-08-28 02:09:54.683915 | instance |     "roth": true,
2026-08-28 02:09:54.683934 | instance |     "rusr": true,
2026-08-28 02:09:54.683953 | instance |     "size": 4096,
2026-08-28 02:09:54.683971 | instance |     "uid": 1000,
2026-08-28 02:09:54.684184 | instance |     "version": "3313493952",
2026-08-28 02:09:54.684218 | instance |     "wgrp": false,
2026-08-28 02:09:54.684246 | instance |     "woth": false,
2026-08-28 02:09:54.684267 | instance |     "writeable": true,
2026-08-28 02:09:54.684286 | instance |     "wusr": true,
2026-08-28 02:09:54.684455 | instance |     "xgrp": true,
2026-08-28 02:09:54.684489 | instance |     "xoth": true,
2026-08-28 02:09:54.684509 | instance |     "xusr": true
2026-08-28 02:09:54.684529 | instance |   }
2026-08-28 02:09:54.684548 | instance | }
2026-08-28 02:09:54.684588 | instance | ok: All items complete
2026-08-28 02:09:54.684611 | 
2026-08-28 02:09:54.687225 | instance | skipping: Conditional result was False
2026-08-28 02:09:54.688658 | instance | skipping: Conditional result was False
2026-08-28 02:09:54.701568 | 
2026-08-28 02:09:54.701642 | LOOP [Switch "ansible_host" to private IP]
2026-08-28 02:09:55.062230 | instance | changed: 1 replacements made
2026-08-28 02:09:55.063496 | instance | skipping: Conditional result was False
2026-08-28 02:09:55.064061 | instance | skipping: Conditional result was False
2026-08-28 02:09:55.082928 | 
2026-08-28 02:09:55.083020 | TASK [Run Molecule scenario]
2026-08-28 02:09:55.979381 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-28 02:09:56.378783 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-28 02:09:56.378871 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-28 02:10:06.602955 | instance | INFO     [csi > dependency] Executing
2026-08-28 02:10:06.603323 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-28 02:10:06.603704 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-28 02:10:06.603933 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-28 02:10:06.614435 | instance | INFO     [csi > cleanup] Executing
2026-08-28 02:10:06.614812 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-28 02:10:06.628584 | instance | INFO     [csi > destroy] Executing
2026-08-28 02:10:06.628619 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-28 02:10:06.628636 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-28 02:10:06.637502 | instance | INFO     [csi > syntax] Executing
2026-08-28 02:10:08.151067 | instance |
2026-08-28 02:10:08.151305 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-28 02:10:08.242698 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-28 02:10:08.257342 | instance | INFO     [csi > create] Executing
2026-08-28 02:10:08.260740 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-28 02:10:08.274940 | instance | INFO     [csi > prepare] Executing
2026-08-28 02:10:09.069592 | instance |
2026-08-28 02:10:09.069793 | instance | PLAY [Prepare] *****************************************************************
2026-08-28 02:10:09.070109 | instance |
2026-08-28 02:10:09.070291 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 02:10:09.070558 | instance | Friday 28 August 2026  02:10:09 +0000 (0:00:00.024)       0:00:00.024 *********
2026-08-28 02:10:10.289597 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-28 02:10:10.289816 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-28 02:10:10.290063 | instance | interpreter could change the meaning of that path. See
2026-08-28 02:10:10.290299 | instance | https://docs.ansible.com/ansible-
2026-08-28 02:10:10.290527 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-28 02:10:10.299903 | instance | ok: [instance]
2026-08-28 02:10:10.300148 | instance |
2026-08-28 02:10:10.300384 | instance | TASK [Configure short hostname] ************************************************
2026-08-28 02:10:10.300613 | instance | Friday 28 August 2026  02:10:10 +0000 (0:00:01.231)       0:00:01.255 *********
2026-08-28 02:10:11.184827 | instance | changed: [instance]
2026-08-28 02:10:11.185119 | instance |
2026-08-28 02:10:11.185472 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-28 02:10:11.185821 | instance | Friday 28 August 2026  02:10:11 +0000 (0:00:00.884)       0:00:02.139 *********
2026-08-28 02:10:11.458462 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-28 02:10:11.458525 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-28 02:10:11.458541 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-28 02:10:11.467169 | instance | changed: [instance]
2026-08-28 02:10:11.467477 | instance |
2026-08-28 02:10:11.467782 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-28 02:10:11.468129 | instance | Friday 28 August 2026  02:10:11 +0000 (0:00:00.282)       0:00:02.422 *********
2026-08-28 02:10:12.216749 | instance | ok: [instance]
2026-08-28 02:10:12.217086 | instance |
2026-08-28 02:10:12.217412 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-28 02:10:12.217688 | instance |
2026-08-28 02:10:12.217977 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 02:10:12.218314 | instance | Friday 28 August 2026  02:10:12 +0000 (0:00:00.749)       0:00:03.171 *********
2026-08-28 02:10:12.962587 | instance | ok: [instance]
2026-08-28 02:10:12.962915 | instance |
2026-08-28 02:10:12.963273 | instance | TASK [Install depedencies] *****************************************************
2026-08-28 02:10:12.963696 | instance | Friday 28 August 2026  02:10:12 +0000 (0:00:00.745)       0:00:03.917 *********
2026-08-28 02:10:35.411872 | instance | changed: [instance]
2026-08-28 02:10:35.411933 | instance |
2026-08-28 02:10:35.411945 | instance | TASK [Start up service] ********************************************************
2026-08-28 02:10:35.411956 | instance | Friday 28 August 2026  02:10:35 +0000 (0:00:22.448)       0:00:26.365 *********
2026-08-28 02:10:35.954180 | instance | ok: [instance]
2026-08-28 02:10:35.954625 | instance |
2026-08-28 02:10:35.954664 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-28 02:10:35.954672 | instance | Friday 28 August 2026  02:10:35 +0000 (0:00:00.543)       0:00:26.909 *********
2026-08-28 02:10:36.229947 | instance | ok: [instance]
2026-08-28 02:10:36.230032 | instance |
2026-08-28 02:10:36.230278 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-28 02:10:36.230323 | instance | Friday 28 August 2026  02:10:36 +0000 (0:00:00.276)       0:00:27.185 *********
2026-08-28 02:10:36.882051 | instance | changed: [instance]
2026-08-28 02:10:36.882791 | instance |
2026-08-28 02:10:36.882842 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-28 02:10:36.882855 | instance | Friday 28 August 2026  02:10:36 +0000 (0:00:00.651)       0:00:27.836 *********
2026-08-28 02:10:37.074652 | instance | ok: [instance]
2026-08-28 02:10:37.075364 | instance |
2026-08-28 02:10:37.075404 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-28 02:10:37.075416 | instance | Friday 28 August 2026  02:10:37 +0000 (0:00:00.192)       0:00:28.029 *********
2026-08-28 02:10:37.100108 | instance | skipping: [instance]
2026-08-28 02:10:37.100591 | instance |
2026-08-28 02:10:37.100617 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-28 02:10:37.100625 | instance | Friday 28 August 2026  02:10:37 +0000 (0:00:00.025)       0:00:28.055 *********
2026-08-28 02:10:37.657499 | instance | changed: [instance] => (item=osd0)
2026-08-28 02:10:37.658640 | instance | changed: [instance] => (item=osd1)
2026-08-28 02:10:37.658694 | instance | changed: [instance] => (item=osd2)
2026-08-28 02:10:37.658706 | instance |
2026-08-28 02:10:37.658715 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-28 02:10:37.658725 | instance | Friday 28 August 2026  02:10:37 +0000 (0:00:00.556)       0:00:28.612 *********
2026-08-28 02:10:38.300779 | instance | changed: [instance] => (item=osd0)
2026-08-28 02:10:38.302042 | instance | changed: [instance] => (item=osd1)
2026-08-28 02:10:38.302096 | instance | changed: [instance] => (item=osd2)
2026-08-28 02:10:38.302107 | instance |
2026-08-28 02:10:38.302117 | instance | TASK [Start loop devices] ******************************************************
2026-08-28 02:10:38.302127 | instance | Friday 28 August 2026  02:10:38 +0000 (0:00:00.643)       0:00:29.255 *********
2026-08-28 02:10:39.032321 | instance | changed: [instance] => (item=osd0)
2026-08-28 02:10:39.032544 | instance | changed: [instance] => (item=osd1)
2026-08-28 02:10:39.032552 | instance | changed: [instance] => (item=osd2)
2026-08-28 02:10:39.032845 | instance |
2026-08-28 02:10:39.032878 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-28 02:10:39.032884 | instance | Friday 28 August 2026  02:10:39 +0000 (0:00:00.732)       0:00:29.987 *********
2026-08-28 02:10:42.190955 | instance | changed: [instance] => (item=osd0)
2026-08-28 02:10:42.192152 | instance | changed: [instance] => (item=osd1)
2026-08-28 02:10:42.192202 | instance | changed: [instance] => (item=osd2)
2026-08-28 02:10:42.192214 | instance |
2026-08-28 02:10:42.192224 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-28 02:10:42.192239 | instance | Friday 28 August 2026  02:10:42 +0000 (0:00:03.157)       0:00:33.145 *********
2026-08-28 02:10:44.041285 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-28 02:10:44.041676 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-28 02:10:44.041760 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-28 02:10:44.044130 | instance |
2026-08-28 02:10:44.044148 | instance | PLAY RECAP *********************************************************************
2026-08-28 02:10:44.044158 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-28 02:10:44.044167 | instance |
2026-08-28 02:10:44.044176 | instance | Friday 28 August 2026  02:10:44 +0000 (0:00:01.851)       0:00:34.996 *********
2026-08-28 02:10:44.044185 | instance | ===============================================================================
2026-08-28 02:10:44.044194 | instance | Install depedencies ---------------------------------------------------- 22.45s
2026-08-28 02:10:44.044202 | instance | Create a volume group for each loop device ------------------------------ 3.16s
2026-08-28 02:10:44.044210 | instance | Create a logical volume for each loop device ---------------------------- 1.85s
2026-08-28 02:10:44.044219 | instance | Gathering Facts --------------------------------------------------------- 1.23s
2026-08-28 02:10:44.044351 | instance | Configure short hostname ------------------------------------------------ 0.88s
2026-08-28 02:10:44.044722 | instance | Purge "snapd" package --------------------------------------------------- 0.75s
2026-08-28 02:10:44.045080 | instance | Gathering Facts --------------------------------------------------------- 0.75s
2026-08-28 02:10:44.045370 | instance | Start loop devices ------------------------------------------------------ 0.73s
2026-08-28 02:10:44.045666 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.65s
2026-08-28 02:10:44.045979 | instance | Set permissions on loopback devices ------------------------------------- 0.64s
2026-08-28 02:10:44.046270 | instance | Create devices for Ceph ------------------------------------------------- 0.56s
2026-08-28 02:10:44.046555 | instance | Start up service -------------------------------------------------------- 0.54s
2026-08-28 02:10:44.046882 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-08-28 02:10:44.047168 | instance | Generate lvm.conf ------------------------------------------------------- 0.28s
2026-08-28 02:10:44.047455 | instance | Get list of all loopback devices ---------------------------------------- 0.19s
2026-08-28 02:10:44.047759 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-08-28 02:10:44.116262 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-28 02:10:44.130837 | instance | INFO     [csi > converge] Executing
2026-08-28 02:10:45.415403 | instance |
2026-08-28 02:10:45.415770 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-28 02:10:45.416106 | instance |
2026-08-28 02:10:45.416403 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 02:10:45.416691 | instance | Friday 28 August 2026  02:10:45 +0000 (0:00:00.006)       0:00:00.006 *********
2026-08-28 02:10:46.403266 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-28 02:10:46.403315 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-28 02:10:46.403327 | instance | interpreter could change the meaning of that path. See
2026-08-28 02:10:46.403336 | instance | https://docs.ansible.com/ansible-
2026-08-28 02:10:46.403345 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-28 02:10:46.416200 | instance | ok: [instance]
2026-08-28 02:10:46.416261 | instance |
2026-08-28 02:10:46.416281 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-28 02:10:46.416517 | instance | Friday 28 August 2026  02:10:46 +0000 (0:00:01.000)       0:00:01.006 *********
2026-08-28 02:10:46.473197 | instance | ok: [instance] => {
2026-08-28 02:10:46.474748 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-28 02:10:46.474798 | instance | }
2026-08-28 02:10:46.474810 | instance |
2026-08-28 02:10:46.474819 | instance | PLAY [all] *********************************************************************
2026-08-28 02:10:46.474828 | instance |
2026-08-28 02:10:46.474836 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 02:10:46.474857 | instance | Friday 28 August 2026  02:10:46 +0000 (0:00:00.057)       0:00:01.064 *********
2026-08-28 02:10:47.394368 | instance | ok: [instance]
2026-08-28 02:10:47.394575 | instance |
2026-08-28 02:10:47.394906 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-28 02:10:47.395226 | instance | Friday 28 August 2026  02:10:47 +0000 (0:00:00.921)       0:00:01.985 *********
2026-08-28 02:10:47.597556 | instance | ok: [instance]
2026-08-28 02:10:47.597647 | instance |
2026-08-28 02:10:47.597867 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-28 02:10:47.598056 | instance |
2026-08-28 02:10:47.598239 | instance | TASK [Gathering Facts] *********************************************************
2026-08-28 02:10:47.598422 | instance | Friday 28 August 2026  02:10:47 +0000 (0:00:00.203)       0:00:02.189 *********
2026-08-28 02:10:48.531699 | instance | ok: [instance]
2026-08-28 02:10:48.531909 | instance |
2026-08-28 02:10:48.532311 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-28 02:10:48.532628 | instance | Friday 28 August 2026  02:10:48 +0000 (0:00:00.933)       0:00:03.122 *********
2026-08-28 02:10:48.839886 | instance | ok: [instance]
2026-08-28 02:10:48.839921 | instance |
2026-08-28 02:10:48.839932 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-28 02:10:48.839941 | instance | Friday 28 August 2026  02:10:48 +0000 (0:00:00.307)       0:00:03.430 *********
2026-08-28 02:10:48.891640 | instance | skipping: [instance]
2026-08-28 02:10:48.891661 | instance |
2026-08-28 02:10:48.891668 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-28 02:10:48.891674 | instance | Friday 28 August 2026  02:10:48 +0000 (0:00:00.052)       0:00:03.483 *********
2026-08-28 02:10:49.249633 | instance | changed: [instance]
2026-08-28 02:10:49.250327 | instance |
2026-08-28 02:10:49.250381 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-28 02:10:49.250393 | instance | Friday 28 August 2026  02:10:49 +0000 (0:00:00.357)       0:00:03.840 *********
2026-08-28 02:10:49.342083 | instance | ok: [instance] => {
2026-08-28 02:10:49.343267 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-28 02:10:49.343301 | instance | }
2026-08-28 02:10:49.343313 | instance |
2026-08-28 02:10:49.343323 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-28 02:10:49.343333 | instance | Friday 28 August 2026  02:10:49 +0000 (0:00:00.092)       0:00:03.933 *********
2026-08-28 02:10:50.216906 | instance | changed: [instance]
2026-08-28 02:10:50.217633 | instance |
2026-08-28 02:10:50.217676 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-28 02:10:50.217688 | instance | Friday 28 August 2026  02:10:50 +0000 (0:00:00.874)       0:00:04.807 *********
2026-08-28 02:10:50.276437 | instance | skipping: [instance]
2026-08-28 02:10:50.276574 | instance |
2026-08-28 02:10:50.276800 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-28 02:10:50.276994 | instance | Friday 28 August 2026  02:10:50 +0000 (0:00:00.060)       0:00:04.867 *********
2026-08-28 02:10:50.339378 | instance | skipping: [instance]
2026-08-28 02:10:50.339498 | instance |
2026-08-28 02:10:50.339656 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-28 02:10:50.339922 | instance | Friday 28 August 2026  02:10:50 +0000 (0:00:00.063)       0:00:04.931 *********
2026-08-28 02:10:50.581423 | instance | ok: [instance]
2026-08-28 02:10:50.581469 | instance |
2026-08-28 02:10:50.581480 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-28 02:10:50.581489 | instance | Friday 28 August 2026  02:10:50 +0000 (0:00:00.240)       0:00:05.171 *********
2026-08-28 02:10:51.761017 | instance | ok: [instance]
2026-08-28 02:10:51.761160 | instance |
2026-08-28 02:10:51.761185 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-28 02:10:51.761192 | instance | Friday 28 August 2026  02:10:51 +0000 (0:00:01.180)       0:00:06.352 *********
2026-08-28 02:10:51.847313 | instance | ok: [instance] => {
2026-08-28 02:10:51.847347 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-28 02:10:51.847372 | instance | }
2026-08-28 02:10:51.847382 | instance |
2026-08-28 02:10:51.847391 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-28 02:10:51.847399 | instance | Friday 28 August 2026  02:10:51 +0000 (0:00:00.085)       0:00:06.437 *********
2026-08-28 02:10:52.730921 | instance | changed: [instance]
2026-08-28 02:10:52.731702 | instance |
2026-08-28 02:10:52.731752 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-28 02:10:52.731764 | instance | Friday 28 August 2026  02:10:52 +0000 (0:00:00.884)       0:00:07.321 *********
2026-08-28 02:10:55.536776 | instance | changed: [instance]
2026-08-28 02:10:55.537506 | instance |
2026-08-28 02:10:55.537556 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-28 02:10:55.537569 | instance | Friday 28 August 2026  02:10:55 +0000 (0:00:02.805)       0:00:10.127 *********
2026-08-28 02:10:55.576977 | instance | skipping: [instance]
2026-08-28 02:10:55.577729 | instance |
2026-08-28 02:10:55.577780 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-28 02:10:55.577792 | instance | Friday 28 August 2026  02:10:55 +0000 (0:00:00.039)       0:00:10.167 *********
2026-08-28 02:10:55.615486 | instance | skipping: [instance]
2026-08-28 02:10:55.616199 | instance |
2026-08-28 02:10:55.616371 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-28 02:10:55.616587 | instance | Friday 28 August 2026  02:10:55 +0000 (0:00:00.038)       0:00:10.206 *********
2026-08-28 02:10:55.654341 | instance | skipping: [instance]
2026-08-28 02:10:55.654584 | instance |
2026-08-28 02:10:55.654910 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-28 02:10:55.655209 | instance | Friday 28 August 2026  02:10:55 +0000 (0:00:00.038)       0:00:10.245 *********
2026-08-28 02:11:01.375148 | instance | changed: [instance]
2026-08-28 02:11:01.375610 | instance |
2026-08-28 02:11:01.375644 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-28 02:11:01.375652 | instance | Friday 28 August 2026  02:11:01 +0000 (0:00:05.720)       0:00:15.966 *********
2026-08-28 02:11:01.981210 | instance | changed: [instance]
2026-08-28 02:11:01.981256 | instance |
2026-08-28 02:11:01.981269 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-28 02:11:01.981280 | instance | Friday 28 August 2026  02:11:01 +0000 (0:00:00.604)       0:00:16.571 *********
2026-08-28 02:11:02.943170 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-28 02:11:02.945009 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-28 02:11:02.945057 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-28 02:11:02.945068 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-28 02:11:02.945078 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-28 02:11:02.945088 | instance |
2026-08-28 02:11:02.945105 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-28 02:11:02.945115 | instance | Friday 28 August 2026  02:11:02 +0000 (0:00:00.962)       0:00:17.534 *********
2026-08-28 02:11:03.519418 | instance | changed: [instance]
2026-08-28 02:11:03.519480 | instance |
2026-08-28 02:11:03.520169 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-28 02:11:03.520305 | instance | Friday 28 August 2026  02:11:03 +0000 (0:00:00.548)       0:00:18.082 *********
2026-08-28 02:11:03.520318 | instance |
2026-08-28 02:11:03.520327 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-28 02:11:03.520340 | instance | Friday 28 August 2026  02:11:03 +0000 (0:00:00.028)       0:00:18.110 *********
2026-08-28 02:11:04.384719 | instance | ok: [instance]
2026-08-28 02:11:04.384770 | instance |
2026-08-28 02:11:04.385027 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-28 02:11:04.385057 | instance | Friday 28 August 2026  02:11:04 +0000 (0:00:00.865)       0:00:18.976 *********
2026-08-28 02:11:04.828475 | instance | changed: [instance]
2026-08-28 02:11:04.829240 | instance |
2026-08-28 02:11:04.829289 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-28 02:11:04.829301 | instance | Friday 28 August 2026  02:11:04 +0000 (0:00:00.443)       0:00:19.419 *********
2026-08-28 02:11:05.388118 | instance | changed: [instance]
2026-08-28 02:11:05.388834 | instance |
2026-08-28 02:11:05.389083 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-28 02:11:05.389096 | instance | Friday 28 August 2026  02:11:05 +0000 (0:00:00.559)       0:00:19.979 *********
2026-08-28 02:11:05.620779 | instance | ok: [instance]
2026-08-28 02:11:05.621472 | instance |
2026-08-28 02:11:05.621524 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-28 02:11:05.621536 | instance | Friday 28 August 2026  02:11:05 +0000 (0:00:00.232)       0:00:20.211 *********
2026-08-28 02:11:05.690648 | instance | ok: [instance] => {
2026-08-28 02:11:05.691411 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-28 02:11:05.691452 | instance | }
2026-08-28 02:11:05.691460 | instance |
2026-08-28 02:11:05.691466 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-28 02:11:05.691472 | instance | Friday 28 August 2026  02:11:05 +0000 (0:00:00.070)       0:00:20.282 *********
2026-08-28 02:11:06.568605 | instance | changed: [instance]
2026-08-28 02:11:06.568681 | instance |
2026-08-28 02:11:06.568917 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-28 02:11:06.568955 | instance | Friday 28 August 2026  02:11:06 +0000 (0:00:00.877)       0:00:21.160 *********
2026-08-28 02:11:10.922155 | instance | changed: [instance]
2026-08-28 02:11:10.922224 | instance |
2026-08-28 02:11:10.922563 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-28 02:11:10.922601 | instance | Friday 28 August 2026  02:11:10 +0000 (0:00:04.353)       0:00:25.513 *********
2026-08-28 02:11:11.950582 | instance | ok: [instance]
2026-08-28 02:11:11.951264 | instance |
2026-08-28 02:11:11.951315 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-28 02:11:11.951327 | instance | Friday 28 August 2026  02:11:11 +0000 (0:00:01.027)       0:00:26.541 *********
2026-08-28 02:11:12.320665 | instance | changed: [instance]
2026-08-28 02:11:12.320704 | instance |
2026-08-28 02:11:12.320711 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-28 02:11:12.320718 | instance | Friday 28 August 2026  02:11:12 +0000 (0:00:00.369)       0:00:26.911 *********
2026-08-28 02:11:12.801039 | instance | changed: [instance]
2026-08-28 02:11:12.801748 | instance |
2026-08-28 02:11:12.801793 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-28 02:11:12.801804 | instance | Friday 28 August 2026  02:11:12 +0000 (0:00:00.480)       0:00:27.392 *********
2026-08-28 02:11:13.390015 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-28 02:11:13.390068 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-28 02:11:13.390531 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-28 02:11:13.390546 | instance |
2026-08-28 02:11:13.390552 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-28 02:11:13.390556 | instance | Friday 28 August 2026  02:11:13 +0000 (0:00:00.589)       0:00:27.981 *********
2026-08-28 02:11:13.862765 | instance | changed: [instance]
2026-08-28 02:11:13.863417 | instance |
2026-08-28 02:11:13.863614 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-28 02:11:13.863627 | instance | Friday 28 August 2026  02:11:13 +0000 (0:00:00.472)       0:00:28.453 *********
2026-08-28 02:11:14.345504 | instance | changed: [instance]
2026-08-28 02:11:14.345561 | instance |
2026-08-28 02:11:14.346061 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-28 02:11:14.346096 | instance | Friday 28 August 2026  02:11:14 +0000 (0:00:00.465)       0:00:28.919 *********
2026-08-28 02:11:14.346101 | instance |
2026-08-28 02:11:14.346106 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-28 02:11:14.346110 | instance | Friday 28 August 2026  02:11:14 +0000 (0:00:00.017)       0:00:28.937 *********
2026-08-28 02:11:15.076321 | instance | ok: [instance]
2026-08-28 02:11:15.077039 | instance |
2026-08-28 02:11:15.077088 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-28 02:11:15.077106 | instance | Friday 28 August 2026  02:11:15 +0000 (0:00:00.730)       0:00:29.667 *********
2026-08-28 02:11:16.158011 | instance | changed: [instance]
2026-08-28 02:11:16.158689 | instance |
2026-08-28 02:11:16.158735 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-28 02:11:16.158747 | instance | Friday 28 August 2026  02:11:16 +0000 (0:00:01.081)       0:00:30.749 *********
2026-08-28 02:11:16.750487 | instance | changed: [instance]
2026-08-28 02:11:16.750921 | instance |
2026-08-28 02:11:16.750958 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-28 02:11:16.750965 | instance | Friday 28 August 2026  02:11:16 +0000 (0:00:00.592)       0:00:31.341 *********
2026-08-28 02:11:16.814972 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-28 02:11:16.815429 | instance |
2026-08-28 02:11:16.815466 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-28 02:11:16.815474 | instance | Friday 28 August 2026  02:11:16 +0000 (0:00:00.064)       0:00:31.406 *********
2026-08-28 02:11:21.708183 | instance | changed: [instance]
2026-08-28 02:11:21.708925 | instance |
2026-08-28 02:11:21.708974 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-28 02:11:21.708986 | instance | Friday 28 August 2026  02:11:21 +0000 (0:00:04.892)       0:00:36.299 *********
2026-08-28 02:11:22.369935 | instance | ok: [instance] => (item=chronyd)
2026-08-28 02:11:22.370840 | instance | ok: [instance] => (item=sshd)
2026-08-28 02:11:22.370889 | instance |
2026-08-28 02:11:22.370901 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-28 02:11:22.370911 | instance | Friday 28 August 2026  02:11:22 +0000 (0:00:00.661)       0:00:36.960 *********
2026-08-28 02:11:22.721687 | instance | changed: [instance]
2026-08-28 02:11:22.722405 | instance |
2026-08-28 02:11:22.722574 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-28 02:11:22.722588 | instance | Friday 28 August 2026  02:11:22 +0000 (0:00:00.351)       0:00:37.312 *********
2026-08-28 02:11:22.941964 | instance | ok: [instance]
2026-08-28 02:11:22.942669 | instance |
2026-08-28 02:11:22.942723 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-28 02:11:22.942735 | instance | Friday 28 August 2026  02:11:22 +0000 (0:00:00.220)       0:00:37.532 *********
2026-08-28 02:11:23.406396 | instance | changed: [instance]
2026-08-28 02:11:23.406844 | instance |
2026-08-28 02:11:23.406886 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-28 02:11:23.406893 | instance | Friday 28 August 2026  02:11:23 +0000 (0:00:00.464)       0:00:37.997 *********
2026-08-28 02:11:23.746749 | instance | changed: [instance]
2026-08-28 02:11:23.746791 | instance |
2026-08-28 02:11:23.747045 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-28 02:11:23.747077 | instance | Friday 28 August 2026  02:11:23 +0000 (0:00:00.340)       0:00:38.338 *********
2026-08-28 02:11:23.874638 | instance | ok: [instance]
2026-08-28 02:11:23.875315 | instance |
2026-08-28 02:11:23.875351 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-28 02:11:23.875381 | instance | Friday 28 August 2026  02:11:23 +0000 (0:00:00.127)       0:00:38.465 *********
2026-08-28 02:11:24.125048 | instance | ok: [instance] => (item=instance)
2026-08-28 02:11:24.125754 | instance |
2026-08-28 02:11:24.125809 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-28 02:11:24.125820 | instance | Friday 28 August 2026  02:11:24 +0000 (0:00:00.250)       0:00:38.715 *********
2026-08-28 02:11:24.190406 | instance | ok: [instance]
2026-08-28 02:11:24.190907 | instance |
2026-08-28 02:11:24.191074 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-28 02:11:24.191085 | instance | Friday 28 August 2026  02:11:24 +0000 (0:00:00.065)       0:00:38.781 *********
2026-08-28 02:11:24.283483 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-28 02:11:24.283887 | instance |
2026-08-28 02:11:24.283912 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-28 02:11:24.283920 | instance | Friday 28 August 2026  02:11:24 +0000 (0:00:00.093)       0:00:38.875 *********
2026-08-28 02:11:24.618984 | instance | changed: [instance]
2026-08-28 02:11:24.619698 | instance |
2026-08-28 02:11:24.619754 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-28 02:11:24.619766 | instance | Friday 28 August 2026  02:11:24 +0000 (0:00:00.334)       0:00:39.209 *********
2026-08-28 02:11:25.317252 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-28 02:11:25.317313 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-28 02:11:25.317737 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-28 02:11:25.317774 | instance |
2026-08-28 02:11:25.317780 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-28 02:11:25.317785 | instance | Friday 28 August 2026  02:11:25 +0000 (0:00:00.699)       0:00:39.908 *********
2026-08-28 02:12:53.997299 | instance | ok: [instance]
2026-08-28 02:12:53.997608 | instance |
2026-08-28 02:12:53.998356 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-28 02:12:53.998395 | instance | Friday 28 August 2026  02:12:53 +0000 (0:01:28.679)       0:02:08.588 *********
2026-08-28 02:12:54.252644 | instance | changed: [instance]
2026-08-28 02:12:54.252821 | instance |
2026-08-28 02:12:54.253082 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-28 02:12:54.253319 | instance | Friday 28 August 2026  02:12:54 +0000 (0:00:00.255)       0:02:08.843 *********
2026-08-28 02:12:54.328658 | instance | ok: [instance]
2026-08-28 02:12:54.328753 | instance |
2026-08-28 02:12:54.329023 | instance | TASK [Install Ceph host] *******************************************************
2026-08-28 02:12:54.329041 | instance | Friday 28 August 2026  02:12:54 +0000 (0:00:00.076)       0:02:08.920 *********
2026-08-28 02:12:54.422674 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-28 02:12:54.422761 | instance |
2026-08-28 02:12:54.422874 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-28 02:12:54.422979 | instance | Friday 28 August 2026  02:12:54 +0000 (0:00:00.094)       0:02:09.014 *********
2026-08-28 02:12:56.032888 | instance | ok: [instance]
2026-08-28 02:12:56.032953 | instance |
2026-08-28 02:12:56.033084 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-28 02:12:56.033189 | instance | Friday 28 August 2026  02:12:56 +0000 (0:00:01.609)       0:02:10.624 *********
2026-08-28 02:12:56.097139 | instance | ok: [instance] => (item=instance)
2026-08-28 02:12:56.097178 | instance |
2026-08-28 02:12:56.097282 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-28 02:12:56.097380 | instance | Friday 28 August 2026  02:12:56 +0000 (0:00:00.064)       0:02:10.689 *********
2026-08-28 02:12:56.430532 | instance | ok: [instance]
2026-08-28 02:12:56.430588 | instance |
2026-08-28 02:12:56.430885 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-28 02:12:56.430948 | instance | Friday 28 August 2026  02:12:56 +0000 (0:00:00.332)       0:02:11.022 *********
2026-08-28 02:12:58.675012 | instance | ok: [instance]
2026-08-28 02:12:58.675064 | instance |
2026-08-28 02:12:58.675076 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-28 02:12:58.675086 | instance | Friday 28 August 2026  02:12:58 +0000 (0:00:02.243)       0:02:13.265 *********
2026-08-28 02:13:49.057836 | 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-28 02:13:49.058649 | 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_mg6dyvxz/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_mg6dyvxz/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_mg6dyvxz/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_mg6dyvxz/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-28T02:13:48.884+0000 7f4c48bf0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgB/7pBqyME2KSAAWvcOJkrKcDoQJMZxcmMBRoj+JbrZDebnb7BS9uKJPFI=: Malformed input [buffer:3]\n2026-08-28T02:13:48.884+0000 7f4c48bf0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-28T02:13:48.888+0000 7f4c48bf0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgB/7pBqyME2KSAAWvcOJkrKcDoQJMZxcmMBRoj+JbrZDebnb7BS9uKJPFI=: Malformed input [buffer:3]\n2026-08-28T02:13:48.888+0000 7f4c48bf0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-28T02:13:48.888+0000 7f4c48bf0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgB/7pBqyME2KSAAWvcOJkrKcDoQJMZxcmMBRoj+JbrZDebnb7BS9uKJPFI=: Malformed input [buffer:3]\n2026-08-28T02:13:48.888+0000 7f4c48bf0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-28T02:13:48.888+0000 7f4c48bf0640 -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-28 02:13:49.061289 | instance |
2026-08-28 02:13:49.061540 | instance | PLAY RECAP *********************************************************************
2026-08-28 02:13:49.064143 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-28 02:13:49.064200 | instance |
2026-08-28 02:13:49.064211 | instance | Friday 28 August 2026  02:13:49 +0000 (0:00:50.387)       0:03:03.653 *********
2026-08-28 02:13:49.064221 | instance | ===============================================================================
2026-08-28 02:13:49.064230 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 88.68s
2026-08-28 02:13:49.064238 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 50.39s
2026-08-28 02:13:49.064247 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.72s
2026-08-28 02:13:49.064255 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.89s
2026-08-28 02:13:49.064264 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.35s
2026-08-28 02:13:49.064272 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.81s
2026-08-28 02:13:49.064286 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.24s
2026-08-28 02:13:49.067030 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.61s
2026-08-28 02:13:49.067082 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.60s
2026-08-28 02:13:49.067092 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.18s
2026-08-28 02:13:49.067102 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.08s
2026-08-28 02:13:49.067110 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.03s
2026-08-28 02:13:49.067119 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-08-28 02:13:49.067127 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.96s
2026-08-28 02:13:49.067135 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-08-28 02:13:49.067144 | instance | Gathering Facts --------------------------------------------------------- 0.92s
2026-08-28 02:13:49.067152 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.88s
2026-08-28 02:13:49.067161 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.88s
2026-08-28 02:13:49.067169 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.87s
2026-08-28 02:13:49.067178 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.70s
2026-08-28 02:13:49.166020 | 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-28 02:13:49.166274 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-28 02:13:49.166454 | 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-28 02:13:49.413128 | instance | ERROR
2026-08-28 02:13:49.413458 | instance | {
2026-08-28 02:13:49.413522 | instance |   "delta": "0:03:53.781435",
2026-08-28 02:13:49.413566 | instance |   "end": "2026-08-28 02:13:49.228764",
2026-08-28 02:13:49.413606 | instance |   "msg": "non-zero return code",
2026-08-28 02:13:49.413646 | instance |   "rc": 2,
2026-08-28 02:13:49.413684 | instance |   "start": "2026-08-28 02:09:55.447329"
2026-08-28 02:13:49.413727 | instance | }
failure
2026-08-28 02:13:49.420042 | 
2026-08-28 02:13:49.420110 | PLAY RECAP
2026-08-28 02:13:49.420167 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-28 02:13:49.420197 | 
2026-08-28 02:13:49.533636 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-28 02:13:49.535960 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-28 02:13:50.138355 | 
2026-08-28 02:13:50.139075 | PLAY [all]
2026-08-28 02:13:50.153437 | 
2026-08-28 02:13:50.153545 | TASK [gather-host-logs : creating directory for system status]
2026-08-28 02:13:50.502962 | instance | changed
2026-08-28 02:13:50.508359 | 
2026-08-28 02:13:50.508443 | TASK [gather-host-logs : Get logs for each host]
2026-08-28 02:13:50.866459 | instance | + systemd-cgls --full --all --no-pager
2026-08-28 02:13:50.887192 | instance | + ip addr
2026-08-28 02:13:50.889886 | instance | + ip route
2026-08-28 02:13:50.892250 | instance | + lsblk
2026-08-28 02:13:50.897112 | instance | + mount
2026-08-28 02:13:50.900191 | instance | + docker images
2026-08-28 02:13:50.922857 | instance | + brctl show
2026-08-28 02:13:50.923273 | instance | /bin/bash: line 8: brctl: command not found
2026-08-28 02:13:50.923562 | instance | + ps aux --sort=-%mem
2026-08-28 02:13:50.959550 | instance | + dpkg -l
2026-08-28 02:13:50.970337 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-28 02:13:50.970865 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-28 02:13:50.989880 | instance | + '[' '!' -z '' ']'
2026-08-28 02:13:51.045674 | instance | ok: Runtime: 0:00:00.127598
2026-08-28 02:13:51.054569 | 
2026-08-28 02:13:51.054641 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-28 02:13:51.697825 | instance | changed:
2026-08-28 02:13:51.697992 | instance | created directory /var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/work/logs/instance
2026-08-28 02:13:51.698021 | instance | cd+++++++++ system/
2026-08-28 02:13:51.698042 | instance | >f+++++++++ system/brctl-show.txt
2026-08-28 02:13:51.698063 | instance | >f+++++++++ system/docker-images.txt
2026-08-28 02:13:51.698083 | instance | >f+++++++++ system/ip-addr.txt
2026-08-28 02:13:51.698120 | instance | >f+++++++++ system/ip-route.txt
2026-08-28 02:13:51.698142 | instance | >f+++++++++ system/lsblk.txt
2026-08-28 02:13:51.698162 | instance | >f+++++++++ system/mount.txt
2026-08-28 02:13:51.698181 | instance | >f+++++++++ system/packages.txt
2026-08-28 02:13:51.698200 | instance | >f+++++++++ system/ps.txt
2026-08-28 02:13:51.698221 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-28 02:13:51.706873 | 
2026-08-28 02:13:51.706938 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-28 02:13:51.914012 | instance | changed: "values"
2026-08-28 02:13:52.088793 | instance | changed: "releases"
2026-08-28 02:13:52.101853 | 
2026-08-28 02:13:52.101992 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-28 02:13:52.317371 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-28 02:13:52.640487 | instance | ok: Runtime: 0:00:00.003134
2026-08-28 02:13:52.646746 | 
2026-08-28 02:13:52.646814 | TASK [helm-release-status : Downloads logs to executor]
2026-08-28 02:13:53.126970 | instance | changed:
2026-08-28 02:13:53.127173 | instance | cd+++++++++ helm/
2026-08-28 02:13:53.127201 | instance | cd+++++++++ helm/releases/
2026-08-28 02:13:53.127224 | instance | cd+++++++++ helm/values/
2026-08-28 02:13:53.136206 | 
2026-08-28 02:13:53.136276 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-28 02:13:53.344753 | instance | changed
2026-08-28 02:13:53.353419 | 
2026-08-28 02:13:53.353499 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-28 02:13:53.622902 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 02:13:53.622983 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 02:13:53.627892 | instance | environment: line 1: kubectl: command not found
2026-08-28 02:13:53.629336 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 02:13:53.630207 | instance | environment: line 1: kubectl: command not found
2026-08-28 02:13:53.631462 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 02:13:53.633117 | instance | environment: line 1: kubectl: command not found
2026-08-28 02:13:53.634383 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 02:13:53.635554 | instance | environment: line 1: kubectl: command not found
2026-08-28 02:13:53.637332 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 02:13:53.637545 | instance | environment: line 1: kubectl: command not found
2026-08-28 02:13:53.638873 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-28 02:13:53.893336 | instance | ok: Runtime: 0:00:00.022992
2026-08-28 02:13:53.900465 | 
2026-08-28 02:13:53.900533 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-28 02:13:54.117712 | instance | changed
2026-08-28 02:13:54.123008 | 
2026-08-28 02:13:54.123099 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-28 02:13:54.341097 | instance | environment: line 5: kubectl: command not found
2026-08-28 02:13:54.341546 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 02:13:54.341670 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 02:13:54.342209 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-28 02:13:54.663795 | instance | ok: Runtime: 0:00:00.007159
2026-08-28 02:13:54.669500 | 
2026-08-28 02:13:54.669641 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-28 02:13:55.163711 | instance | changed:
2026-08-28 02:13:55.164003 | instance | cd+++++++++ objects/
2026-08-28 02:13:55.164060 | instance | cd+++++++++ objects/cluster/
2026-08-28 02:13:55.164095 | instance | cd+++++++++ objects/namespaced/
2026-08-28 02:13:55.175413 | 
2026-08-28 02:13:55.175480 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-28 02:13:55.393648 | instance | changed
2026-08-28 02:13:55.400267 | 
2026-08-28 02:13:55.400353 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-28 02:13:55.614731 | instance | changed
2026-08-28 02:13:55.621431 | 
2026-08-28 02:13:55.621501 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-28 02:13:55.844398 | instance | environment: line 3: kubectl: command not found
2026-08-28 02:13:56.161066 | instance | ok: Runtime: 0:00:00.007532
2026-08-28 02:13:56.168449 | 
2026-08-28 02:13:56.168539 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-28 02:13:56.661374 | instance | changed:
2026-08-28 02:13:56.661558 | instance | cd+++++++++ pod-logs/
2026-08-28 02:13:56.661588 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-28 02:13:56.671240 | 
2026-08-28 02:13:56.671303 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-28 02:13:56.879217 | instance | changed
2026-08-28 02:13:56.884983 | 
2026-08-28 02:13:56.885050 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-28 02:13:57.106149 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-28 02:13:57.423210 | instance | ok: Runtime: 0:00:00.052052
2026-08-28 02:13:57.430099 | 
2026-08-28 02:13:57.430185 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-28 02:13:57.648799 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-28 02:13:57.695014 | instance | ceph-mgr endpoints:
2026-08-28 02:13:57.967516 | instance | ok: Runtime: 0:00:00.051071
2026-08-28 02:13:57.974586 | 
2026-08-28 02:13:57.974677 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-28 02:13:58.190783 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-28 02:13:58.512294 | instance | ok: Runtime: 0:00:00.051830
2026-08-28 02:13:58.518157 | 
2026-08-28 02:13:58.518224 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-28 02:13:59.022277 | instance | changed: cd+++++++++ prometheus/
2026-08-28 02:13:59.031350 | 
2026-08-28 02:13:59.031415 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-28 02:13:59.242152 | instance | changed
2026-08-28 02:13:59.247887 | 
2026-08-28 02:13:59.247972 | TASK [gather-selenium-data : Get selenium data]
2026-08-28 02:13:59.499415 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-28 02:13:59.500872 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-28 02:13:59.784650 | instance | ERROR
2026-08-28 02:13:59.784933 | instance | {
2026-08-28 02:13:59.785067 | instance |   "delta": "0:00:00.005019",
2026-08-28 02:13:59.785119 | instance |   "end": "2026-08-28 02:13:59.501116",
2026-08-28 02:13:59.785169 | instance |   "msg": "non-zero return code",
2026-08-28 02:13:59.785212 | instance |   "rc": 1,
2026-08-28 02:13:59.785254 | instance |   "start": "2026-08-28 02:13:59.496097"
2026-08-28 02:13:59.785295 | instance | }
2026-08-28 02:13:59.785350 | instance | ERROR: Ignoring Errors
2026-08-28 02:13:59.790969 | 
2026-08-28 02:13:59.791035 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-28 02:14:00.274360 | instance | changed: cd+++++++++ selenium/
2026-08-28 02:14:00.282105 | 
2026-08-28 02:14:00.282156 | PLAY RECAP
2026-08-28 02:14:00.282203 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-28 02:14:00.282225 | 
2026-08-28 02:14:00.411786 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-28 02:14:00.415416 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-28 02:14:01.031234 | 
2026-08-28 02:14:01.031820 | PLAY [all]
2026-08-28 02:14:01.043603 | 
2026-08-28 02:14:01.043675 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-28 02:14:01.089162 | instance | skipping: Conditional result was False
2026-08-28 02:14:01.098124 | 
2026-08-28 02:14:01.098230 | TASK [fetch-output : Set log path for single node]
2026-08-28 02:14:01.143501 | instance | ok
2026-08-28 02:14:01.148256 | 
2026-08-28 02:14:01.148322 | LOOP [fetch-output : Ensure local output dirs]
2026-08-28 02:14:01.570501 | instance -> localhost | ok: "/var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/work/logs"
2026-08-28 02:14:01.792496 | instance -> localhost | changed: "/var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/work/artifacts"
2026-08-28 02:14:01.999197 | instance -> localhost | changed: "/var/lib/zuul/builds/bfcf2188c7f643f991dd4d2506d7ff31/work/docs"
2026-08-28 02:14:02.017901 | 
2026-08-28 02:14:02.018083 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-28 02:14:02.698131 | instance | changed: .d..t...... ./
2026-08-28 02:14:02.698337 | instance | changed: All items complete
2026-08-28 02:14:02.698365 | 
2026-08-28 02:14:03.159400 | instance | changed: .d..t...... ./
2026-08-28 02:14:03.599864 | instance | changed: .d..t...... ./
2026-08-28 02:14:03.625799 | 
2026-08-28 02:14:03.626016 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-28 02:14:04.048688 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008958
2026-08-28 02:14:04.265545 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007440
2026-08-28 02:14:04.279873 | 
2026-08-28 02:14:04.280029 | PLAY [all]
2026-08-28 02:14:04.286917 | 
2026-08-28 02:14:04.286980 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-28 02:14:04.696990 | instance | changed
2026-08-28 02:14:04.704146 | 
2026-08-28 02:14:04.704213 | PLAY RECAP
2026-08-28 02:14:04.704274 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-28 02:14:04.704306 | 
2026-08-28 02:14:04.834138 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-28 02:14:04.837083 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-28 02:14:05.424787 | 
2026-08-28 02:14:05.424940 | PLAY [localhost]
2026-08-28 02:14:05.435314 | 
2026-08-28 02:14:05.435390 | TASK [Generate Zuul manifest]
2026-08-28 02:14:05.458105 | localhost | ok
2026-08-28 02:14:05.476757 | 
2026-08-28 02:14:05.476852 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-28 02:14:05.842936 | localhost | changed
2026-08-28 02:14:05.855353 | 
2026-08-28 02:14:05.855438 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-28 02:14:05.888426 | localhost | ok
2026-08-28 02:14:05.897284 | 
2026-08-28 02:14:05.897354 | TASK [Upload logs]
2026-08-28 02:14:05.920357 | localhost | ok
2026-08-28 02:14:05.979729 | 
2026-08-28 02:14:05.979849 | TASK [Set zuul-log-path fact]
2026-08-28 02:14:06.002696 | localhost | ok
2026-08-28 02:14:06.015056 | 
2026-08-28 02:14:06.015124 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-28 02:14:06.048288 | localhost | ok
2026-08-28 02:14:06.057442 | 
2026-08-28 02:14:06.057512 | TASK [upload-logs : Create log directories]
2026-08-28 02:14:06.436037 | localhost | changed
2026-08-28 02:14:06.443437 | 
2026-08-28 02:14:06.443562 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-28 02:14:06.845265 | localhost -> localhost | ok: Runtime: 0:00:00.005687
2026-08-28 02:14:06.852152 | 
2026-08-28 02:14:06.852240 | TASK [upload-logs : Upload logs to log server]
2026-08-28 02:14:07.319826 | localhost | Output suppressed because no_log was given
2026-08-28 02:14:07.368867 | 
2026-08-28 02:14:07.369046 | LOOP [upload-logs : Compress console log and json output]
2026-08-28 02:14:07.414492 | localhost | skipping: Conditional result was False
2026-08-28 02:14:07.421735 | localhost | skipping: Conditional result was False
2026-08-28 02:14:07.429582 | 
2026-08-28 02:14:07.429657 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-28 02:14:07.476117 | localhost | skipping: Conditional result was False
2026-08-28 02:14:07.476627 | 
2026-08-28 02:14:07.480178 | localhost | skipping: Conditional result was False
2026-08-28 02:14:07.491373 | 
2026-08-28 02:14:07.491453 | LOOP [upload-logs : Upload console log and json output]