2026-09-15 11:22:43.615857 | Job console starting
2026-09-15 11:22:43.628627 | Updating git repos
2026-09-15 11:22:45.732037 | Cloning repos into workspace
2026-09-15 11:23:02.197710 | Restoring repo states
2026-09-15 11:23:17.318185 | Merging changes
2026-09-15 11:23:18.419177 | Checking out repos
2026-09-15 11:23:20.146786 | Preparing playbooks
2026-09-15 11:23:21.985592 | Running Ansible setup
2026-09-15 11:23:25.854287 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 11:23:26.555109 | 
2026-09-15 11:23:26.555256 | PLAY [localhost]
2026-09-15 11:23:26.563494 | 
2026-09-15 11:23:26.563590 | TASK [Gathering Facts]
2026-09-15 11:23:28.521554 | localhost | ok
2026-09-15 11:23:28.541739 | 
2026-09-15 11:23:28.542070 | TASK [Setup log path fact]
2026-09-15 11:23:28.566706 | localhost | ok
2026-09-15 11:23:28.579626 | 
2026-09-15 11:23:28.579755 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 11:23:28.607826 | localhost | ok
2026-09-15 11:23:28.613850 | 
2026-09-15 11:23:28.613944 | TASK [emit-job-header : Print job information]
2026-09-15 11:23:28.662985 | # Job Information
2026-09-15 11:23:28.663194 | Ansible Version: 2.16.18
2026-09-15 11:23:28.663237 | Job: atmosphere-molecule-csi-rbd
2026-09-15 11:23:28.663264 | Pipeline: check
2026-09-15 11:23:28.663290 | Executor: 2d72f0692154
2026-09-15 11:23:28.663324 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4092
2026-09-15 11:23:28.663365 | Event ID: 94bd18a0-b0f6-11f1-8718-21b8b4051c32
2026-09-15 11:23:28.667604 | 
2026-09-15 11:23:28.667705 | LOOP [emit-job-header : Print node information]
2026-09-15 11:23:28.812815 | localhost | ok:
2026-09-15 11:23:28.813059 | localhost | # Node Information
2026-09-15 11:23:28.813112 | localhost | Inventory Hostname: instance
2026-09-15 11:23:28.813152 | localhost | Hostname: np0000192824
2026-09-15 11:23:28.813199 | localhost | Username: zuul
2026-09-15 11:23:28.813234 | localhost | Distro: Ubuntu 22.04
2026-09-15 11:23:28.813274 | localhost | Provider: yul1
2026-09-15 11:23:28.813303 | localhost | Region: ca-ymq-1
2026-09-15 11:23:28.813344 | localhost | Label: ubuntu-jammy
2026-09-15 11:23:28.813373 | localhost | Product Name: OpenStack Nova
2026-09-15 11:23:28.814222 | localhost | Interface IP: 162.253.55.124
2026-09-15 11:23:28.829651 | 
2026-09-15 11:23:28.829834 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 11:23:29.312514 | localhost -> localhost | changed
2026-09-15 11:23:29.320488 | 
2026-09-15 11:23:29.320593 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 11:23:30.309342 | localhost -> localhost | changed
2026-09-15 11:23:30.326906 | 
2026-09-15 11:23:30.327605 | PLAY [all]
2026-09-15 11:23:30.344358 | 
2026-09-15 11:23:30.344549 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 11:23:30.622612 | instance -> localhost | ok
2026-09-15 11:23:30.643105 | 
2026-09-15 11:23:30.643443 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 11:23:30.689981 | instance | ok
2026-09-15 11:23:30.714591 | instance | included: /var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 11:23:30.723791 | 
2026-09-15 11:23:30.724026 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 11:23:31.501820 | instance -> localhost | Generating public/private rsa key pair.
2026-09-15 11:23:31.502035 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/work/15aac1e7b4bc435e82504b44d3c63fd3_id_rsa
2026-09-15 11:23:31.502074 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/work/15aac1e7b4bc435e82504b44d3c63fd3_id_rsa.pub
2026-09-15 11:23:31.502120 | instance -> localhost | The key fingerprint is:
2026-09-15 11:23:31.502149 | instance -> localhost | SHA256:3TYoH0Hlh78abbSLkhNedbI6SppVZV8ii/XIzjNEOE8 zuul-build-sshkey
2026-09-15 11:23:31.502196 | instance -> localhost | The key's randomart image is:
2026-09-15 11:23:31.502225 | instance -> localhost | +---[RSA 3072]----+
2026-09-15 11:23:31.502259 | instance -> localhost | |          ...    |
2026-09-15 11:23:31.502288 | instance -> localhost | |         . o .   |
2026-09-15 11:23:31.502315 | instance -> localhost | |          + E = .|
2026-09-15 11:23:31.502353 | instance -> localhost | |         . % Xooo|
2026-09-15 11:23:31.502381 | instance -> localhost | |        S = @.++.|
2026-09-15 11:23:31.502438 | instance -> localhost | |         o.B.+.o |
2026-09-15 11:23:31.502484 | instance -> localhost | |         .++*.=  |
2026-09-15 11:23:31.502532 | instance -> localhost | |         == oB . |
2026-09-15 11:23:31.502565 | instance -> localhost | |        o .+o..  |
2026-09-15 11:23:31.502634 | instance -> localhost | +----[SHA256]-----+
2026-09-15 11:23:31.502750 | instance -> localhost | ok: Runtime: 0:00:00.239017
2026-09-15 11:23:31.513009 | 
2026-09-15 11:23:31.513134 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 11:23:31.548236 | instance | ok
2026-09-15 11:23:31.563971 | instance | included: /var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 11:23:31.578217 | 
2026-09-15 11:23:31.578343 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 11:23:31.603161 | instance | skipping: Conditional result was False
2026-09-15 11:23:31.611781 | 
2026-09-15 11:23:31.611932 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 11:23:32.097467 | instance | changed
2026-09-15 11:23:32.111602 | 
2026-09-15 11:23:32.111837 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 11:23:32.337061 | instance | ok
2026-09-15 11:23:32.344068 | 
2026-09-15 11:23:32.344159 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 11:23:32.842669 | instance | changed
2026-09-15 11:23:32.849934 | 
2026-09-15 11:23:32.850117 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 11:23:33.370886 | instance | changed
2026-09-15 11:23:33.379157 | 
2026-09-15 11:23:33.379371 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 11:23:33.395637 | instance | skipping: Conditional result was False
2026-09-15 11:23:33.403849 | 
2026-09-15 11:23:33.403998 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 11:23:33.825254 | instance -> localhost | changed
2026-09-15 11:23:33.835103 | 
2026-09-15 11:23:33.835243 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 11:23:34.169299 | instance -> localhost | Identity added: /var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/work/15aac1e7b4bc435e82504b44d3c63fd3_id_rsa (zuul-build-sshkey)
2026-09-15 11:23:34.171158 | instance -> localhost | ok: Runtime: 0:00:00.018299
2026-09-15 11:23:34.179149 | 
2026-09-15 11:23:34.179552 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 11:23:34.514653 | instance | ok
2026-09-15 11:23:34.522332 | 
2026-09-15 11:23:34.522501 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 11:23:34.557767 | instance | skipping: Conditional result was False
2026-09-15 11:23:34.571581 | 
2026-09-15 11:23:34.571724 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 11:23:34.933931 | instance | ok
2026-09-15 11:23:34.942511 | 
2026-09-15 11:23:34.943219 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 11:24:14.745582 | instance | Output suppressed because no_log was given
2026-09-15 11:24:14.754397 | 
2026-09-15 11:24:14.754554 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 11:24:14.983305 | instance | ok: "logs"
2026-09-15 11:24:14.983631 | instance | ok: All items complete
2026-09-15 11:24:14.983668 | 
2026-09-15 11:24:15.146123 | instance | ok: "artifacts"
2026-09-15 11:24:15.300582 | instance | ok: "docs"
2026-09-15 11:24:15.313437 | 
2026-09-15 11:24:15.313653 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 11:24:15.533316 | instance | changed: "logs"
2026-09-15 11:24:15.728110 | instance | changed: "artifacts"
2026-09-15 11:24:15.907001 | instance | changed: "docs"
2026-09-15 11:24:15.927798 | 
2026-09-15 11:24:15.927971 | PLAY RECAP
2026-09-15 11:24:15.928071 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 11:24:15.928153 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:24:15.928211 | 
2026-09-15 11:24:16.133918 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 11:24:16.137772 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 11:24:16.911280 | 
2026-09-15 11:24:16.911465 | PLAY [all]
2026-09-15 11:24:16.922390 | 
2026-09-15 11:24:16.922507 | TASK [setup-uv : Extract archive]
2026-09-15 11:24:19.323933 | instance | changed
2026-09-15 11:24:19.336439 | 
2026-09-15 11:24:19.336696 | TASK [setup-uv : Print version]
2026-09-15 11:24:19.736666 | instance | uv 0.8.13
2026-09-15 11:24:19.877050 | instance | ok: Runtime: 0:00:00.013391
2026-09-15 11:24:19.889929 | 
2026-09-15 11:24:19.890146 | TASK [Install Ansible collection siblings]
2026-09-15 11:24:29.396823 | instance | changed:
2026-09-15 11:24:29.397113 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 11:24:29.397172 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 11:24:29.397214 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 11:24:29.397253 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 11:24:29.397291 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 11:24:29.397334 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 11:24:29.397374 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 11:24:29.397442 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 11:24:29.397485 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 11:24:29.397522 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 11:24:29.397560 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 11:24:29.397597 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 11:24:29.397633 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-15 11:24:29.397670 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-15 11:24:29.397706 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-15 11:24:29.397744 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-15 11:24:29.397780 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-15 11:24:29.397816 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-15 11:24:29.397852 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-15 11:24:29.397888 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-15 11:24:29.397923 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-15 11:24:29.397959 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-15 11:24:29.397998 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 11:24:29.398059 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-15 11:24:29.398098 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-15 11:24:29.398133 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 11:24:29.398168 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 11:24:29.398202 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 11:24:29.398237 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-15 11:24:29.398272 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-15 11:24:29.398308 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 11:24:29.398344 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 11:24:29.398381 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-15 11:24:29.398436 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 11:24:29.398488 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.398525 | instance | Process install dependency map
2026-09-15 11:24:29.398561 | instance | Starting collection install process
2026-09-15 11:24:29.398596 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-15 11:24:29.398632 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-15 11:24:29.398668 | instance | ansible.netcommon:* was installed successfully
2026-09-15 11:24:29.398703 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-15 11:24:29.398738 | instance | Creating virtual environment at: .venv
2026-09-15 11:24:29.398773 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 11:24:29.398809 | instance | Downloading setuptools (1.1MiB)
2026-09-15 11:24:29.398844 | instance | Downloading cryptography (4.2MiB)
2026-09-15 11:24:29.398885 | instance | Downloading ansible-core (2.1MiB)
2026-09-15 11:24:29.398921 | instance | Downloading rjsonnet (1.2MiB)
2026-09-15 11:24:29.398956 | instance | Downloading kubernetes (1.9MiB)
2026-09-15 11:24:29.398995 | instance | Downloading openstacksdk (1.7MiB)
2026-09-15 11:24:29.399088 | instance | Downloading pygments (1.2MiB)
2026-09-15 11:24:29.399158 | instance | Downloading netaddr (2.2MiB)
2026-09-15 11:24:29.399217 | instance |    Building pyperclip==1.9.0
2026-09-15 11:24:29.399268 | instance |  Downloading rjsonnet
2026-09-15 11:24:29.399327 | instance |  Downloading netaddr
2026-09-15 11:24:29.399385 | instance |  Downloading pygments
2026-09-15 11:24:29.399464 | instance |  Downloading cryptography
2026-09-15 11:24:29.399517 | instance |  Downloading setuptools
2026-09-15 11:24:29.399562 | instance |  Downloading kubernetes
2026-09-15 11:24:29.399614 | instance |  Downloading ansible-core
2026-09-15 11:24:29.399664 | instance |  Downloading openstacksdk
2026-09-15 11:24:29.399715 | instance |       Built pyperclip==1.9.0
2026-09-15 11:24:29.399768 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 11:24:29.399817 | instance | Installed 79 packages in 55ms
2026-09-15 11:24:29.399861 | instance | Checked out ansible.posix to 1.6.0
2026-09-15 11:24:29.399898 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 11:24:29.399934 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.399970 | instance | Process install dependency map
2026-09-15 11:24:29.400010 | instance | Starting collection install process
2026-09-15 11:24:29.400055 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 11:24:29.400109 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-15 11:24:29.400155 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-15 11:24:29.400192 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.400227 | instance | 2.17.12
2026-09-15 11:24:29.400263 | instance | Checked out ansible.utils to v6.1.0
2026-09-15 11:24:29.400298 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 11:24:29.400335 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.400384 | instance | Process install dependency map
2026-09-15 11:24:29.400440 | instance | Starting collection install process
2026-09-15 11:24:29.400481 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-15 11:24:29.400570 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-15 11:24:29.400619 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-15 11:24:29.400662 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.400713 | instance | 2.17.12
2026-09-15 11:24:29.400763 | instance | Checked out atmosphere.common to v0.7.0
2026-09-15 11:24:29.400818 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 11:24:29.400873 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.400921 | instance | Process install dependency map
2026-09-15 11:24:29.400973 | instance | Starting collection install process
2026-09-15 11:24:29.401241 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-15 11:24:29.401294 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-15 11:24:29.401332 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-15 11:24:29.401368 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.401403 | instance | 2.17.12
2026-09-15 11:24:29.401472 | instance | Checked out community.crypto to 2.2.3
2026-09-15 11:24:29.401510 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 11:24:29.401546 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.401581 | instance | Process install dependency map
2026-09-15 11:24:29.401615 | instance | Starting collection install process
2026-09-15 11:24:29.401649 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 11:24:29.401682 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-15 11:24:29.401716 | instance | community.crypto:2.2.3 was installed successfully
2026-09-15 11:24:29.401750 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.401783 | instance | 2.17.12
2026-09-15 11:24:29.401817 | instance | Checked out community.general to 7.3.0
2026-09-15 11:24:29.401851 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 11:24:29.401885 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.401932 | instance | Process install dependency map
2026-09-15 11:24:29.401967 | instance | Starting collection install process
2026-09-15 11:24:29.402001 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 11:24:29.402035 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-15 11:24:29.402068 | instance | community.general:7.3.0 was installed successfully
2026-09-15 11:24:29.402101 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.402135 | instance | 2.17.12
2026-09-15 11:24:29.402170 | instance | Checked out community.mysql to 3.6.0
2026-09-15 11:24:29.402205 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 11:24:29.402240 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.402279 | instance | Process install dependency map
2026-09-15 11:24:29.402315 | instance | Starting collection install process
2026-09-15 11:24:29.402351 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-15 11:24:29.402386 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-15 11:24:29.402443 | instance | community.mysql:3.6.0 was installed successfully
2026-09-15 11:24:29.402482 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.402517 | instance | 2.17.12
2026-09-15 11:24:29.402551 | instance | Checked out kubernetes.core to 2.4.0
2026-09-15 11:24:29.402586 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 11:24:29.402620 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.402655 | instance | Process install dependency map
2026-09-15 11:24:29.402690 | instance | Starting collection install process
2026-09-15 11:24:29.402725 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 11:24:29.402760 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-15 11:24:29.402794 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-15 11:24:29.402829 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.402863 | instance | 2.17.12
2026-09-15 11:24:29.402898 | instance | Checked out openstack.cloud to 2.6.0
2026-09-15 11:24:29.402933 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 11:24:29.402968 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.403002 | instance | Process install dependency map
2026-09-15 11:24:29.403036 | instance | Starting collection install process
2026-09-15 11:24:29.403071 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-15 11:24:29.403105 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-15 11:24:29.403139 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-15 11:24:29.403173 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.403207 | instance | 2.17.12
2026-09-15 11:24:29.403241 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-15 11:24:29.403276 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 11:24:29.403321 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.403357 | instance | Process install dependency map
2026-09-15 11:24:29.403391 | instance | Starting collection install process
2026-09-15 11:24:29.403451 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-15 11:24:29.403488 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-15 11:24:29.403524 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-15 11:24:29.403559 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.403593 | instance | 2.17.12
2026-09-15 11:24:29.403627 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-15 11:24:29.403661 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 11:24:29.403696 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.403730 | instance | Process install dependency map
2026-09-15 11:24:29.403764 | instance | Starting collection install process
2026-09-15 11:24:29.403798 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-15 11:24:29.403833 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-15 11:24:29.403867 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-15 11:24:29.403990 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.404180 | instance | 2.17.12
2026-09-15 11:24:29.404236 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-15 11:24:29.404275 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 11:24:29.404312 | instance | Starting galaxy collection install process
2026-09-15 11:24:29.404347 | instance | Process install dependency map
2026-09-15 11:24:29.404381 | instance | Starting collection install process
2026-09-15 11:24:29.404438 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-15 11:24:29.404476 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-15 11:24:29.404537 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-15 11:24:29.404575 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:24:29.404610 | instance | 2.17.12
2026-09-15 11:24:29.415753 | 
2026-09-15 11:24:29.415906 | PLAY RECAP
2026-09-15 11:24:29.416027 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:24:29.416093 | 
2026-09-15 11:24:29.604697 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 11:24:29.607639 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 11:24:30.202310 | 
2026-09-15 11:24:30.202476 | PLAY [all]
2026-09-15 11:24:30.213887 | 
2026-09-15 11:24:30.214017 | TASK [Install "jq" for log collection]
2026-09-15 11:24:40.294093 | instance | changed
2026-09-15 11:24:40.297574 | 
2026-09-15 11:24:40.297707 | PLAY RECAP
2026-09-15 11:24:40.297808 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:24:40.297908 | 
2026-09-15 11:24:40.472178 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 11:24:40.477081 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 11:24:41.110973 | 
2026-09-15 11:24:41.111148 | PLAY [all]
2026-09-15 11:24:41.122697 | 
2026-09-15 11:24:41.122831 | LOOP [Check inventory directories]
2026-09-15 11:24:41.483805 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-15 11:24:41.653867 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-15 11:24:41.847046 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-15 11:24:41.863495 | 
2026-09-15 11:24:41.863835 | LOOP [Copy inventory file for Zuul]
2026-09-15 11:24:42.782500 | instance | changed:
2026-09-15 11:24:42.782909 | instance | {
2026-09-15 11:24:42.783004 | instance |   "ansible_loop_var": "item",
2026-09-15 11:24:42.783077 | instance |   "changed": false,
2026-09-15 11:24:42.783143 | instance |   "failed": false,
2026-09-15 11:24:42.783223 | instance |   "invocation": {
2026-09-15 11:24:42.783299 | instance |     "module_args": {
2026-09-15 11:24:42.783368 | instance |       "checksum_algorithm": "sha1",
2026-09-15 11:24:42.783461 | instance |       "follow": true,
2026-09-15 11:24:42.783528 | instance |       "get_attributes": true,
2026-09-15 11:24:42.783591 | instance |       "get_checksum": true,
2026-09-15 11:24:42.783650 | instance |       "get_mime": true,
2026-09-15 11:24:42.783707 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-15 11:24:42.783764 | instance |     }
2026-09-15 11:24:42.783820 | instance |   },
2026-09-15 11:24:42.783879 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-15 11:24:42.783936 | instance |   "stat": {
2026-09-15 11:24:42.783991 | instance |     "atime": 1789471462.2140727,
2026-09-15 11:24:42.784058 | instance |     "attr_flags": "e",
2026-09-15 11:24:42.784129 | instance |     "attributes": [
2026-09-15 11:24:42.784197 | instance |       "extents"
2026-09-15 11:24:42.784292 | instance |     ],
2026-09-15 11:24:42.784365 | instance |     "block_size": 4096,
2026-09-15 11:24:42.784459 | instance |     "blocks": 8,
2026-09-15 11:24:42.784567 | instance |     "charset": "unknown",
2026-09-15 11:24:42.784638 | instance |     "ctime": 1789471460.8178935,
2026-09-15 11:24:42.784701 | instance |     "dev": 64513,
2026-09-15 11:24:42.784761 | instance |     "device_type": 0,
2026-09-15 11:24:42.784824 | instance |     "executable": true,
2026-09-15 11:24:42.784886 | instance |     "exists": true,
2026-09-15 11:24:42.784947 | instance |     "gid": 1000,
2026-09-15 11:24:42.785008 | instance |     "gr_name": "zuul",
2026-09-15 11:24:42.785071 | instance |     "inode": 1047506,
2026-09-15 11:24:42.785134 | instance |     "isblk": false,
2026-09-15 11:24:42.785197 | instance |     "ischr": false,
2026-09-15 11:24:42.785259 | instance |     "isdir": true,
2026-09-15 11:24:42.785322 | instance |     "isfifo": false,
2026-09-15 11:24:42.785383 | instance |     "isgid": false,
2026-09-15 11:24:42.785466 | instance |     "islnk": false,
2026-09-15 11:24:42.785523 | instance |     "isreg": false,
2026-09-15 11:24:42.785573 | instance |     "issock": false,
2026-09-15 11:24:42.785623 | instance |     "isuid": false,
2026-09-15 11:24:42.785675 | instance |     "mimetype": "unknown",
2026-09-15 11:24:42.785744 | instance |     "mode": "0755",
2026-09-15 11:24:42.786095 | instance |     "mtime": 1789471460.8178935,
2026-09-15 11:24:42.786210 | instance |     "nlink": 21,
2026-09-15 11:24:42.786269 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-15 11:24:42.786328 | instance |     "pw_name": "zuul",
2026-09-15 11:24:42.786404 | instance |     "readable": true,
2026-09-15 11:24:42.786492 | instance |     "rgrp": true,
2026-09-15 11:24:42.786557 | instance |     "roth": true,
2026-09-15 11:24:42.786620 | instance |     "rusr": true,
2026-09-15 11:24:42.786684 | instance |     "size": 4096,
2026-09-15 11:24:42.786744 | instance |     "uid": 1000,
2026-09-15 11:24:42.786831 | instance |     "version": "570127195",
2026-09-15 11:24:42.786897 | instance |     "wgrp": false,
2026-09-15 11:24:42.786962 | instance |     "woth": false,
2026-09-15 11:24:42.787021 | instance |     "writeable": true,
2026-09-15 11:24:42.787083 | instance |     "wusr": true,
2026-09-15 11:24:42.787144 | instance |     "xgrp": true,
2026-09-15 11:24:42.787202 | instance |     "xoth": true,
2026-09-15 11:24:42.787255 | instance |     "xusr": true
2026-09-15 11:24:42.787310 | instance |   }
2026-09-15 11:24:42.787371 | instance | }
2026-09-15 11:24:42.787507 | instance | ok: All items complete
2026-09-15 11:24:42.787585 | 
2026-09-15 11:24:42.792120 | instance | skipping: Conditional result was False
2026-09-15 11:24:42.794452 | instance | skipping: Conditional result was False
2026-09-15 11:24:42.810940 | 
2026-09-15 11:24:42.811146 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 11:24:43.241527 | instance | changed: 1 replacements made
2026-09-15 11:24:43.251686 | instance | skipping: Conditional result was False
2026-09-15 11:24:43.263166 | instance | skipping: Conditional result was False
2026-09-15 11:24:43.284775 | 
2026-09-15 11:24:43.284988 | TASK [Run Molecule scenario]
2026-09-15 11:24:44.297946 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-15 11:24:44.697903 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 11:24:44.698088 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-15 11:24:55.118977 | instance | INFO     [csi > dependency] Executing
2026-09-15 11:24:55.119623 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-15 11:24:55.120085 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-15 11:24:55.120377 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-15 11:24:55.135237 | instance | INFO     [csi > cleanup] Executing
2026-09-15 11:24:55.135930 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 11:24:55.145440 | instance | INFO     [csi > destroy] Executing
2026-09-15 11:24:55.145615 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-15 11:24:55.145744 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-15 11:24:55.154723 | instance | INFO     [csi > syntax] Executing
2026-09-15 11:24:56.702155 | instance |
2026-09-15 11:24:56.702658 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 11:24:56.822390 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-15 11:24:56.839311 | instance | INFO     [csi > create] Executing
2026-09-15 11:24:56.843206 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 11:24:56.858060 | instance | INFO     [csi > prepare] Executing
2026-09-15 11:24:57.601066 | instance |
2026-09-15 11:24:57.601592 | instance | PLAY [Prepare] *****************************************************************
2026-09-15 11:24:57.602036 | instance |
2026-09-15 11:24:57.602459 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:24:57.602766 | instance | Tuesday 15 September 2026  11:24:57 +0000 (0:00:00.023)       0:00:00.023 *****
2026-09-15 11:24:58.858318 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 11:24:58.858546 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 11:24:58.858753 | instance | interpreter could change the meaning of that path. See
2026-09-15 11:24:58.858929 | instance | https://docs.ansible.com/ansible-
2026-09-15 11:24:58.859096 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 11:24:58.870996 | instance | ok: [instance]
2026-09-15 11:24:58.871204 | instance |
2026-09-15 11:24:58.871378 | instance | TASK [Configure short hostname] ************************************************
2026-09-15 11:24:58.871544 | instance | Tuesday 15 September 2026  11:24:58 +0000 (0:00:01.270)       0:00:01.294 *****
2026-09-15 11:24:59.636801 | instance | changed: [instance]
2026-09-15 11:24:59.637155 | instance |
2026-09-15 11:24:59.637466 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-15 11:24:59.637802 | instance | Tuesday 15 September 2026  11:24:59 +0000 (0:00:00.765)       0:00:02.059 *****
2026-09-15 11:24:59.914868 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-15 11:24:59.915244 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-15 11:24:59.915543 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-15 11:24:59.924238 | instance | changed: [instance]
2026-09-15 11:24:59.924525 | instance |
2026-09-15 11:24:59.924842 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-15 11:24:59.925127 | instance | Tuesday 15 September 2026  11:24:59 +0000 (0:00:00.288)       0:00:02.347 *****
2026-09-15 11:25:00.820795 | instance | ok: [instance]
2026-09-15 11:25:00.821174 | instance |
2026-09-15 11:25:00.821476 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-15 11:25:00.821774 | instance |
2026-09-15 11:25:00.822059 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:25:00.822374 | instance | Tuesday 15 September 2026  11:25:00 +0000 (0:00:00.895)       0:00:03.243 *****
2026-09-15 11:25:01.590638 | instance | ok: [instance]
2026-09-15 11:25:01.591073 | instance |
2026-09-15 11:25:01.591438 | instance | TASK [Install depedencies] *****************************************************
2026-09-15 11:25:01.591994 | instance | Tuesday 15 September 2026  11:25:01 +0000 (0:00:00.769)       0:00:04.013 *****
2026-09-15 11:25:23.811931 | instance | changed: [instance]
2026-09-15 11:25:23.812050 | instance |
2026-09-15 11:25:23.812063 | instance | TASK [Start up service] ********************************************************
2026-09-15 11:25:23.812074 | instance | Tuesday 15 September 2026  11:25:23 +0000 (0:00:22.220)       0:00:26.234 *****
2026-09-15 11:25:24.369280 | instance | ok: [instance]
2026-09-15 11:25:24.369349 | instance |
2026-09-15 11:25:24.369365 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-15 11:25:24.369379 | instance | Tuesday 15 September 2026  11:25:24 +0000 (0:00:00.557)       0:00:26.791 *****
2026-09-15 11:25:24.721879 | instance | ok: [instance]
2026-09-15 11:25:24.721953 | instance |
2026-09-15 11:25:24.721970 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-15 11:25:24.721983 | instance | Tuesday 15 September 2026  11:25:24 +0000 (0:00:00.352)       0:00:27.143 *****
2026-09-15 11:25:25.469111 | instance | changed: [instance]
2026-09-15 11:25:25.469577 | instance |
2026-09-15 11:25:25.470085 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-15 11:25:25.470480 | instance | Tuesday 15 September 2026  11:25:25 +0000 (0:00:00.748)       0:00:27.892 *****
2026-09-15 11:25:25.692220 | instance | ok: [instance]
2026-09-15 11:25:25.692300 | instance |
2026-09-15 11:25:25.692434 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-15 11:25:25.692647 | instance | Tuesday 15 September 2026  11:25:25 +0000 (0:00:00.223)       0:00:28.115 *****
2026-09-15 11:25:25.724068 | instance | skipping: [instance]
2026-09-15 11:25:25.724233 | instance |
2026-09-15 11:25:25.724499 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-15 11:25:25.724848 | instance | Tuesday 15 September 2026  11:25:25 +0000 (0:00:00.028)       0:00:28.144 *****
2026-09-15 11:25:26.322660 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:25:26.323237 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:25:26.323339 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:25:26.323577 | instance |
2026-09-15 11:25:26.323826 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-15 11:25:26.324218 | instance | Tuesday 15 September 2026  11:25:26 +0000 (0:00:00.600)       0:00:28.745 *****
2026-09-15 11:25:27.034430 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:25:27.034641 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:25:27.035032 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:25:27.035387 | instance |
2026-09-15 11:25:27.035684 | instance | TASK [Start loop devices] ******************************************************
2026-09-15 11:25:27.035951 | instance | Tuesday 15 September 2026  11:25:27 +0000 (0:00:00.711)       0:00:29.456 *****
2026-09-15 11:25:27.783608 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:25:27.783830 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:25:27.784096 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:25:27.784331 | instance |
2026-09-15 11:25:27.784597 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-15 11:25:27.784839 | instance | Tuesday 15 September 2026  11:25:27 +0000 (0:00:00.749)       0:00:30.206 *****
2026-09-15 11:25:31.158018 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:25:31.158275 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:25:31.158530 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:25:31.158767 | instance |
2026-09-15 11:25:31.159013 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-15 11:25:31.159452 | instance | Tuesday 15 September 2026  11:25:31 +0000 (0:00:03.374)       0:00:33.581 *****
2026-09-15 11:25:33.095219 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-15 11:25:33.095320 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-15 11:25:33.095771 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-15 11:25:33.099200 | instance |
2026-09-15 11:25:33.099250 | instance | PLAY RECAP *********************************************************************
2026-09-15 11:25:33.099443 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-15 11:25:33.099453 | instance |
2026-09-15 11:25:33.099461 | instance | Tuesday 15 September 2026  11:25:33 +0000 (0:00:01.937)       0:00:35.518 *****
2026-09-15 11:25:33.099470 | instance | ===============================================================================
2026-09-15 11:25:33.099479 | instance | Install depedencies ---------------------------------------------------- 22.22s
2026-09-15 11:25:33.099488 | instance | Create a volume group for each loop device ------------------------------ 3.37s
2026-09-15 11:25:33.099496 | instance | Create a logical volume for each loop device ---------------------------- 1.94s
2026-09-15 11:25:33.099507 | instance | Gathering Facts --------------------------------------------------------- 1.27s
2026-09-15 11:25:33.099515 | instance | Purge "snapd" package --------------------------------------------------- 0.90s
2026-09-15 11:25:33.099524 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-15 11:25:33.099561 | instance | Configure short hostname ------------------------------------------------ 0.77s
2026-09-15 11:25:33.100004 | instance | Start loop devices ------------------------------------------------------ 0.75s
2026-09-15 11:25:33.100427 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.75s
2026-09-15 11:25:33.100799 | instance | Set permissions on loopback devices ------------------------------------- 0.71s
2026-09-15 11:25:33.101163 | instance | Create devices for Ceph ------------------------------------------------- 0.60s
2026-09-15 11:25:33.101468 | instance | Start up service -------------------------------------------------------- 0.56s
2026-09-15 11:25:33.101844 | instance | Generate lvm.conf ------------------------------------------------------- 0.35s
2026-09-15 11:25:33.102208 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-15 11:25:33.102549 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-15 11:25:33.102984 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-15 11:25:33.217222 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-15 11:25:33.232172 | instance | INFO     [csi > converge] Executing
2026-09-15 11:25:34.574412 | instance |
2026-09-15 11:25:34.574774 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-15 11:25:34.575101 | instance |
2026-09-15 11:25:34.575433 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:25:34.575715 | instance | Tuesday 15 September 2026  11:25:34 +0000 (0:00:00.007)       0:00:00.007 *****
2026-09-15 11:25:35.597256 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 11:25:35.597658 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 11:25:35.597958 | instance | interpreter could change the meaning of that path. See
2026-09-15 11:25:35.598273 | instance | https://docs.ansible.com/ansible-
2026-09-15 11:25:35.598564 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 11:25:35.611844 | instance | ok: [instance]
2026-09-15 11:25:35.612163 | instance |
2026-09-15 11:25:35.612456 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-15 11:25:35.612747 | instance | Tuesday 15 September 2026  11:25:35 +0000 (0:00:01.037)       0:00:01.044 *****
2026-09-15 11:25:35.674964 | instance | ok: [instance] => {
2026-09-15 11:25:35.675367 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-15 11:25:35.675660 | instance | }
2026-09-15 11:25:35.675936 | instance |
2026-09-15 11:25:35.676337 | instance | PLAY [all] *********************************************************************
2026-09-15 11:25:35.676698 | instance |
2026-09-15 11:25:35.677003 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:25:35.677345 | instance | Tuesday 15 September 2026  11:25:35 +0000 (0:00:00.063)       0:00:01.107 *****
2026-09-15 11:25:36.599239 | instance | ok: [instance]
2026-09-15 11:25:36.599590 | instance |
2026-09-15 11:25:36.599896 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-15 11:25:36.600228 | instance | Tuesday 15 September 2026  11:25:36 +0000 (0:00:00.923)       0:00:02.031 *****
2026-09-15 11:25:36.795766 | instance | ok: [instance]
2026-09-15 11:25:36.795989 | instance |
2026-09-15 11:25:36.796194 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-15 11:25:36.796432 | instance |
2026-09-15 11:25:36.796692 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:25:36.796951 | instance | Tuesday 15 September 2026  11:25:36 +0000 (0:00:00.195)       0:00:02.227 *****
2026-09-15 11:25:37.747900 | instance | ok: [instance]
2026-09-15 11:25:37.748086 | instance |
2026-09-15 11:25:37.748439 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 11:25:37.748748 | instance | Tuesday 15 September 2026  11:25:37 +0000 (0:00:00.952)       0:00:03.179 *****
2026-09-15 11:25:38.059828 | instance | ok: [instance]
2026-09-15 11:25:38.060009 | instance |
2026-09-15 11:25:38.060379 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 11:25:38.060669 | instance | Tuesday 15 September 2026  11:25:38 +0000 (0:00:00.309)       0:00:03.489 *****
2026-09-15 11:25:38.119546 | instance | skipping: [instance]
2026-09-15 11:25:38.119777 | instance |
2026-09-15 11:25:38.119994 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-15 11:25:38.120226 | instance | Tuesday 15 September 2026  11:25:38 +0000 (0:00:00.058)       0:00:03.548 *****
2026-09-15 11:25:38.439172 | instance | changed: [instance]
2026-09-15 11:25:38.439363 | instance |
2026-09-15 11:25:38.439513 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 11:25:38.439661 | instance | Tuesday 15 September 2026  11:25:38 +0000 (0:00:00.323)       0:00:03.871 *****
2026-09-15 11:25:38.532024 | instance | ok: [instance] => {
2026-09-15 11:25:38.532422 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-15 11:25:38.532769 | instance | }
2026-09-15 11:25:38.533103 | instance |
2026-09-15 11:25:38.533437 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 11:25:38.533765 | instance | Tuesday 15 September 2026  11:25:38 +0000 (0:00:00.092)       0:00:03.964 *****
2026-09-15 11:25:39.196342 | instance | changed: [instance]
2026-09-15 11:25:39.196401 | instance |
2026-09-15 11:25:39.196415 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 11:25:39.196427 | instance | Tuesday 15 September 2026  11:25:39 +0000 (0:00:00.663)       0:00:04.627 *****
2026-09-15 11:25:39.260665 | instance | skipping: [instance]
2026-09-15 11:25:39.260709 | instance |
2026-09-15 11:25:39.260721 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 11:25:39.260731 | instance | Tuesday 15 September 2026  11:25:39 +0000 (0:00:00.065)       0:00:04.692 *****
2026-09-15 11:25:39.310244 | instance | skipping: [instance]
2026-09-15 11:25:39.310287 | instance |
2026-09-15 11:25:39.310298 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 11:25:39.310310 | instance | Tuesday 15 September 2026  11:25:39 +0000 (0:00:00.049)       0:00:04.742 *****
2026-09-15 11:25:39.542750 | instance | ok: [instance]
2026-09-15 11:25:39.542801 | instance |
2026-09-15 11:25:39.542813 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 11:25:39.542822 | instance | Tuesday 15 September 2026  11:25:39 +0000 (0:00:00.231)       0:00:04.974 *****
2026-09-15 11:25:40.831842 | instance | ok: [instance]
2026-09-15 11:25:40.831883 | instance |
2026-09-15 11:25:40.831888 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 11:25:40.831893 | instance | Tuesday 15 September 2026  11:25:40 +0000 (0:00:01.290)       0:00:06.264 *****
2026-09-15 11:25:40.919357 | instance | ok: [instance] => {
2026-09-15 11:25:40.919434 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-15 11:25:40.919475 | instance | }
2026-09-15 11:25:40.919493 | instance |
2026-09-15 11:25:40.920161 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 11:25:40.920226 | instance | Tuesday 15 September 2026  11:25:40 +0000 (0:00:00.085)       0:00:06.350 *****
2026-09-15 11:25:41.485031 | instance | changed: [instance]
2026-09-15 11:25:41.485111 | instance |
2026-09-15 11:25:41.485125 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 11:25:41.485135 | instance | Tuesday 15 September 2026  11:25:41 +0000 (0:00:00.566)       0:00:06.916 *****
2026-09-15 11:25:44.341843 | instance | changed: [instance]
2026-09-15 11:25:44.341922 | instance |
2026-09-15 11:25:44.342271 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-15 11:25:44.342438 | instance | Tuesday 15 September 2026  11:25:44 +0000 (0:00:02.858)       0:00:09.774 *****
2026-09-15 11:25:44.384991 | instance | skipping: [instance]
2026-09-15 11:25:44.385776 | instance |
2026-09-15 11:25:44.385829 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-15 11:25:44.385841 | instance | Tuesday 15 September 2026  11:25:44 +0000 (0:00:00.042)       0:00:09.817 *****
2026-09-15 11:25:44.429911 | instance | skipping: [instance]
2026-09-15 11:25:44.431000 | instance |
2026-09-15 11:25:44.431033 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-15 11:25:44.431046 | instance | Tuesday 15 September 2026  11:25:44 +0000 (0:00:00.044)       0:00:09.862 *****
2026-09-15 11:25:44.472038 | instance | skipping: [instance]
2026-09-15 11:25:44.472301 | instance |
2026-09-15 11:25:44.472688 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-15 11:25:44.473045 | instance | Tuesday 15 September 2026  11:25:44 +0000 (0:00:00.042)       0:00:09.904 *****
2026-09-15 11:25:50.681216 | instance | changed: [instance]
2026-09-15 11:25:50.681936 | instance |
2026-09-15 11:25:50.681967 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-15 11:25:50.681979 | instance | Tuesday 15 September 2026  11:25:50 +0000 (0:00:06.208)       0:00:16.113 *****
2026-09-15 11:25:51.363596 | instance | changed: [instance]
2026-09-15 11:25:51.363651 | instance |
2026-09-15 11:25:51.363663 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-15 11:25:51.363673 | instance | Tuesday 15 September 2026  11:25:51 +0000 (0:00:00.681)       0:00:16.795 *****
2026-09-15 11:25:52.345358 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-15 11:25:52.345404 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-15 11:25:52.345413 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-15 11:25:52.345419 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-15 11:25:52.345425 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-15 11:25:52.345431 | instance |
2026-09-15 11:25:52.345443 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-15 11:25:52.345449 | instance | Tuesday 15 September 2026  11:25:52 +0000 (0:00:00.981)       0:00:17.777 *****
2026-09-15 11:25:52.982770 | instance | changed: [instance]
2026-09-15 11:25:52.982819 | instance |
2026-09-15 11:25:52.982826 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-15 11:25:52.982832 | instance | Tuesday 15 September 2026  11:25:52 +0000 (0:00:00.606)       0:00:18.384 *****
2026-09-15 11:25:52.982838 | instance |
2026-09-15 11:25:52.982843 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 11:25:52.982848 | instance | Tuesday 15 September 2026  11:25:52 +0000 (0:00:00.030)       0:00:18.414 *****
2026-09-15 11:25:53.933012 | instance | ok: [instance]
2026-09-15 11:25:53.933761 | instance |
2026-09-15 11:25:53.933813 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-15 11:25:53.933824 | instance | Tuesday 15 September 2026  11:25:53 +0000 (0:00:00.950)       0:00:19.365 *****
2026-09-15 11:25:54.404890 | instance | changed: [instance]
2026-09-15 11:25:54.405100 | instance |
2026-09-15 11:25:54.405618 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-15 11:25:54.405669 | instance | Tuesday 15 September 2026  11:25:54 +0000 (0:00:00.471)       0:00:19.837 *****
2026-09-15 11:25:54.988746 | instance | changed: [instance]
2026-09-15 11:25:54.988787 | instance |
2026-09-15 11:25:54.988795 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 11:25:54.988801 | instance | Tuesday 15 September 2026  11:25:54 +0000 (0:00:00.583)       0:00:20.421 *****
2026-09-15 11:25:55.185674 | instance | ok: [instance]
2026-09-15 11:25:55.185729 | instance |
2026-09-15 11:25:55.185964 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 11:25:55.185979 | instance | Tuesday 15 September 2026  11:25:55 +0000 (0:00:00.197)       0:00:20.619 *****
2026-09-15 11:25:55.236604 | instance | ok: [instance] => {
2026-09-15 11:25:55.237973 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-15 11:25:55.238032 | instance | }
2026-09-15 11:25:55.238044 | instance |
2026-09-15 11:25:55.238053 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 11:25:55.238062 | instance | Tuesday 15 September 2026  11:25:55 +0000 (0:00:00.049)       0:00:20.668 *****
2026-09-15 11:25:56.085733 | instance | changed: [instance]
2026-09-15 11:25:56.086538 | instance |
2026-09-15 11:25:56.086571 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 11:25:56.086584 | instance | Tuesday 15 September 2026  11:25:56 +0000 (0:00:00.849)       0:00:21.518 *****
2026-09-15 11:26:00.428630 | instance | changed: [instance]
2026-09-15 11:26:00.428774 | instance |
2026-09-15 11:26:00.429331 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-15 11:26:00.429387 | instance | Tuesday 15 September 2026  11:26:00 +0000 (0:00:04.342)       0:00:25.861 *****
2026-09-15 11:26:01.619650 | instance | ok: [instance]
2026-09-15 11:26:01.619751 | instance |
2026-09-15 11:26:01.620141 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-15 11:26:01.620164 | instance | Tuesday 15 September 2026  11:26:01 +0000 (0:00:01.191)       0:00:27.052 *****
2026-09-15 11:26:02.013145 | instance | changed: [instance]
2026-09-15 11:26:02.013260 | instance |
2026-09-15 11:26:02.013592 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-15 11:26:02.013775 | instance | Tuesday 15 September 2026  11:26:02 +0000 (0:00:00.393)       0:00:27.446 *****
2026-09-15 11:26:02.534009 | instance | changed: [instance]
2026-09-15 11:26:02.534832 | instance |
2026-09-15 11:26:02.534904 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-15 11:26:02.534917 | instance | Tuesday 15 September 2026  11:26:02 +0000 (0:00:00.520)       0:00:27.966 *****
2026-09-15 11:26:03.182371 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-15 11:26:03.183229 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-15 11:26:03.183292 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-15 11:26:03.183304 | instance |
2026-09-15 11:26:03.183321 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-15 11:26:03.183555 | instance | Tuesday 15 September 2026  11:26:03 +0000 (0:00:00.648)       0:00:28.614 *****
2026-09-15 11:26:03.684500 | instance | changed: [instance]
2026-09-15 11:26:03.685292 | instance |
2026-09-15 11:26:03.685345 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-15 11:26:03.685358 | instance | Tuesday 15 September 2026  11:26:03 +0000 (0:00:00.502)       0:00:29.116 *****
2026-09-15 11:26:04.281910 | instance | changed: [instance]
2026-09-15 11:26:04.281951 | instance |
2026-09-15 11:26:04.281958 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-15 11:26:04.281964 | instance | Tuesday 15 September 2026  11:26:04 +0000 (0:00:00.571)       0:00:29.688 *****
2026-09-15 11:26:04.281970 | instance |
2026-09-15 11:26:04.281975 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 11:26:04.281980 | instance | Tuesday 15 September 2026  11:26:04 +0000 (0:00:00.025)       0:00:29.714 *****
2026-09-15 11:26:05.083006 | instance | ok: [instance]
2026-09-15 11:26:05.083896 | instance |
2026-09-15 11:26:05.083955 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-15 11:26:05.083976 | instance | Tuesday 15 September 2026  11:26:05 +0000 (0:00:00.801)       0:00:30.515 *****
2026-09-15 11:26:06.106177 | instance | changed: [instance]
2026-09-15 11:26:06.107009 | instance |
2026-09-15 11:26:06.107236 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-15 11:26:06.107251 | instance | Tuesday 15 September 2026  11:26:06 +0000 (0:00:01.022)       0:00:31.538 *****
2026-09-15 11:26:06.766484 | instance | changed: [instance]
2026-09-15 11:26:06.766573 | instance |
2026-09-15 11:26:06.766939 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-15 11:26:06.767000 | instance | Tuesday 15 September 2026  11:26:06 +0000 (0:00:00.660)       0:00:32.199 *****
2026-09-15 11:26:06.844981 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-15 11:26:06.845788 | instance |
2026-09-15 11:26:06.845852 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-15 11:26:06.845865 | instance | Tuesday 15 September 2026  11:26:06 +0000 (0:00:00.077)       0:00:32.277 *****
2026-09-15 11:26:12.718879 | instance | changed: [instance]
2026-09-15 11:26:12.719314 | instance |
2026-09-15 11:26:12.719766 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-15 11:26:12.719856 | instance | Tuesday 15 September 2026  11:26:12 +0000 (0:00:05.873)       0:00:38.150 *****
2026-09-15 11:26:14.481842 | instance | ok: [instance] => (item=chronyd)
2026-09-15 11:26:14.482755 | instance | ok: [instance] => (item=sshd)
2026-09-15 11:26:14.482786 | instance |
2026-09-15 11:26:14.482797 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-15 11:26:14.482807 | instance | Tuesday 15 September 2026  11:26:14 +0000 (0:00:01.763)       0:00:39.914 *****
2026-09-15 11:26:34.732007 | instance | changed: [instance]
2026-09-15 11:26:34.732123 | instance |
2026-09-15 11:26:34.732455 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-15 11:26:34.732529 | instance | Tuesday 15 September 2026  11:26:34 +0000 (0:00:20.250)       0:01:00.164 *****
2026-09-15 11:26:34.969276 | instance | ok: [instance]
2026-09-15 11:26:34.969373 | instance |
2026-09-15 11:26:34.969747 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-15 11:26:34.969811 | instance | Tuesday 15 September 2026  11:26:34 +0000 (0:00:00.237)       0:01:00.401 *****
2026-09-15 11:26:35.510405 | instance | changed: [instance]
2026-09-15 11:26:35.510493 | instance |
2026-09-15 11:26:35.510869 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-15 11:26:35.510939 | instance | Tuesday 15 September 2026  11:26:35 +0000 (0:00:00.541)       0:01:00.942 *****
2026-09-15 11:26:35.906511 | instance | changed: [instance]
2026-09-15 11:26:35.906621 | instance |
2026-09-15 11:26:35.906635 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-15 11:26:35.906806 | instance | Tuesday 15 September 2026  11:26:35 +0000 (0:00:00.396)       0:01:01.339 *****
2026-09-15 11:26:36.050597 | instance | ok: [instance]
2026-09-15 11:26:36.050710 | instance |
2026-09-15 11:26:36.051300 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-15 11:26:36.051412 | instance | Tuesday 15 September 2026  11:26:36 +0000 (0:00:00.143)       0:01:01.482 *****
2026-09-15 11:26:36.336456 | instance | ok: [instance] => (item=instance)
2026-09-15 11:26:36.336672 | instance |
2026-09-15 11:26:36.337258 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-15 11:26:36.337330 | instance | Tuesday 15 September 2026  11:26:36 +0000 (0:00:00.286)       0:01:01.768 *****
2026-09-15 11:26:36.419347 | instance | ok: [instance]
2026-09-15 11:26:36.419451 | instance |
2026-09-15 11:26:36.419829 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-15 11:26:36.419890 | instance | Tuesday 15 September 2026  11:26:36 +0000 (0:00:00.082)       0:01:01.851 *****
2026-09-15 11:26:36.507266 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-15 11:26:36.507364 | instance |
2026-09-15 11:26:36.507381 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-15 11:26:36.507523 | instance | Tuesday 15 September 2026  11:26:36 +0000 (0:00:00.088)       0:01:01.940 *****
2026-09-15 11:26:36.832124 | instance | changed: [instance]
2026-09-15 11:26:36.832341 | instance |
2026-09-15 11:26:36.832639 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-15 11:26:36.832898 | instance | Tuesday 15 September 2026  11:26:36 +0000 (0:00:00.324)       0:01:02.264 *****
2026-09-15 11:26:37.688200 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-15 11:26:37.688472 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-15 11:26:37.688703 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-15 11:26:37.688923 | instance |
2026-09-15 11:26:37.689197 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-15 11:26:37.689426 | instance | Tuesday 15 September 2026  11:26:37 +0000 (0:00:00.856)       0:01:03.120 *****
2026-09-15 11:28:10.900317 | instance | ok: [instance]
2026-09-15 11:28:10.900385 | instance |
2026-09-15 11:28:10.900899 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-15 11:28:10.900976 | instance | Tuesday 15 September 2026  11:28:10 +0000 (0:01:33.210)       0:02:36.331 *****
2026-09-15 11:28:11.114504 | instance | changed: [instance]
2026-09-15 11:28:11.114543 | instance |
2026-09-15 11:28:11.114548 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-15 11:28:11.114553 | instance | Tuesday 15 September 2026  11:28:11 +0000 (0:00:00.215)       0:02:36.547 *****
2026-09-15 11:28:11.143998 | instance | ok: [instance]
2026-09-15 11:28:11.144040 | instance |
2026-09-15 11:28:11.144048 | instance | TASK [Install Ceph host] *******************************************************
2026-09-15 11:28:11.144055 | instance | Tuesday 15 September 2026  11:28:11 +0000 (0:00:00.028)       0:02:36.576 *****
2026-09-15 11:28:11.210758 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-15 11:28:11.210786 | instance |
2026-09-15 11:28:11.210792 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-15 11:28:11.210796 | instance | Tuesday 15 September 2026  11:28:11 +0000 (0:00:00.067)       0:02:36.643 *****
2026-09-15 11:28:12.838718 | instance | ok: [instance]
2026-09-15 11:28:12.838760 | instance |
2026-09-15 11:28:12.838767 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-15 11:28:12.838774 | instance | Tuesday 15 September 2026  11:28:12 +0000 (0:00:01.627)       0:02:38.271 *****
2026-09-15 11:28:12.903781 | instance | ok: [instance] => (item=instance)
2026-09-15 11:28:12.903813 | instance |
2026-09-15 11:28:12.903824 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-15 11:28:12.903833 | instance | Tuesday 15 September 2026  11:28:12 +0000 (0:00:00.064)       0:02:38.335 *****
2026-09-15 11:28:13.368359 | instance | ok: [instance]
2026-09-15 11:28:13.368392 | instance |
2026-09-15 11:28:13.368398 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-15 11:28:13.368403 | instance | Tuesday 15 September 2026  11:28:13 +0000 (0:00:00.465)       0:02:38.801 *****
2026-09-15 11:28:15.788988 | instance | ok: [instance]
2026-09-15 11:28:15.789093 | instance |
2026-09-15 11:28:15.789568 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-15 11:28:15.789616 | instance | Tuesday 15 September 2026  11:28:15 +0000 (0:00:02.420)       0:02:41.221 *****
2026-09-15 11:29:11.586369 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-15 11:29:11.587320 | 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__g7vgldt/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__g7vgldt/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__g7vgldt/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__g7vgldt/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-15T11:29:11.392+0000 7f88219c5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCgK6lqvK2/CCAAM2M5bo5V894GOvO96sr3VcmtbmMmG4QywyRsWzVkc/o=: Malformed input [buffer:3]\n2026-09-15T11:29:11.392+0000 7f88219c5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T11:29:11.396+0000 7f88219c5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCgK6lqvK2/CCAAM2M5bo5V894GOvO96sr3VcmtbmMmG4QywyRsWzVkc/o=: Malformed input [buffer:3]\n2026-09-15T11:29:11.396+0000 7f88219c5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T11:29:11.396+0000 7f88219c5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCgK6lqvK2/CCAAM2M5bo5V894GOvO96sr3VcmtbmMmG4QywyRsWzVkc/o=: Malformed input [buffer:3]\n2026-09-15T11:29:11.396+0000 7f88219c5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T11:29:11.396+0000 7f88219c5640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-15 11:29:11.591056 | instance |
2026-09-15 11:29:11.591618 | instance | PLAY RECAP *********************************************************************
2026-09-15 11:29:11.591678 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-15 11:29:11.595212 | instance |
2026-09-15 11:29:11.595269 | instance | Tuesday 15 September 2026  11:29:11 +0000 (0:00:55.802)       0:03:37.024 *****
2026-09-15 11:29:11.595281 | instance | ===============================================================================
2026-09-15 11:29:11.595291 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 93.21s
2026-09-15 11:29:11.595327 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 55.80s
2026-09-15 11:29:11.595340 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 20.25s
2026-09-15 11:29:11.595353 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.21s
2026-09-15 11:29:11.595365 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.87s
2026-09-15 11:29:11.595379 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.34s
2026-09-15 11:29:11.595391 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.86s
2026-09-15 11:29:11.595404 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.42s
2026-09-15 11:29:11.595436 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 1.76s
2026-09-15 11:29:11.595449 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.75s
2026-09-15 11:29:11.595462 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.63s
2026-09-15 11:29:11.595485 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.29s
2026-09-15 11:29:11.595537 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.19s
2026-09-15 11:29:11.595801 | instance | Gathering Facts --------------------------------------------------------- 1.04s
2026-09-15 11:29:11.596135 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.02s
2026-09-15 11:29:11.596491 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.98s
2026-09-15 11:29:11.596795 | instance | Gathering Facts --------------------------------------------------------- 0.95s
2026-09-15 11:29:11.597095 | instance | Gathering Facts --------------------------------------------------------- 0.92s
2026-09-15 11:29:11.597463 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.86s
2026-09-15 11:29:11.597774 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.85s
2026-09-15 11:29:11.707327 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 11:29:11.707628 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-15 11:29:11.707789 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 11:29:12.176626 | instance | ERROR
2026-09-15 11:29:12.176982 | instance | {
2026-09-15 11:29:12.177071 | instance |   "delta": "0:04:28.095449",
2026-09-15 11:29:12.177131 | instance |   "end": "2026-09-15 11:29:11.797514",
2026-09-15 11:29:12.177173 | instance |   "msg": "non-zero return code",
2026-09-15 11:29:12.177209 | instance |   "rc": 2,
2026-09-15 11:29:12.177244 | instance |   "start": "2026-09-15 11:24:43.702065"
2026-09-15 11:29:12.177279 | instance | }
failure
2026-09-15 11:29:12.186278 | 
2026-09-15 11:29:12.186404 | PLAY RECAP
2026-09-15 11:29:12.186535 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:29:12.186584 | 
2026-09-15 11:29:12.378649 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 11:29:12.383010 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 11:29:13.008019 | 
2026-09-15 11:29:13.008206 | PLAY [all]
2026-09-15 11:29:13.023847 | 
2026-09-15 11:29:13.023999 | TASK [gather-host-logs : creating directory for system status]
2026-09-15 11:29:13.413261 | instance | changed
2026-09-15 11:29:13.424160 | 
2026-09-15 11:29:13.424368 | TASK [gather-host-logs : Get logs for each host]
2026-09-15 11:29:13.821014 | instance | + systemd-cgls --full --all --no-pager
2026-09-15 11:29:13.836251 | instance | + ip addr
2026-09-15 11:29:13.839340 | instance | + ip route
2026-09-15 11:29:13.842118 | instance | + lsblk
2026-09-15 11:29:13.847189 | instance | + mount
2026-09-15 11:29:13.849567 | instance | + docker images
2026-09-15 11:29:13.872232 | instance | + brctl show
2026-09-15 11:29:13.872935 | instance | /bin/bash: line 8: brctl: command not found
2026-09-15 11:29:13.873256 | instance | + ip neigh
2026-09-15 11:29:13.875640 | instance | + bridge fdb show
2026-09-15 11:29:13.878650 | instance | + ps aux --sort=-%mem
2026-09-15 11:29:13.899028 | instance | + dpkg -l
2026-09-15 11:29:13.911976 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-15 11:29:13.912749 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-15 11:29:13.933372 | instance | + '[' '!' -z '' ']'
2026-09-15 11:29:13.981715 | instance | ok: Runtime: 0:00:00.116981
2026-09-15 11:29:13.989733 | 
2026-09-15 11:29:13.989879 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-15 11:29:14.670850 | instance | changed:
2026-09-15 11:29:14.671042 | instance | created directory /var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/work/logs/instance
2026-09-15 11:29:14.671076 | instance | cd+++++++++ system/
2026-09-15 11:29:14.671103 | instance | >f+++++++++ system/brctl-show.txt
2026-09-15 11:29:14.671127 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-15 11:29:14.671149 | instance | >f+++++++++ system/docker-images.txt
2026-09-15 11:29:14.671177 | instance | >f+++++++++ system/ip-addr.txt
2026-09-15 11:29:14.671202 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-15 11:29:14.671224 | instance | >f+++++++++ system/ip-route.txt
2026-09-15 11:29:14.671248 | instance | >f+++++++++ system/lsblk.txt
2026-09-15 11:29:14.671273 | instance | >f+++++++++ system/mount.txt
2026-09-15 11:29:14.671296 | instance | >f+++++++++ system/packages.txt
2026-09-15 11:29:14.671317 | instance | >f+++++++++ system/ps.txt
2026-09-15 11:29:14.671338 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-15 11:29:14.680041 | 
2026-09-15 11:29:14.680106 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 11:29:14.908938 | instance | changed: "values"
2026-09-15 11:29:15.095262 | instance | changed: "releases"
2026-09-15 11:29:15.114016 | 
2026-09-15 11:29:15.114207 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 11:29:15.376994 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-15 11:29:15.676272 | instance | ok: Runtime: 0:00:00.006147
2026-09-15 11:29:15.686412 | 
2026-09-15 11:29:15.686586 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 11:29:16.188037 | instance | changed:
2026-09-15 11:29:16.188412 | instance | cd+++++++++ helm/
2026-09-15 11:29:16.188582 | instance | cd+++++++++ helm/releases/
2026-09-15 11:29:16.188663 | instance | cd+++++++++ helm/values/
2026-09-15 11:29:16.208142 | 
2026-09-15 11:29:16.208365 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 11:29:16.426653 | instance | changed
2026-09-15 11:29:16.438404 | 
2026-09-15 11:29:16.438656 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 11:29:16.740280 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:29:16.740427 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:29:16.746049 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:29:16.748026 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:29:16.748463 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:29:16.750198 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:29:16.751380 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:29:16.752801 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:29:16.754387 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:29:16.755787 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:29:16.756166 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:29:16.757488 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:29:16.985510 | instance | ok: Runtime: 0:00:00.025507
2026-09-15 11:29:16.993579 | 
2026-09-15 11:29:16.993779 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 11:29:17.209033 | instance | changed
2026-09-15 11:29:17.219074 | 
2026-09-15 11:29:17.219207 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 11:29:17.479337 | instance | environment: line 5: kubectl: command not found
2026-09-15 11:29:17.480947 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:29:17.480983 | instance |
2026-09-15 11:29:17.481277 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:29:17.770237 | instance | ok: Runtime: 0:00:00.009491
2026-09-15 11:29:17.784230 | 
2026-09-15 11:29:17.784480 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 11:29:18.285892 | instance | changed:
2026-09-15 11:29:18.286166 | instance | cd+++++++++ objects/
2026-09-15 11:29:18.286223 | instance | cd+++++++++ objects/cluster/
2026-09-15 11:29:18.286265 | instance | cd+++++++++ objects/namespaced/
2026-09-15 11:29:18.304325 | 
2026-09-15 11:29:18.304581 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 11:29:18.530155 | instance | changed
2026-09-15 11:29:18.552634 | 
2026-09-15 11:29:18.552814 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 11:29:18.775646 | instance | changed
2026-09-15 11:29:18.784996 | 
2026-09-15 11:29:18.785156 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 11:29:19.042173 | instance | environment: line 3: kubectl: command not found
2026-09-15 11:29:19.323705 | instance | ok: Runtime: 0:00:00.009681
2026-09-15 11:29:19.333672 | 
2026-09-15 11:29:19.333814 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 11:29:19.873659 | instance | changed:
2026-09-15 11:29:19.873908 | instance | cd+++++++++ pod-logs/
2026-09-15 11:29:19.873961 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-15 11:29:19.893289 | 
2026-09-15 11:29:19.893485 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-15 11:29:20.120991 | instance | changed
2026-09-15 11:29:20.130485 | 
2026-09-15 11:29:20.130621 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-15 11:29:20.413074 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 11:29:20.696441 | instance | ok: Runtime: 0:00:00.053931
2026-09-15 11:29:20.704345 | 
2026-09-15 11:29:20.704477 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-15 11:29:20.948876 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 11:29:20.996580 | instance | ceph-mgr endpoints:
2026-09-15 11:29:21.242026 | instance | ok: Runtime: 0:00:00.054263
2026-09-15 11:29:21.253323 | 
2026-09-15 11:29:21.253540 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-15 11:29:21.513450 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-15 11:29:21.801775 | instance | ok: Runtime: 0:00:00.047421
2026-09-15 11:29:21.811449 | 
2026-09-15 11:29:21.811582 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-15 11:29:22.332061 | instance | changed: cd+++++++++ prometheus/
2026-09-15 11:29:22.350403 | 
2026-09-15 11:29:22.350565 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-15 11:29:22.570652 | instance | changed
2026-09-15 11:29:22.580360 | 
2026-09-15 11:29:22.580543 | TASK [gather-selenium-data : Get selenium data]
2026-09-15 11:29:22.868295 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-15 11:29:22.869174 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-15 11:29:23.122037 | instance | ERROR
2026-09-15 11:29:23.122245 | instance | {
2026-09-15 11:29:23.122272 | instance |   "delta": "0:00:00.005429",
2026-09-15 11:29:23.122291 | instance |   "end": "2026-09-15 11:29:22.869523",
2026-09-15 11:29:23.122308 | instance |   "msg": "non-zero return code",
2026-09-15 11:29:23.122323 | instance |   "rc": 1,
2026-09-15 11:29:23.122339 | instance |   "start": "2026-09-15 11:29:22.864094"
2026-09-15 11:29:23.122356 | instance | }
2026-09-15 11:29:23.122378 | instance | ERROR: Ignoring Errors
2026-09-15 11:29:23.126847 | 
2026-09-15 11:29:23.126909 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-15 11:29:23.676291 | instance | changed: cd+++++++++ selenium/
2026-09-15 11:29:23.688027 | 
2026-09-15 11:29:23.688123 | PLAY RECAP
2026-09-15 11:29:23.688206 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-15 11:29:23.688249 | 
2026-09-15 11:29:23.830786 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 11:29:23.834784 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 11:29:24.423997 | 
2026-09-15 11:29:24.424166 | PLAY [all]
2026-09-15 11:29:24.435960 | 
2026-09-15 11:29:24.436079 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 11:29:24.500509 | instance | skipping: Conditional result was False
2026-09-15 11:29:24.507553 | 
2026-09-15 11:29:24.507663 | TASK [fetch-output : Set log path for single node]
2026-09-15 11:29:24.561313 | instance | ok
2026-09-15 11:29:24.571141 | 
2026-09-15 11:29:24.571297 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 11:29:24.983358 | instance -> localhost | ok: "/var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/work/logs"
2026-09-15 11:29:25.231653 | instance -> localhost | changed: "/var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/work/artifacts"
2026-09-15 11:29:25.473927 | instance -> localhost | changed: "/var/lib/zuul/builds/15aac1e7b4bc435e82504b44d3c63fd3/work/docs"
2026-09-15 11:29:25.489698 | 
2026-09-15 11:29:25.489857 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 11:29:26.181727 | instance | changed: .d..t...... ./
2026-09-15 11:29:26.182042 | instance | changed: All items complete
2026-09-15 11:29:26.182097 | 
2026-09-15 11:29:26.643948 | instance | changed: .d..t...... ./
2026-09-15 11:29:27.166869 | instance | changed: .d..t...... ./
2026-09-15 11:29:27.194265 | 
2026-09-15 11:29:27.194484 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 11:29:27.662151 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007017
2026-09-15 11:29:27.934504 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006876
2026-09-15 11:29:27.947504 | 
2026-09-15 11:29:27.947649 | PLAY [all]
2026-09-15 11:29:27.962770 | 
2026-09-15 11:29:27.962907 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 11:29:28.414288 | instance | changed
2026-09-15 11:29:28.427384 | 
2026-09-15 11:29:28.427524 | PLAY RECAP
2026-09-15 11:29:28.427617 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 11:29:28.427665 | 
2026-09-15 11:29:28.574252 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 11:29:28.578357 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 11:29:29.240559 | 
2026-09-15 11:29:29.240714 | PLAY [localhost]
2026-09-15 11:29:29.251417 | 
2026-09-15 11:29:29.251517 | TASK [Generate Zuul manifest]
2026-09-15 11:29:29.269587 | localhost | ok
2026-09-15 11:29:29.289506 | 
2026-09-15 11:29:29.289616 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 11:29:29.787358 | localhost | changed
2026-09-15 11:29:29.808006 | 
2026-09-15 11:29:29.808259 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 11:29:29.846717 | localhost | ok
2026-09-15 11:29:29.853074 | 
2026-09-15 11:29:29.853147 | TASK [Upload logs]
2026-09-15 11:29:29.872336 | localhost | ok
2026-09-15 11:29:29.929199 | 
2026-09-15 11:29:29.929388 | TASK [Set zuul-log-path fact]
2026-09-15 11:29:29.947778 | localhost | ok
2026-09-15 11:29:29.958587 | 
2026-09-15 11:29:29.958656 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 11:29:29.997131 | localhost | ok
2026-09-15 11:29:30.008016 | 
2026-09-15 11:29:30.008161 | TASK [upload-logs : Create log directories]
2026-09-15 11:29:30.476651 | localhost | changed
2026-09-15 11:29:30.483878 | 
2026-09-15 11:29:30.484272 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 11:29:30.900721 | localhost -> localhost | ok: Runtime: 0:00:00.005157
2026-09-15 11:29:30.907756 | 
2026-09-15 11:29:30.907881 | TASK [upload-logs : Upload logs to log server]
2026-09-15 11:29:31.406167 | localhost | Output suppressed because no_log was given
2026-09-15 11:29:31.483557 | 
2026-09-15 11:29:31.483742 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 11:29:31.527899 | localhost | skipping: Conditional result was False
2026-09-15 11:29:31.534490 | localhost | skipping: Conditional result was False
2026-09-15 11:29:31.546301 | 
2026-09-15 11:29:31.546495 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 11:29:31.607820 | localhost | skipping: Conditional result was False
2026-09-15 11:29:31.608241 | 
2026-09-15 11:29:31.613411 | localhost | skipping: Conditional result was False
2026-09-15 11:29:31.628953 | 
2026-09-15 11:29:31.629142 | LOOP [upload-logs : Upload console log and json output]