2026-09-12 03:55:23.408202 | Job console starting 2026-09-12 03:55:23.503564 | Updating git repos 2026-09-12 03:55:28.110969 | Cloning repos into workspace 2026-09-12 03:55:45.604114 | Restoring repo states 2026-09-12 03:55:50.405546 | Merging changes 2026-09-12 03:55:52.089850 | Checking out repos 2026-09-12 03:55:54.157351 | Preparing playbooks 2026-09-12 03:55:57.319700 | Running Ansible setup 2026-09-12 03:56:01.893956 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:56:02.505495 | 2026-09-12 03:56:02.505648 | PLAY [localhost] 2026-09-12 03:56:02.515682 | 2026-09-12 03:56:02.515783 | TASK [Gathering Facts] 2026-09-12 03:56:03.433401 | localhost | ok 2026-09-12 03:56:03.452297 | 2026-09-12 03:56:03.452590 | TASK [Setup log path fact] 2026-09-12 03:56:03.490349 | localhost | ok 2026-09-12 03:56:03.504736 | 2026-09-12 03:56:03.504884 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 03:56:03.534440 | localhost | ok 2026-09-12 03:56:03.543293 | 2026-09-12 03:56:03.543390 | TASK [emit-job-header : Print job information] 2026-09-12 03:56:03.596390 | # Job Information 2026-09-12 03:56:03.596676 | Ansible Version: 2.16.18 2026-09-12 03:56:03.596747 | Job: atmosphere-molecule-csi-rbd 2026-09-12 03:56:03.596825 | Pipeline: check 2026-09-12 03:56:03.596882 | Executor: 2d72f0692154 2026-09-12 03:56:03.596938 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4367 2026-09-12 03:56:03.597001 | Event ID: e5d23960-ae5c-11f1-86f6-aad21acdc43e 2026-09-12 03:56:03.604266 | 2026-09-12 03:56:03.604373 | LOOP [emit-job-header : Print node information] 2026-09-12 03:56:03.689515 | localhost | ok: 2026-09-12 03:56:03.689802 | localhost | # Node Information 2026-09-12 03:56:03.689884 | localhost | Inventory Hostname: instance 2026-09-12 03:56:03.689943 | localhost | Hostname: np0000192691 2026-09-12 03:56:03.689997 | localhost | Username: zuul 2026-09-12 03:56:03.690059 | localhost | Distro: Ubuntu 22.04 2026-09-12 03:56:03.690115 | localhost | Provider: yul1 2026-09-12 03:56:03.690168 | localhost | Region: ca-ymq-1 2026-09-12 03:56:03.690218 | localhost | Label: ubuntu-jammy 2026-09-12 03:56:03.690269 | localhost | Product Name: OpenStack Nova 2026-09-12 03:56:03.690309 | localhost | Interface IP: 162.253.55.119 2026-09-12 03:56:03.706923 | 2026-09-12 03:56:03.707119 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-12 03:56:04.112325 | localhost -> localhost | changed 2026-09-12 03:56:04.121380 | 2026-09-12 03:56:04.121537 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-12 03:56:05.159589 | localhost -> localhost | changed 2026-09-12 03:56:05.174224 | 2026-09-12 03:56:05.174342 | PLAY [all] 2026-09-12 03:56:05.194879 | 2026-09-12 03:56:05.195053 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-12 03:56:05.472145 | instance -> localhost | ok 2026-09-12 03:56:05.482867 | 2026-09-12 03:56:05.483042 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-12 03:56:05.516204 | instance | ok 2026-09-12 03:56:05.535778 | instance | included: /var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-12 03:56:05.542107 | 2026-09-12 03:56:05.542180 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-12 03:56:06.815429 | instance -> localhost | Generating public/private rsa key pair. 2026-09-12 03:56:06.815712 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/work/c7275687afb64e2abbd918b84e9a7c7e_id_rsa 2026-09-12 03:56:06.815792 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/work/c7275687afb64e2abbd918b84e9a7c7e_id_rsa.pub 2026-09-12 03:56:06.815840 | instance -> localhost | The key fingerprint is: 2026-09-12 03:56:06.815883 | instance -> localhost | SHA256:7eF6tOsiw4f+NO3cMuRBNMycJlYItE+wQHDnoO1qoaI zuul-build-sshkey 2026-09-12 03:56:06.815941 | instance -> localhost | The key's randomart image is: 2026-09-12 03:56:06.815983 | instance -> localhost | +---[RSA 3072]----+ 2026-09-12 03:56:06.816033 | instance -> localhost | | .o=.=. *.. | 2026-09-12 03:56:06.816076 | instance -> localhost | | + = ++ O | 2026-09-12 03:56:06.816116 | instance -> localhost | | . . +..+ . | 2026-09-12 03:56:06.816154 | instance -> localhost | | . o .. | 2026-09-12 03:56:06.816194 | instance -> localhost | | . . S.o | 2026-09-12 03:56:06.816232 | instance -> localhost | | . o ++. | 2026-09-12 03:56:06.816269 | instance -> localhost | |o o . .o++o | 2026-09-12 03:56:06.816307 | instance -> localhost | |o. =.o=*. | 2026-09-12 03:56:06.816351 | instance -> localhost | |E ..=o+=+. | 2026-09-12 03:56:06.816391 | instance -> localhost | +----[SHA256]-----+ 2026-09-12 03:56:06.816481 | instance -> localhost | ok: Runtime: 0:00:00.837252 2026-09-12 03:56:06.829337 | 2026-09-12 03:56:06.829468 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-12 03:56:06.863787 | instance | ok 2026-09-12 03:56:06.897510 | instance | included: /var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-12 03:56:06.910933 | 2026-09-12 03:56:06.911001 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-12 03:56:06.945293 | instance | skipping: Conditional result was False 2026-09-12 03:56:06.957369 | 2026-09-12 03:56:06.957469 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-12 03:56:07.462449 | instance | changed 2026-09-12 03:56:07.481072 | 2026-09-12 03:56:07.481359 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-12 03:56:07.711233 | instance | ok 2026-09-12 03:56:07.717721 | 2026-09-12 03:56:07.717821 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-12 03:56:08.245188 | instance | changed 2026-09-12 03:56:08.250944 | 2026-09-12 03:56:08.251022 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-12 03:56:08.774563 | instance | changed 2026-09-12 03:56:08.780173 | 2026-09-12 03:56:08.780243 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-12 03:56:08.803557 | instance | skipping: Conditional result was False 2026-09-12 03:56:08.808748 | 2026-09-12 03:56:08.808822 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-12 03:56:09.248930 | instance -> localhost | changed 2026-09-12 03:56:09.270426 | 2026-09-12 03:56:09.270583 | TASK [add-build-sshkey : Add back temp key] 2026-09-12 03:56:09.647340 | instance -> localhost | Identity added: /var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/work/c7275687afb64e2abbd918b84e9a7c7e_id_rsa (zuul-build-sshkey) 2026-09-12 03:56:09.647662 | instance -> localhost | ok: Runtime: 0:00:00.028884 2026-09-12 03:56:09.656797 | 2026-09-12 03:56:09.656878 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-12 03:56:09.980888 | instance | ok 2026-09-12 03:56:09.985252 | 2026-09-12 03:56:10.061015 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-12 03:56:10.088442 | instance | skipping: Conditional result was False 2026-09-12 03:56:10.117362 | 2026-09-12 03:56:10.117530 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-12 03:56:10.497671 | instance | ok 2026-09-12 03:56:10.507018 | 2026-09-12 03:56:10.507123 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-12 03:56:16.898485 | instance | Output suppressed because no_log was given 2026-09-12 03:56:16.929853 | 2026-09-12 03:56:16.930629 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-12 03:56:17.144578 | instance | ok: "logs" 2026-09-12 03:56:17.144841 | instance | ok: All items complete 2026-09-12 03:56:17.144870 | 2026-09-12 03:56:17.331445 | instance | ok: "artifacts" 2026-09-12 03:56:17.513609 | instance | ok: "docs" 2026-09-12 03:56:17.525912 | 2026-09-12 03:56:17.526060 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-12 03:56:17.759565 | instance | changed: "logs" 2026-09-12 03:56:17.910524 | instance | changed: "artifacts" 2026-09-12 03:56:18.095612 | instance | changed: "docs" 2026-09-12 03:56:18.109935 | 2026-09-12 03:56:18.110046 | PLAY RECAP 2026-09-12 03:56:18.110094 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-12 03:56:18.110120 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:56:18.110141 | 2026-09-12 03:56:18.251338 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:56:18.253891 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:56:18.777462 | 2026-09-12 03:56:18.830639 | PLAY [all] 2026-09-12 03:56:18.843801 | 2026-09-12 03:56:18.843898 | TASK [setup-uv : Extract archive] 2026-09-12 03:56:21.194659 | instance | changed 2026-09-12 03:56:21.206113 | 2026-09-12 03:56:21.206310 | TASK [setup-uv : Print version] 2026-09-12 03:56:21.594407 | instance | uv 0.8.13 2026-09-12 03:56:21.750029 | instance | ok: Runtime: 0:00:00.014902 2026-09-12 03:56:21.769488 | 2026-09-12 03:56:21.769702 | TASK [Install Ansible collection siblings] 2026-09-12 03:56:31.323468 | instance | changed: 2026-09-12 03:56:31.323719 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 03:56:31.323794 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 03:56:31.323839 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 03:56:31.323879 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 03:56:31.323916 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 03:56:31.323961 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 03:56:31.324001 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 03:56:31.324043 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 03:56:31.324081 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 03:56:31.324119 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 03:56:31.324156 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 03:56:31.324192 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 03:56:31.324228 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-12 03:56:31.324264 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-12 03:56:31.324300 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-12 03:56:31.324338 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-12 03:56:31.324373 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-12 03:56:31.324409 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-12 03:56:31.324444 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-12 03:56:31.324480 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-12 03:56:31.324543 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2 2026-09-12 03:56:31.324580 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-12 03:56:31.324619 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:56:31.324675 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-12 03:56:31.324713 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-12 03:56:31.324748 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:56:31.324810 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:56:31.324848 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:56:31.324883 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-12 03:56:31.324918 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-12 03:56:31.324953 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:56:31.324990 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:56:31.325025 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-12 03:56:31.325061 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 03:56:31.325106 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.325142 | instance | Process install dependency map 2026-09-12 03:56:31.325389 | instance | Starting collection install process 2026-09-12 03:56:31.325449 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-12 03:56:31.325488 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-12 03:56:31.325527 | instance | ansible.netcommon:* was installed successfully 2026-09-12 03:56:31.325563 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-12 03:56:31.325599 | instance | Creating virtual environment at: .venv 2026-09-12 03:56:31.325636 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:56:31.325671 | instance | Downloading netaddr (2.2MiB) 2026-09-12 03:56:31.325705 | instance | Downloading setuptools (1.1MiB) 2026-09-12 03:56:31.325749 | instance | Downloading rjsonnet (1.2MiB) 2026-09-12 03:56:31.325805 | instance | Downloading ansible-core (2.1MiB) 2026-09-12 03:56:31.325843 | instance | Downloading openstacksdk (1.7MiB) 2026-09-12 03:56:31.325878 | instance | Downloading kubernetes (1.9MiB) 2026-09-12 03:56:31.325912 | instance | Downloading pygments (1.2MiB) 2026-09-12 03:56:31.325947 | instance | Downloading cryptography (4.2MiB) 2026-09-12 03:56:31.325981 | instance | Building pyperclip==1.9.0 2026-09-12 03:56:31.326016 | instance | Downloading rjsonnet 2026-09-12 03:56:31.326055 | instance | Downloading netaddr 2026-09-12 03:56:31.326093 | instance | Downloading pygments 2026-09-12 03:56:31.326130 | instance | Downloading cryptography 2026-09-12 03:56:31.326167 | instance | Downloading setuptools 2026-09-12 03:56:31.326202 | instance | Downloading kubernetes 2026-09-12 03:56:31.326237 | instance | Downloading ansible-core 2026-09-12 03:56:31.326271 | instance | Downloading openstacksdk 2026-09-12 03:56:31.326306 | instance | Built pyperclip==1.9.0 2026-09-12 03:56:31.326342 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:56:31.326377 | instance | Installed 79 packages in 55ms 2026-09-12 03:56:31.326414 | instance | Checked out ansible.posix to 1.6.0 2026-09-12 03:56:31.326449 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 03:56:31.326486 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.326522 | instance | Process install dependency map 2026-09-12 03:56:31.326558 | instance | Starting collection install process 2026-09-12 03:56:31.326593 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-12 03:56:31.326642 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-12 03:56:31.326679 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-12 03:56:31.326714 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.326749 | instance | 2.17.12 2026-09-12 03:56:31.326802 | instance | Checked out ansible.utils to v6.1.0 2026-09-12 03:56:31.326840 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 03:56:31.326877 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.326913 | instance | Process install dependency map 2026-09-12 03:56:31.326948 | instance | Starting collection install process 2026-09-12 03:56:31.326984 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-12 03:56:31.327019 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-12 03:56:31.327055 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-12 03:56:31.327091 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.327128 | instance | 2.17.12 2026-09-12 03:56:31.327164 | instance | Checked out atmosphere.common to v0.7.0 2026-09-12 03:56:31.327201 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 03:56:31.327237 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.327272 | instance | Process install dependency map 2026-09-12 03:56:31.327307 | instance | Starting collection install process 2026-09-12 03:56:31.327341 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-12 03:56:31.327376 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-12 03:56:31.327410 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-12 03:56:31.327445 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.327479 | instance | 2.17.12 2026-09-12 03:56:31.327514 | instance | Checked out community.crypto to 2.2.3 2026-09-12 03:56:31.327549 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 03:56:31.327584 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.327620 | instance | Process install dependency map 2026-09-12 03:56:31.327655 | instance | Starting collection install process 2026-09-12 03:56:31.327690 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-12 03:56:31.327724 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-12 03:56:31.327759 | instance | community.crypto:2.2.3 was installed successfully 2026-09-12 03:56:31.327813 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.327850 | instance | 2.17.12 2026-09-12 03:56:31.327886 | instance | Checked out community.general to 7.3.0 2026-09-12 03:56:31.327921 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 03:56:31.327957 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.328003 | instance | Process install dependency map 2026-09-12 03:56:31.328039 | instance | Starting collection install process 2026-09-12 03:56:31.328074 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-12 03:56:31.328109 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-12 03:56:31.328143 | instance | community.general:7.3.0 was installed successfully 2026-09-12 03:56:31.328178 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.328214 | instance | 2.17.12 2026-09-12 03:56:31.328250 | instance | Checked out community.mysql to 3.6.0 2026-09-12 03:56:31.328287 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 03:56:31.328324 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.328362 | instance | Process install dependency map 2026-09-12 03:56:31.328397 | instance | Starting collection install process 2026-09-12 03:56:31.328434 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-12 03:56:31.328470 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-12 03:56:31.328533 | instance | community.mysql:3.6.0 was installed successfully 2026-09-12 03:56:31.328571 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.328606 | instance | 2.17.12 2026-09-12 03:56:31.328641 | instance | Checked out kubernetes.core to 2.4.0 2026-09-12 03:56:31.328675 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 03:56:31.328710 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.328745 | instance | Process install dependency map 2026-09-12 03:56:31.328796 | instance | Starting collection install process 2026-09-12 03:56:31.328832 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-12 03:56:31.328867 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-12 03:56:31.328901 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-12 03:56:31.328936 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.328970 | instance | 2.17.12 2026-09-12 03:56:31.329004 | instance | Checked out openstack.cloud to 2.6.0 2026-09-12 03:56:31.329040 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 03:56:31.329075 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.329109 | instance | Process install dependency map 2026-09-12 03:56:31.329143 | instance | Starting collection install process 2026-09-12 03:56:31.329176 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-12 03:56:31.329210 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-12 03:56:31.329244 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-12 03:56:31.329278 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.329312 | instance | 2.17.12 2026-09-12 03:56:31.329346 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-12 03:56:31.329381 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 03:56:31.329435 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.329470 | instance | Process install dependency map 2026-09-12 03:56:31.329504 | instance | Starting collection install process 2026-09-12 03:56:31.329537 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-12 03:56:31.329570 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-12 03:56:31.329605 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-12 03:56:31.329639 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.329673 | instance | 2.17.12 2026-09-12 03:56:31.329706 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-12 03:56:31.329741 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 03:56:31.329794 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.329830 | instance | Process install dependency map 2026-09-12 03:56:31.329864 | instance | Starting collection install process 2026-09-12 03:56:31.329899 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-12 03:56:31.329933 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-12 03:56:31.329968 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-12 03:56:31.330002 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.330036 | instance | 2.17.12 2026-09-12 03:56:31.330071 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-12 03:56:31.330109 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 03:56:31.330144 | instance | Starting galaxy collection install process 2026-09-12 03:56:31.330179 | instance | Process install dependency map 2026-09-12 03:56:31.330213 | instance | Starting collection install process 2026-09-12 03:56:31.330247 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-12 03:56:31.330282 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-12 03:56:31.330316 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-12 03:56:31.330350 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:56:31.330384 | instance | 2.17.12 2026-09-12 03:56:31.341220 | 2026-09-12 03:56:31.341309 | PLAY RECAP 2026-09-12 03:56:31.341512 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:56:31.341635 | 2026-09-12 03:56:31.475268 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:56:31.481180 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:56:32.122721 | 2026-09-12 03:56:32.122914 | PLAY [all] 2026-09-12 03:56:32.135308 | 2026-09-12 03:56:32.135440 | TASK [Install "jq" for log collection] 2026-09-12 03:56:42.208993 | instance | changed 2026-09-12 03:56:42.211675 | 2026-09-12 03:56:42.211762 | PLAY RECAP 2026-09-12 03:56:42.211850 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:56:42.211918 | 2026-09-12 03:56:42.380886 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:56:42.383502 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 03:56:43.147312 | 2026-09-12 03:56:43.147424 | PLAY [all] 2026-09-12 03:56:43.157171 | 2026-09-12 03:56:43.157237 | LOOP [Check inventory directories] 2026-09-12 03:56:43.568010 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-12 03:56:43.741191 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-12 03:56:43.919383 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-12 03:56:43.942304 | 2026-09-12 03:56:43.942622 | LOOP [Copy inventory file for Zuul] 2026-09-12 03:56:44.831234 | instance | changed: 2026-09-12 03:56:44.832550 | instance | { 2026-09-12 03:56:44.832652 | instance | "ansible_loop_var": "item", 2026-09-12 03:56:44.832705 | instance | "changed": false, 2026-09-12 03:56:44.832746 | instance | "failed": false, 2026-09-12 03:56:44.832813 | instance | "invocation": { 2026-09-12 03:56:44.832854 | instance | "module_args": { 2026-09-12 03:56:44.832890 | instance | "checksum_algorithm": "sha1", 2026-09-12 03:56:44.832926 | instance | "follow": true, 2026-09-12 03:56:44.832962 | instance | "get_attributes": true, 2026-09-12 03:56:44.833000 | instance | "get_checksum": true, 2026-09-12 03:56:44.833036 | instance | "get_mime": true, 2026-09-12 03:56:44.833072 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-12 03:56:44.833108 | instance | } 2026-09-12 03:56:44.833143 | instance | }, 2026-09-12 03:56:44.833178 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:56:44.833212 | instance | "stat": { 2026-09-12 03:56:44.833246 | instance | "atime": 1789185384.3132288, 2026-09-12 03:56:44.833282 | instance | "attr_flags": "e", 2026-09-12 03:56:44.833321 | instance | "attributes": [ 2026-09-12 03:56:44.833374 | instance | "extents" 2026-09-12 03:56:44.833418 | instance | ], 2026-09-12 03:56:44.833453 | instance | "block_size": 4096, 2026-09-12 03:56:44.833487 | instance | "blocks": 8, 2026-09-12 03:56:44.833523 | instance | "charset": "unknown", 2026-09-12 03:56:44.833560 | instance | "ctime": 1789185382.66516, 2026-09-12 03:56:44.833596 | instance | "dev": 64513, 2026-09-12 03:56:44.833631 | instance | "device_type": 0, 2026-09-12 03:56:44.833666 | instance | "executable": true, 2026-09-12 03:56:44.833699 | instance | "exists": true, 2026-09-12 03:56:44.833733 | instance | "gid": 1000, 2026-09-12 03:56:44.833767 | instance | "gr_name": "zuul", 2026-09-12 03:56:44.833823 | instance | "inode": 1049203, 2026-09-12 03:56:44.833859 | instance | "isblk": false, 2026-09-12 03:56:44.833895 | instance | "ischr": false, 2026-09-12 03:56:44.833930 | instance | "isdir": true, 2026-09-12 03:56:44.833964 | instance | "isfifo": false, 2026-09-12 03:56:44.833999 | instance | "isgid": false, 2026-09-12 03:56:44.834033 | instance | "islnk": false, 2026-09-12 03:56:44.834067 | instance | "isreg": false, 2026-09-12 03:56:44.834101 | instance | "issock": false, 2026-09-12 03:56:44.834136 | instance | "isuid": false, 2026-09-12 03:56:44.834170 | instance | "mimetype": "unknown", 2026-09-12 03:56:44.834205 | instance | "mode": "0755", 2026-09-12 03:56:44.834238 | instance | "mtime": 1789185382.66516, 2026-09-12 03:56:44.834273 | instance | "nlink": 21, 2026-09-12 03:56:44.834307 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:56:44.834341 | instance | "pw_name": "zuul", 2026-09-12 03:56:44.834377 | instance | "readable": true, 2026-09-12 03:56:44.834412 | instance | "rgrp": true, 2026-09-12 03:56:44.834447 | instance | "roth": true, 2026-09-12 03:56:44.834481 | instance | "rusr": true, 2026-09-12 03:56:44.834516 | instance | "size": 4096, 2026-09-12 03:56:44.834553 | instance | "uid": 1000, 2026-09-12 03:56:44.834626 | instance | "version": "3671127710", 2026-09-12 03:56:44.835016 | instance | "wgrp": false, 2026-09-12 03:56:44.835082 | instance | "woth": false, 2026-09-12 03:56:44.835127 | instance | "writeable": true, 2026-09-12 03:56:44.835180 | instance | "wusr": true, 2026-09-12 03:56:44.835531 | instance | "xgrp": true, 2026-09-12 03:56:44.835633 | instance | "xoth": true, 2026-09-12 03:56:44.835681 | instance | "xusr": true 2026-09-12 03:56:44.835724 | instance | } 2026-09-12 03:56:44.835772 | instance | } 2026-09-12 03:56:44.837274 | instance | ok: All items complete 2026-09-12 03:56:44.837386 | 2026-09-12 03:56:44.840945 | instance | skipping: Conditional result was False 2026-09-12 03:56:44.843187 | instance | skipping: Conditional result was False 2026-09-12 03:56:44.862468 | 2026-09-12 03:56:44.862588 | LOOP [Switch "ansible_host" to private IP] 2026-09-12 03:56:45.265678 | instance | changed: 1 replacements made 2026-09-12 03:56:45.266842 | instance | skipping: Conditional result was False 2026-09-12 03:56:45.269075 | instance | skipping: Conditional result was False 2026-09-12 03:56:45.296355 | 2026-09-12 03:56:45.296583 | TASK [Run Molecule scenario] 2026-09-12 03:56:46.318668 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-12 03:56:46.708215 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-12 03:56:46.708439 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-12 03:56:57.857085 | instance | INFO [csi > dependency] Executing 2026-09-12 03:56:57.857490 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-12 03:56:57.857898 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-12 03:56:57.858127 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-12 03:56:57.872797 | instance | INFO [csi > cleanup] Executing 2026-09-12 03:56:57.873423 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:56:57.885714 | instance | INFO [csi > destroy] Executing 2026-09-12 03:56:57.885773 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-12 03:56:57.885901 | instance | INFO [csi > destroy] Executed: Successful 2026-09-12 03:56:57.894939 | instance | INFO [csi > syntax] Executing 2026-09-12 03:56:59.414453 | instance | 2026-09-12 03:56:59.414700 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 03:56:59.493880 | instance | INFO [csi > syntax] Executed: Successful 2026-09-12 03:56:59.509669 | instance | INFO [csi > create] Executing 2026-09-12 03:56:59.513289 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:56:59.527549 | instance | INFO [csi > prepare] Executing 2026-09-12 03:57:00.336102 | instance | 2026-09-12 03:57:00.336324 | instance | PLAY [Prepare] ***************************************************************** 2026-09-12 03:57:00.336533 | instance | 2026-09-12 03:57:00.336763 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:57:00.336992 | instance | Saturday 12 September 2026 03:57:00 +0000 (0:00:00.023) 0:00:00.023 **** 2026-09-12 03:57:01.620448 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:57:01.620659 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:57:01.620914 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:57:01.621247 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:57:01.621473 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:57:01.631766 | instance | ok: [instance] 2026-09-12 03:57:01.631938 | instance | 2026-09-12 03:57:01.632227 | instance | TASK [Configure short hostname] ************************************************ 2026-09-12 03:57:01.632471 | instance | Saturday 12 September 2026 03:57:01 +0000 (0:00:01.295) 0:00:01.319 **** 2026-09-12 03:57:02.361621 | instance | changed: [instance] 2026-09-12 03:57:02.361838 | instance | 2026-09-12 03:57:02.362092 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-12 03:57:02.362375 | instance | Saturday 12 September 2026 03:57:02 +0000 (0:00:00.729) 0:00:02.049 **** 2026-09-12 03:57:02.648236 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-12 03:57:02.648315 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-12 03:57:02.648399 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-12 03:57:02.657990 | instance | changed: [instance] 2026-09-12 03:57:02.658084 | instance | 2026-09-12 03:57:02.658349 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-12 03:57:02.658454 | instance | Saturday 12 September 2026 03:57:02 +0000 (0:00:00.297) 0:00:02.346 **** 2026-09-12 03:57:03.461468 | instance | ok: [instance] 2026-09-12 03:57:03.461541 | instance | 2026-09-12 03:57:03.461637 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-12 03:57:03.461732 | instance | 2026-09-12 03:57:03.461833 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:57:03.461961 | instance | Saturday 12 September 2026 03:57:03 +0000 (0:00:00.802) 0:00:03.149 **** 2026-09-12 03:57:04.261802 | instance | ok: [instance] 2026-09-12 03:57:04.262035 | instance | 2026-09-12 03:57:04.262303 | instance | TASK [Install depedencies] ***************************************************** 2026-09-12 03:57:04.262541 | instance | Saturday 12 September 2026 03:57:04 +0000 (0:00:00.800) 0:00:03.949 **** 2026-09-12 03:57:27.894744 | instance | changed: [instance] 2026-09-12 03:57:27.894829 | instance | 2026-09-12 03:57:27.894841 | instance | TASK [Start up service] ******************************************************** 2026-09-12 03:57:27.894852 | instance | Saturday 12 September 2026 03:57:27 +0000 (0:00:23.632) 0:00:27.582 **** 2026-09-12 03:57:28.484932 | instance | ok: [instance] 2026-09-12 03:57:28.484985 | instance | 2026-09-12 03:57:28.484997 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-12 03:57:28.485007 | instance | Saturday 12 September 2026 03:57:28 +0000 (0:00:00.589) 0:00:28.171 **** 2026-09-12 03:57:28.818795 | instance | ok: [instance] 2026-09-12 03:57:28.819736 | instance | 2026-09-12 03:57:28.819831 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-12 03:57:28.819845 | instance | Saturday 12 September 2026 03:57:28 +0000 (0:00:00.334) 0:00:28.506 **** 2026-09-12 03:57:29.549400 | instance | changed: [instance] 2026-09-12 03:57:29.549650 | instance | 2026-09-12 03:57:29.549665 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-12 03:57:29.549678 | instance | Saturday 12 September 2026 03:57:29 +0000 (0:00:00.730) 0:00:29.236 **** 2026-09-12 03:57:29.775714 | instance | ok: [instance] 2026-09-12 03:57:29.776565 | instance | 2026-09-12 03:57:29.776599 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-12 03:57:29.776611 | instance | Saturday 12 September 2026 03:57:29 +0000 (0:00:00.227) 0:00:29.463 **** 2026-09-12 03:57:29.800678 | instance | skipping: [instance] 2026-09-12 03:57:29.801202 | instance | 2026-09-12 03:57:29.801227 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-12 03:57:29.801236 | instance | Saturday 12 September 2026 03:57:29 +0000 (0:00:00.025) 0:00:29.489 **** 2026-09-12 03:57:30.392848 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:57:30.394401 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:57:30.394436 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:57:30.394450 | instance | 2026-09-12 03:57:30.394463 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-12 03:57:30.394476 | instance | Saturday 12 September 2026 03:57:30 +0000 (0:00:00.591) 0:00:30.080 **** 2026-09-12 03:57:31.062174 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:57:31.062235 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:57:31.062249 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:57:31.062262 | instance | 2026-09-12 03:57:31.062274 | instance | TASK [Start loop devices] ****************************************************** 2026-09-12 03:57:31.062288 | instance | Saturday 12 September 2026 03:57:31 +0000 (0:00:00.667) 0:00:30.748 **** 2026-09-12 03:57:31.834671 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:57:31.835932 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:57:31.836006 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:57:31.836017 | instance | 2026-09-12 03:57:31.836028 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-12 03:57:31.836038 | instance | Saturday 12 September 2026 03:57:31 +0000 (0:00:00.774) 0:00:31.522 **** 2026-09-12 03:57:35.106237 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:57:35.107387 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:57:35.107419 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:57:35.107431 | instance | 2026-09-12 03:57:35.107441 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-12 03:57:35.107451 | instance | Saturday 12 September 2026 03:57:35 +0000 (0:00:03.271) 0:00:34.793 **** 2026-09-12 03:57:37.104383 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-12 03:57:37.104610 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-12 03:57:37.104873 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-12 03:57:37.105146 | instance | 2026-09-12 03:57:37.105391 | instance | PLAY RECAP ********************************************************************* 2026-09-12 03:57:37.105650 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-12 03:57:37.106069 | instance | 2026-09-12 03:57:37.106331 | instance | Saturday 12 September 2026 03:57:37 +0000 (0:00:01.998) 0:00:36.792 **** 2026-09-12 03:57:37.106564 | instance | =============================================================================== 2026-09-12 03:57:37.106811 | instance | Install depedencies ---------------------------------------------------- 23.63s 2026-09-12 03:57:37.107038 | instance | Create a volume group for each loop device ------------------------------ 3.27s 2026-09-12 03:57:37.107302 | instance | Create a logical volume for each loop device ---------------------------- 2.00s 2026-09-12 03:57:37.107541 | instance | Gathering Facts --------------------------------------------------------- 1.30s 2026-09-12 03:57:37.107857 | instance | Purge "snapd" package --------------------------------------------------- 0.80s 2026-09-12 03:57:37.108082 | instance | Gathering Facts --------------------------------------------------------- 0.80s 2026-09-12 03:57:37.108341 | instance | Start loop devices ------------------------------------------------------ 0.77s 2026-09-12 03:57:37.108570 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.73s 2026-09-12 03:57:37.108810 | instance | Configure short hostname ------------------------------------------------ 0.73s 2026-09-12 03:57:37.109051 | instance | Set permissions on loopback devices ------------------------------------- 0.67s 2026-09-12 03:57:37.109310 | instance | Create devices for Ceph ------------------------------------------------- 0.59s 2026-09-12 03:57:37.109537 | instance | Start up service -------------------------------------------------------- 0.59s 2026-09-12 03:57:37.109761 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s 2026-09-12 03:57:37.110013 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s 2026-09-12 03:57:37.110296 | instance | Get list of all loopback devices ---------------------------------------- 0.23s 2026-09-12 03:57:37.110531 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-12 03:57:37.200057 | instance | INFO [csi > prepare] Executed: Successful 2026-09-12 03:57:37.215335 | instance | INFO [csi > converge] Executing 2026-09-12 03:57:38.572565 | instance | 2026-09-12 03:57:38.572670 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-12 03:57:38.572722 | instance | 2026-09-12 03:57:38.572876 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:57:38.572987 | instance | Saturday 12 September 2026 03:57:38 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 03:57:39.619496 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:57:39.619708 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:57:39.620009 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:57:39.620245 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:57:39.620477 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:57:39.634035 | instance | ok: [instance] 2026-09-12 03:57:39.634256 | instance | 2026-09-12 03:57:39.634493 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-12 03:57:39.634720 | instance | Saturday 12 September 2026 03:57:39 +0000 (0:00:01.061) 0:00:01.068 **** 2026-09-12 03:57:39.698270 | instance | ok: [instance] => { 2026-09-12 03:57:39.698495 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-12 03:57:39.698725 | instance | } 2026-09-12 03:57:39.698952 | instance | 2026-09-12 03:57:39.699177 | instance | PLAY [all] ********************************************************************* 2026-09-12 03:57:39.699387 | instance | 2026-09-12 03:57:39.699612 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:57:39.699891 | instance | Saturday 12 September 2026 03:57:39 +0000 (0:00:00.064) 0:00:01.133 **** 2026-09-12 03:57:40.671559 | instance | ok: [instance] 2026-09-12 03:57:40.671832 | instance | 2026-09-12 03:57:40.672117 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-12 03:57:40.672502 | instance | Saturday 12 September 2026 03:57:40 +0000 (0:00:00.972) 0:00:02.105 **** 2026-09-12 03:57:40.877689 | instance | ok: [instance] 2026-09-12 03:57:40.877867 | instance | 2026-09-12 03:57:40.878101 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-12 03:57:40.878315 | instance | 2026-09-12 03:57:40.878542 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:57:40.878805 | instance | Saturday 12 September 2026 03:57:40 +0000 (0:00:00.206) 0:00:02.312 **** 2026-09-12 03:57:41.899910 | instance | ok: [instance] 2026-09-12 03:57:41.899984 | instance | 2026-09-12 03:57:41.899995 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:57:41.900147 | instance | Saturday 12 September 2026 03:57:41 +0000 (0:00:01.020) 0:00:03.333 **** 2026-09-12 03:57:42.230426 | instance | ok: [instance] 2026-09-12 03:57:42.231117 | instance | 2026-09-12 03:57:42.231151 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:57:42.231162 | instance | Saturday 12 September 2026 03:57:42 +0000 (0:00:00.331) 0:00:03.664 **** 2026-09-12 03:57:42.292078 | instance | skipping: [instance] 2026-09-12 03:57:42.292301 | instance | 2026-09-12 03:57:42.292552 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-12 03:57:42.292817 | instance | Saturday 12 September 2026 03:57:42 +0000 (0:00:00.061) 0:00:03.726 **** 2026-09-12 03:57:42.630458 | instance | changed: [instance] 2026-09-12 03:57:42.630637 | instance | 2026-09-12 03:57:42.630905 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:57:42.631137 | instance | Saturday 12 September 2026 03:57:42 +0000 (0:00:00.338) 0:00:04.064 **** 2026-09-12 03:57:42.728943 | instance | ok: [instance] => { 2026-09-12 03:57:42.729157 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-12 03:57:42.729400 | instance | } 2026-09-12 03:57:42.729616 | instance | 2026-09-12 03:57:42.729936 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:57:42.730167 | instance | Saturday 12 September 2026 03:57:42 +0000 (0:00:00.098) 0:00:04.163 **** 2026-09-12 03:57:43.339151 | instance | changed: [instance] 2026-09-12 03:57:43.339359 | instance | 2026-09-12 03:57:43.339628 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:57:43.339948 | instance | Saturday 12 September 2026 03:57:43 +0000 (0:00:00.610) 0:00:04.773 **** 2026-09-12 03:57:43.399866 | instance | skipping: [instance] 2026-09-12 03:57:43.400074 | instance | 2026-09-12 03:57:43.400319 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:57:43.400551 | instance | Saturday 12 September 2026 03:57:43 +0000 (0:00:00.060) 0:00:04.834 **** 2026-09-12 03:57:43.442385 | instance | skipping: [instance] 2026-09-12 03:57:43.442576 | instance | 2026-09-12 03:57:43.442829 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:57:43.443059 | instance | Saturday 12 September 2026 03:57:43 +0000 (0:00:00.042) 0:00:04.877 **** 2026-09-12 03:57:43.718866 | instance | ok: [instance] 2026-09-12 03:57:43.719068 | instance | 2026-09-12 03:57:43.719301 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:57:43.719533 | instance | Saturday 12 September 2026 03:57:43 +0000 (0:00:00.275) 0:00:05.153 **** 2026-09-12 03:57:45.206400 | instance | ok: [instance] 2026-09-12 03:57:45.206793 | instance | 2026-09-12 03:57:45.207117 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:57:45.207429 | instance | Saturday 12 September 2026 03:57:45 +0000 (0:00:01.487) 0:00:06.641 **** 2026-09-12 03:57:45.305088 | instance | ok: [instance] => { 2026-09-12 03:57:45.305520 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-12 03:57:45.305907 | instance | } 2026-09-12 03:57:45.306200 | instance | 2026-09-12 03:57:45.306508 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:57:45.306851 | instance | Saturday 12 September 2026 03:57:45 +0000 (0:00:00.094) 0:00:06.735 **** 2026-09-12 03:57:45.996402 | instance | changed: [instance] 2026-09-12 03:57:45.996496 | instance | 2026-09-12 03:57:45.996558 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:57:45.996677 | instance | Saturday 12 September 2026 03:57:45 +0000 (0:00:00.693) 0:00:07.429 **** 2026-09-12 03:57:49.032878 | instance | changed: [instance] 2026-09-12 03:57:49.032951 | instance | 2026-09-12 03:57:49.032963 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-12 03:57:49.032974 | instance | Saturday 12 September 2026 03:57:49 +0000 (0:00:03.037) 0:00:10.466 **** 2026-09-12 03:57:49.076681 | instance | skipping: [instance] 2026-09-12 03:57:49.077150 | instance | 2026-09-12 03:57:49.077199 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-12 03:57:49.077207 | instance | Saturday 12 September 2026 03:57:49 +0000 (0:00:00.044) 0:00:10.511 **** 2026-09-12 03:57:49.122190 | instance | skipping: [instance] 2026-09-12 03:57:49.123370 | instance | 2026-09-12 03:57:49.123416 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-12 03:57:49.123428 | instance | Saturday 12 September 2026 03:57:49 +0000 (0:00:00.045) 0:00:10.556 **** 2026-09-12 03:57:49.168408 | instance | skipping: [instance] 2026-09-12 03:57:49.169208 | instance | 2026-09-12 03:57:49.169469 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-12 03:57:49.169483 | instance | Saturday 12 September 2026 03:57:49 +0000 (0:00:00.046) 0:00:10.602 **** 2026-09-12 03:57:55.599373 | instance | changed: [instance] 2026-09-12 03:57:55.599672 | instance | 2026-09-12 03:57:55.599978 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-12 03:57:55.600332 | instance | Saturday 12 September 2026 03:57:55 +0000 (0:00:06.431) 0:00:17.033 **** 2026-09-12 03:57:56.332478 | instance | changed: [instance] 2026-09-12 03:57:56.332678 | instance | 2026-09-12 03:57:56.333221 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-12 03:57:56.333289 | instance | Saturday 12 September 2026 03:57:56 +0000 (0:00:00.733) 0:00:17.766 **** 2026-09-12 03:57:57.397205 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-12 03:57:57.398920 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-12 03:57:57.399001 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-12 03:57:57.399013 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-12 03:57:57.399025 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-12 03:57:57.399035 | instance | 2026-09-12 03:57:57.399054 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-12 03:57:57.399065 | instance | Saturday 12 September 2026 03:57:57 +0000 (0:00:01.064) 0:00:18.831 **** 2026-09-12 03:57:58.051686 | instance | changed: [instance] 2026-09-12 03:57:58.051825 | instance | 2026-09-12 03:57:58.052545 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-12 03:57:58.052606 | instance | Saturday 12 September 2026 03:57:58 +0000 (0:00:00.629) 0:00:19.460 **** 2026-09-12 03:57:58.052614 | instance | 2026-09-12 03:57:58.052620 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 03:57:58.052626 | instance | Saturday 12 September 2026 03:57:58 +0000 (0:00:00.025) 0:00:19.486 **** 2026-09-12 03:57:59.070041 | instance | ok: [instance] 2026-09-12 03:57:59.070250 | instance | 2026-09-12 03:57:59.070648 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-12 03:57:59.070954 | instance | Saturday 12 September 2026 03:57:59 +0000 (0:00:01.017) 0:00:20.504 **** 2026-09-12 03:57:59.578359 | instance | changed: [instance] 2026-09-12 03:57:59.578557 | instance | 2026-09-12 03:57:59.579096 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-12 03:57:59.579214 | instance | Saturday 12 September 2026 03:57:59 +0000 (0:00:00.508) 0:00:21.012 **** 2026-09-12 03:58:00.212731 | instance | changed: [instance] 2026-09-12 03:58:00.212811 | instance | 2026-09-12 03:58:00.213167 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:58:00.213232 | instance | Saturday 12 September 2026 03:58:00 +0000 (0:00:00.634) 0:00:21.647 **** 2026-09-12 03:58:00.452933 | instance | ok: [instance] 2026-09-12 03:58:00.453042 | instance | 2026-09-12 03:58:00.453054 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:58:00.453201 | instance | Saturday 12 September 2026 03:58:00 +0000 (0:00:00.240) 0:00:21.888 **** 2026-09-12 03:58:00.545840 | instance | ok: [instance] => { 2026-09-12 03:58:00.545923 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-12 03:58:00.545936 | instance | } 2026-09-12 03:58:00.545946 | instance | 2026-09-12 03:58:00.545956 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:58:00.545966 | instance | Saturday 12 September 2026 03:58:00 +0000 (0:00:00.089) 0:00:21.978 **** 2026-09-12 03:58:01.412857 | instance | changed: [instance] 2026-09-12 03:58:01.412926 | instance | 2026-09-12 03:58:01.412938 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:58:01.412950 | instance | Saturday 12 September 2026 03:58:01 +0000 (0:00:00.868) 0:00:22.846 **** 2026-09-12 03:58:05.956761 | instance | changed: [instance] 2026-09-12 03:58:05.957479 | instance | 2026-09-12 03:58:05.957557 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-12 03:58:05.957566 | instance | Saturday 12 September 2026 03:58:05 +0000 (0:00:04.544) 0:00:27.390 **** 2026-09-12 03:58:07.101859 | instance | ok: [instance] 2026-09-12 03:58:07.101970 | instance | 2026-09-12 03:58:07.102498 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-12 03:58:07.102568 | instance | Saturday 12 September 2026 03:58:07 +0000 (0:00:01.145) 0:00:28.536 **** 2026-09-12 03:58:07.503866 | instance | changed: [instance] 2026-09-12 03:58:07.503933 | instance | 2026-09-12 03:58:07.503945 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-12 03:58:07.503961 | instance | Saturday 12 September 2026 03:58:07 +0000 (0:00:00.401) 0:00:28.937 **** 2026-09-12 03:58:08.037080 | instance | changed: [instance] 2026-09-12 03:58:08.037138 | instance | 2026-09-12 03:58:08.037149 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-12 03:58:08.037160 | instance | Saturday 12 September 2026 03:58:08 +0000 (0:00:00.533) 0:00:29.470 **** 2026-09-12 03:58:08.668575 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-12 03:58:08.668639 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-12 03:58:08.668651 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-12 03:58:08.668661 | instance | 2026-09-12 03:58:08.668670 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-12 03:58:08.668679 | instance | Saturday 12 September 2026 03:58:08 +0000 (0:00:00.630) 0:00:30.101 **** 2026-09-12 03:58:09.179995 | instance | changed: [instance] 2026-09-12 03:58:09.180070 | instance | 2026-09-12 03:58:09.180084 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-12 03:58:09.180098 | instance | Saturday 12 September 2026 03:58:09 +0000 (0:00:00.511) 0:00:30.612 **** 2026-09-12 03:58:09.730598 | instance | changed: [instance] 2026-09-12 03:58:09.730704 | instance | 2026-09-12 03:58:09.730722 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-12 03:58:09.730737 | instance | Saturday 12 September 2026 03:58:09 +0000 (0:00:00.519) 0:00:31.132 **** 2026-09-12 03:58:09.730749 | instance | 2026-09-12 03:58:09.730759 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 03:58:09.730768 | instance | Saturday 12 September 2026 03:58:09 +0000 (0:00:00.030) 0:00:31.163 **** 2026-09-12 03:58:10.516779 | instance | ok: [instance] 2026-09-12 03:58:10.517275 | instance | 2026-09-12 03:58:10.517590 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-12 03:58:10.517608 | instance | Saturday 12 September 2026 03:58:10 +0000 (0:00:00.788) 0:00:31.951 **** 2026-09-12 03:58:11.453267 | instance | changed: [instance] 2026-09-12 03:58:11.454148 | instance | 2026-09-12 03:58:11.454217 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-12 03:58:11.454233 | instance | Saturday 12 September 2026 03:58:11 +0000 (0:00:00.936) 0:00:32.887 **** 2026-09-12 03:58:12.078142 | instance | changed: [instance] 2026-09-12 03:58:12.078188 | instance | 2026-09-12 03:58:12.078195 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-12 03:58:12.078203 | instance | Saturday 12 September 2026 03:58:12 +0000 (0:00:00.624) 0:00:33.512 **** 2026-09-12 03:58:12.154045 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-12 03:58:12.154530 | instance | 2026-09-12 03:58:12.154595 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-12 03:58:12.154604 | instance | Saturday 12 September 2026 03:58:12 +0000 (0:00:00.075) 0:00:33.588 **** 2026-09-12 03:58:17.836726 | instance | changed: [instance] 2026-09-12 03:58:17.836874 | instance | 2026-09-12 03:58:17.837446 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-12 03:58:17.837523 | instance | Saturday 12 September 2026 03:58:17 +0000 (0:00:05.682) 0:00:39.270 **** 2026-09-12 03:58:18.545471 | instance | ok: [instance] => (item=chronyd) 2026-09-12 03:58:18.546443 | instance | ok: [instance] => (item=sshd) 2026-09-12 03:58:18.546482 | instance | 2026-09-12 03:58:18.546497 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-12 03:58:18.546510 | instance | Saturday 12 September 2026 03:58:18 +0000 (0:00:00.708) 0:00:39.979 **** 2026-09-12 03:58:33.827860 | instance | changed: [instance] 2026-09-12 03:58:33.828704 | instance | 2026-09-12 03:58:33.828772 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-12 03:58:33.828784 | instance | Saturday 12 September 2026 03:58:33 +0000 (0:00:15.281) 0:00:55.261 **** 2026-09-12 03:58:34.078749 | instance | ok: [instance] 2026-09-12 03:58:34.079506 | instance | 2026-09-12 03:58:34.079541 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-12 03:58:34.079552 | instance | Saturday 12 September 2026 03:58:34 +0000 (0:00:00.250) 0:00:55.512 **** 2026-09-12 03:58:34.576797 | instance | changed: [instance] 2026-09-12 03:58:34.576873 | instance | 2026-09-12 03:58:34.576884 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-12 03:58:34.576895 | instance | Saturday 12 September 2026 03:58:34 +0000 (0:00:00.497) 0:00:56.009 **** 2026-09-12 03:58:34.964399 | instance | changed: [instance] 2026-09-12 03:58:34.964456 | instance | 2026-09-12 03:58:34.964463 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-12 03:58:34.964470 | instance | Saturday 12 September 2026 03:58:34 +0000 (0:00:00.388) 0:00:56.398 **** 2026-09-12 03:58:35.112233 | instance | ok: [instance] 2026-09-12 03:58:35.112356 | instance | 2026-09-12 03:58:35.112627 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-12 03:58:35.112850 | instance | Saturday 12 September 2026 03:58:35 +0000 (0:00:00.147) 0:00:56.546 **** 2026-09-12 03:58:35.377805 | instance | ok: [instance] => (item=instance) 2026-09-12 03:58:35.377966 | instance | 2026-09-12 03:58:35.378205 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-12 03:58:35.378463 | instance | Saturday 12 September 2026 03:58:35 +0000 (0:00:00.265) 0:00:56.812 **** 2026-09-12 03:58:35.443328 | instance | ok: [instance] 2026-09-12 03:58:35.443526 | instance | 2026-09-12 03:58:35.443799 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-12 03:58:35.444062 | instance | Saturday 12 September 2026 03:58:35 +0000 (0:00:00.066) 0:00:56.878 **** 2026-09-12 03:58:35.545664 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-12 03:58:35.545856 | instance | 2026-09-12 03:58:35.546093 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-12 03:58:35.546344 | instance | Saturday 12 September 2026 03:58:35 +0000 (0:00:00.102) 0:00:56.980 **** 2026-09-12 03:58:35.904060 | instance | changed: [instance] 2026-09-12 03:58:35.904277 | instance | 2026-09-12 03:58:35.904517 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-12 03:58:35.904750 | instance | Saturday 12 September 2026 03:58:35 +0000 (0:00:00.357) 0:00:57.338 **** 2026-09-12 03:58:36.680854 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-12 03:58:36.681097 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-12 03:58:36.681388 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-12 03:58:36.681610 | instance | 2026-09-12 03:58:36.681878 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-12 03:58:36.682107 | instance | Saturday 12 September 2026 03:58:36 +0000 (0:00:00.776) 0:00:58.115 **** 2026-09-12 04:00:16.266565 | instance | ok: [instance] 2026-09-12 04:00:16.266931 | instance | 2026-09-12 04:00:16.267309 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-12 04:00:16.267643 | instance | Saturday 12 September 2026 04:00:16 +0000 (0:01:39.583) 0:02:37.699 **** 2026-09-12 04:00:16.509999 | instance | changed: [instance] 2026-09-12 04:00:16.510059 | instance | 2026-09-12 04:00:16.510176 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-12 04:00:16.510282 | instance | Saturday 12 September 2026 04:00:16 +0000 (0:00:00.246) 0:02:37.945 **** 2026-09-12 04:00:16.541791 | instance | ok: [instance] 2026-09-12 04:00:16.541947 | instance | 2026-09-12 04:00:16.542103 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-12 04:00:16.542241 | instance | Saturday 12 September 2026 04:00:16 +0000 (0:00:00.031) 0:02:37.977 **** 2026-09-12 04:00:16.616401 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-12 04:00:16.616471 | instance | 2026-09-12 04:00:16.616527 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-12 04:00:16.616641 | instance | Saturday 12 September 2026 04:00:16 +0000 (0:00:00.074) 0:02:38.052 **** 2026-09-12 04:00:18.352365 | instance | ok: [instance] 2026-09-12 04:00:18.353122 | instance | 2026-09-12 04:00:18.353179 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-12 04:00:18.353191 | instance | Saturday 12 September 2026 04:00:18 +0000 (0:00:01.734) 0:02:39.786 **** 2026-09-12 04:00:18.428850 | instance | ok: [instance] => (item=instance) 2026-09-12 04:00:18.429606 | instance | 2026-09-12 04:00:18.429640 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-12 04:00:18.429653 | instance | Saturday 12 September 2026 04:00:18 +0000 (0:00:00.076) 0:02:39.863 **** 2026-09-12 04:00:18.818667 | instance | ok: [instance] 2026-09-12 04:00:18.818777 | instance | 2026-09-12 04:00:18.818954 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-12 04:00:18.819106 | instance | Saturday 12 September 2026 04:00:18 +0000 (0:00:00.390) 0:02:40.253 **** 2026-09-12 04:00:21.311479 | instance | ok: [instance] 2026-09-12 04:00:21.311579 | instance | 2026-09-12 04:00:21.311952 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-12 04:00:21.312000 | instance | Saturday 12 September 2026 04:00:21 +0000 (0:00:02.492) 0:02:42.746 **** 2026-09-12 04:01:14.987074 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-12 04:01:14.987936 | 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_79m7rcda/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_79m7rcda/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_79m7rcda/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_79m7rcda/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-12T04:01:14.780+0000 7f555facb640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAlzqRqUdRADiAA+jdIl+o7vRrjMRhhYz/8J2rtgW9CMt5ziLD5U7Zyma4=: Malformed input [buffer:3]\n2026-09-12T04:01:14.780+0000 7f555facb640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:01:14.780+0000 7f555facb640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAlzqRqUdRADiAA+jdIl+o7vRrjMRhhYz/8J2rtgW9CMt5ziLD5U7Zyma4=: Malformed input [buffer:3]\n2026-09-12T04:01:14.780+0000 7f555facb640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:01:14.780+0000 7f555facb640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAlzqRqUdRADiAA+jdIl+o7vRrjMRhhYz/8J2rtgW9CMt5ziLD5U7Zyma4=: Malformed input [buffer:3]\n2026-09-12T04:01:14.780+0000 7f555facb640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:01:14.780+0000 7f555facb640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-12 04:01:14.993055 | instance | 2026-09-12 04:01:14.995980 | instance | PLAY RECAP ********************************************************************* 2026-09-12 04:01:14.996075 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=6 rescued=0 ignored=0 2026-09-12 04:01:14.996088 | instance | 2026-09-12 04:01:14.996101 | instance | Saturday 12 September 2026 04:01:14 +0000 (0:00:53.680) 0:03:36.427 **** 2026-09-12 04:01:14.996110 | instance | =============================================================================== 2026-09-12 04:01:14.996119 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 99.58s 2026-09-12 04:01:14.996128 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 53.68s 2026-09-12 04:01:14.996137 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 15.28s 2026-09-12 04:01:14.996147 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.43s 2026-09-12 04:01:14.996157 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.68s 2026-09-12 04:01:14.996365 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.54s 2026-09-12 04:01:14.996840 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.04s 2026-09-12 04:01:14.997319 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.49s 2026-09-12 04:01:14.997802 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.81s 2026-09-12 04:01:14.998274 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.73s 2026-09-12 04:01:14.998866 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.49s 2026-09-12 04:01:14.999288 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.15s 2026-09-12 04:01:14.999722 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.06s 2026-09-12 04:01:15.000246 | instance | Gathering Facts --------------------------------------------------------- 1.06s 2026-09-12 04:01:15.000683 | instance | Gathering Facts --------------------------------------------------------- 1.02s 2026-09-12 04:01:15.001105 | instance | Gathering Facts --------------------------------------------------------- 0.97s 2026-09-12 04:01:15.001559 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.94s 2026-09-12 04:01:15.001948 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.87s 2026-09-12 04:01:15.002334 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.78s 2026-09-12 04:01:15.002679 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.73s 2026-09-12 04:01:15.182527 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 04:01:15.182810 | instance | ERROR [csi > converge] Executed: Failed 2026-09-12 04:01:15.182994 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 04:01:15.694890 | instance | ERROR 2026-09-12 04:01:15.695112 | instance | { 2026-09-12 04:01:15.695162 | instance | "delta": "0:04:29.506883", 2026-09-12 04:01:15.695192 | instance | "end": "2026-09-12 04:01:15.259580", 2026-09-12 04:01:15.695219 | instance | "msg": "non-zero return code", 2026-09-12 04:01:15.695245 | instance | "rc": 2, 2026-09-12 04:01:15.695270 | instance | "start": "2026-09-12 03:56:45.752697" 2026-09-12 04:01:15.695295 | instance | } failure 2026-09-12 04:01:15.701260 | 2026-09-12 04:01:15.701321 | PLAY RECAP 2026-09-12 04:01:15.701377 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-12 04:01:15.701408 | 2026-09-12 04:01:15.854896 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 04:01:15.859415 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 04:01:16.543789 | 2026-09-12 04:01:16.543937 | PLAY [all] 2026-09-12 04:01:16.557831 | 2026-09-12 04:01:16.557927 | TASK [gather-host-logs : creating directory for system status] 2026-09-12 04:01:16.943885 | instance | changed 2026-09-12 04:01:16.953869 | 2026-09-12 04:01:16.954052 | TASK [gather-host-logs : Get logs for each host] 2026-09-12 04:01:17.355174 | instance | + systemd-cgls --full --all --no-pager 2026-09-12 04:01:17.371668 | instance | + ip addr 2026-09-12 04:01:17.374071 | instance | + ip route 2026-09-12 04:01:17.377127 | instance | + lsblk 2026-09-12 04:01:17.382597 | instance | + mount 2026-09-12 04:01:17.385520 | instance | + docker images 2026-09-12 04:01:17.408776 | instance | + brctl show 2026-09-12 04:01:17.409427 | instance | /bin/bash: line 8: brctl: command not found 2026-09-12 04:01:17.409721 | instance | + ip neigh 2026-09-12 04:01:17.412336 | instance | + bridge fdb show 2026-09-12 04:01:17.415825 | instance | + ps aux --sort=-%mem 2026-09-12 04:01:17.440591 | instance | + dpkg -l 2026-09-12 04:01:17.452209 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-12 04:01:17.452732 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-12 04:01:17.473144 | instance | + '[' '!' -z '' ']' 2026-09-12 04:01:17.531215 | instance | ok: Runtime: 0:00:00.123278 2026-09-12 04:01:17.545530 | 2026-09-12 04:01:17.545700 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-12 04:01:18.232666 | instance | changed: 2026-09-12 04:01:18.232954 | instance | created directory /var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/work/logs/instance 2026-09-12 04:01:18.233012 | instance | cd+++++++++ system/ 2026-09-12 04:01:18.233056 | instance | >f+++++++++ system/brctl-show.txt 2026-09-12 04:01:18.233096 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-12 04:01:18.233135 | instance | >f+++++++++ system/docker-images.txt 2026-09-12 04:01:18.233178 | instance | >f+++++++++ system/ip-addr.txt 2026-09-12 04:01:18.233222 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-12 04:01:18.233263 | instance | >f+++++++++ system/ip-route.txt 2026-09-12 04:01:18.233306 | instance | >f+++++++++ system/lsblk.txt 2026-09-12 04:01:18.233345 | instance | >f+++++++++ system/mount.txt 2026-09-12 04:01:18.233383 | instance | >f+++++++++ system/packages.txt 2026-09-12 04:01:18.233420 | instance | >f+++++++++ system/ps.txt 2026-09-12 04:01:18.233456 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-12 04:01:18.250938 | 2026-09-12 04:01:18.251080 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-12 04:01:18.490402 | instance | changed: "values" 2026-09-12 04:01:18.673377 | instance | changed: "releases" 2026-09-12 04:01:18.695241 | 2026-09-12 04:01:18.695471 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-12 04:01:18.944111 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-12 04:01:19.246325 | instance | ok: Runtime: 0:00:00.006223 2026-09-12 04:01:19.256135 | 2026-09-12 04:01:19.256284 | TASK [helm-release-status : Downloads logs to executor] 2026-09-12 04:01:19.776778 | instance | changed: 2026-09-12 04:01:19.777048 | instance | cd+++++++++ helm/ 2026-09-12 04:01:19.777105 | instance | cd+++++++++ helm/releases/ 2026-09-12 04:01:19.777147 | instance | cd+++++++++ helm/values/ 2026-09-12 04:01:19.794951 | 2026-09-12 04:01:19.795080 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-12 04:01:20.012878 | instance | changed 2026-09-12 04:01:20.024811 | 2026-09-12 04:01:20.025140 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-12 04:01:20.314248 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:01:20.314561 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:01:20.319722 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:01:20.320995 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:01:20.322167 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:01:20.323831 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:01:20.324580 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:01:20.325446 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:01:20.327578 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:01:20.328655 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:01:20.329560 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:01:20.330659 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:01:20.571297 | instance | ok: Runtime: 0:00:00.024412 2026-09-12 04:01:20.580977 | 2026-09-12 04:01:20.581105 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-12 04:01:20.797878 | instance | changed 2026-09-12 04:01:20.807716 | 2026-09-12 04:01:20.808036 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-12 04:01:21.034532 | instance | environment: line 5: kubectl: command not found 2026-09-12 04:01:21.035543 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:01:21.036097 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:01:21.036771 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:01:21.358807 | instance | ok: Runtime: 0:00:00.009329 2026-09-12 04:01:21.365533 | 2026-09-12 04:01:21.365616 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-12 04:01:21.895031 | instance | changed: 2026-09-12 04:01:21.895303 | instance | cd+++++++++ objects/ 2026-09-12 04:01:21.895359 | instance | cd+++++++++ objects/cluster/ 2026-09-12 04:01:21.895400 | instance | cd+++++++++ objects/namespaced/ 2026-09-12 04:01:21.913222 | 2026-09-12 04:01:21.913351 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-12 04:01:22.128426 | instance | changed 2026-09-12 04:01:22.137775 | 2026-09-12 04:01:22.137923 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-12 04:01:22.366362 | instance | changed 2026-09-12 04:01:22.375873 | 2026-09-12 04:01:22.376019 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-12 04:01:22.633636 | instance | environment: line 3: kubectl: command not found 2026-09-12 04:01:22.923080 | instance | ok: Runtime: 0:00:00.007858 2026-09-12 04:01:22.932432 | 2026-09-12 04:01:22.932589 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-12 04:01:23.463102 | instance | changed: 2026-09-12 04:01:23.463355 | instance | cd+++++++++ pod-logs/ 2026-09-12 04:01:23.463410 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-12 04:01:23.481572 | 2026-09-12 04:01:23.481698 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-12 04:01:23.717451 | instance | changed 2026-09-12 04:01:23.727021 | 2026-09-12 04:01:23.727150 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-12 04:01:23.977737 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 04:01:24.270529 | instance | ok: Runtime: 0:00:00.051680 2026-09-12 04:01:24.275085 | 2026-09-12 04:01:24.275153 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-12 04:01:24.512596 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 04:01:24.557020 | instance | ceph-mgr endpoints: 2026-09-12 04:01:24.810311 | instance | ok: Runtime: 0:00:00.050515 2026-09-12 04:01:24.821197 | 2026-09-12 04:01:24.821333 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-12 04:01:25.070863 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-12 04:01:25.363962 | instance | ok: Runtime: 0:00:00.053317 2026-09-12 04:01:25.368774 | 2026-09-12 04:01:25.368850 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-12 04:01:25.874177 | instance | changed: cd+++++++++ prometheus/ 2026-09-12 04:01:25.891924 | 2026-09-12 04:01:25.892081 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-12 04:01:26.119891 | instance | changed 2026-09-12 04:01:26.127378 | 2026-09-12 04:01:26.127540 | TASK [gather-selenium-data : Get selenium data] 2026-09-12 04:01:26.412280 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-12 04:01:26.413822 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-12 04:01:26.671470 | instance | ERROR 2026-09-12 04:01:26.671771 | instance | { 2026-09-12 04:01:26.671829 | instance | "delta": "0:00:00.007773", 2026-09-12 04:01:26.671894 | instance | "end": "2026-09-12 04:01:26.414352", 2026-09-12 04:01:26.671931 | instance | "msg": "non-zero return code", 2026-09-12 04:01:26.671967 | instance | "rc": 1, 2026-09-12 04:01:26.672001 | instance | "start": "2026-09-12 04:01:26.406579" 2026-09-12 04:01:26.672035 | instance | } 2026-09-12 04:01:26.672080 | instance | ERROR: Ignoring Errors 2026-09-12 04:01:26.681384 | 2026-09-12 04:01:26.681505 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-12 04:01:27.243726 | instance | changed: cd+++++++++ selenium/ 2026-09-12 04:01:27.256656 | 2026-09-12 04:01:27.256766 | PLAY RECAP 2026-09-12 04:01:27.256895 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-12 04:01:27.256943 | 2026-09-12 04:01:27.422049 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 04:01:27.426153 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 04:01:28.045706 | 2026-09-12 04:01:28.045863 | PLAY [all] 2026-09-12 04:01:28.056912 | 2026-09-12 04:01:28.056984 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-12 04:01:28.121141 | instance | skipping: Conditional result was False 2026-09-12 04:01:28.127396 | 2026-09-12 04:01:28.127493 | TASK [fetch-output : Set log path for single node] 2026-09-12 04:01:28.180075 | instance | ok 2026-09-12 04:01:28.189157 | 2026-09-12 04:01:28.189289 | LOOP [fetch-output : Ensure local output dirs] 2026-09-12 04:01:28.657349 | instance -> localhost | ok: "/var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/work/logs" 2026-09-12 04:01:28.919482 | instance -> localhost | changed: "/var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/work/artifacts" 2026-09-12 04:01:29.127821 | instance -> localhost | changed: "/var/lib/zuul/builds/c7275687afb64e2abbd918b84e9a7c7e/work/docs" 2026-09-12 04:01:29.149596 | 2026-09-12 04:01:29.149793 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-12 04:01:29.890283 | instance | changed: .d..t...... ./ 2026-09-12 04:01:29.890604 | instance | changed: All items complete 2026-09-12 04:01:29.890660 | 2026-09-12 04:01:30.399698 | instance | changed: .d..t...... ./ 2026-09-12 04:01:30.908990 | instance | changed: .d..t...... ./ 2026-09-12 04:01:30.936351 | 2026-09-12 04:01:30.936583 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-12 04:01:31.415978 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008128 2026-09-12 04:01:31.682689 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008135 2026-09-12 04:01:31.703235 | 2026-09-12 04:01:31.703409 | PLAY [all] 2026-09-12 04:01:31.714798 | 2026-09-12 04:01:31.714950 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-12 04:01:32.155257 | instance | changed 2026-09-12 04:01:32.167244 | 2026-09-12 04:01:32.167339 | PLAY RECAP 2026-09-12 04:01:32.167420 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-12 04:01:32.167462 | 2026-09-12 04:01:32.301089 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 04:01:32.306703 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-12 04:01:32.892311 | 2026-09-12 04:01:32.892461 | PLAY [localhost] 2026-09-12 04:01:32.901972 | 2026-09-12 04:01:32.902052 | TASK [Generate Zuul manifest] 2026-09-12 04:01:32.918511 | localhost | ok 2026-09-12 04:01:32.933756 | 2026-09-12 04:01:32.933832 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-12 04:01:33.328930 | localhost | changed 2026-09-12 04:01:33.348007 | 2026-09-12 04:01:33.348172 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-12 04:01:33.388779 | localhost | ok 2026-09-12 04:01:33.399153 | 2026-09-12 04:01:33.399334 | TASK [Upload logs] 2026-09-12 04:01:33.418832 | localhost | ok 2026-09-12 04:01:33.477890 | 2026-09-12 04:01:33.478043 | TASK [Set zuul-log-path fact] 2026-09-12 04:01:33.494958 | localhost | ok 2026-09-12 04:01:33.505661 | 2026-09-12 04:01:33.505726 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 04:01:33.543490 | localhost | ok 2026-09-12 04:01:33.552047 | 2026-09-12 04:01:33.552134 | TASK [upload-logs : Create log directories] 2026-09-12 04:01:33.953983 | localhost | changed 2026-09-12 04:01:33.963260 | 2026-09-12 04:01:33.963403 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-12 04:01:34.391748 | localhost -> localhost | ok: Runtime: 0:00:00.005794 2026-09-12 04:01:34.401901 | 2026-09-12 04:01:34.402031 | TASK [upload-logs : Upload logs to log server] 2026-09-12 04:01:34.935446 | localhost | Output suppressed because no_log was given 2026-09-12 04:01:34.979989 | 2026-09-12 04:01:34.980127 | LOOP [upload-logs : Compress console log and json output] 2026-09-12 04:01:35.033859 | localhost | skipping: Conditional result was False 2026-09-12 04:01:35.042296 | localhost | skipping: Conditional result was False 2026-09-12 04:01:35.053288 | 2026-09-12 04:01:35.053500 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-12 04:01:35.114058 | localhost | skipping: Conditional result was False 2026-09-12 04:01:35.114460 | 2026-09-12 04:01:35.119234 | localhost | skipping: Conditional result was False 2026-09-12 04:01:35.129420 | 2026-09-12 04:01:35.129621 | LOOP [upload-logs : Upload console log and json output]