2026-09-09 06:04:20.756401 | Job console starting 2026-09-09 06:04:20.768097 | Updating git repos 2026-09-09 06:04:25.984516 | Cloning repos into workspace 2026-09-09 06:04:43.555174 | Restoring repo states 2026-09-09 06:04:49.261391 | Merging changes 2026-09-09 06:04:50.901203 | Checking out repos 2026-09-09 06:04:53.194130 | Preparing playbooks 2026-09-09 06:04:55.883969 | Running Ansible setup 2026-09-09 06:04:59.441306 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-09 06:04:59.960379 | 2026-09-09 06:04:59.960750 | PLAY [localhost] 2026-09-09 06:04:59.967490 | 2026-09-09 06:04:59.967552 | TASK [Gathering Facts] 2026-09-09 06:05:00.933840 | localhost | ok 2026-09-09 06:05:00.944891 | 2026-09-09 06:05:00.945051 | TASK [Setup log path fact] 2026-09-09 06:05:00.966605 | localhost | ok 2026-09-09 06:05:00.983869 | 2026-09-09 06:05:00.984184 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-09 06:05:01.014639 | localhost | ok 2026-09-09 06:05:01.024169 | 2026-09-09 06:05:01.024389 | TASK [emit-job-header : Print job information] 2026-09-09 06:05:01.064265 | # Job Information 2026-09-09 06:05:01.064449 | Ansible Version: 2.16.18 2026-09-09 06:05:01.064485 | Job: atmosphere-molecule-csi-rbd 2026-09-09 06:05:01.064540 | Pipeline: check 2026-09-09 06:05:01.064563 | Executor: 2d72f0692154 2026-09-09 06:05:01.064584 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-09-09 06:05:01.064610 | Event ID: 8e998d60-ac13-11f1-90f3-dd0ae7128642 2026-09-09 06:05:01.067806 | 2026-09-09 06:05:01.067879 | LOOP [emit-job-header : Print node information] 2026-09-09 06:05:01.179410 | localhost | ok: 2026-09-09 06:05:01.179620 | localhost | # Node Information 2026-09-09 06:05:01.179664 | localhost | Inventory Hostname: instance 2026-09-09 06:05:01.179697 | localhost | Hostname: np0000192341 2026-09-09 06:05:01.179725 | localhost | Username: zuul 2026-09-09 06:05:01.179759 | localhost | Distro: Ubuntu 22.04 2026-09-09 06:05:01.179790 | localhost | Provider: yul1 2026-09-09 06:05:01.179822 | localhost | Region: ca-ymq-1 2026-09-09 06:05:01.179853 | localhost | Label: ubuntu-jammy 2026-09-09 06:05:01.179885 | localhost | Product Name: OpenStack Nova 2026-09-09 06:05:01.179918 | localhost | Interface IP: 162.253.55.213 2026-09-09 06:05:01.198131 | 2026-09-09 06:05:01.198535 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-09 06:05:01.870502 | localhost -> localhost | changed 2026-09-09 06:05:01.881217 | 2026-09-09 06:05:01.881413 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-09 06:05:02.868600 | localhost -> localhost | changed 2026-09-09 06:05:02.876088 | 2026-09-09 06:05:02.876160 | PLAY [all] 2026-09-09 06:05:02.899873 | 2026-09-09 06:05:02.900103 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-09 06:05:03.180803 | instance -> localhost | ok 2026-09-09 06:05:03.186408 | 2026-09-09 06:05:03.186474 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-09 06:05:03.206077 | instance | ok 2026-09-09 06:05:03.218565 | instance | included: /var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-09 06:05:03.224098 | 2026-09-09 06:05:03.224180 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-09 06:05:04.367035 | instance -> localhost | Generating public/private rsa key pair. 2026-09-09 06:05:04.367356 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/work/e8a9c8dd59844ca2861acb2de7ce0743_id_rsa 2026-09-09 06:05:04.367385 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/work/e8a9c8dd59844ca2861acb2de7ce0743_id_rsa.pub 2026-09-09 06:05:04.367405 | instance -> localhost | The key fingerprint is: 2026-09-09 06:05:04.367424 | instance -> localhost | SHA256:BiuITm66YKGwzQbNDEMdmK3El4JzMp1TNhzyoaerWJ4 zuul-build-sshkey 2026-09-09 06:05:04.367457 | instance -> localhost | The key's randomart image is: 2026-09-09 06:05:04.367476 | instance -> localhost | +---[RSA 3072]----+ 2026-09-09 06:05:04.367495 | instance -> localhost | |oo*=O. | 2026-09-09 06:05:04.367514 | instance -> localhost | |**=X.o | 2026-09-09 06:05:04.367531 | instance -> localhost | |+==.o . | 2026-09-09 06:05:04.367549 | instance -> localhost | | O + o | 2026-09-09 06:05:04.367566 | instance -> localhost | |+o* . . S | 2026-09-09 06:05:04.367583 | instance -> localhost | |*=.. . . | 2026-09-09 06:05:04.367600 | instance -> localhost | |+=* | 2026-09-09 06:05:04.367616 | instance -> localhost | |*= . | 2026-09-09 06:05:04.367636 | instance -> localhost | |=.E | 2026-09-09 06:05:04.367654 | instance -> localhost | +----[SHA256]-----+ 2026-09-09 06:05:04.367698 | instance -> localhost | ok: Runtime: 0:00:00.454270 2026-09-09 06:05:04.372806 | 2026-09-09 06:05:04.372865 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-09 06:05:04.399341 | instance | ok 2026-09-09 06:05:04.406356 | instance | included: /var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-09 06:05:04.412645 | 2026-09-09 06:05:04.412704 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-09 06:05:04.435467 | instance | skipping: Conditional result was False 2026-09-09 06:05:04.440721 | 2026-09-09 06:05:04.440786 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-09 06:05:04.867342 | instance | changed 2026-09-09 06:05:04.873036 | 2026-09-09 06:05:04.873094 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-09 06:05:05.089306 | instance | ok 2026-09-09 06:05:05.096593 | 2026-09-09 06:05:05.096698 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-09 06:05:05.614119 | instance | changed 2026-09-09 06:05:05.690695 | 2026-09-09 06:05:05.690843 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-09 06:05:06.257432 | instance | changed 2026-09-09 06:05:06.346815 | 2026-09-09 06:05:06.346976 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-09 06:05:06.370823 | instance | skipping: Conditional result was False 2026-09-09 06:05:06.380943 | 2026-09-09 06:05:06.381099 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-09 06:05:06.832170 | instance -> localhost | changed 2026-09-09 06:05:06.850010 | 2026-09-09 06:05:06.850171 | TASK [add-build-sshkey : Add back temp key] 2026-09-09 06:05:07.370018 | instance -> localhost | Identity added: /var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/work/e8a9c8dd59844ca2861acb2de7ce0743_id_rsa (zuul-build-sshkey) 2026-09-09 06:05:07.370320 | instance -> localhost | ok: Runtime: 0:00:00.018679 2026-09-09 06:05:07.383788 | 2026-09-09 06:05:07.383968 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-09 06:05:07.718883 | instance | ok 2026-09-09 06:05:07.727153 | 2026-09-09 06:05:07.727271 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-09 06:05:07.753209 | instance | skipping: Conditional result was False 2026-09-09 06:05:07.775632 | 2026-09-09 06:05:07.775750 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-09 06:05:08.171484 | instance | ok 2026-09-09 06:05:08.184155 | 2026-09-09 06:05:08.184328 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-09 06:05:21.064174 | instance | Output suppressed because no_log was given 2026-09-09 06:05:21.137992 | 2026-09-09 06:05:21.138185 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-09 06:05:21.348257 | instance | ok: "logs" 2026-09-09 06:05:21.348623 | instance | ok: All items complete 2026-09-09 06:05:21.348664 | 2026-09-09 06:05:21.475129 | instance | ok: "artifacts" 2026-09-09 06:05:21.624596 | instance | ok: "docs" 2026-09-09 06:05:21.641955 | 2026-09-09 06:05:21.642203 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-09 06:05:21.832198 | instance | changed: "logs" 2026-09-09 06:05:22.021508 | instance | changed: "artifacts" 2026-09-09 06:05:22.172827 | instance | changed: "docs" 2026-09-09 06:05:22.185365 | 2026-09-09 06:05:22.185529 | PLAY RECAP 2026-09-09 06:05:22.185601 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-09 06:05:22.185639 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-09 06:05:22.185667 | 2026-09-09 06:05:22.360431 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-09 06:05:22.362408 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-09 06:05:23.051998 | 2026-09-09 06:05:23.052171 | PLAY [all] 2026-09-09 06:05:23.065803 | 2026-09-09 06:05:23.065946 | TASK [setup-uv : Extract archive] 2026-09-09 06:05:25.388365 | instance | changed 2026-09-09 06:05:25.483577 | 2026-09-09 06:05:25.483734 | TASK [setup-uv : Print version] 2026-09-09 06:05:25.909760 | instance | uv 0.8.13 2026-09-09 06:05:26.016887 | instance | ok: Runtime: 0:00:00.012951 2026-09-09 06:05:26.024573 | 2026-09-09 06:05:26.024639 | TASK [Install Ansible collection siblings] 2026-09-09 06:05:35.398348 | instance | changed: 2026-09-09 06:05:35.398550 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-09 06:05:35.398581 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-09 06:05:35.398602 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-09 06:05:35.398622 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-09 06:05:35.398641 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-09 06:05:35.398663 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-09 06:05:35.398685 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-09 06:05:35.398706 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-09 06:05:35.398725 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-09 06:05:35.398743 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-09 06:05:35.398760 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-09 06:05:35.398782 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-09 06:05:35.398810 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-09 06:05:35.398831 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-09 06:05:35.398848 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-09 06:05:35.398867 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-09 06:05:35.398884 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-09 06:05:35.398903 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-09 06:05:35.398921 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-09 06:05:35.398938 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-09 06:05:35.398957 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-09 06:05:35.398989 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-09 06:05:35.399011 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-09 06:05:35.399045 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-09 06:05:35.399063 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-09 06:05:35.399081 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-09 06:05:35.399099 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-09 06:05:35.399116 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-09 06:05:35.399134 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-09 06:05:35.399150 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-09 06:05:35.399168 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-09 06:05:35.399185 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-09 06:05:35.399202 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-09 06:05:35.399221 | 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-09 06:05:35.399247 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.399264 | instance | Process install dependency map 2026-09-09 06:05:35.399282 | instance | Starting collection install process 2026-09-09 06:05:35.399300 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-09 06:05:35.399317 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-09 06:05:35.399335 | instance | ansible.netcommon:* was installed successfully 2026-09-09 06:05:35.399353 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-09 06:05:35.399370 | instance | Creating virtual environment at: .venv 2026-09-09 06:05:35.399388 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-09 06:05:35.399406 | instance | Downloading cryptography (4.2MiB) 2026-09-09 06:05:35.399424 | instance | Downloading netaddr (2.2MiB) 2026-09-09 06:05:35.399447 | instance | Downloading pydantic-core (2.0MiB) 2026-09-09 06:05:35.399464 | instance | Downloading openstacksdk (1.7MiB) 2026-09-09 06:05:35.399481 | instance | Downloading setuptools (1.1MiB) 2026-09-09 06:05:35.399499 | instance | Downloading kubernetes (1.9MiB) 2026-09-09 06:05:35.399517 | instance | Downloading ansible-core (2.1MiB) 2026-09-09 06:05:35.399535 | instance | Downloading rjsonnet (1.2MiB) 2026-09-09 06:05:35.399553 | instance | Downloading pygments (1.2MiB) 2026-09-09 06:05:35.399570 | instance | Building pyperclip==1.9.0 2026-09-09 06:05:35.399589 | instance | Downloading rjsonnet 2026-09-09 06:05:35.399607 | instance | Downloading pydantic-core 2026-09-09 06:05:35.399624 | instance | Downloading netaddr 2026-09-09 06:05:35.399642 | instance | Downloading pygments 2026-09-09 06:05:35.399660 | instance | Downloading cryptography 2026-09-09 06:05:35.399677 | instance | Downloading setuptools 2026-09-09 06:05:35.399693 | instance | Downloading kubernetes 2026-09-09 06:05:35.399711 | instance | Downloading ansible-core 2026-09-09 06:05:35.399728 | instance | Downloading openstacksdk 2026-09-09 06:05:35.399746 | instance | Built pyperclip==1.9.0 2026-09-09 06:05:35.399762 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-09 06:05:35.399780 | instance | Installed 83 packages in 51ms 2026-09-09 06:05:35.399800 | instance | Checked out ansible.posix to 1.6.0 2026-09-09 06:05:35.399824 | 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-09 06:05:35.399845 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.399864 | instance | Process install dependency map 2026-09-09 06:05:35.399890 | instance | Starting collection install process 2026-09-09 06:05:35.399914 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-09 06:05:35.399935 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-09 06:05:35.399956 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-09 06:05:35.399992 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.400012 | instance | 2.17.12 2026-09-09 06:05:35.400028 | instance | Checked out ansible.utils to v6.1.0 2026-09-09 06:05:35.400045 | 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-09 06:05:35.400063 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.400080 | instance | Process install dependency map 2026-09-09 06:05:35.400098 | instance | Starting collection install process 2026-09-09 06:05:35.400116 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-09 06:05:35.400133 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-09 06:05:35.400151 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-09 06:05:35.400169 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.400185 | instance | 2.17.12 2026-09-09 06:05:35.400202 | instance | Checked out atmosphere.common to v0.7.0 2026-09-09 06:05:35.400218 | 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-09 06:05:35.400236 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.400253 | instance | Process install dependency map 2026-09-09 06:05:35.400269 | instance | Starting collection install process 2026-09-09 06:05:35.400287 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-09 06:05:35.400303 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-09 06:05:35.400320 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-09 06:05:35.400335 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.400352 | instance | 2.17.12 2026-09-09 06:05:35.400370 | instance | Checked out community.crypto to 2.2.3 2026-09-09 06:05:35.400388 | 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-09 06:05:35.400405 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.400420 | instance | Process install dependency map 2026-09-09 06:05:35.400436 | instance | Starting collection install process 2026-09-09 06:05:35.400453 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-09 06:05:35.400470 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-09 06:05:35.400508 | instance | community.crypto:2.2.3 was installed successfully 2026-09-09 06:05:35.400530 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.400547 | instance | 2.17.12 2026-09-09 06:05:35.400564 | instance | Checked out community.general to 7.3.0 2026-09-09 06:05:35.400581 | 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-09 06:05:35.400606 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.400625 | instance | Process install dependency map 2026-09-09 06:05:35.400642 | instance | Starting collection install process 2026-09-09 06:05:35.400660 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-09 06:05:35.400677 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-09 06:05:35.400763 | instance | community.general:7.3.0 was installed successfully 2026-09-09 06:05:35.400787 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.400806 | instance | 2.17.12 2026-09-09 06:05:35.400826 | instance | Checked out community.mysql to 3.6.0 2026-09-09 06:05:35.400854 | 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-09 06:05:35.400874 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.400891 | instance | Process install dependency map 2026-09-09 06:05:35.400909 | instance | Starting collection install process 2026-09-09 06:05:35.400927 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-09 06:05:35.400945 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-09 06:05:35.400963 | instance | community.mysql:3.6.0 was installed successfully 2026-09-09 06:05:35.400998 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.401018 | instance | 2.17.12 2026-09-09 06:05:35.401035 | instance | Checked out kubernetes.core to 2.4.0 2026-09-09 06:05:35.401053 | 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-09 06:05:35.401071 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.401089 | instance | Process install dependency map 2026-09-09 06:05:35.401106 | instance | Starting collection install process 2026-09-09 06:05:35.401123 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-09 06:05:35.401140 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-09 06:05:35.401157 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-09 06:05:35.401175 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.401192 | instance | 2.17.12 2026-09-09 06:05:35.401209 | instance | Checked out openstack.cloud to 2.6.0 2026-09-09 06:05:35.401225 | 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-09 06:05:35.401243 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.401259 | instance | Process install dependency map 2026-09-09 06:05:35.401275 | instance | Starting collection install process 2026-09-09 06:05:35.401291 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-09 06:05:35.401308 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-09 06:05:35.401324 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-09 06:05:35.401340 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.401356 | instance | 2.17.12 2026-09-09 06:05:35.401383 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-09 06:05:35.401400 | 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-09 06:05:35.401418 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.401436 | instance | Process install dependency map 2026-09-09 06:05:35.401452 | instance | Starting collection install process 2026-09-09 06:05:35.401470 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-09 06:05:35.401486 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-09 06:05:35.401503 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-09 06:05:35.401519 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.401535 | instance | 2.17.12 2026-09-09 06:05:35.401550 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-09 06:05:35.401566 | 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-09 06:05:35.401584 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.401601 | instance | Process install dependency map 2026-09-09 06:05:35.401617 | instance | Starting collection install process 2026-09-09 06:05:35.401634 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-09 06:05:35.401652 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-09 06:05:35.401669 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-09 06:05:35.401685 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.401701 | instance | 2.17.12 2026-09-09 06:05:35.401718 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-09 06:05:35.401734 | 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-09 06:05:35.401750 | instance | Starting galaxy collection install process 2026-09-09 06:05:35.401766 | instance | Process install dependency map 2026-09-09 06:05:35.401781 | instance | Starting collection install process 2026-09-09 06:05:35.401797 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-09 06:05:35.401813 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-09 06:05:35.401830 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-09 06:05:35.401850 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-09 06:05:35.401875 | instance | 2.17.12 2026-09-09 06:05:35.407605 | 2026-09-09 06:05:35.407663 | PLAY RECAP 2026-09-09 06:05:35.407711 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-09 06:05:35.407732 | 2026-09-09 06:05:35.584891 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-09 06:05:35.588373 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-09 06:05:36.183540 | 2026-09-09 06:05:36.183655 | PLAY [all] 2026-09-09 06:05:36.194088 | 2026-09-09 06:05:36.194171 | TASK [Install "jq" for log collection] 2026-09-09 06:05:45.991605 | instance | changed 2026-09-09 06:05:45.993311 | 2026-09-09 06:05:45.993377 | PLAY RECAP 2026-09-09 06:05:45.993428 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-09 06:05:45.993489 | 2026-09-09 06:05:46.098901 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-09 06:05:46.110128 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-09 06:05:46.739187 | 2026-09-09 06:05:46.739352 | PLAY [all] 2026-09-09 06:05:46.750848 | 2026-09-09 06:05:46.751017 | LOOP [Check inventory directories] 2026-09-09 06:05:47.096388 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-09 06:05:47.231987 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-09 06:05:47.358592 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-09 06:05:47.366113 | 2026-09-09 06:05:47.366295 | LOOP [Copy inventory file for Zuul] 2026-09-09 06:05:48.178051 | instance | changed: 2026-09-09 06:05:48.178277 | instance | { 2026-09-09 06:05:48.178312 | instance | "ansible_loop_var": "item", 2026-09-09 06:05:48.178335 | instance | "changed": false, 2026-09-09 06:05:48.178355 | instance | "failed": false, 2026-09-09 06:05:48.178378 | instance | "invocation": { 2026-09-09 06:05:48.178395 | instance | "module_args": { 2026-09-09 06:05:48.178412 | instance | "checksum_algorithm": "sha1", 2026-09-09 06:05:48.178428 | instance | "follow": true, 2026-09-09 06:05:48.178444 | instance | "get_attributes": true, 2026-09-09 06:05:48.178462 | instance | "get_checksum": true, 2026-09-09 06:05:48.178479 | instance | "get_mime": true, 2026-09-09 06:05:48.178495 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-09 06:05:48.178512 | instance | } 2026-09-09 06:05:48.178528 | instance | }, 2026-09-09 06:05:48.178544 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-09 06:05:48.178560 | instance | "stat": { 2026-09-09 06:05:48.178577 | instance | "atime": 1788933928.6167955, 2026-09-09 06:05:48.178663 | instance | "attr_flags": "e", 2026-09-09 06:05:48.178691 | instance | "attributes": [ 2026-09-09 06:05:48.178709 | instance | "extents" 2026-09-09 06:05:48.178725 | instance | ], 2026-09-09 06:05:48.178741 | instance | "block_size": 4096, 2026-09-09 06:05:48.178757 | instance | "blocks": 8, 2026-09-09 06:05:48.178773 | instance | "charset": "unknown", 2026-09-09 06:05:48.178791 | instance | "ctime": 1788933926.9727018, 2026-09-09 06:05:48.178808 | instance | "dev": 64513, 2026-09-09 06:05:48.178825 | instance | "device_type": 0, 2026-09-09 06:05:48.178841 | instance | "executable": true, 2026-09-09 06:05:48.178856 | instance | "exists": true, 2026-09-09 06:05:48.178872 | instance | "gid": 1000, 2026-09-09 06:05:48.178887 | instance | "gr_name": "zuul", 2026-09-09 06:05:48.178902 | instance | "inode": 1047152, 2026-09-09 06:05:48.178918 | instance | "isblk": false, 2026-09-09 06:05:48.178935 | instance | "ischr": false, 2026-09-09 06:05:48.178950 | instance | "isdir": true, 2026-09-09 06:05:48.178965 | instance | "isfifo": false, 2026-09-09 06:05:48.179000 | instance | "isgid": false, 2026-09-09 06:05:48.179016 | instance | "islnk": false, 2026-09-09 06:05:48.179031 | instance | "isreg": false, 2026-09-09 06:05:48.179045 | instance | "issock": false, 2026-09-09 06:05:48.179060 | instance | "isuid": false, 2026-09-09 06:05:48.179074 | instance | "mimetype": "unknown", 2026-09-09 06:05:48.179090 | instance | "mode": "0755", 2026-09-09 06:05:48.179105 | instance | "mtime": 1788933926.9727018, 2026-09-09 06:05:48.179120 | instance | "nlink": 23, 2026-09-09 06:05:48.179135 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-09 06:05:48.179151 | instance | "pw_name": "zuul", 2026-09-09 06:05:48.179173 | instance | "readable": true, 2026-09-09 06:05:48.179197 | instance | "rgrp": true, 2026-09-09 06:05:48.179214 | instance | "roth": true, 2026-09-09 06:05:48.179246 | instance | "rusr": true, 2026-09-09 06:05:48.179262 | instance | "size": 4096, 2026-09-09 06:05:48.179278 | instance | "uid": 1000, 2026-09-09 06:05:48.179292 | instance | "version": "2575867891", 2026-09-09 06:05:48.179308 | instance | "wgrp": false, 2026-09-09 06:05:48.179325 | instance | "woth": false, 2026-09-09 06:05:48.179341 | instance | "writeable": true, 2026-09-09 06:05:48.179356 | instance | "wusr": true, 2026-09-09 06:05:48.179371 | instance | "xgrp": true, 2026-09-09 06:05:48.179386 | instance | "xoth": true, 2026-09-09 06:05:48.179402 | instance | "xusr": true 2026-09-09 06:05:48.179417 | instance | } 2026-09-09 06:05:48.179434 | instance | } 2026-09-09 06:05:48.179473 | instance | ok: All items complete 2026-09-09 06:05:48.179493 | 2026-09-09 06:05:48.180721 | instance | skipping: Conditional result was False 2026-09-09 06:05:48.181274 | instance | skipping: Conditional result was False 2026-09-09 06:05:48.193579 | 2026-09-09 06:05:48.194038 | LOOP [Switch "ansible_host" to private IP] 2026-09-09 06:05:48.615639 | instance | changed: 1 replacements made 2026-09-09 06:05:48.617956 | instance | skipping: Conditional result was False 2026-09-09 06:05:48.618697 | instance | skipping: Conditional result was False 2026-09-09 06:05:48.631113 | 2026-09-09 06:05:48.631281 | TASK [Run Molecule scenario] 2026-09-09 06:05:49.506186 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-09 06:05:49.933123 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-09 06:05:49.933208 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-09 06:06:00.345567 | instance | INFO [csi > dependency] Executing 2026-09-09 06:06:00.345936 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-09 06:06:00.346365 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-09 06:06:00.346625 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-09 06:06:00.361691 | instance | INFO [csi > cleanup] Executing 2026-09-09 06:06:00.362280 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-09 06:06:00.375290 | instance | INFO [csi > destroy] Executing 2026-09-09 06:06:00.375382 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-09 06:06:00.375497 | instance | INFO [csi > destroy] Executed: Successful 2026-09-09 06:06:00.384684 | instance | INFO [csi > syntax] Executing 2026-09-09 06:06:01.833440 | instance | 2026-09-09 06:06:01.833745 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-09 06:06:01.891285 | instance | INFO [csi > syntax] Executed: Successful 2026-09-09 06:06:01.908569 | instance | INFO [csi > create] Executing 2026-09-09 06:06:01.912471 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-09 06:06:01.928697 | instance | INFO [csi > prepare] Executing 2026-09-09 06:06:02.754641 | instance | 2026-09-09 06:06:02.754875 | instance | PLAY [Prepare] ***************************************************************** 2026-09-09 06:06:02.755115 | instance | 2026-09-09 06:06:02.755382 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-09 06:06:02.755597 | instance | Wednesday 09 September 2026 06:06:02 +0000 (0:00:00.024) 0:00:00.024 *** 2026-09-09 06:06:04.013539 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-09 06:06:04.013636 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-09 06:06:04.013825 | instance | interpreter could change the meaning of that path. See 2026-09-09 06:06:04.013998 | instance | https://docs.ansible.com/ansible- 2026-09-09 06:06:04.014177 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-09 06:06:04.025290 | instance | ok: [instance] 2026-09-09 06:06:04.025357 | instance | 2026-09-09 06:06:04.025494 | instance | TASK [Configure short hostname] ************************************************ 2026-09-09 06:06:04.025597 | instance | Wednesday 09 September 2026 06:06:04 +0000 (0:00:01.271) 0:00:01.296 *** 2026-09-09 06:06:04.798442 | instance | changed: [instance] 2026-09-09 06:06:04.798730 | instance | 2026-09-09 06:06:04.798862 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-09 06:06:04.799172 | instance | Wednesday 09 September 2026 06:06:04 +0000 (0:00:00.772) 0:00:02.068 *** 2026-09-09 06:06:05.068346 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-09 06:06:05.068398 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-09 06:06:05.068575 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-09 06:06:05.079402 | instance | changed: [instance] 2026-09-09 06:06:05.079656 | instance | 2026-09-09 06:06:05.079906 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-09 06:06:05.080090 | instance | Wednesday 09 September 2026 06:06:05 +0000 (0:00:00.281) 0:00:02.349 *** 2026-09-09 06:06:05.886687 | instance | ok: [instance] 2026-09-09 06:06:05.886890 | instance | 2026-09-09 06:06:05.887166 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-09 06:06:05.887429 | instance | 2026-09-09 06:06:05.887646 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-09 06:06:05.887873 | instance | Wednesday 09 September 2026 06:06:05 +0000 (0:00:00.807) 0:00:03.157 *** 2026-09-09 06:06:06.648181 | instance | ok: [instance] 2026-09-09 06:06:06.648425 | instance | 2026-09-09 06:06:06.648673 | instance | TASK [Install depedencies] ***************************************************** 2026-09-09 06:06:06.648912 | instance | Wednesday 09 September 2026 06:06:06 +0000 (0:00:00.761) 0:00:03.918 *** 2026-09-09 06:06:30.090792 | instance | changed: [instance] 2026-09-09 06:06:30.090866 | instance | 2026-09-09 06:06:30.091132 | instance | TASK [Start up service] ******************************************************** 2026-09-09 06:06:30.091377 | instance | Wednesday 09 September 2026 06:06:30 +0000 (0:00:23.442) 0:00:27.361 *** 2026-09-09 06:06:30.610908 | instance | ok: [instance] 2026-09-09 06:06:30.611073 | instance | 2026-09-09 06:06:30.611254 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-09 06:06:30.611451 | instance | Wednesday 09 September 2026 06:06:30 +0000 (0:00:00.520) 0:00:27.881 *** 2026-09-09 06:06:30.929474 | instance | ok: [instance] 2026-09-09 06:06:30.929607 | instance | 2026-09-09 06:06:30.929766 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-09 06:06:30.929923 | instance | Wednesday 09 September 2026 06:06:30 +0000 (0:00:00.318) 0:00:28.199 *** 2026-09-09 06:06:31.629444 | instance | changed: [instance] 2026-09-09 06:06:31.630184 | instance | 2026-09-09 06:06:31.630238 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-09 06:06:31.630250 | instance | Wednesday 09 September 2026 06:06:31 +0000 (0:00:00.699) 0:00:28.899 *** 2026-09-09 06:06:31.838265 | instance | ok: [instance] 2026-09-09 06:06:31.839060 | instance | 2026-09-09 06:06:31.839146 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-09 06:06:31.839169 | instance | Wednesday 09 September 2026 06:06:31 +0000 (0:00:00.208) 0:00:29.108 *** 2026-09-09 06:06:31.855447 | instance | skipping: [instance] 2026-09-09 06:06:31.855889 | instance | 2026-09-09 06:06:31.855919 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-09 06:06:31.855928 | instance | Wednesday 09 September 2026 06:06:31 +0000 (0:00:00.017) 0:00:29.126 *** 2026-09-09 06:06:32.427785 | instance | changed: [instance] => (item=osd0) 2026-09-09 06:06:32.429009 | instance | changed: [instance] => (item=osd1) 2026-09-09 06:06:32.429062 | instance | changed: [instance] => (item=osd2) 2026-09-09 06:06:32.429073 | instance | 2026-09-09 06:06:32.429083 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-09 06:06:32.429093 | instance | Wednesday 09 September 2026 06:06:32 +0000 (0:00:00.571) 0:00:29.697 *** 2026-09-09 06:06:33.076780 | instance | changed: [instance] => (item=osd0) 2026-09-09 06:06:33.077065 | instance | changed: [instance] => (item=osd1) 2026-09-09 06:06:33.077445 | instance | changed: [instance] => (item=osd2) 2026-09-09 06:06:33.077800 | instance | 2026-09-09 06:06:33.078124 | instance | TASK [Start loop devices] ****************************************************** 2026-09-09 06:06:33.078440 | instance | Wednesday 09 September 2026 06:06:33 +0000 (0:00:00.648) 0:00:30.346 *** 2026-09-09 06:06:33.844766 | instance | changed: [instance] => (item=osd0) 2026-09-09 06:06:33.844822 | instance | changed: [instance] => (item=osd1) 2026-09-09 06:06:33.844833 | instance | changed: [instance] => (item=osd2) 2026-09-09 06:06:33.844842 | instance | 2026-09-09 06:06:33.844852 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-09 06:06:33.844861 | instance | Wednesday 09 September 2026 06:06:33 +0000 (0:00:00.766) 0:00:31.113 *** 2026-09-09 06:06:37.193897 | instance | changed: [instance] => (item=osd0) 2026-09-09 06:06:37.194128 | instance | changed: [instance] => (item=osd1) 2026-09-09 06:06:37.195039 | instance | changed: [instance] => (item=osd2) 2026-09-09 06:06:37.195106 | instance | 2026-09-09 06:06:37.195117 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-09 06:06:37.195133 | instance | Wednesday 09 September 2026 06:06:37 +0000 (0:00:03.350) 0:00:34.464 *** 2026-09-09 06:06:39.076470 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-09 06:06:39.079015 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-09 06:06:39.079066 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-09 06:06:39.079079 | instance | 2026-09-09 06:06:39.079090 | instance | PLAY RECAP ********************************************************************* 2026-09-09 06:06:39.079100 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-09 06:06:39.079109 | instance | 2026-09-09 06:06:39.079118 | instance | Wednesday 09 September 2026 06:06:39 +0000 (0:00:01.883) 0:00:36.347 *** 2026-09-09 06:06:39.079127 | instance | =============================================================================== 2026-09-09 06:06:39.079136 | instance | Install depedencies ---------------------------------------------------- 23.44s 2026-09-09 06:06:39.079270 | instance | Create a volume group for each loop device ------------------------------ 3.35s 2026-09-09 06:06:39.079746 | instance | Create a logical volume for each loop device ---------------------------- 1.88s 2026-09-09 06:06:39.080075 | instance | Gathering Facts --------------------------------------------------------- 1.27s 2026-09-09 06:06:39.080495 | instance | Purge "snapd" package --------------------------------------------------- 0.81s 2026-09-09 06:06:39.080912 | instance | Configure short hostname ------------------------------------------------ 0.77s 2026-09-09 06:06:39.081222 | instance | Start loop devices ------------------------------------------------------ 0.77s 2026-09-09 06:06:39.081537 | instance | Gathering Facts --------------------------------------------------------- 0.76s 2026-09-09 06:06:39.081826 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.70s 2026-09-09 06:06:39.082113 | instance | Set permissions on loopback devices ------------------------------------- 0.65s 2026-09-09 06:06:39.082415 | instance | Create devices for Ceph ------------------------------------------------- 0.57s 2026-09-09 06:06:39.082704 | instance | Start up service -------------------------------------------------------- 0.52s 2026-09-09 06:06:39.083022 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s 2026-09-09 06:06:39.083315 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s 2026-09-09 06:06:39.083626 | instance | Get list of all loopback devices ---------------------------------------- 0.21s 2026-09-09 06:06:39.083915 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-09 06:06:39.169212 | instance | INFO [csi > prepare] Executed: Successful 2026-09-09 06:06:39.185295 | instance | INFO [csi > converge] Executing 2026-09-09 06:06:40.515171 | instance | 2026-09-09 06:06:40.515631 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-09 06:06:40.515946 | instance | 2026-09-09 06:06:40.516276 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-09 06:06:40.516593 | instance | Wednesday 09 September 2026 06:06:40 +0000 (0:00:00.006) 0:00:00.006 *** 2026-09-09 06:06:41.514286 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-09 06:06:41.514865 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-09 06:06:41.515403 | instance | interpreter could change the meaning of that path. See 2026-09-09 06:06:41.515872 | instance | https://docs.ansible.com/ansible- 2026-09-09 06:06:41.516323 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-09 06:06:41.529009 | instance | ok: [instance] 2026-09-09 06:06:41.529457 | instance | 2026-09-09 06:06:41.529889 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-09 06:06:41.530318 | instance | Wednesday 09 September 2026 06:06:41 +0000 (0:00:01.014) 0:00:01.020 *** 2026-09-09 06:06:41.591014 | instance | ok: [instance] => { 2026-09-09 06:06:41.591510 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-09 06:06:41.591920 | instance | } 2026-09-09 06:06:41.592313 | instance | 2026-09-09 06:06:41.592787 | instance | PLAY [all] ********************************************************************* 2026-09-09 06:06:41.593207 | instance | 2026-09-09 06:06:41.593628 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-09 06:06:41.594026 | instance | Wednesday 09 September 2026 06:06:41 +0000 (0:00:00.062) 0:00:01.083 *** 2026-09-09 06:06:42.569150 | instance | ok: [instance] 2026-09-09 06:06:42.569592 | instance | 2026-09-09 06:06:42.569999 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-09 06:06:42.570314 | instance | Wednesday 09 September 2026 06:06:42 +0000 (0:00:00.977) 0:00:02.060 *** 2026-09-09 06:06:42.618098 | instance | skipping: [instance] 2026-09-09 06:06:42.618449 | instance | 2026-09-09 06:06:42.618763 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-09 06:06:42.619102 | instance | Wednesday 09 September 2026 06:06:42 +0000 (0:00:00.049) 0:00:02.110 *** 2026-09-09 06:06:42.834180 | instance | ok: [instance] 2026-09-09 06:06:42.834564 | instance | 2026-09-09 06:06:42.834873 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-09 06:06:42.835219 | instance | 2026-09-09 06:06:42.835553 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-09 06:06:42.835870 | instance | Wednesday 09 September 2026 06:06:42 +0000 (0:00:00.216) 0:00:02.326 *** 2026-09-09 06:06:43.809019 | instance | ok: [instance] 2026-09-09 06:06:43.809074 | instance | 2026-09-09 06:06:43.809085 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-09 06:06:43.809095 | instance | Wednesday 09 September 2026 06:06:43 +0000 (0:00:00.973) 0:00:03.300 *** 2026-09-09 06:06:44.168981 | instance | ok: [instance] 2026-09-09 06:06:44.169733 | instance | 2026-09-09 06:06:44.169775 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-09 06:06:44.169784 | instance | Wednesday 09 September 2026 06:06:44 +0000 (0:00:00.360) 0:00:03.660 *** 2026-09-09 06:06:44.224055 | instance | skipping: [instance] 2026-09-09 06:06:44.224249 | instance | 2026-09-09 06:06:44.224615 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-09 06:06:44.224946 | instance | Wednesday 09 September 2026 06:06:44 +0000 (0:00:00.055) 0:00:03.715 *** 2026-09-09 06:06:44.589037 | instance | changed: [instance] 2026-09-09 06:06:44.589260 | instance | 2026-09-09 06:06:44.589653 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-09 06:06:44.590026 | instance | Wednesday 09 September 2026 06:06:44 +0000 (0:00:00.364) 0:00:04.080 *** 2026-09-09 06:06:44.682176 | instance | ok: [instance] => { 2026-09-09 06:06:44.682414 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-09 06:06:44.682732 | instance | } 2026-09-09 06:06:44.683037 | instance | 2026-09-09 06:06:44.683357 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-09 06:06:44.683799 | instance | Wednesday 09 September 2026 06:06:44 +0000 (0:00:00.093) 0:00:04.174 *** 2026-09-09 06:06:45.375749 | instance | changed: [instance] 2026-09-09 06:06:45.376033 | instance | 2026-09-09 06:06:45.376434 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-09 06:06:45.376876 | instance | Wednesday 09 September 2026 06:06:45 +0000 (0:00:00.689) 0:00:04.863 *** 2026-09-09 06:06:45.435582 | instance | skipping: [instance] 2026-09-09 06:06:45.435685 | instance | 2026-09-09 06:06:45.435922 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-09 06:06:45.436120 | instance | Wednesday 09 September 2026 06:06:45 +0000 (0:00:00.062) 0:00:04.926 *** 2026-09-09 06:06:45.498183 | instance | skipping: [instance] 2026-09-09 06:06:45.498419 | instance | 2026-09-09 06:06:45.498619 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-09 06:06:45.498826 | instance | Wednesday 09 September 2026 06:06:45 +0000 (0:00:00.062) 0:00:04.988 *** 2026-09-09 06:06:45.759500 | instance | ok: [instance] 2026-09-09 06:06:45.759848 | instance | 2026-09-09 06:06:45.760154 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-09 06:06:45.760477 | instance | Wednesday 09 September 2026 06:06:45 +0000 (0:00:00.263) 0:00:05.251 *** 2026-09-09 06:06:46.984405 | instance | ok: [instance] 2026-09-09 06:06:46.984770 | instance | 2026-09-09 06:06:46.985083 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-09 06:06:46.985446 | instance | Wednesday 09 September 2026 06:06:46 +0000 (0:00:01.224) 0:00:06.476 *** 2026-09-09 06:06:47.074902 | instance | ok: [instance] => { 2026-09-09 06:06:47.075332 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-09 06:06:47.075638 | instance | } 2026-09-09 06:06:47.075917 | instance | 2026-09-09 06:06:47.076218 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-09 06:06:47.076544 | instance | Wednesday 09 September 2026 06:06:47 +0000 (0:00:00.090) 0:00:06.567 *** 2026-09-09 06:06:47.695263 | instance | changed: [instance] 2026-09-09 06:06:47.695460 | instance | 2026-09-09 06:06:47.695613 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-09 06:06:47.695754 | instance | Wednesday 09 September 2026 06:06:47 +0000 (0:00:00.620) 0:00:07.187 *** 2026-09-09 06:06:50.565023 | instance | changed: [instance] 2026-09-09 06:06:50.565199 | instance | 2026-09-09 06:06:50.565362 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-09 06:06:50.565505 | instance | Wednesday 09 September 2026 06:06:50 +0000 (0:00:02.870) 0:00:10.057 *** 2026-09-09 06:06:50.587435 | instance | skipping: [instance] 2026-09-09 06:06:50.587737 | instance | 2026-09-09 06:06:50.588317 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-09 06:06:50.588626 | instance | Wednesday 09 September 2026 06:06:50 +0000 (0:00:00.021) 0:00:10.079 *** 2026-09-09 06:06:50.609433 | instance | skipping: [instance] 2026-09-09 06:06:50.609611 | instance | 2026-09-09 06:06:50.609756 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-09 06:06:50.609895 | instance | Wednesday 09 September 2026 06:06:50 +0000 (0:00:00.021) 0:00:10.100 *** 2026-09-09 06:06:50.655121 | instance | skipping: [instance] 2026-09-09 06:06:50.655600 | instance | 2026-09-09 06:06:50.655817 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-09 06:06:50.656025 | instance | Wednesday 09 September 2026 06:06:50 +0000 (0:00:00.046) 0:00:10.147 *** 2026-09-09 06:06:56.160949 | instance | changed: [instance] 2026-09-09 06:06:56.161076 | instance | 2026-09-09 06:06:56.161087 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-09 06:06:56.161244 | instance | Wednesday 09 September 2026 06:06:56 +0000 (0:00:05.506) 0:00:15.653 *** 2026-09-09 06:06:56.806664 | instance | changed: [instance] 2026-09-09 06:06:56.807050 | instance | 2026-09-09 06:06:56.807559 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-09 06:06:56.807646 | instance | Wednesday 09 September 2026 06:06:56 +0000 (0:00:00.644) 0:00:16.297 *** 2026-09-09 06:06:57.802908 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-09 06:06:57.803093 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-09 06:06:57.803994 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-09 06:06:57.804057 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-09 06:06:57.804066 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-09 06:06:57.804073 | instance | 2026-09-09 06:06:57.804080 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-09 06:06:57.804086 | instance | Wednesday 09 September 2026 06:06:57 +0000 (0:00:00.997) 0:00:17.295 *** 2026-09-09 06:06:58.401427 | instance | changed: [instance] 2026-09-09 06:06:58.402606 | instance | 2026-09-09 06:06:58.402631 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-09 06:06:58.402641 | instance | Wednesday 09 September 2026 06:06:58 +0000 (0:00:00.572) 0:00:17.867 *** 2026-09-09 06:06:58.402648 | instance | 2026-09-09 06:06:58.402656 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-09 06:06:58.402663 | instance | Wednesday 09 September 2026 06:06:58 +0000 (0:00:00.026) 0:00:17.894 *** 2026-09-09 06:06:59.425829 | instance | ok: [instance] 2026-09-09 06:06:59.427008 | instance | 2026-09-09 06:06:59.427073 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-09 06:06:59.427088 | instance | Wednesday 09 September 2026 06:06:59 +0000 (0:00:01.023) 0:00:18.917 *** 2026-09-09 06:06:59.897334 | instance | changed: [instance] 2026-09-09 06:06:59.897406 | instance | 2026-09-09 06:06:59.897417 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-09 06:06:59.897428 | instance | Wednesday 09 September 2026 06:06:59 +0000 (0:00:00.470) 0:00:19.388 *** 2026-09-09 06:07:00.456421 | instance | changed: [instance] 2026-09-09 06:07:00.456698 | instance | 2026-09-09 06:07:00.456718 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-09 06:07:00.456732 | instance | Wednesday 09 September 2026 06:07:00 +0000 (0:00:00.558) 0:00:19.947 *** 2026-09-09 06:07:00.688618 | instance | ok: [instance] 2026-09-09 06:07:00.689690 | instance | 2026-09-09 06:07:00.689712 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-09 06:07:00.689720 | instance | Wednesday 09 September 2026 06:07:00 +0000 (0:00:00.232) 0:00:20.180 *** 2026-09-09 06:07:00.757166 | instance | ok: [instance] => { 2026-09-09 06:07:00.757961 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-09 06:07:00.758015 | instance | } 2026-09-09 06:07:00.758023 | instance | 2026-09-09 06:07:00.758029 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-09 06:07:00.758038 | instance | Wednesday 09 September 2026 06:07:00 +0000 (0:00:00.068) 0:00:20.249 *** 2026-09-09 06:07:01.657869 | instance | changed: [instance] 2026-09-09 06:07:01.657949 | instance | 2026-09-09 06:07:01.657963 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-09 06:07:01.658111 | instance | Wednesday 09 September 2026 06:07:01 +0000 (0:00:00.901) 0:00:21.150 *** 2026-09-09 06:07:05.969056 | instance | changed: [instance] 2026-09-09 06:07:05.969105 | instance | 2026-09-09 06:07:05.969112 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-09 06:07:05.969119 | instance | Wednesday 09 September 2026 06:07:05 +0000 (0:00:04.310) 0:00:25.460 *** 2026-09-09 06:07:07.047696 | instance | ok: [instance] 2026-09-09 06:07:07.048431 | instance | 2026-09-09 06:07:07.048483 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-09 06:07:07.048495 | instance | Wednesday 09 September 2026 06:07:07 +0000 (0:00:01.078) 0:00:26.539 *** 2026-09-09 06:07:07.419366 | instance | changed: [instance] 2026-09-09 06:07:07.419428 | instance | 2026-09-09 06:07:07.419756 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-09 06:07:07.419800 | instance | Wednesday 09 September 2026 06:07:07 +0000 (0:00:00.372) 0:00:26.912 *** 2026-09-09 06:07:07.925170 | instance | changed: [instance] 2026-09-09 06:07:07.925883 | instance | 2026-09-09 06:07:07.925915 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-09 06:07:07.925927 | instance | Wednesday 09 September 2026 06:07:07 +0000 (0:00:00.505) 0:00:27.417 *** 2026-09-09 06:07:08.533715 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-09 06:07:08.535038 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-09 06:07:08.535273 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-09 06:07:08.535286 | instance | 2026-09-09 06:07:08.535297 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-09 06:07:08.535306 | instance | Wednesday 09 September 2026 06:07:08 +0000 (0:00:00.608) 0:00:28.025 *** 2026-09-09 06:07:09.019270 | instance | changed: [instance] 2026-09-09 06:07:09.019721 | instance | 2026-09-09 06:07:09.019760 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-09 06:07:09.019768 | instance | Wednesday 09 September 2026 06:07:09 +0000 (0:00:00.486) 0:00:28.511 *** 2026-09-09 06:07:09.518027 | instance | changed: [instance] 2026-09-09 06:07:09.518083 | instance | 2026-09-09 06:07:09.518652 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-09 06:07:09.518699 | instance | Wednesday 09 September 2026 06:07:09 +0000 (0:00:00.485) 0:00:28.996 *** 2026-09-09 06:07:09.518704 | instance | 2026-09-09 06:07:09.518709 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-09 06:07:09.518718 | instance | Wednesday 09 September 2026 06:07:09 +0000 (0:00:00.013) 0:00:29.010 *** 2026-09-09 06:07:10.257399 | instance | ok: [instance] 2026-09-09 06:07:10.257465 | instance | 2026-09-09 06:07:10.257691 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-09 06:07:10.257729 | instance | Wednesday 09 September 2026 06:07:10 +0000 (0:00:00.739) 0:00:29.750 *** 2026-09-09 06:07:11.217446 | instance | changed: [instance] 2026-09-09 06:07:11.218162 | instance | 2026-09-09 06:07:11.218236 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-09 06:07:11.218249 | instance | Wednesday 09 September 2026 06:07:11 +0000 (0:00:00.959) 0:00:30.709 *** 2026-09-09 06:07:11.825099 | instance | changed: [instance] 2026-09-09 06:07:11.825536 | instance | 2026-09-09 06:07:11.825555 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-09 06:07:11.825562 | instance | Wednesday 09 September 2026 06:07:11 +0000 (0:00:00.608) 0:00:31.317 *** 2026-09-09 06:07:11.902795 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-09 06:07:11.903440 | instance | 2026-09-09 06:07:11.903466 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-09 06:07:11.903476 | instance | Wednesday 09 September 2026 06:07:11 +0000 (0:00:00.077) 0:00:31.395 *** 2026-09-09 06:07:17.128406 | instance | changed: [instance] 2026-09-09 06:07:17.128470 | instance | 2026-09-09 06:07:17.128481 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-09 06:07:17.128491 | instance | Wednesday 09 September 2026 06:07:17 +0000 (0:00:05.224) 0:00:36.619 *** 2026-09-09 06:07:17.812219 | instance | ok: [instance] => (item=chronyd) 2026-09-09 06:07:17.812295 | instance | ok: [instance] => (item=sshd) 2026-09-09 06:07:17.812307 | instance | 2026-09-09 06:07:17.812316 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-09 06:07:17.812326 | instance | Wednesday 09 September 2026 06:07:17 +0000 (0:00:00.683) 0:00:37.303 *** 2026-09-09 06:07:29.202425 | instance | changed: [instance] 2026-09-09 06:07:29.202650 | instance | 2026-09-09 06:07:29.203076 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-09 06:07:29.203149 | instance | Wednesday 09 September 2026 06:07:29 +0000 (0:00:11.391) 0:00:48.694 *** 2026-09-09 06:07:29.441751 | instance | ok: [instance] 2026-09-09 06:07:29.442635 | instance | 2026-09-09 06:07:29.442703 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-09 06:07:29.442719 | instance | Wednesday 09 September 2026 06:07:29 +0000 (0:00:00.238) 0:00:48.933 *** 2026-09-09 06:07:29.911506 | instance | changed: [instance] 2026-09-09 06:07:29.912232 | instance | 2026-09-09 06:07:29.912284 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-09 06:07:29.912295 | instance | Wednesday 09 September 2026 06:07:29 +0000 (0:00:00.469) 0:00:49.403 *** 2026-09-09 06:07:30.319234 | instance | changed: [instance] 2026-09-09 06:07:30.319308 | instance | 2026-09-09 06:07:30.319546 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-09 06:07:30.319590 | instance | Wednesday 09 September 2026 06:07:30 +0000 (0:00:00.408) 0:00:49.811 *** 2026-09-09 06:07:30.527583 | instance | ok: [instance] 2026-09-09 06:07:30.527659 | instance | 2026-09-09 06:07:30.528009 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-09 06:07:30.528056 | instance | Wednesday 09 September 2026 06:07:30 +0000 (0:00:00.208) 0:00:50.019 *** 2026-09-09 06:07:30.794590 | instance | ok: [instance] => (item=instance) 2026-09-09 06:07:30.795032 | instance | 2026-09-09 06:07:30.795236 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-09 06:07:30.795386 | instance | Wednesday 09 September 2026 06:07:30 +0000 (0:00:00.266) 0:00:50.286 *** 2026-09-09 06:07:30.846624 | instance | ok: [instance] 2026-09-09 06:07:30.847004 | instance | 2026-09-09 06:07:30.847072 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-09 06:07:30.847079 | instance | Wednesday 09 September 2026 06:07:30 +0000 (0:00:00.052) 0:00:50.338 *** 2026-09-09 06:07:30.949586 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-09 06:07:30.949639 | instance | 2026-09-09 06:07:30.949900 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-09 06:07:30.949957 | instance | Wednesday 09 September 2026 06:07:30 +0000 (0:00:00.103) 0:00:50.441 *** 2026-09-09 06:07:31.274605 | instance | changed: [instance] 2026-09-09 06:07:31.274712 | instance | 2026-09-09 06:07:31.274724 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-09 06:07:31.274885 | instance | Wednesday 09 September 2026 06:07:31 +0000 (0:00:00.324) 0:00:50.766 *** 2026-09-09 06:07:31.998676 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-09 06:07:31.998864 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-09 06:07:31.999919 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-09 06:07:31.999976 | instance | 2026-09-09 06:07:31.999989 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-09 06:07:31.999999 | instance | Wednesday 09 September 2026 06:07:31 +0000 (0:00:00.724) 0:00:51.490 *** 2026-09-09 06:09:02.508713 | instance | ok: [instance] 2026-09-09 06:09:02.509012 | instance | 2026-09-09 06:09:02.509341 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-09 06:09:02.509672 | instance | Wednesday 09 September 2026 06:09:02 +0000 (0:01:30.509) 0:02:22.000 *** 2026-09-09 06:09:02.755972 | instance | changed: [instance] 2026-09-09 06:09:02.756247 | instance | 2026-09-09 06:09:02.756592 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-09 06:09:02.757042 | instance | Wednesday 09 September 2026 06:09:02 +0000 (0:00:00.247) 0:02:22.247 *** 2026-09-09 06:09:02.806449 | instance | ok: [instance] 2026-09-09 06:09:02.806806 | instance | 2026-09-09 06:09:02.807194 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-09 06:09:02.807519 | instance | Wednesday 09 September 2026 06:09:02 +0000 (0:00:00.050) 0:02:22.298 *** 2026-09-09 06:09:02.882306 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-09 06:09:02.882493 | instance | 2026-09-09 06:09:02.882812 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-09 06:09:02.883079 | instance | Wednesday 09 September 2026 06:09:02 +0000 (0:00:00.076) 0:02:22.374 *** 2026-09-09 06:09:04.576633 | instance | ok: [instance] 2026-09-09 06:09:04.576892 | instance | 2026-09-09 06:09:04.577331 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-09 06:09:04.577582 | instance | Wednesday 09 September 2026 06:09:04 +0000 (0:00:01.693) 0:02:24.068 *** 2026-09-09 06:09:04.662386 | instance | ok: [instance] => (item=instance) 2026-09-09 06:09:04.662564 | instance | 2026-09-09 06:09:04.662852 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-09 06:09:04.663155 | instance | Wednesday 09 September 2026 06:09:04 +0000 (0:00:00.085) 0:02:24.153 *** 2026-09-09 06:09:05.050194 | instance | ok: [instance] 2026-09-09 06:09:05.050239 | instance | 2026-09-09 06:09:05.050251 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-09 06:09:05.050267 | instance | Wednesday 09 September 2026 06:09:05 +0000 (0:00:00.386) 0:02:24.540 *** 2026-09-09 06:09:07.356176 | instance | ok: [instance] 2026-09-09 06:09:07.356383 | instance | 2026-09-09 06:09:07.356618 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-09 06:09:07.356870 | instance | Wednesday 09 September 2026 06:09:07 +0000 (0:00:02.307) 0:02:26.848 *** 2026-09-09 06:09:56.217573 | 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-09 06:09:56.217689 | 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_6eakga7w/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_6eakga7w/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_6eakga7w/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_6eakga7w/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-09T06:09:56.022+0000 7ff4e354a640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDW96Bq/QjIMSAAjmWc2ro1J+Eb/aZQM5UK4SdoEGP4s/clOG4hddvy/5g=: Malformed input [buffer:3]\n2026-09-09T06:09:56.022+0000 7ff4e354a640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-09T06:09:56.026+0000 7ff4e354a640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDW96Bq/QjIMSAAjmWc2ro1J+Eb/aZQM5UK4SdoEGP4s/clOG4hddvy/5g=: Malformed input [buffer:3]\n2026-09-09T06:09:56.026+0000 7ff4e354a640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-09T06:09:56.026+0000 7ff4e354a640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDW96Bq/QjIMSAAjmWc2ro1J+Eb/aZQM5UK4SdoEGP4s/clOG4hddvy/5g=: Malformed input [buffer:3]\n2026-09-09T06:09:56.026+0000 7ff4e354a640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-09T06:09:56.026+0000 7ff4e354a640 -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-09 06:09:56.219484 | instance | 2026-09-09 06:09:56.223019 | instance | PLAY RECAP ********************************************************************* 2026-09-09 06:09:56.223060 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-09 06:09:56.223071 | instance | 2026-09-09 06:09:56.223081 | instance | Wednesday 09 September 2026 06:09:56 +0000 (0:00:48.863) 0:03:15.711 *** 2026-09-09 06:09:56.223090 | instance | =============================================================================== 2026-09-09 06:09:56.223099 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 90.51s 2026-09-09 06:09:56.223107 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 48.86s 2026-09-09 06:09:56.223116 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 11.39s 2026-09-09 06:09:56.223125 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.51s 2026-09-09 06:09:56.223134 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.22s 2026-09-09 06:09:56.223385 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.31s 2026-09-09 06:09:56.223849 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.87s 2026-09-09 06:09:56.224271 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.31s 2026-09-09 06:09:56.224666 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.76s 2026-09-09 06:09:56.225062 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.69s 2026-09-09 06:09:56.225475 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.22s 2026-09-09 06:09:56.225867 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.08s 2026-09-09 06:09:56.226297 | instance | Gathering Facts --------------------------------------------------------- 1.01s 2026-09-09 06:09:56.226711 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.00s 2026-09-09 06:09:56.227147 | instance | Gathering Facts --------------------------------------------------------- 0.98s 2026-09-09 06:09:56.227590 | instance | Gathering Facts --------------------------------------------------------- 0.97s 2026-09-09 06:09:56.228000 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.96s 2026-09-09 06:09:56.228425 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.90s 2026-09-09 06:09:56.228830 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.72s 2026-09-09 06:09:56.229260 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.69s 2026-09-09 06:09:56.316496 | 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-09 06:09:56.316645 | instance | ERROR [csi > converge] Executed: Failed 2026-09-09 06:09:56.316737 | 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-09 06:09:56.480275 | instance | ERROR 2026-09-09 06:09:56.480407 | instance | { 2026-09-09 06:09:56.480432 | instance | "delta": "0:04:07.415890", 2026-09-09 06:09:56.480450 | instance | "end": "2026-09-09 06:09:56.371564", 2026-09-09 06:09:56.480465 | instance | "msg": "non-zero return code", 2026-09-09 06:09:56.480481 | instance | "rc": 2, 2026-09-09 06:09:56.480646 | instance | "start": "2026-09-09 06:05:48.955674" 2026-09-09 06:09:56.480672 | instance | } failure 2026-09-09 06:09:56.485587 | 2026-09-09 06:09:56.485636 | PLAY RECAP 2026-09-09 06:09:56.485678 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-09 06:09:56.485699 | 2026-09-09 06:09:56.763842 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-09 06:09:56.772277 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-09 06:09:57.449209 | 2026-09-09 06:09:57.449418 | PLAY [all] 2026-09-09 06:09:57.466602 | 2026-09-09 06:09:57.466788 | TASK [gather-host-logs : creating directory for system status] 2026-09-09 06:09:57.846140 | instance | changed 2026-09-09 06:09:57.855881 | 2026-09-09 06:09:57.856140 | TASK [gather-host-logs : Get logs for each host] 2026-09-09 06:09:58.276650 | instance | + systemd-cgls --full --all --no-pager 2026-09-09 06:09:58.291747 | instance | + ip addr 2026-09-09 06:09:58.294985 | instance | + ip route 2026-09-09 06:09:58.297397 | instance | + lsblk 2026-09-09 06:09:58.302859 | instance | + mount 2026-09-09 06:09:58.306353 | instance | + docker images 2026-09-09 06:09:58.328942 | instance | + brctl show 2026-09-09 06:09:58.329579 | instance | /bin/bash: line 8: brctl: command not found 2026-09-09 06:09:58.329879 | instance | + ps aux --sort=-%mem 2026-09-09 06:09:58.351255 | instance | + dpkg -l 2026-09-09 06:09:58.363758 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-09 06:09:58.364318 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-09 06:09:58.384302 | instance | + '[' '!' -z '' ']' 2026-09-09 06:09:58.438804 | instance | ok: Runtime: 0:00:00.111732 2026-09-09 06:09:58.449797 | 2026-09-09 06:09:58.449957 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-09 06:09:59.156971 | instance | changed: 2026-09-09 06:09:59.157155 | instance | created directory /var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/work/logs/instance 2026-09-09 06:09:59.157194 | instance | cd+++++++++ system/ 2026-09-09 06:09:59.157222 | instance | >f+++++++++ system/brctl-show.txt 2026-09-09 06:09:59.157250 | instance | >f+++++++++ system/docker-images.txt 2026-09-09 06:09:59.157275 | instance | >f+++++++++ system/ip-addr.txt 2026-09-09 06:09:59.157306 | instance | >f+++++++++ system/ip-route.txt 2026-09-09 06:09:59.157335 | instance | >f+++++++++ system/lsblk.txt 2026-09-09 06:09:59.157361 | instance | >f+++++++++ system/mount.txt 2026-09-09 06:09:59.157999 | instance | >f+++++++++ system/packages.txt 2026-09-09 06:09:59.158036 | instance | >f+++++++++ system/ps.txt 2026-09-09 06:09:59.158066 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-09 06:09:59.169394 | 2026-09-09 06:09:59.169481 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-09 06:09:59.383396 | instance | changed: "values" 2026-09-09 06:09:59.584851 | instance | changed: "releases" 2026-09-09 06:09:59.603400 | 2026-09-09 06:09:59.603636 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-09 06:09:59.854310 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-09 06:10:00.152957 | instance | ok: Runtime: 0:00:00.005090 2026-09-09 06:10:00.162084 | 2026-09-09 06:10:00.162214 | TASK [helm-release-status : Downloads logs to executor] 2026-09-09 06:10:00.694709 | instance | changed: 2026-09-09 06:10:00.694843 | instance | cd+++++++++ helm/ 2026-09-09 06:10:00.694870 | instance | cd+++++++++ helm/releases/ 2026-09-09 06:10:00.694891 | instance | cd+++++++++ helm/values/ 2026-09-09 06:10:00.705167 | 2026-09-09 06:10:00.705252 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-09 06:10:00.946417 | instance | changed 2026-09-09 06:10:01.081224 | 2026-09-09 06:10:01.081351 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-09 06:10:01.379455 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-09 06:10:01.379593 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-09 06:10:01.384431 | instance | environment: line 1: kubectl: command not found 2026-09-09 06:10:01.385765 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-09 06:10:01.385799 | instance | environment: line 1: kubectl: command not found 2026-09-09 06:10:01.387909 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-09 06:10:01.388938 | instance | environment: line 1: kubectl: command not found 2026-09-09 06:10:01.391231 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-09 06:10:01.391903 | instance | environment: line 1: kubectl: command not found 2026-09-09 06:10:01.393253 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-09 06:10:01.394370 | instance | environment: line 1: kubectl: command not found 2026-09-09 06:10:01.395727 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-09 06:10:01.640663 | instance | ok: Runtime: 0:00:00.025782 2026-09-09 06:10:01.649126 | 2026-09-09 06:10:01.649213 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-09 06:10:01.877901 | instance | changed 2026-09-09 06:10:01.890466 | 2026-09-09 06:10:01.890679 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-09 06:10:02.140073 | instance | environment: line 5: kubectl: command not found 2026-09-09 06:10:02.140913 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-09 06:10:02.141131 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-09 06:10:02.141545 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-09 06:10:02.436067 | instance | ok: Runtime: 0:00:00.008885 2026-09-09 06:10:02.445952 | 2026-09-09 06:10:02.446078 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-09 06:10:03.077248 | instance | changed: 2026-09-09 06:10:03.077528 | instance | cd+++++++++ objects/ 2026-09-09 06:10:03.077588 | instance | cd+++++++++ objects/cluster/ 2026-09-09 06:10:03.077638 | instance | cd+++++++++ objects/namespaced/ 2026-09-09 06:10:03.095172 | 2026-09-09 06:10:03.095353 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-09 06:10:03.322743 | instance | changed 2026-09-09 06:10:03.332073 | 2026-09-09 06:10:03.332180 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-09 06:10:03.559391 | instance | changed 2026-09-09 06:10:03.671687 | 2026-09-09 06:10:03.671963 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-09 06:10:03.923474 | instance | environment: line 3: kubectl: command not found 2026-09-09 06:10:04.213512 | instance | ok: Runtime: 0:00:00.008491 2026-09-09 06:10:04.226744 | 2026-09-09 06:10:04.226887 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-09 06:10:04.913608 | instance | changed: 2026-09-09 06:10:04.913861 | instance | cd+++++++++ pod-logs/ 2026-09-09 06:10:04.913943 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-09 06:10:04.940245 | 2026-09-09 06:10:04.940458 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-09 06:10:05.159773 | instance | changed 2026-09-09 06:10:05.171225 | 2026-09-09 06:10:05.171388 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-09 06:10:05.425863 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-09 06:10:05.721144 | instance | ok: Runtime: 0:00:00.052104 2026-09-09 06:10:05.731320 | 2026-09-09 06:10:05.731477 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-09 06:10:06.012265 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-09 06:10:06.062098 | instance | ceph-mgr endpoints: 2026-09-09 06:10:06.293685 | instance | ok: Runtime: 0:00:00.055811 2026-09-09 06:10:06.300450 | 2026-09-09 06:10:06.304852 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-09 06:10:06.531561 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-09 06:10:06.854229 | instance | ok: Runtime: 0:00:00.052373 2026-09-09 06:10:06.865515 | 2026-09-09 06:10:06.865651 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-09 06:10:07.418095 | instance | changed: cd+++++++++ prometheus/ 2026-09-09 06:10:07.437871 | 2026-09-09 06:10:07.438092 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-09 06:10:07.696611 | instance | changed 2026-09-09 06:10:07.706346 | 2026-09-09 06:10:07.706487 | TASK [gather-selenium-data : Get selenium data] 2026-09-09 06:10:08.001285 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-09 06:10:08.003128 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-09 06:10:08.249897 | instance | ERROR 2026-09-09 06:10:08.250321 | instance | { 2026-09-09 06:10:08.250385 | instance | "delta": "0:00:00.008298", 2026-09-09 06:10:08.250427 | instance | "end": "2026-09-09 06:10:08.003589", 2026-09-09 06:10:08.250470 | instance | "msg": "non-zero return code", 2026-09-09 06:10:08.250507 | instance | "rc": 1, 2026-09-09 06:10:08.250543 | instance | "start": "2026-09-09 06:10:07.995291" 2026-09-09 06:10:08.250578 | instance | } 2026-09-09 06:10:08.250625 | instance | ERROR: Ignoring Errors 2026-09-09 06:10:08.260020 | 2026-09-09 06:10:08.260149 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-09 06:10:08.818196 | instance | changed: cd+++++++++ selenium/ 2026-09-09 06:10:08.829651 | 2026-09-09 06:10:08.829757 | PLAY RECAP 2026-09-09 06:10:08.829847 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-09 06:10:08.829890 | 2026-09-09 06:10:09.041311 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-09 06:10:09.047130 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-09 06:10:09.708879 | 2026-09-09 06:10:09.709001 | PLAY [all] 2026-09-09 06:10:09.720774 | 2026-09-09 06:10:09.720858 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-09 06:10:09.776353 | instance | skipping: Conditional result was False 2026-09-09 06:10:09.783217 | 2026-09-09 06:10:09.783318 | TASK [fetch-output : Set log path for single node] 2026-09-09 06:10:09.827813 | instance | ok 2026-09-09 06:10:09.838933 | 2026-09-09 06:10:09.839040 | LOOP [fetch-output : Ensure local output dirs] 2026-09-09 06:10:10.264333 | instance -> localhost | ok: "/var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/work/logs" 2026-09-09 06:10:10.486381 | instance -> localhost | changed: "/var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/work/artifacts" 2026-09-09 06:10:10.679814 | instance -> localhost | changed: "/var/lib/zuul/builds/e8a9c8dd59844ca2861acb2de7ce0743/work/docs" 2026-09-09 06:10:10.695670 | 2026-09-09 06:10:10.695810 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-09 06:10:11.387233 | instance | changed: .d..t...... ./ 2026-09-09 06:10:11.387448 | instance | changed: All items complete 2026-09-09 06:10:11.387496 | 2026-09-09 06:10:11.898105 | instance | changed: .d..t...... ./ 2026-09-09 06:10:12.410817 | instance | changed: .d..t...... ./ 2026-09-09 06:10:12.434850 | 2026-09-09 06:10:12.435084 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-09 06:10:12.966704 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.012982 2026-09-09 06:10:13.204336 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007204 2026-09-09 06:10:13.219240 | 2026-09-09 06:10:13.219389 | PLAY [all] 2026-09-09 06:10:13.225432 | 2026-09-09 06:10:13.225554 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-09 06:10:13.688442 | instance | changed 2026-09-09 06:10:13.693610 | 2026-09-09 06:10:13.693658 | PLAY RECAP 2026-09-09 06:10:13.693701 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-09 06:10:13.693722 | 2026-09-09 06:10:13.848430 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-09 06:10:13.858405 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-09 06:10:14.511753 | 2026-09-09 06:10:14.511894 | PLAY [localhost] 2026-09-09 06:10:14.521518 | 2026-09-09 06:10:14.521586 | TASK [Generate Zuul manifest] 2026-09-09 06:10:14.540521 | localhost | ok 2026-09-09 06:10:14.560402 | 2026-09-09 06:10:14.560475 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-09 06:10:14.955397 | localhost | changed 2026-09-09 06:10:14.996385 | 2026-09-09 06:10:14.996579 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-09 06:10:15.037985 | localhost | ok 2026-09-09 06:10:15.050856 | 2026-09-09 06:10:15.051024 | TASK [Upload logs] 2026-09-09 06:10:15.076227 | localhost | ok 2026-09-09 06:10:15.165457 | 2026-09-09 06:10:15.165668 | TASK [Set zuul-log-path fact] 2026-09-09 06:10:15.182371 | localhost | ok 2026-09-09 06:10:15.197621 | 2026-09-09 06:10:15.197723 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-09 06:10:15.225499 | localhost | ok 2026-09-09 06:10:15.234056 | 2026-09-09 06:10:15.234148 | TASK [upload-logs : Create log directories] 2026-09-09 06:10:15.708089 | localhost | changed 2026-09-09 06:10:15.714010 | 2026-09-09 06:10:15.714101 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-09 06:10:16.150631 | localhost -> localhost | ok: Runtime: 0:00:00.007727 2026-09-09 06:10:16.160337 | 2026-09-09 06:10:16.160470 | TASK [upload-logs : Upload logs to log server] 2026-09-09 06:10:16.664653 | localhost | Output suppressed because no_log was given 2026-09-09 06:10:16.695399 | 2026-09-09 06:10:16.695520 | LOOP [upload-logs : Compress console log and json output] 2026-09-09 06:10:16.753273 | localhost | skipping: Conditional result was False 2026-09-09 06:10:16.761230 | localhost | skipping: Conditional result was False 2026-09-09 06:10:16.777282 | 2026-09-09 06:10:16.777629 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-09 06:10:16.857379 | localhost | skipping: Conditional result was False 2026-09-09 06:10:16.857588 | 2026-09-09 06:10:16.858101 | localhost | skipping: Conditional result was False 2026-09-09 06:10:16.871247 | 2026-09-09 06:10:16.871433 | LOOP [upload-logs : Upload console log and json output]