2026-08-27 00:02:26.421087 | Job console starting 2026-08-27 00:02:26.431539 | Updating git repos 2026-08-27 00:02:30.265992 | Cloning repos into workspace 2026-08-27 00:02:49.379351 | Restoring repo states 2026-08-27 00:03:04.053225 | Merging changes 2026-08-27 00:03:09.339519 | Checking out repos 2026-08-27 00:03:17.030000 | Preparing playbooks 2026-08-27 00:03:32.573749 | Running Ansible setup 2026-08-27 00:03:38.869687 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-27 00:03:39.457156 | 2026-08-27 00:03:39.457293 | PLAY [localhost] 2026-08-27 00:03:39.464764 | 2026-08-27 00:03:39.464833 | TASK [Gathering Facts] 2026-08-27 00:03:40.382649 | localhost | ok 2026-08-27 00:03:40.394948 | 2026-08-27 00:03:40.395474 | TASK [Setup log path fact] 2026-08-27 00:03:40.416632 | localhost | ok 2026-08-27 00:03:40.430049 | 2026-08-27 00:03:40.430146 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-27 00:03:40.461226 | localhost | ok 2026-08-27 00:03:40.470647 | 2026-08-27 00:03:40.470747 | TASK [emit-job-header : Print job information] 2026-08-27 00:03:40.512369 | # Job Information 2026-08-27 00:03:40.512573 | Ansible Version: 2.16.18 2026-08-27 00:03:40.512645 | Job: atmosphere-molecule-csi-rbd 2026-08-27 00:03:40.512694 | Pipeline: check 2026-08-27 00:03:40.512739 | Executor: 2d72f0692154 2026-08-27 00:03:40.512783 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-08-27 00:03:40.512833 | Event ID: 82ffc060-a1a9-11f1-9fc1-9d94a7497734 2026-08-27 00:03:40.516710 | 2026-08-27 00:03:40.516768 | LOOP [emit-job-header : Print node information] 2026-08-27 00:03:40.611782 | localhost | ok: 2026-08-27 00:03:40.612395 | localhost | # Node Information 2026-08-27 00:03:40.612467 | localhost | Inventory Hostname: instance 2026-08-27 00:03:40.612536 | localhost | Hostname: np0000191327 2026-08-27 00:03:40.612594 | localhost | Username: zuul 2026-08-27 00:03:40.612657 | localhost | Distro: Ubuntu 22.04 2026-08-27 00:03:40.612712 | localhost | Provider: yul1 2026-08-27 00:03:40.612766 | localhost | Region: ca-ymq-1 2026-08-27 00:03:40.612819 | localhost | Label: ubuntu-jammy 2026-08-27 00:03:40.612870 | localhost | Product Name: OpenStack Nova 2026-08-27 00:03:40.612923 | localhost | Interface IP: 162.253.55.80 2026-08-27 00:03:40.628134 | 2026-08-27 00:03:40.628330 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-27 00:03:41.039973 | localhost -> localhost | changed 2026-08-27 00:03:41.045817 | 2026-08-27 00:03:41.045934 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-27 00:03:41.872303 | localhost -> localhost | changed 2026-08-27 00:03:41.882875 | 2026-08-27 00:03:41.883017 | PLAY [all] 2026-08-27 00:03:41.892834 | 2026-08-27 00:03:41.893042 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-27 00:03:42.120239 | instance -> localhost | ok 2026-08-27 00:03:42.131844 | 2026-08-27 00:03:42.131977 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-27 00:03:42.165252 | instance | ok 2026-08-27 00:03:42.180880 | instance | included: /var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-27 00:03:42.186315 | 2026-08-27 00:03:42.186376 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-27 00:03:43.126158 | instance -> localhost | Generating public/private rsa key pair. 2026-08-27 00:03:43.126353 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/work/29e8954c94094ea3bcdefb161ca357da_id_rsa 2026-08-27 00:03:43.126383 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/work/29e8954c94094ea3bcdefb161ca357da_id_rsa.pub 2026-08-27 00:03:43.126406 | instance -> localhost | The key fingerprint is: 2026-08-27 00:03:43.126426 | instance -> localhost | SHA256:ANJwGix+YiQHOhdvMTlgfVjx97pqb7XjmQIl69WMqYc zuul-build-sshkey 2026-08-27 00:03:43.126462 | instance -> localhost | The key's randomart image is: 2026-08-27 00:03:43.126484 | instance -> localhost | +---[RSA 3072]----+ 2026-08-27 00:03:43.126510 | instance -> localhost | |.oB==+o. | 2026-08-27 00:03:43.126532 | instance -> localhost | |+ooB*+.. | 2026-08-27 00:03:43.126552 | instance -> localhost | |*oo oo. . . | 2026-08-27 00:03:43.126572 | instance -> localhost | | * o ..... | 2026-08-27 00:03:43.126591 | instance -> localhost | |. o S+ =. | 2026-08-27 00:03:43.126611 | instance -> localhost | | o +.+ | 2026-08-27 00:03:43.126630 | instance -> localhost | | . =.. . | 2026-08-27 00:03:43.126650 | instance -> localhost | | E +.oo | 2026-08-27 00:03:43.126672 | instance -> localhost | | ..=oo+. | 2026-08-27 00:03:43.126692 | instance -> localhost | +----[SHA256]-----+ 2026-08-27 00:03:43.126736 | instance -> localhost | ok: Runtime: 0:00:00.529551 2026-08-27 00:03:43.135063 | 2026-08-27 00:03:43.135127 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-27 00:03:43.171217 | instance | ok 2026-08-27 00:03:43.179640 | instance | included: /var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-27 00:03:43.187290 | 2026-08-27 00:03:43.187351 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-27 00:03:43.212549 | instance | skipping: Conditional result was False 2026-08-27 00:03:43.219917 | 2026-08-27 00:03:43.219990 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-27 00:03:43.679107 | instance | changed 2026-08-27 00:03:43.686185 | 2026-08-27 00:03:43.686250 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-27 00:03:43.878620 | instance | ok 2026-08-27 00:03:43.883570 | 2026-08-27 00:03:43.883632 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-27 00:03:44.348435 | instance | changed 2026-08-27 00:03:44.353626 | 2026-08-27 00:03:44.353695 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-27 00:03:44.842511 | instance | changed 2026-08-27 00:03:44.849225 | 2026-08-27 00:03:44.849354 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-27 00:03:44.875712 | instance | skipping: Conditional result was False 2026-08-27 00:03:44.885532 | 2026-08-27 00:03:44.885600 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-27 00:03:45.279233 | instance -> localhost | changed 2026-08-27 00:03:45.294510 | 2026-08-27 00:03:45.294607 | TASK [add-build-sshkey : Add back temp key] 2026-08-27 00:03:45.564849 | instance -> localhost | Identity added: /var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/work/29e8954c94094ea3bcdefb161ca357da_id_rsa (zuul-build-sshkey) 2026-08-27 00:03:45.565086 | instance -> localhost | ok: Runtime: 0:00:00.011003 2026-08-27 00:03:45.570281 | 2026-08-27 00:03:45.570351 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-27 00:03:45.912473 | instance | ok 2026-08-27 00:03:45.917969 | 2026-08-27 00:03:45.918037 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-27 00:03:45.943968 | instance | skipping: Conditional result was False 2026-08-27 00:03:45.954631 | 2026-08-27 00:03:45.954723 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-27 00:03:46.308052 | instance | ok 2026-08-27 00:03:46.314563 | 2026-08-27 00:03:46.314719 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-27 00:03:51.160847 | instance | Output suppressed because no_log was given 2026-08-27 00:03:51.172865 | 2026-08-27 00:03:51.172961 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-27 00:03:51.383891 | instance | ok: "logs" 2026-08-27 00:03:51.384265 | instance | ok: All items complete 2026-08-27 00:03:51.384297 | 2026-08-27 00:03:51.546604 | instance | ok: "artifacts" 2026-08-27 00:03:51.725852 | instance | ok: "docs" 2026-08-27 00:03:51.745643 | 2026-08-27 00:03:51.745806 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-27 00:03:51.943328 | instance | changed: "logs" 2026-08-27 00:03:52.091491 | instance | changed: "artifacts" 2026-08-27 00:03:52.253453 | instance | changed: "docs" 2026-08-27 00:03:52.265847 | 2026-08-27 00:03:52.265971 | PLAY RECAP 2026-08-27 00:03:52.266035 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-27 00:03:52.266117 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-27 00:03:52.266153 | 2026-08-27 00:03:52.407970 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-27 00:03:52.412437 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-27 00:03:52.988879 | 2026-08-27 00:03:52.989029 | PLAY [all] 2026-08-27 00:03:52.999893 | 2026-08-27 00:03:52.999972 | TASK [setup-uv : Extract archive] 2026-08-27 00:04:03.162779 | instance | changed 2026-08-27 00:04:03.169789 | 2026-08-27 00:04:03.169876 | TASK [setup-uv : Print version] 2026-08-27 00:04:03.521424 | instance | uv 0.8.13 2026-08-27 00:04:03.705939 | instance | ok: Runtime: 0:00:00.012853 2026-08-27 00:04:03.714625 | 2026-08-27 00:04:03.714693 | TASK [Install Ansible collection siblings] 2026-08-27 00:04:15.495569 | instance | changed: 2026-08-27 00:04:15.495773 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-08-27 00:04:15.495802 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-08-27 00:04:15.495824 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-08-27 00:04:15.495844 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-08-27 00:04:15.495864 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-08-27 00:04:15.495895 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-08-27 00:04:15.495926 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-08-27 00:04:15.495950 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-08-27 00:04:15.504521 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-08-27 00:04:15.504617 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-08-27 00:04:15.504669 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-08-27 00:04:15.504714 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-08-27 00:04:15.504756 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-08-27 00:04:15.504800 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-08-27 00:04:15.504842 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-08-27 00:04:15.504886 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-08-27 00:04:15.504928 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-08-27 00:04:15.505046 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-08-27 00:04:15.505125 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-08-27 00:04:15.505198 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-08-27 00:04:15.505262 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-08-27 00:04:15.505308 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-08-27 00:04:15.505356 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-27 00:04:15.505432 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-08-27 00:04:15.505481 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-08-27 00:04:15.505525 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-27 00:04:15.505579 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-08-27 00:04:15.505627 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-27 00:04:15.505669 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-08-27 00:04:15.505711 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-08-27 00:04:15.505752 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-08-27 00:04:15.505794 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-27 00:04:15.505835 | instance | Checked out ansible.netcommon to 1.2.0 2026-08-27 00:04:15.505878 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-08-27 00:04:15.505934 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.505976 | instance | Process install dependency map 2026-08-27 00:04:15.506018 | instance | Starting collection install process 2026-08-27 00:04:15.506059 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-08-27 00:04:15.506100 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-08-27 00:04:15.506141 | instance | ansible.netcommon:* was installed successfully 2026-08-27 00:04:15.506181 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-08-27 00:04:15.506222 | instance | Creating virtual environment at: .venv 2026-08-27 00:04:15.506263 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-27 00:04:15.506305 | instance | Downloading pygments (1.2MiB) 2026-08-27 00:04:15.506345 | instance | Downloading cryptography (4.2MiB) 2026-08-27 00:04:15.506388 | instance | Downloading ansible-core (2.1MiB) 2026-08-27 00:04:15.506429 | instance | Downloading netaddr (2.2MiB) 2026-08-27 00:04:15.506470 | instance | Downloading openstacksdk (1.7MiB) 2026-08-27 00:04:15.506510 | instance | Downloading setuptools (1.1MiB) 2026-08-27 00:04:15.506550 | instance | Downloading pydantic-core (2.0MiB) 2026-08-27 00:04:15.506592 | instance | Downloading kubernetes (1.9MiB) 2026-08-27 00:04:15.506632 | instance | Downloading rjsonnet (1.2MiB) 2026-08-27 00:04:15.506673 | instance | Building pyperclip==1.9.0 2026-08-27 00:04:15.506716 | instance | Downloading rjsonnet 2026-08-27 00:04:15.506758 | instance | Downloading pydantic-core 2026-08-27 00:04:15.506800 | instance | Downloading netaddr 2026-08-27 00:04:15.506840 | instance | Downloading pygments 2026-08-27 00:04:15.506880 | instance | Downloading cryptography 2026-08-27 00:04:15.506920 | instance | Downloading setuptools 2026-08-27 00:04:15.506959 | instance | Downloading kubernetes 2026-08-27 00:04:15.506999 | instance | Downloading ansible-core 2026-08-27 00:04:15.507040 | instance | Downloading openstacksdk 2026-08-27 00:04:15.507081 | instance | Built pyperclip==1.9.0 2026-08-27 00:04:15.507123 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-27 00:04:15.507163 | instance | Installed 83 packages in 65ms 2026-08-27 00:04:15.507203 | instance | Checked out ansible.posix to 1.6.0 2026-08-27 00:04:15.507244 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-08-27 00:04:15.507285 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.507323 | instance | Process install dependency map 2026-08-27 00:04:15.507373 | instance | Starting collection install process 2026-08-27 00:04:15.507415 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-08-27 00:04:15.507456 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-08-27 00:04:15.507496 | instance | ansible.posix:1.6.0 was installed successfully 2026-08-27 00:04:15.507536 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.507576 | instance | 2.17.12 2026-08-27 00:04:15.507617 | instance | Checked out ansible.utils to v6.1.0 2026-08-27 00:04:15.507657 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-08-27 00:04:15.507698 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.507738 | instance | Process install dependency map 2026-08-27 00:04:15.507778 | instance | Starting collection install process 2026-08-27 00:04:15.507818 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-08-27 00:04:15.507858 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-08-27 00:04:15.507898 | instance | ansible.utils:6.1.0 was installed successfully 2026-08-27 00:04:15.507939 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.507978 | instance | 2.17.12 2026-08-27 00:04:15.508018 | instance | Checked out atmosphere.common to v0.7.0 2026-08-27 00:04:15.508059 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-08-27 00:04:15.508100 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.508140 | instance | Process install dependency map 2026-08-27 00:04:15.508180 | instance | Starting collection install process 2026-08-27 00:04:15.508224 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-08-27 00:04:15.508264 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-08-27 00:04:15.508304 | instance | atmosphere.common:0.7.0 was installed successfully 2026-08-27 00:04:15.508345 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.508384 | instance | 2.17.12 2026-08-27 00:04:15.508425 | instance | Checked out community.crypto to 2.2.3 2026-08-27 00:04:15.508467 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-08-27 00:04:15.508507 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.508547 | instance | Process install dependency map 2026-08-27 00:04:15.508587 | instance | Starting collection install process 2026-08-27 00:04:15.508627 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-08-27 00:04:15.508667 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-08-27 00:04:15.508707 | instance | community.crypto:2.2.3 was installed successfully 2026-08-27 00:04:15.508747 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.508787 | instance | 2.17.12 2026-08-27 00:04:15.508827 | instance | Checked out community.general to 7.3.0 2026-08-27 00:04:15.508867 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-08-27 00:04:15.508919 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.509056 | instance | Process install dependency map 2026-08-27 00:04:15.509103 | instance | Starting collection install process 2026-08-27 00:04:15.509145 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-08-27 00:04:15.509185 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-08-27 00:04:15.509213 | instance | community.general:7.3.0 was installed successfully 2026-08-27 00:04:15.509249 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.509277 | instance | 2.17.12 2026-08-27 00:04:15.509304 | instance | Checked out community.mysql to 3.6.0 2026-08-27 00:04:15.509332 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-08-27 00:04:15.509359 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.509386 | instance | Process install dependency map 2026-08-27 00:04:15.509412 | instance | Starting collection install process 2026-08-27 00:04:15.509438 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-08-27 00:04:15.509464 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-08-27 00:04:15.509490 | instance | community.mysql:3.6.0 was installed successfully 2026-08-27 00:04:15.509516 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.509541 | instance | 2.17.12 2026-08-27 00:04:15.509566 | instance | Checked out kubernetes.core to 2.4.0 2026-08-27 00:04:15.509592 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-08-27 00:04:15.509618 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.509643 | instance | Process install dependency map 2026-08-27 00:04:15.509670 | instance | Starting collection install process 2026-08-27 00:04:15.509695 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-08-27 00:04:15.509721 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-08-27 00:04:15.509747 | instance | kubernetes.core:2.4.0 was installed successfully 2026-08-27 00:04:15.509773 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.509799 | instance | 2.17.12 2026-08-27 00:04:15.509825 | instance | Checked out openstack.cloud to 2.6.0 2026-08-27 00:04:15.509850 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-08-27 00:04:15.509877 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.509902 | instance | Process install dependency map 2026-08-27 00:04:15.509928 | instance | Starting collection install process 2026-08-27 00:04:15.509953 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-08-27 00:04:15.509978 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-08-27 00:04:15.510003 | instance | openstack.cloud:2.6.0 was installed successfully 2026-08-27 00:04:15.510028 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.510053 | instance | 2.17.12 2026-08-27 00:04:15.510086 | instance | Checked out vexxhost.ceph to v4.2.0 2026-08-27 00:04:15.510113 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-08-27 00:04:15.510139 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.510164 | instance | Process install dependency map 2026-08-27 00:04:15.510189 | instance | Starting collection install process 2026-08-27 00:04:15.510215 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-08-27 00:04:15.510240 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-08-27 00:04:15.510265 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-08-27 00:04:15.510291 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.510316 | instance | 2.17.12 2026-08-27 00:04:15.510342 | instance | Checked out vexxhost.containers to v1.6.8 2026-08-27 00:04:15.510367 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-08-27 00:04:15.510392 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.510418 | instance | Process install dependency map 2026-08-27 00:04:15.510444 | instance | Starting collection install process 2026-08-27 00:04:15.510469 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-08-27 00:04:15.510494 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-08-27 00:04:15.510519 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-08-27 00:04:15.510544 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.510569 | instance | 2.17.12 2026-08-27 00:04:15.510595 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-08-27 00:04:15.510620 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-08-27 00:04:15.510646 | instance | Starting galaxy collection install process 2026-08-27 00:04:15.510670 | instance | Process install dependency map 2026-08-27 00:04:15.510696 | instance | Starting collection install process 2026-08-27 00:04:15.510721 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-08-27 00:04:15.510745 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-08-27 00:04:15.510770 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-08-27 00:04:15.510796 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-08-27 00:04:15.510820 | instance | 2.17.12 2026-08-27 00:04:15.519490 | 2026-08-27 00:04:15.519541 | PLAY RECAP 2026-08-27 00:04:15.519595 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-27 00:04:15.519616 | 2026-08-27 00:04:15.662890 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-27 00:04:15.668553 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-27 00:04:16.272078 | 2026-08-27 00:04:16.272208 | PLAY [all] 2026-08-27 00:04:16.283840 | 2026-08-27 00:04:16.283948 | TASK [Install "jq" for log collection] 2026-08-27 00:04:49.900891 | instance | changed 2026-08-27 00:04:49.903181 | 2026-08-27 00:04:49.903245 | PLAY RECAP 2026-08-27 00:04:49.903300 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-27 00:04:49.903412 | 2026-08-27 00:04:50.053277 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-27 00:04:50.055325 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-27 00:04:50.668999 | 2026-08-27 00:04:50.669128 | PLAY [all] 2026-08-27 00:04:50.680918 | 2026-08-27 00:04:50.681015 | LOOP [Check inventory directories] 2026-08-27 00:04:51.024315 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-08-27 00:04:51.190161 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-08-27 00:04:51.365087 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-08-27 00:04:51.385407 | 2026-08-27 00:04:51.385642 | LOOP [Copy inventory file for Zuul] 2026-08-27 00:04:52.223570 | instance | changed: 2026-08-27 00:04:52.223825 | instance | { 2026-08-27 00:04:52.223866 | instance | "ansible_loop_var": "item", 2026-08-27 00:04:52.223900 | instance | "changed": false, 2026-08-27 00:04:52.223929 | instance | "failed": false, 2026-08-27 00:04:52.223962 | instance | "invocation": { 2026-08-27 00:04:52.223990 | instance | "module_args": { 2026-08-27 00:04:52.224016 | instance | "checksum_algorithm": "sha1", 2026-08-27 00:04:52.224043 | instance | "follow": true, 2026-08-27 00:04:52.224069 | instance | "get_attributes": true, 2026-08-27 00:04:52.224097 | instance | "get_checksum": true, 2026-08-27 00:04:52.224124 | instance | "get_mime": true, 2026-08-27 00:04:52.224151 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-08-27 00:04:52.224177 | instance | } 2026-08-27 00:04:52.224203 | instance | }, 2026-08-27 00:04:52.224229 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-08-27 00:04:52.224255 | instance | "stat": { 2026-08-27 00:04:52.224280 | instance | "atime": 1787789048.2137194, 2026-08-27 00:04:52.224307 | instance | "attr_flags": "e", 2026-08-27 00:04:52.224333 | instance | "attributes": [ 2026-08-27 00:04:52.224359 | instance | "extents" 2026-08-27 00:04:52.224385 | instance | ], 2026-08-27 00:04:52.224411 | instance | "block_size": 4096, 2026-08-27 00:04:52.224436 | instance | "blocks": 8, 2026-08-27 00:04:52.224462 | instance | "charset": "unknown", 2026-08-27 00:04:52.224489 | instance | "ctime": 1787789044.5855668, 2026-08-27 00:04:52.224516 | instance | "dev": 64513, 2026-08-27 00:04:52.224542 | instance | "device_type": 0, 2026-08-27 00:04:52.224568 | instance | "executable": true, 2026-08-27 00:04:52.224594 | instance | "exists": true, 2026-08-27 00:04:52.224620 | instance | "gid": 1000, 2026-08-27 00:04:52.224645 | instance | "gr_name": "zuul", 2026-08-27 00:04:52.224671 | instance | "inode": 1047716, 2026-08-27 00:04:52.224697 | instance | "isblk": false, 2026-08-27 00:04:52.224723 | instance | "ischr": false, 2026-08-27 00:04:52.224749 | instance | "isdir": true, 2026-08-27 00:04:52.224774 | instance | "isfifo": false, 2026-08-27 00:04:52.224799 | instance | "isgid": false, 2026-08-27 00:04:52.224825 | instance | "islnk": false, 2026-08-27 00:04:52.224850 | instance | "isreg": false, 2026-08-27 00:04:52.224876 | instance | "issock": false, 2026-08-27 00:04:52.224901 | instance | "isuid": false, 2026-08-27 00:04:52.224927 | instance | "mimetype": "unknown", 2026-08-27 00:04:52.224998 | instance | "mode": "0755", 2026-08-27 00:04:52.225067 | instance | "mtime": 1787789044.5855668, 2026-08-27 00:04:52.225099 | instance | "nlink": 23, 2026-08-27 00:04:52.225126 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-08-27 00:04:52.225152 | instance | "pw_name": "zuul", 2026-08-27 00:04:52.225180 | instance | "readable": true, 2026-08-27 00:04:52.225206 | instance | "rgrp": true, 2026-08-27 00:04:52.225231 | instance | "roth": true, 2026-08-27 00:04:52.225276 | instance | "rusr": true, 2026-08-27 00:04:52.225302 | instance | "size": 4096, 2026-08-27 00:04:52.225328 | instance | "uid": 1000, 2026-08-27 00:04:52.225353 | instance | "version": "1217589984", 2026-08-27 00:04:52.225379 | instance | "wgrp": false, 2026-08-27 00:04:52.225407 | instance | "woth": false, 2026-08-27 00:04:52.225433 | instance | "writeable": true, 2026-08-27 00:04:52.225459 | instance | "wusr": true, 2026-08-27 00:04:52.225484 | instance | "xgrp": true, 2026-08-27 00:04:52.225527 | instance | "xoth": true, 2026-08-27 00:04:52.225554 | instance | "xusr": true 2026-08-27 00:04:52.225580 | instance | } 2026-08-27 00:04:52.225605 | instance | } 2026-08-27 00:04:52.225657 | instance | ok: All items complete 2026-08-27 00:04:52.225689 | 2026-08-27 00:04:52.227554 | instance | skipping: Conditional result was False 2026-08-27 00:04:52.228385 | instance | skipping: Conditional result was False 2026-08-27 00:04:52.243687 | 2026-08-27 00:04:52.243839 | LOOP [Switch "ansible_host" to private IP] 2026-08-27 00:04:52.599545 | instance | changed: 1 replacements made 2026-08-27 00:04:52.733629 | instance | skipping: Conditional result was False 2026-08-27 00:04:52.735162 | instance | skipping: Conditional result was False 2026-08-27 00:04:52.751525 | 2026-08-27 00:04:52.751773 | TASK [Run Molecule scenario] 2026-08-27 00:04:53.671264 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-08-27 00:04:54.077269 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-08-27 00:04:54.077321 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-08-27 00:05:04.358079 | instance | INFO [csi > dependency] Executing 2026-08-27 00:05:04.358138 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-08-27 00:05:04.358146 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-08-27 00:05:04.358158 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-08-27 00:05:04.372287 | instance | INFO [csi > cleanup] Executing 2026-08-27 00:05:04.372929 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-27 00:05:04.387091 | instance | INFO [csi > destroy] Executing 2026-08-27 00:05:04.387182 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-08-27 00:05:04.387281 | instance | INFO [csi > destroy] Executed: Successful 2026-08-27 00:05:04.396683 | instance | INFO [csi > syntax] Executing 2026-08-27 00:05:05.838616 | instance | 2026-08-27 00:05:05.838940 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-08-27 00:05:05.895167 | instance | INFO [csi > syntax] Executed: Successful 2026-08-27 00:05:05.911039 | instance | INFO [csi > create] Executing 2026-08-27 00:05:05.914605 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-27 00:05:05.928784 | instance | INFO [csi > prepare] Executing 2026-08-27 00:05:06.702074 | instance | 2026-08-27 00:05:06.702156 | instance | PLAY [Prepare] ***************************************************************** 2026-08-27 00:05:06.702295 | instance | 2026-08-27 00:05:06.702438 | instance | TASK [Gathering Facts] ********************************************************* 2026-08-27 00:05:06.702583 | instance | Thursday 27 August 2026 00:05:06 +0000 (0:00:00.024) 0:00:00.024 ******* 2026-08-27 00:05:07.975794 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-08-27 00:05:07.976026 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-08-27 00:05:07.976262 | instance | interpreter could change the meaning of that path. See 2026-08-27 00:05:07.976492 | instance | https://docs.ansible.com/ansible- 2026-08-27 00:05:07.976727 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-08-27 00:05:07.987191 | instance | ok: [instance] 2026-08-27 00:05:07.987419 | instance | 2026-08-27 00:05:07.987665 | instance | TASK [Configure short hostname] ************************************************ 2026-08-27 00:05:07.987930 | instance | Thursday 27 August 2026 00:05:07 +0000 (0:00:01.285) 0:00:01.309 ******* 2026-08-27 00:05:26.315976 | instance | changed: [instance] 2026-08-27 00:05:26.316179 | instance | 2026-08-27 00:05:26.316419 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-08-27 00:05:26.316653 | instance | Thursday 27 August 2026 00:05:26 +0000 (0:00:18.328) 0:00:19.637 ******* 2026-08-27 00:05:29.496501 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-08-27 00:05:29.496713 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-08-27 00:05:29.496937 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-08-27 00:05:29.506810 | instance | changed: [instance] 2026-08-27 00:05:29.507036 | instance | 2026-08-27 00:05:29.507261 | instance | TASK [Purge "snapd" package] *************************************************** 2026-08-27 00:05:29.507481 | instance | Thursday 27 August 2026 00:05:29 +0000 (0:00:03.191) 0:00:22.829 ******* 2026-08-27 00:05:30.277360 | instance | ok: [instance] 2026-08-27 00:05:30.277458 | instance | 2026-08-27 00:05:30.277564 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-08-27 00:05:30.277679 | instance | 2026-08-27 00:05:30.277807 | instance | TASK [Gathering Facts] ********************************************************* 2026-08-27 00:05:30.277908 | instance | Thursday 27 August 2026 00:05:30 +0000 (0:00:00.770) 0:00:23.599 ******* 2026-08-27 00:05:31.044158 | instance | ok: [instance] 2026-08-27 00:05:31.044324 | instance | 2026-08-27 00:05:31.044577 | instance | TASK [Install depedencies] ***************************************************** 2026-08-27 00:05:31.044857 | instance | Thursday 27 August 2026 00:05:31 +0000 (0:00:00.766) 0:00:24.366 ******* 2026-08-27 00:06:12.702318 | instance | changed: [instance] 2026-08-27 00:06:12.702386 | instance | 2026-08-27 00:06:12.702404 | instance | TASK [Start up service] ******************************************************** 2026-08-27 00:06:12.702420 | instance | Thursday 27 August 2026 00:06:12 +0000 (0:00:41.657) 0:01:06.023 ******* 2026-08-27 00:06:13.253131 | instance | ok: [instance] 2026-08-27 00:06:13.253198 | instance | 2026-08-27 00:06:13.253405 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-08-27 00:06:13.253437 | instance | Thursday 27 August 2026 00:06:13 +0000 (0:00:00.552) 0:01:06.575 ******* 2026-08-27 00:06:13.578568 | instance | ok: [instance] 2026-08-27 00:06:13.578881 | instance | 2026-08-27 00:06:13.579363 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-08-27 00:06:13.579425 | instance | Thursday 27 August 2026 00:06:13 +0000 (0:00:00.324) 0:01:06.900 ******* 2026-08-27 00:06:14.301657 | instance | changed: [instance] 2026-08-27 00:06:14.301883 | instance | 2026-08-27 00:06:14.302465 | instance | TASK [Get list of all loopback devices] **************************************** 2026-08-27 00:06:14.302826 | instance | Thursday 27 August 2026 00:06:14 +0000 (0:00:00.723) 0:01:07.623 ******* 2026-08-27 00:06:14.517620 | instance | ok: [instance] 2026-08-27 00:06:14.517660 | instance | 2026-08-27 00:06:14.517821 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-08-27 00:06:14.518022 | instance | Thursday 27 August 2026 00:06:14 +0000 (0:00:00.216) 0:01:07.840 ******* 2026-08-27 00:06:14.547537 | instance | skipping: [instance] 2026-08-27 00:06:14.547774 | instance | 2026-08-27 00:06:14.548121 | instance | TASK [Create devices for Ceph] ************************************************* 2026-08-27 00:06:14.548431 | instance | Thursday 27 August 2026 00:06:14 +0000 (0:00:00.029) 0:01:07.869 ******* 2026-08-27 00:06:15.087866 | instance | changed: [instance] => (item=osd0) 2026-08-27 00:06:15.088255 | instance | changed: [instance] => (item=osd1) 2026-08-27 00:06:15.088547 | instance | changed: [instance] => (item=osd2) 2026-08-27 00:06:15.088821 | instance | 2026-08-27 00:06:15.089135 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-08-27 00:06:15.089430 | instance | Thursday 27 August 2026 00:06:15 +0000 (0:00:00.537) 0:01:08.407 ******* 2026-08-27 00:06:15.746620 | instance | changed: [instance] => (item=osd0) 2026-08-27 00:06:15.747081 | instance | changed: [instance] => (item=osd1) 2026-08-27 00:06:15.747419 | instance | changed: [instance] => (item=osd2) 2026-08-27 00:06:15.747731 | instance | 2026-08-27 00:06:15.748045 | instance | TASK [Start loop devices] ****************************************************** 2026-08-27 00:06:15.748343 | instance | Thursday 27 August 2026 00:06:15 +0000 (0:00:00.658) 0:01:09.066 ******* 2026-08-27 00:06:16.437265 | instance | changed: [instance] => (item=osd0) 2026-08-27 00:06:16.437612 | instance | changed: [instance] => (item=osd1) 2026-08-27 00:06:16.437902 | instance | changed: [instance] => (item=osd2) 2026-08-27 00:06:16.438200 | instance | 2026-08-27 00:06:16.438492 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-08-27 00:06:16.438824 | instance | Thursday 27 August 2026 00:06:16 +0000 (0:00:00.693) 0:01:09.759 ******* 2026-08-27 00:06:19.513968 | instance | changed: [instance] => (item=osd0) 2026-08-27 00:06:19.514323 | instance | changed: [instance] => (item=osd1) 2026-08-27 00:06:19.514615 | instance | changed: [instance] => (item=osd2) 2026-08-27 00:06:19.514989 | instance | 2026-08-27 00:06:19.515319 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-08-27 00:06:19.515611 | instance | Thursday 27 August 2026 00:06:19 +0000 (0:00:03.076) 0:01:12.836 ******* 2026-08-27 00:06:21.427615 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-08-27 00:06:21.427965 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-08-27 00:06:21.428004 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-08-27 00:06:21.428014 | instance | 2026-08-27 00:06:21.428024 | instance | PLAY RECAP ********************************************************************* 2026-08-27 00:06:21.428035 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-08-27 00:06:21.428046 | instance | 2026-08-27 00:06:21.428056 | instance | Thursday 27 August 2026 00:06:21 +0000 (0:00:01.911) 0:01:14.748 ******* 2026-08-27 00:06:21.428075 | instance | =============================================================================== 2026-08-27 00:06:21.428085 | instance | Install depedencies ---------------------------------------------------- 41.66s 2026-08-27 00:06:21.428094 | instance | Configure short hostname ----------------------------------------------- 18.33s 2026-08-27 00:06:21.428102 | instance | Ensure hostname inside hosts file --------------------------------------- 3.19s 2026-08-27 00:06:21.428111 | instance | Create a volume group for each loop device ------------------------------ 3.08s 2026-08-27 00:06:21.430697 | instance | Create a logical volume for each loop device ---------------------------- 1.91s 2026-08-27 00:06:21.430798 | instance | Gathering Facts --------------------------------------------------------- 1.29s 2026-08-27 00:06:21.430819 | instance | Purge "snapd" package --------------------------------------------------- 0.77s 2026-08-27 00:06:21.430832 | instance | Gathering Facts --------------------------------------------------------- 0.77s 2026-08-27 00:06:21.430845 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.72s 2026-08-27 00:06:21.430857 | instance | Start loop devices ------------------------------------------------------ 0.69s 2026-08-27 00:06:21.430866 | instance | Set permissions on loopback devices ------------------------------------- 0.66s 2026-08-27 00:06:21.430875 | instance | Start up service -------------------------------------------------------- 0.55s 2026-08-27 00:06:21.430884 | instance | Create devices for Ceph ------------------------------------------------- 0.54s 2026-08-27 00:06:21.430895 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s 2026-08-27 00:06:21.430905 | instance | Get list of all loopback devices ---------------------------------------- 0.22s 2026-08-27 00:06:21.430914 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-08-27 00:06:21.508734 | instance | INFO [csi > prepare] Executed: Successful 2026-08-27 00:06:21.524099 | instance | INFO [csi > converge] Executing 2026-08-27 00:06:22.871710 | instance | 2026-08-27 00:06:22.872074 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-08-27 00:06:22.872356 | instance | 2026-08-27 00:06:22.872645 | instance | TASK [Gathering Facts] ********************************************************* 2026-08-27 00:06:22.872931 | instance | Thursday 27 August 2026 00:06:22 +0000 (0:00:00.007) 0:00:00.007 ******* 2026-08-27 00:06:23.917428 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-08-27 00:06:23.917782 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-08-27 00:06:23.918096 | instance | interpreter could change the meaning of that path. See 2026-08-27 00:06:23.918383 | instance | https://docs.ansible.com/ansible- 2026-08-27 00:06:23.918669 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-08-27 00:06:23.932039 | instance | ok: [instance] 2026-08-27 00:06:23.932348 | instance | 2026-08-27 00:06:23.932649 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-08-27 00:06:23.932939 | instance | Thursday 27 August 2026 00:06:23 +0000 (0:00:01.059) 0:00:01.067 ******* 2026-08-27 00:06:23.997734 | instance | ok: [instance] => { 2026-08-27 00:06:23.998090 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-08-27 00:06:23.998378 | instance | } 2026-08-27 00:06:23.998649 | instance | 2026-08-27 00:06:23.998985 | instance | PLAY [all] ********************************************************************* 2026-08-27 00:06:23.999259 | instance | 2026-08-27 00:06:23.999541 | instance | TASK [Gathering Facts] ********************************************************* 2026-08-27 00:06:23.999849 | instance | Thursday 27 August 2026 00:06:23 +0000 (0:00:00.065) 0:00:01.133 ******* 2026-08-27 00:06:24.947532 | instance | ok: [instance] 2026-08-27 00:06:24.947866 | instance | 2026-08-27 00:06:24.948189 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-08-27 00:06:24.948484 | instance | Thursday 27 August 2026 00:06:24 +0000 (0:00:00.949) 0:00:02.082 ******* 2026-08-27 00:06:24.995498 | instance | skipping: [instance] 2026-08-27 00:06:24.995837 | instance | 2026-08-27 00:06:24.996168 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-08-27 00:06:24.996463 | instance | Thursday 27 August 2026 00:06:24 +0000 (0:00:00.047) 0:00:02.130 ******* 2026-08-27 00:06:25.175438 | instance | ok: [instance] 2026-08-27 00:06:25.175755 | instance | 2026-08-27 00:06:25.176084 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-08-27 00:06:25.176368 | instance | 2026-08-27 00:06:25.176655 | instance | TASK [Gathering Facts] ********************************************************* 2026-08-27 00:06:25.176978 | instance | Thursday 27 August 2026 00:06:25 +0000 (0:00:00.179) 0:00:02.310 ******* 2026-08-27 00:06:26.103480 | instance | ok: [instance] 2026-08-27 00:06:26.103804 | instance | 2026-08-27 00:06:26.104116 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-08-27 00:06:26.104403 | instance | Thursday 27 August 2026 00:06:26 +0000 (0:00:00.928) 0:00:03.238 ******* 2026-08-27 00:06:26.503346 | instance | ok: [instance] 2026-08-27 00:06:26.503660 | instance | 2026-08-27 00:06:26.503995 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-08-27 00:06:26.504294 | instance | Thursday 27 August 2026 00:06:26 +0000 (0:00:00.399) 0:00:03.638 ******* 2026-08-27 00:06:26.557472 | instance | skipping: [instance] 2026-08-27 00:06:26.557694 | instance | 2026-08-27 00:06:26.557887 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-08-27 00:06:26.558102 | instance | Thursday 27 August 2026 00:06:26 +0000 (0:00:00.052) 0:00:03.691 ******* 2026-08-27 00:06:26.952812 | instance | changed: [instance] 2026-08-27 00:06:26.953158 | instance | 2026-08-27 00:06:26.953463 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-08-27 00:06:26.953758 | instance | Thursday 27 August 2026 00:06:26 +0000 (0:00:00.396) 0:00:04.087 ******* 2026-08-27 00:06:27.045191 | instance | ok: [instance] => { 2026-08-27 00:06:27.045571 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-08-27 00:06:27.045874 | instance | } 2026-08-27 00:06:27.046186 | instance | 2026-08-27 00:06:27.046513 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-08-27 00:06:27.046869 | instance | Thursday 27 August 2026 00:06:27 +0000 (0:00:00.092) 0:00:04.180 ******* 2026-08-27 00:06:58.365162 | instance | FAILED - RETRYING: [instance]: Download item (4 retries left). 2026-08-27 00:06:58.365559 | instance | changed: [instance] 2026-08-27 00:06:58.365844 | instance | 2026-08-27 00:06:58.366172 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-08-27 00:06:58.366472 | instance | Thursday 27 August 2026 00:06:58 +0000 (0:00:31.319) 0:00:35.499 ******* 2026-08-27 00:06:58.425383 | instance | skipping: [instance] 2026-08-27 00:06:58.425698 | instance | 2026-08-27 00:06:58.426021 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-08-27 00:06:58.426314 | instance | Thursday 27 August 2026 00:06:58 +0000 (0:00:00.061) 0:00:35.561 ******* 2026-08-27 00:06:58.477191 | instance | skipping: [instance] 2026-08-27 00:06:58.477582 | instance | 2026-08-27 00:06:58.478022 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-08-27 00:06:58.478426 | instance | Thursday 27 August 2026 00:06:58 +0000 (0:00:00.051) 0:00:35.612 ******* 2026-08-27 00:06:58.713098 | instance | ok: [instance] 2026-08-27 00:06:58.713318 | instance | 2026-08-27 00:06:58.713507 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-08-27 00:06:58.713690 | instance | Thursday 27 August 2026 00:06:58 +0000 (0:00:00.235) 0:00:35.848 ******* 2026-08-27 00:07:00.036367 | instance | ok: [instance] 2026-08-27 00:07:00.036736 | instance | 2026-08-27 00:07:00.037092 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-08-27 00:07:00.037392 | instance | Thursday 27 August 2026 00:07:00 +0000 (0:00:01.322) 0:00:37.170 ******* 2026-08-27 00:07:00.127884 | instance | ok: [instance] => { 2026-08-27 00:07:00.128306 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-08-27 00:07:00.128600 | instance | } 2026-08-27 00:07:00.128875 | instance | 2026-08-27 00:07:00.129190 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-08-27 00:07:00.129571 | instance | Thursday 27 August 2026 00:07:00 +0000 (0:00:00.090) 0:00:37.261 ******* 2026-08-27 00:07:35.707872 | instance | FAILED - RETRYING: [instance]: Download item (4 retries left). 2026-08-27 00:07:35.708282 | instance | changed: [instance] 2026-08-27 00:07:35.708568 | instance | 2026-08-27 00:07:35.708891 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-08-27 00:07:35.709191 | instance | Thursday 27 August 2026 00:07:35 +0000 (0:00:35.580) 0:01:12.842 ******* 2026-08-27 00:07:38.700045 | instance | changed: [instance] 2026-08-27 00:07:38.700165 | instance | 2026-08-27 00:07:38.700673 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-08-27 00:07:38.700917 | instance | Thursday 27 August 2026 00:07:38 +0000 (0:00:02.992) 0:01:15.834 ******* 2026-08-27 00:07:38.744076 | instance | skipping: [instance] 2026-08-27 00:07:38.744837 | instance | 2026-08-27 00:07:38.744890 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-08-27 00:07:38.744902 | instance | Thursday 27 August 2026 00:07:38 +0000 (0:00:00.043) 0:01:15.878 ******* 2026-08-27 00:07:38.787160 | instance | skipping: [instance] 2026-08-27 00:07:38.787655 | instance | 2026-08-27 00:07:38.787720 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-08-27 00:07:38.787729 | instance | Thursday 27 August 2026 00:07:38 +0000 (0:00:00.043) 0:01:15.922 ******* 2026-08-27 00:07:38.831766 | instance | skipping: [instance] 2026-08-27 00:07:38.832012 | instance | 2026-08-27 00:07:38.832354 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-08-27 00:07:38.832657 | instance | Thursday 27 August 2026 00:07:38 +0000 (0:00:00.044) 0:01:15.966 ******* 2026-08-27 00:09:04.268684 | instance | FAILED - RETRYING: [instance]: Install AppArmor packages (5 retries left). 2026-08-27 00:09:04.268767 | instance | changed: [instance] 2026-08-27 00:09:04.268779 | instance | 2026-08-27 00:09:04.268789 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-08-27 00:09:04.268799 | instance | Thursday 27 August 2026 00:09:04 +0000 (0:01:25.435) 0:02:41.402 ******* 2026-08-27 00:09:04.925012 | instance | changed: [instance] 2026-08-27 00:09:04.925947 | instance | 2026-08-27 00:09:04.926032 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-08-27 00:09:04.926046 | instance | Thursday 27 August 2026 00:09:04 +0000 (0:00:00.656) 0:02:42.059 ******* 2026-08-27 00:09:05.928726 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-08-27 00:09:05.928792 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-08-27 00:09:05.928804 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-08-27 00:09:05.928814 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-08-27 00:09:05.928825 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-08-27 00:09:05.928834 | instance | 2026-08-27 00:09:05.928843 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-08-27 00:09:05.928852 | instance | Thursday 27 August 2026 00:09:05 +0000 (0:00:01.002) 0:02:43.061 ******* 2026-08-27 00:09:06.561925 | instance | changed: [instance] 2026-08-27 00:09:06.561986 | instance | 2026-08-27 00:09:06.562822 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-08-27 00:09:06.562888 | instance | Thursday 27 August 2026 00:09:06 +0000 (0:00:00.609) 0:02:43.671 ******* 2026-08-27 00:09:06.562913 | instance | 2026-08-27 00:09:06.562920 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-08-27 00:09:06.562926 | instance | Thursday 27 August 2026 00:09:06 +0000 (0:00:00.025) 0:02:43.696 ******* 2026-08-27 00:09:07.583788 | instance | ok: [instance] 2026-08-27 00:09:07.584516 | instance | 2026-08-27 00:09:07.584576 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-08-27 00:09:07.584589 | instance | Thursday 27 August 2026 00:09:07 +0000 (0:00:01.021) 0:02:44.718 ******* 2026-08-27 00:09:08.123392 | instance | changed: [instance] 2026-08-27 00:09:08.124125 | instance | 2026-08-27 00:09:08.124181 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-08-27 00:09:08.124193 | instance | Thursday 27 August 2026 00:09:08 +0000 (0:00:00.539) 0:02:45.258 ******* 2026-08-27 00:09:08.753168 | instance | changed: [instance] 2026-08-27 00:09:08.753260 | instance | 2026-08-27 00:09:08.753604 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-08-27 00:09:08.753649 | instance | Thursday 27 August 2026 00:09:08 +0000 (0:00:00.630) 0:02:45.888 ******* 2026-08-27 00:09:09.005289 | instance | ok: [instance] 2026-08-27 00:09:09.006034 | instance | 2026-08-27 00:09:09.006120 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-08-27 00:09:09.006133 | instance | Thursday 27 August 2026 00:09:09 +0000 (0:00:00.251) 0:02:46.139 ******* 2026-08-27 00:09:09.085057 | instance | ok: [instance] => { 2026-08-27 00:09:09.086279 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-08-27 00:09:09.086320 | instance | } 2026-08-27 00:09:09.086332 | instance | 2026-08-27 00:09:09.086342 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-08-27 00:09:09.086352 | instance | Thursday 27 August 2026 00:09:09 +0000 (0:00:00.080) 0:02:46.220 ******* 2026-08-27 00:09:25.871459 | instance | FAILED - RETRYING: [instance]: Download item (4 retries left). 2026-08-27 00:09:25.871618 | instance | changed: [instance] 2026-08-27 00:09:25.871869 | instance | 2026-08-27 00:09:25.872095 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-08-27 00:09:25.872345 | instance | Thursday 27 August 2026 00:09:25 +0000 (0:00:16.785) 0:03:03.006 ******* 2026-08-27 00:09:30.235669 | instance | changed: [instance] 2026-08-27 00:09:30.235845 | instance | 2026-08-27 00:09:30.236097 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-08-27 00:09:30.236325 | instance | Thursday 27 August 2026 00:09:30 +0000 (0:00:04.364) 0:03:07.370 ******* 2026-08-27 00:09:31.452660 | instance | ok: [instance] 2026-08-27 00:09:31.452723 | instance | 2026-08-27 00:09:31.452735 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-08-27 00:09:31.452746 | instance | Thursday 27 August 2026 00:09:31 +0000 (0:00:01.216) 0:03:08.586 ******* 2026-08-27 00:09:31.813047 | instance | changed: [instance] 2026-08-27 00:09:31.813830 | instance | 2026-08-27 00:09:31.813902 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-08-27 00:09:31.813915 | instance | Thursday 27 August 2026 00:09:31 +0000 (0:00:00.360) 0:03:08.947 ******* 2026-08-27 00:09:32.317050 | instance | changed: [instance] 2026-08-27 00:09:32.317212 | instance | 2026-08-27 00:09:32.317786 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-08-27 00:09:32.317855 | instance | Thursday 27 August 2026 00:09:32 +0000 (0:00:00.503) 0:03:09.451 ******* 2026-08-27 00:09:32.948178 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-08-27 00:09:32.949383 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-08-27 00:09:32.949458 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-08-27 00:09:32.949470 | instance | 2026-08-27 00:09:32.949481 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-08-27 00:09:32.949491 | instance | Thursday 27 August 2026 00:09:32 +0000 (0:00:00.631) 0:03:10.082 ******* 2026-08-27 00:09:33.445565 | instance | changed: [instance] 2026-08-27 00:09:33.445620 | instance | 2026-08-27 00:09:33.445662 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-08-27 00:09:33.445813 | instance | Thursday 27 August 2026 00:09:33 +0000 (0:00:00.497) 0:03:10.580 ******* 2026-08-27 00:09:33.984507 | instance | changed: [instance] 2026-08-27 00:09:33.984565 | instance | 2026-08-27 00:09:33.985351 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-08-27 00:09:33.985399 | instance | Thursday 27 August 2026 00:09:33 +0000 (0:00:00.510) 0:03:11.091 ******* 2026-08-27 00:09:33.985406 | instance | 2026-08-27 00:09:33.985412 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-08-27 00:09:33.985419 | instance | Thursday 27 August 2026 00:09:33 +0000 (0:00:00.028) 0:03:11.120 ******* 2026-08-27 00:09:34.826593 | instance | ok: [instance] 2026-08-27 00:09:34.826877 | instance | 2026-08-27 00:09:34.827398 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-08-27 00:09:34.827471 | instance | Thursday 27 August 2026 00:09:34 +0000 (0:00:00.841) 0:03:11.961 ******* 2026-08-27 00:09:35.603157 | instance | changed: [instance] 2026-08-27 00:09:35.603830 | instance | 2026-08-27 00:09:35.603861 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-08-27 00:09:35.603873 | instance | Thursday 27 August 2026 00:09:35 +0000 (0:00:00.777) 0:03:12.738 ******* 2026-08-27 00:09:36.295795 | instance | changed: [instance] 2026-08-27 00:09:36.295835 | instance | 2026-08-27 00:09:36.295843 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-08-27 00:09:36.295849 | instance | Thursday 27 August 2026 00:09:36 +0000 (0:00:00.692) 0:03:13.430 ******* 2026-08-27 00:09:36.365321 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-08-27 00:09:36.365385 | instance | 2026-08-27 00:09:36.365672 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-08-27 00:09:36.365695 | instance | Thursday 27 August 2026 00:09:36 +0000 (0:00:00.069) 0:03:13.500 ******* 2026-08-27 00:09:42.181469 | instance | changed: [instance] 2026-08-27 00:09:42.181724 | instance | 2026-08-27 00:09:42.181739 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-08-27 00:09:42.181750 | instance | Thursday 27 August 2026 00:09:42 +0000 (0:00:05.814) 0:03:19.315 ******* 2026-08-27 00:09:42.878758 | instance | ok: [instance] => (item=chronyd) 2026-08-27 00:09:42.879704 | instance | ok: [instance] => (item=sshd) 2026-08-27 00:09:42.879758 | instance | 2026-08-27 00:09:42.879770 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-08-27 00:09:42.879780 | instance | Thursday 27 August 2026 00:09:42 +0000 (0:00:00.698) 0:03:20.013 ******* 2026-08-27 00:09:45.062612 | instance | changed: [instance] 2026-08-27 00:09:45.063050 | instance | 2026-08-27 00:09:45.063153 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-08-27 00:09:45.063374 | instance | Thursday 27 August 2026 00:09:45 +0000 (0:00:02.183) 0:03:22.196 ******* 2026-08-27 00:09:45.292170 | instance | ok: [instance] 2026-08-27 00:09:45.292832 | instance | 2026-08-27 00:09:45.292876 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-08-27 00:09:45.292888 | instance | Thursday 27 August 2026 00:09:45 +0000 (0:00:00.230) 0:03:22.427 ******* 2026-08-27 00:09:45.785209 | instance | changed: [instance] 2026-08-27 00:09:45.785952 | instance | 2026-08-27 00:09:45.786174 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-08-27 00:09:45.786190 | instance | Thursday 27 August 2026 00:09:45 +0000 (0:00:00.492) 0:03:22.920 ******* 2026-08-27 00:09:46.137375 | instance | changed: [instance] 2026-08-27 00:09:46.137471 | instance | 2026-08-27 00:09:46.137486 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-08-27 00:09:46.137664 | instance | Thursday 27 August 2026 00:09:46 +0000 (0:00:00.352) 0:03:23.273 ******* 2026-08-27 00:09:46.379144 | instance | ok: [instance] 2026-08-27 00:09:46.379231 | instance | 2026-08-27 00:09:46.379243 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-08-27 00:09:46.379357 | instance | Thursday 27 August 2026 00:09:46 +0000 (0:00:00.241) 0:03:23.514 ******* 2026-08-27 00:09:46.639524 | instance | ok: [instance] => (item=instance) 2026-08-27 00:09:46.639720 | instance | 2026-08-27 00:09:46.639955 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-08-27 00:09:46.640205 | instance | Thursday 27 August 2026 00:09:46 +0000 (0:00:00.260) 0:03:23.774 ******* 2026-08-27 00:09:46.709190 | instance | ok: [instance] 2026-08-27 00:09:46.709390 | instance | 2026-08-27 00:09:46.709625 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-08-27 00:09:46.709855 | instance | Thursday 27 August 2026 00:09:46 +0000 (0:00:00.070) 0:03:23.844 ******* 2026-08-27 00:09:46.789466 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-08-27 00:09:46.789689 | instance | 2026-08-27 00:09:46.789942 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-08-27 00:09:46.790228 | instance | Thursday 27 August 2026 00:09:46 +0000 (0:00:00.080) 0:03:23.925 ******* 2026-08-27 00:09:47.105233 | instance | changed: [instance] 2026-08-27 00:09:47.105425 | instance | 2026-08-27 00:09:47.105651 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-08-27 00:09:47.105871 | instance | Thursday 27 August 2026 00:09:47 +0000 (0:00:00.314) 0:03:24.240 ******* 2026-08-27 00:09:47.826850 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-08-27 00:09:47.827124 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-08-27 00:09:47.827368 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-08-27 00:09:47.827579 | instance | 2026-08-27 00:09:47.827813 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-08-27 00:09:47.828064 | instance | Thursday 27 August 2026 00:09:47 +0000 (0:00:00.721) 0:03:24.961 ******* 2026-08-27 00:11:25.322383 | instance | ok: [instance] 2026-08-27 00:11:25.322483 | instance | 2026-08-27 00:11:25.322497 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-08-27 00:11:25.322509 | instance | Thursday 27 August 2026 00:11:25 +0000 (0:01:37.491) 0:05:02.452 ******* 2026-08-27 00:11:25.561786 | instance | changed: [instance] 2026-08-27 00:11:25.561843 | instance | 2026-08-27 00:11:25.561855 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-08-27 00:11:25.561865 | instance | Thursday 27 August 2026 00:11:25 +0000 (0:00:00.243) 0:05:02.696 ******* 2026-08-27 00:11:25.615941 | instance | ok: [instance] 2026-08-27 00:11:25.615998 | instance | 2026-08-27 00:11:25.616028 | instance | TASK [Install Ceph host] ******************************************************* 2026-08-27 00:11:25.616182 | instance | Thursday 27 August 2026 00:11:25 +0000 (0:00:00.054) 0:05:02.750 ******* 2026-08-27 00:11:25.711432 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-08-27 00:11:25.711497 | instance | 2026-08-27 00:11:25.711504 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-08-27 00:11:25.711510 | instance | Thursday 27 August 2026 00:11:25 +0000 (0:00:00.095) 0:05:02.846 ******* 2026-08-27 00:11:27.391478 | instance | ok: [instance] 2026-08-27 00:11:27.391649 | instance | 2026-08-27 00:11:27.392177 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-08-27 00:11:27.392242 | instance | Thursday 27 August 2026 00:11:27 +0000 (0:00:01.679) 0:05:04.526 ******* 2026-08-27 00:11:27.470866 | instance | ok: [instance] => (item=instance) 2026-08-27 00:11:27.470927 | instance | 2026-08-27 00:11:27.470938 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-08-27 00:11:27.470956 | instance | Thursday 27 August 2026 00:11:27 +0000 (0:00:00.078) 0:05:04.604 ******* 2026-08-27 00:11:27.850535 | instance | ok: [instance] 2026-08-27 00:11:27.850600 | instance | 2026-08-27 00:11:27.850630 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-08-27 00:11:27.850640 | instance | Thursday 27 August 2026 00:11:27 +0000 (0:00:00.379) 0:05:04.984 ******* 2026-08-27 00:11:30.358546 | instance | ok: [instance] 2026-08-27 00:11:30.358642 | instance | 2026-08-27 00:11:30.358842 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-08-27 00:11:30.359015 | instance | Thursday 27 August 2026 00:11:30 +0000 (0:00:02.509) 0:05:07.494 ******* 2026-08-27 00:12:34.706259 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-08-27 00:12:34.707067 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n File \"\", line 107, in \n File \"\", line 99, in _ansiballz_main\n File \"\", 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_jmnip6d0/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in \n File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_jmnip6d0/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_jmnip6d0/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_jmnip6d0/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-08-27T00:12:34.509+0000 7f65b282f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCEgI9qP3vLGyAAVxA/iqrASod0pxef1lrODETdFFM6O7IAKsODMQ2w8gI=: Malformed input [buffer:3]\n2026-08-27T00:12:34.509+0000 7f65b282f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-27T00:12:34.513+0000 7f65b282f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCEgI9qP3vLGyAAVxA/iqrASod0pxef1lrODETdFFM6O7IAKsODMQ2w8gI=: Malformed input [buffer:3]\n2026-08-27T00:12:34.513+0000 7f65b282f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-27T00:12:34.513+0000 7f65b282f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCEgI9qP3vLGyAAVxA/iqrASod0pxef1lrODETdFFM6O7IAKsODMQ2w8gI=: Malformed input [buffer:3]\n2026-08-27T00:12:34.513+0000 7f65b282f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-27T00:12:34.513+0000 7f65b282f640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-08-27 00:12:34.710673 | instance | 2026-08-27 00:12:34.714892 | instance | PLAY RECAP ********************************************************************* 2026-08-27 00:12:34.714964 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-08-27 00:12:34.714976 | instance | 2026-08-27 00:12:34.714986 | instance | Thursday 27 August 2026 00:12:34 +0000 (0:01:04.352) 0:06:11.846 ******* 2026-08-27 00:12:34.714996 | instance | =============================================================================== 2026-08-27 00:12:34.715005 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 97.49s 2026-08-27 00:12:34.715013 | instance | vexxhost.containers.containerd : Install AppArmor packages ------------- 85.44s 2026-08-27 00:12:34.715038 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 64.35s 2026-08-27 00:12:34.715047 | instance | vexxhost.containers.download_artifact : Download item ------------------ 35.58s 2026-08-27 00:12:34.715055 | instance | vexxhost.containers.download_artifact : Download item ------------------ 31.32s 2026-08-27 00:12:34.715064 | instance | vexxhost.containers.download_artifact : Download item ------------------ 16.79s 2026-08-27 00:12:34.715072 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.81s 2026-08-27 00:12:34.715081 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.36s 2026-08-27 00:12:34.715089 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.99s 2026-08-27 00:12:34.715098 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.51s 2026-08-27 00:12:34.715106 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 2.18s 2026-08-27 00:12:34.715114 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.86s 2026-08-27 00:12:34.715132 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.68s 2026-08-27 00:12:34.715141 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.32s 2026-08-27 00:12:34.715407 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.22s 2026-08-27 00:12:34.715772 | instance | Gathering Facts --------------------------------------------------------- 1.06s 2026-08-27 00:12:34.716105 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.00s 2026-08-27 00:12:34.716399 | instance | Gathering Facts --------------------------------------------------------- 0.95s 2026-08-27 00:12:34.716689 | instance | Gathering Facts --------------------------------------------------------- 0.93s 2026-08-27 00:12:34.717026 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.78s 2026-08-27 00:12:34.815559 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-08-27 00:12:34.815887 | instance | ERROR [csi > converge] Executed: Failed 2026-08-27 00:12:34.816086 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-08-27 00:12:35.575347 | instance | ERROR 2026-08-27 00:12:35.575587 | instance | { 2026-08-27 00:12:35.575615 | instance | "delta": "0:07:41.766415", 2026-08-27 00:12:35.575638 | instance | "end": "2026-08-27 00:12:34.882310", 2026-08-27 00:12:35.575658 | instance | "msg": "non-zero return code", 2026-08-27 00:12:35.575676 | instance | "rc": 2, 2026-08-27 00:12:35.575693 | instance | "start": "2026-08-27 00:04:53.115895" 2026-08-27 00:12:35.575711 | instance | } failure 2026-08-27 00:12:35.581702 | 2026-08-27 00:12:35.581754 | PLAY RECAP 2026-08-27 00:12:35.581800 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-08-27 00:12:35.581822 | 2026-08-27 00:12:35.715149 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-27 00:12:35.717402 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-27 00:12:36.336285 | 2026-08-27 00:12:36.336418 | PLAY [all] 2026-08-27 00:12:36.350623 | 2026-08-27 00:12:36.350700 | TASK [gather-host-logs : creating directory for system status] 2026-08-27 00:12:36.719856 | instance | changed 2026-08-27 00:12:36.725092 | 2026-08-27 00:12:36.725171 | TASK [gather-host-logs : Get logs for each host] 2026-08-27 00:12:37.078203 | instance | + systemd-cgls --full --all --no-pager 2026-08-27 00:12:37.104001 | instance | + ip addr 2026-08-27 00:12:37.106266 | instance | + ip route 2026-08-27 00:12:37.109202 | instance | + lsblk 2026-08-27 00:12:37.114170 | instance | + mount 2026-08-27 00:12:37.116772 | instance | + docker images 2026-08-27 00:12:37.141107 | instance | + brctl show 2026-08-27 00:12:37.141710 | instance | /bin/bash: line 8: brctl: command not found 2026-08-27 00:12:37.142106 | instance | + ps aux --sort=-%mem 2026-08-27 00:12:37.161987 | instance | + dpkg -l 2026-08-27 00:12:37.172728 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-08-27 00:12:37.173280 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-08-27 00:12:37.194161 | instance | + '[' '!' -z '' ']' 2026-08-27 00:12:37.263380 | instance | ok: Runtime: 0:00:00.121470 2026-08-27 00:12:37.270872 | 2026-08-27 00:12:37.270939 | TASK [gather-host-logs : Downloads logs to executor] 2026-08-27 00:12:37.913999 | instance | changed: 2026-08-27 00:12:37.914208 | instance | created directory /var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/work/logs/instance 2026-08-27 00:12:37.914246 | instance | cd+++++++++ system/ 2026-08-27 00:12:37.914277 | instance | >f+++++++++ system/brctl-show.txt 2026-08-27 00:12:37.914307 | instance | >f+++++++++ system/docker-images.txt 2026-08-27 00:12:37.914338 | instance | >f+++++++++ system/ip-addr.txt 2026-08-27 00:12:37.914405 | instance | >f+++++++++ system/ip-route.txt 2026-08-27 00:12:37.914438 | instance | >f+++++++++ system/lsblk.txt 2026-08-27 00:12:37.914466 | instance | >f+++++++++ system/mount.txt 2026-08-27 00:12:37.914492 | instance | >f+++++++++ system/packages.txt 2026-08-27 00:12:37.914518 | instance | >f+++++++++ system/ps.txt 2026-08-27 00:12:37.914547 | instance | >f+++++++++ system/systemd-cgls.txt 2026-08-27 00:12:37.924850 | 2026-08-27 00:12:37.924925 | LOOP [helm-release-status : creating directory for helm release status] 2026-08-27 00:12:38.141471 | instance | changed: "values" 2026-08-27 00:12:38.333024 | instance | changed: "releases" 2026-08-27 00:12:38.344769 | 2026-08-27 00:12:38.344915 | TASK [helm-release-status : Gather get release status for helm charts] 2026-08-27 00:12:38.577011 | instance | /bin/bash: line 3: kubectl: command not found 2026-08-27 00:12:38.886047 | instance | ok: Runtime: 0:00:00.007090 2026-08-27 00:12:38.893252 | 2026-08-27 00:12:38.893348 | TASK [helm-release-status : Downloads logs to executor] 2026-08-27 00:12:39.404756 | instance | changed: 2026-08-27 00:12:39.404939 | instance | cd+++++++++ helm/ 2026-08-27 00:12:39.404988 | instance | cd+++++++++ helm/releases/ 2026-08-27 00:12:39.405012 | instance | cd+++++++++ helm/values/ 2026-08-27 00:12:39.414121 | 2026-08-27 00:12:39.414185 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-08-27 00:12:39.631076 | instance | changed 2026-08-27 00:12:39.638122 | 2026-08-27 00:12:39.638200 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-08-27 00:12:39.913681 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-27 00:12:39.913846 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-27 00:12:39.920384 | instance | environment: line 1: kubectl: command not found 2026-08-27 00:12:39.921661 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-27 00:12:39.922073 | instance | environment: line 1: kubectl: command not found 2026-08-27 00:12:39.923656 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-27 00:12:39.925327 | instance | environment: line 1: kubectl: command not found 2026-08-27 00:12:39.926826 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-27 00:12:39.927430 | instance | environment: line 1: kubectl: command not found 2026-08-27 00:12:39.929026 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-27 00:12:39.931103 | instance | environment: line 1: kubectl: command not found 2026-08-27 00:12:39.932833 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-27 00:12:40.177171 | instance | ok: Runtime: 0:00:00.027749 2026-08-27 00:12:40.184098 | 2026-08-27 00:12:40.184188 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-08-27 00:12:40.400018 | instance | changed 2026-08-27 00:12:40.406416 | 2026-08-27 00:12:40.406484 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-08-27 00:12:40.633731 | instance | environment: line 5: kubectl: command not found 2026-08-27 00:12:40.634695 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-27 00:12:40.634941 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-27 00:12:40.635485 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-27 00:12:40.944366 | instance | ok: Runtime: 0:00:00.009969 2026-08-27 00:12:40.951310 | 2026-08-27 00:12:40.951372 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-08-27 00:12:41.462906 | instance | changed: 2026-08-27 00:12:41.463215 | instance | cd+++++++++ objects/ 2026-08-27 00:12:41.463335 | instance | cd+++++++++ objects/cluster/ 2026-08-27 00:12:41.463403 | instance | cd+++++++++ objects/namespaced/ 2026-08-27 00:12:41.472354 | 2026-08-27 00:12:41.472417 | TASK [gather-pod-logs : creating directory for pod logs] 2026-08-27 00:12:41.720095 | instance | changed 2026-08-27 00:12:41.726946 | 2026-08-27 00:12:41.727032 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-08-27 00:12:41.946771 | instance | changed 2026-08-27 00:12:41.952488 | 2026-08-27 00:12:41.952576 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-08-27 00:12:42.174820 | instance | environment: line 3: kubectl: command not found 2026-08-27 00:12:42.488736 | instance | ok: Runtime: 0:00:00.010141 2026-08-27 00:12:42.495216 | 2026-08-27 00:12:42.495282 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-08-27 00:12:42.986428 | instance | changed: 2026-08-27 00:12:42.986645 | instance | cd+++++++++ pod-logs/ 2026-08-27 00:12:42.986684 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-08-27 00:12:42.997330 | 2026-08-27 00:12:42.997395 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-08-27 00:12:43.210136 | instance | changed 2026-08-27 00:12:43.215130 | 2026-08-27 00:12:43.215199 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-08-27 00:12:43.440891 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-27 00:12:43.753318 | instance | ok: Runtime: 0:00:00.050808 2026-08-27 00:12:43.759493 | 2026-08-27 00:12:43.759578 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-08-27 00:12:43.984293 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-27 00:12:44.030894 | instance | ceph-mgr endpoints: 2026-08-27 00:12:44.297427 | instance | ok: Runtime: 0:00:00.053531 2026-08-27 00:12:44.304182 | 2026-08-27 00:12:44.304258 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-08-27 00:12:44.526112 | instance | /bin/bash: line 4: kubectl: command not found 2026-08-27 00:12:44.840398 | instance | ok: Runtime: 0:00:00.050026 2026-08-27 00:12:44.846968 | 2026-08-27 00:12:44.847038 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-08-27 00:12:45.344259 | instance | changed: cd+++++++++ prometheus/ 2026-08-27 00:12:45.354427 | 2026-08-27 00:12:45.354499 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-08-27 00:12:45.562560 | instance | changed 2026-08-27 00:12:45.568530 | 2026-08-27 00:12:45.568595 | TASK [gather-selenium-data : Get selenium data] 2026-08-27 00:12:45.831085 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-08-27 00:12:45.832549 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-08-27 00:12:46.101907 | instance | ERROR 2026-08-27 00:12:46.102150 | instance | { 2026-08-27 00:12:46.102192 | instance | "delta": "0:00:00.006591", 2026-08-27 00:12:46.102221 | instance | "end": "2026-08-27 00:12:45.832887", 2026-08-27 00:12:46.102252 | instance | "msg": "non-zero return code", 2026-08-27 00:12:46.102279 | instance | "rc": 1, 2026-08-27 00:12:46.102308 | instance | "start": "2026-08-27 00:12:45.826296" 2026-08-27 00:12:46.102385 | instance | } 2026-08-27 00:12:46.102424 | instance | ERROR: Ignoring Errors 2026-08-27 00:12:46.108601 | 2026-08-27 00:12:46.108666 | TASK [gather-selenium-data : Downloads logs to executor] 2026-08-27 00:12:46.614599 | instance | changed: cd+++++++++ selenium/ 2026-08-27 00:12:46.638353 | 2026-08-27 00:12:46.638593 | PLAY RECAP 2026-08-27 00:12:46.638699 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-08-27 00:12:46.638749 | 2026-08-27 00:12:46.808123 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-27 00:12:46.812854 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-27 00:12:47.403050 | 2026-08-27 00:12:47.403205 | PLAY [all] 2026-08-27 00:12:47.415050 | 2026-08-27 00:12:47.415133 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-27 00:12:47.460691 | instance | skipping: Conditional result was False 2026-08-27 00:12:47.470827 | 2026-08-27 00:12:47.470918 | TASK [fetch-output : Set log path for single node] 2026-08-27 00:12:47.515537 | instance | ok 2026-08-27 00:12:47.521839 | 2026-08-27 00:12:47.521924 | LOOP [fetch-output : Ensure local output dirs] 2026-08-27 00:12:47.914906 | instance -> localhost | ok: "/var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/work/logs" 2026-08-27 00:12:48.128651 | instance -> localhost | changed: "/var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/work/artifacts" 2026-08-27 00:12:48.342662 | instance -> localhost | changed: "/var/lib/zuul/builds/29e8954c94094ea3bcdefb161ca357da/work/docs" 2026-08-27 00:12:48.362661 | 2026-08-27 00:12:48.362799 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-27 00:12:49.036128 | instance | changed: .d..t...... ./ 2026-08-27 00:12:49.036332 | instance | changed: All items complete 2026-08-27 00:12:49.036361 | 2026-08-27 00:12:49.495853 | instance | changed: .d..t...... ./ 2026-08-27 00:12:49.962450 | instance | changed: .d..t...... ./ 2026-08-27 00:12:49.979260 | 2026-08-27 00:12:49.979412 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-27 00:12:50.393159 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007628 2026-08-27 00:12:50.606347 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008904 2026-08-27 00:12:50.621890 | 2026-08-27 00:12:50.622021 | PLAY [all] 2026-08-27 00:12:50.627817 | 2026-08-27 00:12:50.627886 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-27 00:12:51.057007 | instance | changed 2026-08-27 00:12:51.062636 | 2026-08-27 00:12:51.062687 | PLAY RECAP 2026-08-27 00:12:51.062735 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-27 00:12:51.062758 | 2026-08-27 00:12:51.200035 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-27 00:12:51.204396 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-27 00:12:51.790718 | 2026-08-27 00:12:51.790866 | PLAY [localhost] 2026-08-27 00:12:51.801087 | 2026-08-27 00:12:51.801158 | TASK [Generate Zuul manifest] 2026-08-27 00:12:51.822513 | localhost | ok 2026-08-27 00:12:51.839889 | 2026-08-27 00:12:51.839989 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-27 00:12:52.215733 | localhost | changed 2026-08-27 00:12:52.228850 | 2026-08-27 00:12:52.228929 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-27 00:12:52.261428 | localhost | ok 2026-08-27 00:12:52.271045 | 2026-08-27 00:12:52.271126 | TASK [Upload logs] 2026-08-27 00:12:52.295438 | localhost | ok 2026-08-27 00:12:52.352762 | 2026-08-27 00:12:52.352855 | TASK [Set zuul-log-path fact] 2026-08-27 00:12:52.376103 | localhost | ok 2026-08-27 00:12:52.390138 | 2026-08-27 00:12:52.390209 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-27 00:12:52.421882 | localhost | ok 2026-08-27 00:12:52.431234 | 2026-08-27 00:12:52.431352 | TASK [upload-logs : Create log directories] 2026-08-27 00:12:52.837206 | localhost | changed 2026-08-27 00:12:52.844046 | 2026-08-27 00:12:52.844145 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-27 00:12:53.242138 | localhost -> localhost | ok: Runtime: 0:00:00.006910 2026-08-27 00:12:53.248262 | 2026-08-27 00:12:53.248335 | TASK [upload-logs : Upload logs to log server] 2026-08-27 00:12:53.719566 | localhost | Output suppressed because no_log was given 2026-08-27 00:12:53.761306 | 2026-08-27 00:12:53.761473 | LOOP [upload-logs : Compress console log and json output] 2026-08-27 00:12:53.807887 | localhost | skipping: Conditional result was False 2026-08-27 00:12:53.814734 | localhost | skipping: Conditional result was False 2026-08-27 00:12:53.827857 | 2026-08-27 00:12:53.827989 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-27 00:12:53.872255 | localhost | skipping: Conditional result was False 2026-08-27 00:12:53.872630 | 2026-08-27 00:12:53.876388 | localhost | skipping: Conditional result was False 2026-08-27 00:12:53.893407 | 2026-08-27 00:12:53.893590 | LOOP [upload-logs : Upload console log and json output]