2026-08-24 15:27:50.456587 | Job console starting 2026-08-24 15:27:50.479423 | Updating git repos 2026-08-24 15:27:52.812402 | Cloning repos into workspace 2026-08-24 15:28:26.957597 | Restoring repo states 2026-08-24 15:28:38.229443 | Merging changes 2026-08-24 15:28:41.225229 | Checking out repos 2026-08-24 15:28:49.882283 | Preparing playbooks 2026-08-24 15:28:53.341422 | Running Ansible setup 2026-08-24 15:28:56.865619 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-24 15:28:57.635300 | 2026-08-24 15:28:57.635455 | PLAY [localhost] 2026-08-24 15:28:57.643947 | 2026-08-24 15:28:57.644044 | TASK [Gathering Facts] 2026-08-24 15:28:58.690988 | localhost | ok 2026-08-24 15:28:58.699035 | 2026-08-24 15:28:58.699160 | TASK [Setup log path fact] 2026-08-24 15:28:58.719115 | localhost | ok 2026-08-24 15:28:58.747432 | 2026-08-24 15:28:58.747853 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-24 15:28:58.781617 | localhost | ok 2026-08-24 15:28:58.792005 | 2026-08-24 15:28:58.792208 | TASK [emit-job-header : Print job information] 2026-08-24 15:28:58.842963 | # Job Information 2026-08-24 15:28:58.843203 | Ansible Version: 2.16.18 2026-08-24 15:28:58.843254 | Job: atmosphere-molecule-csi-rbd 2026-08-24 15:28:58.843286 | Pipeline: check 2026-08-24 15:28:58.843313 | Executor: 2d72f0692154 2026-08-24 15:28:58.843343 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3864 2026-08-24 15:28:58.843379 | Event ID: 34730ab0-9fce-11f1-9ad9-dbc9c4f82e28 2026-08-24 15:28:58.848545 | 2026-08-24 15:28:58.848663 | LOOP [emit-job-header : Print node information] 2026-08-24 15:28:58.944629 | localhost | ok: 2026-08-24 15:28:58.944845 | localhost | # Node Information 2026-08-24 15:28:58.944874 | localhost | Inventory Hostname: instance 2026-08-24 15:28:58.944896 | localhost | Hostname: np0000190881 2026-08-24 15:28:58.944916 | localhost | Username: zuul 2026-08-24 15:28:58.944942 | localhost | Distro: Ubuntu 22.04 2026-08-24 15:28:58.944996 | localhost | Provider: yul1 2026-08-24 15:28:58.945019 | localhost | Region: ca-ymq-1 2026-08-24 15:28:58.945043 | localhost | Label: ubuntu-jammy 2026-08-24 15:28:58.945067 | localhost | Product Name: OpenStack Nova 2026-08-24 15:28:58.945086 | localhost | Interface IP: 199.19.213.193 2026-08-24 15:28:58.960814 | 2026-08-24 15:28:58.961034 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-24 15:28:59.415370 | localhost -> localhost | changed 2026-08-24 15:28:59.425874 | 2026-08-24 15:28:59.426286 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-24 15:29:00.405892 | localhost -> localhost | changed 2026-08-24 15:29:00.415863 | 2026-08-24 15:29:00.415934 | PLAY [all] 2026-08-24 15:29:00.428274 | 2026-08-24 15:29:00.691115 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-24 15:29:00.974924 | instance -> localhost | ok 2026-08-24 15:29:00.986224 | 2026-08-24 15:29:00.986408 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-24 15:29:01.023132 | instance | ok 2026-08-24 15:29:01.044972 | instance | included: /var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-24 15:29:01.053560 | 2026-08-24 15:29:01.053674 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-24 15:29:02.425104 | instance -> localhost | Generating public/private rsa key pair. 2026-08-24 15:29:02.425310 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/work/eff7567f391e47ee85622afe71e6ead5_id_rsa 2026-08-24 15:29:02.425362 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/work/eff7567f391e47ee85622afe71e6ead5_id_rsa.pub 2026-08-24 15:29:02.425404 | instance -> localhost | The key fingerprint is: 2026-08-24 15:29:02.425444 | instance -> localhost | SHA256:y3Cn5ESs3bH9Etne/Alp4se0oLNPp88kkU55cI32Y24 zuul-build-sshkey 2026-08-24 15:29:02.425503 | instance -> localhost | The key's randomart image is: 2026-08-24 15:29:02.425545 | instance -> localhost | +---[RSA 3072]----+ 2026-08-24 15:29:02.425587 | instance -> localhost | | | 2026-08-24 15:29:02.425628 | instance -> localhost | | . o | 2026-08-24 15:29:02.425666 | instance -> localhost | | o .. + . | 2026-08-24 15:29:02.425705 | instance -> localhost | | + . +*o. | 2026-08-24 15:29:02.425744 | instance -> localhost | | o S +=+..+ | 2026-08-24 15:29:02.425782 | instance -> localhost | | B +o.o=+o.| 2026-08-24 15:29:02.425821 | instance -> localhost | | = .==*+Eo| 2026-08-24 15:29:02.425859 | instance -> localhost | | oo O=o o| 2026-08-24 15:29:02.425901 | instance -> localhost | | .++oo ..| 2026-08-24 15:29:02.425940 | instance -> localhost | +----[SHA256]-----+ 2026-08-24 15:29:02.426018 | instance -> localhost | ok: Runtime: 0:00:00.895494 2026-08-24 15:29:02.436024 | 2026-08-24 15:29:02.436118 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-24 15:29:02.485412 | instance | ok 2026-08-24 15:29:02.496769 | instance | included: /var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-24 15:29:02.507174 | 2026-08-24 15:29:02.507293 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-24 15:29:02.532170 | instance | skipping: Conditional result was False 2026-08-24 15:29:02.541880 | 2026-08-24 15:29:02.542007 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-24 15:29:03.248285 | instance | changed 2026-08-24 15:29:03.255023 | 2026-08-24 15:29:03.255100 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-24 15:29:03.440769 | instance | ok 2026-08-24 15:29:03.449538 | 2026-08-24 15:29:03.449666 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-24 15:29:03.937593 | instance | changed 2026-08-24 15:29:03.942794 | 2026-08-24 15:29:03.942922 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-24 15:29:04.439888 | instance | changed 2026-08-24 15:29:04.487243 | 2026-08-24 15:29:04.487389 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-24 15:29:04.512185 | instance | skipping: Conditional result was False 2026-08-24 15:29:04.520923 | 2026-08-24 15:29:04.521052 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-24 15:29:05.063666 | instance -> localhost | changed 2026-08-24 15:29:05.074834 | 2026-08-24 15:29:05.074973 | TASK [add-build-sshkey : Add back temp key] 2026-08-24 15:29:05.701333 | instance -> localhost | Identity added: /var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/work/eff7567f391e47ee85622afe71e6ead5_id_rsa (zuul-build-sshkey) 2026-08-24 15:29:05.701636 | instance -> localhost | ok: Runtime: 0:00:00.015489 2026-08-24 15:29:05.708644 | 2026-08-24 15:29:05.708732 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-24 15:29:06.123424 | instance | ok 2026-08-24 15:29:06.130568 | 2026-08-24 15:29:06.130718 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-24 15:29:06.161480 | instance | skipping: Conditional result was False 2026-08-24 15:29:06.181370 | 2026-08-24 15:29:06.181573 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-24 15:29:06.555845 | instance | ok 2026-08-24 15:29:06.617645 | 2026-08-24 15:29:06.617798 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-24 15:29:13.972330 | instance | Output suppressed because no_log was given 2026-08-24 15:29:13.988305 | 2026-08-24 15:29:13.988442 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-24 15:29:14.194321 | instance | ok: "logs" 2026-08-24 15:29:14.194599 | instance | ok: All items complete 2026-08-24 15:29:14.194663 | 2026-08-24 15:29:14.351557 | instance | ok: "artifacts" 2026-08-24 15:29:14.516100 | instance | ok: "docs" 2026-08-24 15:29:14.533901 | 2026-08-24 15:29:14.534083 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-24 15:29:14.717987 | instance | changed: "logs" 2026-08-24 15:29:14.870803 | instance | changed: "artifacts" 2026-08-24 15:29:15.045299 | instance | changed: "docs" 2026-08-24 15:29:15.064117 | 2026-08-24 15:29:15.064249 | PLAY RECAP 2026-08-24 15:29:15.064305 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-24 15:29:15.064336 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:29:15.064361 | 2026-08-24 15:29:15.285975 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-24 15:29:15.289423 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-24 15:29:16.078025 | 2026-08-24 15:29:16.078148 | PLAY [all] 2026-08-24 15:29:16.088635 | 2026-08-24 15:29:16.088714 | TASK [setup-uv : Extract archive] 2026-08-24 15:29:18.879013 | instance | changed 2026-08-24 15:29:18.884968 | 2026-08-24 15:29:18.885054 | TASK [setup-uv : Print version] 2026-08-24 15:29:19.293566 | instance | uv 0.8.13 2026-08-24 15:29:19.431797 | instance | ok: Runtime: 0:00:00.012711 2026-08-24 15:29:19.444159 | 2026-08-24 15:29:19.444250 | TASK [Install Ansible collection siblings] 2026-08-24 15:29:29.466982 | instance | changed: 2026-08-24 15:29:29.467135 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-08-24 15:29:29.467165 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-08-24 15:29:29.467329 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-08-24 15:29:29.467534 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-08-24 15:29:29.467625 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-08-24 15:29:29.467653 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-08-24 15:29:29.467674 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-08-24 15:29:29.467758 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-08-24 15:29:29.467853 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-08-24 15:29:29.467881 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-08-24 15:29:29.467908 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-08-24 15:29:29.467934 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-08-24 15:29:29.467959 | instance | Resolved ansible.netcommon to v8.5.0 for 8.5.0 2026-08-24 15:29:29.467984 | instance | Resolved ansible.posix to 2.1.0 for 2.1.0 2026-08-24 15:29:29.468010 | instance | Resolved ansible.utils to v6.1.0 for >=6.0.0,>=3.0.0 2026-08-24 15:29:29.468037 | instance | Resolved community.crypto to 3.2.0 for 3.2.0 2026-08-24 15:29:29.468062 | instance | Resolved community.general to 12.6.0 for 12.6.0 2026-08-24 15:29:29.468089 | instance | Resolved community.mysql to 4.2.0 for 4.2.0 2026-08-24 15:29:29.468115 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0 2026-08-24 15:29:29.468141 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-08-24 15:29:29.468167 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-08-24 15:29:29.468195 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-08-24 15:29:29.468226 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-24 15:29:29.468264 | instance | Resolved ansible.utils to v6.1.0 for >=6.0.0,>=3.0.0 2026-08-24 15:29:29.468303 | instance | Resolved community.general to 12.6.0 for 12.6.0,>=4.5.0 2026-08-24 15:29:29.468337 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-24 15:29:29.468380 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0,>=2.4.0,>=2.3.2 2026-08-24 15:29:29.468405 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-24 15:29:29.468426 | instance | Resolved ansible.posix to 2.1.0 for 2.1.0,>=1.6.0 2026-08-24 15:29:29.468445 | instance | Resolved community.crypto to 3.2.0 for 3.2.0,>=2.2.3 2026-08-24 15:29:29.468463 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0,>=2.4.0,>=2.3.2 2026-08-24 15:29:29.468543 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-24 15:29:29.468564 | instance | Checked out ansible.netcommon to v8.5.0 2026-08-24 15:29:29.468583 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-08-24 15:29:29.468637 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.468658 | instance | Process install dependency map 2026-08-24 15:29:29.468677 | instance | Starting collection install process 2026-08-24 15:29:29.468695 | instance | Installing 'ansible.netcommon:8.5.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-08-24 15:29:29.468713 | instance | Created collection for ansible.netcommon:8.5.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-08-24 15:29:29.468763 | instance | ansible.netcommon:8.5.0 was installed successfully 2026-08-24 15:29:29.468783 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-08-24 15:29:29.468801 | instance | Creating virtual environment at: .venv 2026-08-24 15:29:29.468819 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-24 15:29:29.468837 | instance | Downloading netaddr (2.2MiB) 2026-08-24 15:29:29.468854 | instance | Downloading openstacksdk (1.7MiB) 2026-08-24 15:29:29.468875 | instance | Downloading setuptools (1.1MiB) 2026-08-24 15:29:29.468893 | instance | Downloading pydantic-core (2.0MiB) 2026-08-24 15:29:29.468911 | instance | Downloading pygments (1.2MiB) 2026-08-24 15:29:29.468929 | instance | Downloading kubernetes (1.9MiB) 2026-08-24 15:29:29.468947 | instance | Downloading ansible-core (2.1MiB) 2026-08-24 15:29:29.468983 | instance | Downloading cryptography (4.2MiB) 2026-08-24 15:29:29.469003 | instance | Downloading rjsonnet (1.2MiB) 2026-08-24 15:29:29.469021 | instance | Building pyperclip==1.9.0 2026-08-24 15:29:29.469041 | instance | Downloading rjsonnet 2026-08-24 15:29:29.469060 | instance | Downloading pydantic-core 2026-08-24 15:29:29.469079 | instance | Downloading netaddr 2026-08-24 15:29:29.469098 | instance | Downloading pygments 2026-08-24 15:29:29.469116 | instance | Downloading cryptography 2026-08-24 15:29:29.469134 | instance | Downloading setuptools 2026-08-24 15:29:29.469151 | instance | Downloading kubernetes 2026-08-24 15:29:29.469169 | instance | Downloading ansible-core 2026-08-24 15:29:29.469186 | instance | Downloading openstacksdk 2026-08-24 15:29:29.469220 | instance | Built pyperclip==1.9.0 2026-08-24 15:29:29.469257 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-24 15:29:29.469278 | instance | Installed 83 packages in 229ms 2026-08-24 15:29:29.469297 | instance | Checked out ansible.posix to 2.1.0 2026-08-24 15:29:29.469316 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-08-24 15:29:29.469334 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.469352 | instance | Process install dependency map 2026-08-24 15:29:29.469377 | instance | Starting collection install process 2026-08-24 15:29:29.469396 | instance | Installing 'ansible.posix:2.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-08-24 15:29:29.469414 | instance | Created collection for ansible.posix:2.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-08-24 15:29:29.469432 | instance | ansible.posix:2.1.0 was installed successfully 2026-08-24 15:29:29.469450 | instance | Checked out ansible.utils to v6.1.0 2026-08-24 15:29:29.469468 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-08-24 15:29:29.469486 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.469503 | instance | Process install dependency map 2026-08-24 15:29:29.469521 | instance | Starting collection install process 2026-08-24 15:29:29.469582 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-08-24 15:29:29.469604 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-08-24 15:29:29.469623 | instance | ansible.utils:6.1.0 was installed successfully 2026-08-24 15:29:29.469642 | instance | Checked out atmosphere.common to v0.7.0 2026-08-24 15:29:29.469660 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-08-24 15:29:29.469679 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.469696 | instance | Process install dependency map 2026-08-24 15:29:29.469714 | instance | Starting collection install process 2026-08-24 15:29:29.469732 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-08-24 15:29:29.469749 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-08-24 15:29:29.469767 | instance | atmosphere.common:0.7.0 was installed successfully 2026-08-24 15:29:29.469785 | instance | Checked out community.crypto to 3.2.0 2026-08-24 15:29:29.469802 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-08-24 15:29:29.469820 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.469837 | instance | Process install dependency map 2026-08-24 15:29:29.469855 | instance | Starting collection install process 2026-08-24 15:29:29.469872 | instance | Installing 'community.crypto:3.2.0' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-08-24 15:29:29.469889 | instance | Created collection for community.crypto:3.2.0 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-08-24 15:29:29.469907 | instance | community.crypto:3.2.0 was installed successfully 2026-08-24 15:29:29.469925 | instance | Checked out community.general to 12.6.0 2026-08-24 15:29:29.469942 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-08-24 15:29:29.469959 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.469977 | instance | Process install dependency map 2026-08-24 15:29:29.469994 | instance | Starting collection install process 2026-08-24 15:29:29.470011 | instance | Installing 'community.general:12.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-08-24 15:29:29.470028 | instance | Created collection for community.general:12.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-08-24 15:29:29.470051 | instance | community.general:12.6.0 was installed successfully 2026-08-24 15:29:29.470069 | instance | Checked out community.mysql to 4.2.0 2026-08-24 15:29:29.470086 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-08-24 15:29:29.470103 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.470121 | instance | Process install dependency map 2026-08-24 15:29:29.470138 | instance | Starting collection install process 2026-08-24 15:29:29.470155 | instance | Installing 'community.mysql:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-08-24 15:29:29.470172 | instance | Created collection for community.mysql:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-08-24 15:29:29.470190 | instance | community.mysql:4.2.0 was installed successfully 2026-08-24 15:29:29.470214 | instance | Checked out kubernetes.core to 6.3.0 2026-08-24 15:29:29.470240 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-08-24 15:29:29.470262 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.470284 | instance | Process install dependency map 2026-08-24 15:29:29.470303 | instance | Starting collection install process 2026-08-24 15:29:29.470321 | instance | Installing 'kubernetes.core:6.3.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-08-24 15:29:29.470339 | instance | Created collection for kubernetes.core:6.3.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-08-24 15:29:29.470356 | instance | kubernetes.core:6.3.0 was installed successfully 2026-08-24 15:29:29.470374 | instance | Checked out openstack.cloud to 2.6.0 2026-08-24 15:29:29.470392 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-08-24 15:29:29.470410 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.470428 | instance | Process install dependency map 2026-08-24 15:29:29.470445 | instance | Starting collection install process 2026-08-24 15:29:29.470463 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-08-24 15:29:29.470481 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-08-24 15:29:29.470499 | instance | openstack.cloud:2.6.0 was installed successfully 2026-08-24 15:29:29.470517 | instance | Checked out vexxhost.ceph to v4.2.0 2026-08-24 15:29:29.470535 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-08-24 15:29:29.470552 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.470570 | instance | Process install dependency map 2026-08-24 15:29:29.470587 | instance | Starting collection install process 2026-08-24 15:29:29.470604 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-08-24 15:29:29.470621 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-08-24 15:29:29.470639 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-08-24 15:29:29.470656 | instance | Checked out vexxhost.containers to v1.6.8 2026-08-24 15:29:29.470677 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-08-24 15:29:29.470699 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.470727 | instance | Process install dependency map 2026-08-24 15:29:29.470749 | instance | Starting collection install process 2026-08-24 15:29:29.470770 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-08-24 15:29:29.470792 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-08-24 15:29:29.470814 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-08-24 15:29:29.470836 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-08-24 15:29:29.470855 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-08-24 15:29:29.470872 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.470889 | instance | Process install dependency map 2026-08-24 15:29:29.470906 | instance | Starting collection install process 2026-08-24 15:29:29.470923 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-08-24 15:29:29.470940 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-08-24 15:29:29.470957 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-08-24 15:29:29.477139 | 2026-08-24 15:29:29.477198 | PLAY RECAP 2026-08-24 15:29:29.477257 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:29:29.477345 | 2026-08-24 15:29:29.641576 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-24 15:29:29.644156 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-24 15:29:30.343910 | 2026-08-24 15:29:30.344028 | PLAY [all] 2026-08-24 15:29:30.354578 | 2026-08-24 15:29:30.354657 | TASK [Install "jq" for log collection] 2026-08-24 15:29:40.442638 | instance | changed 2026-08-24 15:29:40.444404 | 2026-08-24 15:29:40.444464 | PLAY RECAP 2026-08-24 15:29:40.444513 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:29:40.444560 | 2026-08-24 15:29:40.569312 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-24 15:29:40.571211 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-24 15:29:41.319618 | 2026-08-24 15:29:41.319765 | PLAY [all] 2026-08-24 15:29:41.335674 | 2026-08-24 15:29:41.336008 | LOOP [Check inventory directories] 2026-08-24 15:29:41.697978 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-08-24 15:29:41.855154 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-08-24 15:29:42.021173 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-08-24 15:29:42.039226 | 2026-08-24 15:29:42.039456 | LOOP [Copy inventory file for Zuul] 2026-08-24 15:29:42.852743 | instance | changed: 2026-08-24 15:29:42.853009 | instance | { 2026-08-24 15:29:42.853062 | instance | "ansible_loop_var": "item", 2026-08-24 15:29:42.853104 | instance | "changed": false, 2026-08-24 15:29:42.853139 | instance | "failed": false, 2026-08-24 15:29:42.853192 | instance | "invocation": { 2026-08-24 15:29:42.853246 | instance | "module_args": { 2026-08-24 15:29:42.853283 | instance | "checksum_algorithm": "sha1", 2026-08-24 15:29:42.853316 | instance | "follow": true, 2026-08-24 15:29:42.853349 | instance | "get_attributes": true, 2026-08-24 15:29:42.853383 | instance | "get_checksum": true, 2026-08-24 15:29:42.853414 | instance | "get_mime": true, 2026-08-24 15:29:42.853446 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-08-24 15:29:42.853594 | instance | } 2026-08-24 15:29:42.853654 | instance | }, 2026-08-24 15:29:42.853686 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-08-24 15:29:42.853714 | instance | "stat": { 2026-08-24 15:29:42.853741 | instance | "atime": 1787585361.260994, 2026-08-24 15:29:42.853769 | instance | "attr_flags": "e", 2026-08-24 15:29:42.853805 | instance | "attributes": [ 2026-08-24 15:29:42.853832 | instance | "extents" 2026-08-24 15:29:42.853858 | instance | ], 2026-08-24 15:29:42.853883 | instance | "block_size": 4096, 2026-08-24 15:29:42.853909 | instance | "blocks": 8, 2026-08-24 15:29:42.853935 | instance | "charset": "unknown", 2026-08-24 15:29:42.853963 | instance | "ctime": 1787585360.3529782, 2026-08-24 15:29:42.853990 | instance | "dev": 64513, 2026-08-24 15:29:42.854017 | instance | "device_type": 0, 2026-08-24 15:29:42.854042 | instance | "executable": true, 2026-08-24 15:29:42.854066 | instance | "exists": true, 2026-08-24 15:29:42.854091 | instance | "gid": 1000, 2026-08-24 15:29:42.854117 | instance | "gr_name": "zuul", 2026-08-24 15:29:42.854142 | instance | "inode": 1047532, 2026-08-24 15:29:42.854167 | instance | "isblk": false, 2026-08-24 15:29:42.854190 | instance | "ischr": false, 2026-08-24 15:29:42.854210 | instance | "isdir": true, 2026-08-24 15:29:42.854229 | instance | "isfifo": false, 2026-08-24 15:29:42.854246 | instance | "isgid": false, 2026-08-24 15:29:42.854266 | instance | "islnk": false, 2026-08-24 15:29:42.854291 | instance | "isreg": false, 2026-08-24 15:29:42.854311 | instance | "issock": false, 2026-08-24 15:29:42.854328 | instance | "isuid": false, 2026-08-24 15:29:42.854345 | instance | "mimetype": "unknown", 2026-08-24 15:29:42.854362 | instance | "mode": "0755", 2026-08-24 15:29:42.854379 | instance | "mtime": 1787585360.3529782, 2026-08-24 15:29:42.854396 | instance | "nlink": 25, 2026-08-24 15:29:42.854413 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-08-24 15:29:42.854430 | instance | "pw_name": "zuul", 2026-08-24 15:29:42.854447 | instance | "readable": true, 2026-08-24 15:29:42.854464 | instance | "rgrp": true, 2026-08-24 15:29:42.854481 | instance | "roth": true, 2026-08-24 15:29:42.854498 | instance | "rusr": true, 2026-08-24 15:29:42.854515 | instance | "size": 4096, 2026-08-24 15:29:42.854531 | instance | "uid": 1000, 2026-08-24 15:29:42.854548 | instance | "version": "751527158", 2026-08-24 15:29:42.854931 | instance | "wgrp": false, 2026-08-24 15:29:42.855251 | instance | "woth": false, 2026-08-24 15:29:42.855298 | instance | "writeable": true, 2026-08-24 15:29:42.855322 | instance | "wusr": true, 2026-08-24 15:29:42.855341 | instance | "xgrp": true, 2026-08-24 15:29:42.855358 | instance | "xoth": true, 2026-08-24 15:29:42.855376 | instance | "xusr": true 2026-08-24 15:29:42.855393 | instance | } 2026-08-24 15:29:42.855410 | instance | } 2026-08-24 15:29:42.855451 | instance | ok: All items complete 2026-08-24 15:29:42.855474 | 2026-08-24 15:29:42.856864 | instance | skipping: Conditional result was False 2026-08-24 15:29:42.857512 | instance | skipping: Conditional result was False 2026-08-24 15:29:42.874056 | 2026-08-24 15:29:42.874181 | LOOP [Switch "ansible_host" to private IP] 2026-08-24 15:29:43.245332 | instance | changed: 1 replacements made 2026-08-24 15:29:43.247976 | instance | skipping: Conditional result was False 2026-08-24 15:29:43.249168 | instance | skipping: Conditional result was False 2026-08-24 15:29:43.267486 | 2026-08-24 15:29:43.268510 | TASK [Run Molecule scenario] 2026-08-24 15:29:44.198988 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-08-24 15:29:44.563335 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-08-24 15:29:44.563461 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-08-24 15:29:54.759833 | instance | INFO [csi > dependency] Executing 2026-08-24 15:29:54.760004 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-08-24 15:29:54.760217 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-08-24 15:29:54.760359 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-08-24 15:29:54.768115 | instance | INFO [csi > cleanup] Executing 2026-08-24 15:29:54.768478 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:29:54.776660 | instance | INFO [csi > destroy] Executing 2026-08-24 15:29:54.776707 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-08-24 15:29:54.776781 | instance | INFO [csi > destroy] Executed: Successful 2026-08-24 15:29:54.785182 | instance | INFO [csi > syntax] Executing 2026-08-24 15:29:55.497859 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:55.498097 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:55.635009 | instance | 2026-08-24 15:29:55.635220 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-08-24 15:29:55.701592 | instance | INFO [csi > syntax] Executed: Successful 2026-08-24 15:29:55.710447 | instance | INFO [csi > create] Executing 2026-08-24 15:29:55.712262 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:29:55.721726 | instance | INFO [csi > prepare] Executing 2026-08-24 15:29:56.341230 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:56.341449 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:56.465118 | instance | [WARNING]: Could not match supplied host pattern, ignoring: cephs 2026-08-24 15:29:56.465401 | instance | 2026-08-24 15:29:56.465676 | instance | PLAY [Prepare] ***************************************************************** 2026-08-24 15:29:56.465945 | instance | skipping: no hosts matched 2026-08-24 15:29:56.466201 | instance | 2026-08-24 15:29:56.466473 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-08-24 15:29:56.466743 | instance | skipping: no hosts matched 2026-08-24 15:29:56.467104 | instance | 2026-08-24 15:29:56.467361 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:56.467614 | instance | 2026-08-24 15:29:56.467864 | instance | 2026-08-24 15:29:56.468130 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:56.468403 | instance | Monday 24 August 2026 15:29:56 +0000 (0:00:00.012) 0:00:00.012 ********* 2026-08-24 15:29:56.468663 | instance | =============================================================================== 2026-08-24 15:29:56.514274 | instance | INFO [csi > prepare] Executed: Successful 2026-08-24 15:29:56.523991 | instance | INFO [csi > converge] Executing 2026-08-24 15:29:57.104899 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:57.105121 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:57.186539 | instance | 2026-08-24 15:29:57.186611 | instance | PLAY [all] ********************************************************************* 2026-08-24 15:29:57.186816 | instance | skipping: no hosts matched 2026-08-24 15:29:57.186864 | instance | 2026-08-24 15:29:57.186977 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:57.187123 | instance | 2026-08-24 15:29:57.187290 | instance | 2026-08-24 15:29:57.187467 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:57.187650 | instance | Monday 24 August 2026 15:29:57 +0000 (0:00:00.003) 0:00:00.003 ********* 2026-08-24 15:29:57.187823 | instance | =============================================================================== 2026-08-24 15:29:57.244240 | instance | INFO [csi > converge] Executed: Successful 2026-08-24 15:29:57.255092 | instance | INFO [csi > idempotence] Executing 2026-08-24 15:29:57.900537 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:57.900736 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:57.970293 | instance | 2026-08-24 15:29:57.970699 | instance | PLAY [all] ********************************************************************* 2026-08-24 15:29:57.971161 | instance | skipping: no hosts matched 2026-08-24 15:29:57.971487 | instance | 2026-08-24 15:29:57.971862 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:57.972232 | instance | 2026-08-24 15:29:57.972598 | instance | 2026-08-24 15:29:57.973006 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:57.973340 | instance | Monday 24 August 2026 15:29:57 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-24 15:29:57.973654 | instance | =============================================================================== 2026-08-24 15:29:58.012912 | instance | INFO [csi > idempotence] Executed: Successful 2026-08-24 15:29:58.023786 | instance | INFO [csi > side_effect] Executing 2026-08-24 15:29:58.024091 | instance | WARNING [csi > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:29:58.031628 | instance | INFO [csi > verify] Executing 2026-08-24 15:29:58.672578 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:58.672690 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:58.718049 | instance | [WARNING]: Could not match supplied host pattern, ignoring: controllers 2026-08-24 15:29:58.718217 | instance | 2026-08-24 15:29:58.718386 | instance | PLAY [Verify] ****************************************************************** 2026-08-24 15:29:58.718551 | instance | skipping: no hosts matched 2026-08-24 15:29:58.718721 | instance | 2026-08-24 15:29:58.718899 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:58.719049 | instance | 2026-08-24 15:29:58.719210 | instance | 2026-08-24 15:29:58.719381 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:58.719557 | instance | Monday 24 August 2026 15:29:58 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-24 15:29:58.719723 | instance | =============================================================================== 2026-08-24 15:29:58.755914 | instance | INFO [csi > verify] Executed: Successful 2026-08-24 15:29:58.769637 | instance | INFO [csi > cleanup] Executing 2026-08-24 15:29:58.769914 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:29:58.777435 | instance | INFO [csi > destroy] Executing 2026-08-24 15:29:58.777509 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-08-24 15:29:58.777598 | instance | INFO [csi > destroy] Executed: Successful 2026-08-24 15:29:58.777890 | instance | WARNING Molecule executed 1 scenario (1 missing files) 2026-08-24 15:29:59.333771 | instance | ok: Runtime: 0:00:15.225905 2026-08-24 15:29:59.337696 | 2026-08-24 15:29:59.337809 | PLAY RECAP 2026-08-24 15:29:59.337870 | instance | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:29:59.337937 | 2026-08-24 15:29:59.477536 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-24 15:29:59.480695 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-24 15:30:00.585488 | 2026-08-24 15:30:00.585614 | PLAY [all] 2026-08-24 15:30:00.600734 | 2026-08-24 15:30:00.600863 | TASK [gather-host-logs : creating directory for system status] 2026-08-24 15:30:00.993790 | instance | changed 2026-08-24 15:30:00.998953 | 2026-08-24 15:30:00.999031 | TASK [gather-host-logs : Get logs for each host] 2026-08-24 15:30:01.428600 | instance | + systemd-cgls --full --all --no-pager 2026-08-24 15:30:01.440852 | instance | + ip addr 2026-08-24 15:30:01.442503 | instance | + ip route 2026-08-24 15:30:01.443828 | instance | + lsblk 2026-08-24 15:30:01.446242 | instance | + mount 2026-08-24 15:30:01.447484 | instance | + docker images 2026-08-24 15:30:01.447839 | instance | /bin/bash: line 7: docker: command not found 2026-08-24 15:30:01.447998 | instance | + brctl show 2026-08-24 15:30:01.448287 | instance | /bin/bash: line 8: brctl: command not found 2026-08-24 15:30:01.448455 | instance | + ps aux --sort=-%mem 2026-08-24 15:30:01.459684 | instance | + dpkg -l 2026-08-24 15:30:01.467557 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-08-24 15:30:01.467927 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-08-24 15:30:01.467974 | instance | /bin/bash: line 11: docker: command not found 2026-08-24 15:30:01.468343 | instance | + '[' '!' -z '' ']' 2026-08-24 15:30:01.559648 | instance | ok: Runtime: 0:00:00.044581 2026-08-24 15:30:01.567306 | 2026-08-24 15:30:01.567386 | TASK [gather-host-logs : Downloads logs to executor] 2026-08-24 15:30:02.866805 | instance | changed: 2026-08-24 15:30:02.866983 | instance | created directory /var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/work/logs/instance 2026-08-24 15:30:02.867012 | instance | cd+++++++++ system/ 2026-08-24 15:30:02.867035 | instance | >f+++++++++ system/brctl-show.txt 2026-08-24 15:30:02.867056 | instance | >f+++++++++ system/docker-images.txt 2026-08-24 15:30:02.867075 | instance | >f+++++++++ system/ip-addr.txt 2026-08-24 15:30:02.867098 | instance | >f+++++++++ system/ip-route.txt 2026-08-24 15:30:02.867119 | instance | >f+++++++++ system/lsblk.txt 2026-08-24 15:30:02.867138 | instance | >f+++++++++ system/mount.txt 2026-08-24 15:30:02.867160 | instance | >f+++++++++ system/packages.txt 2026-08-24 15:30:02.867179 | instance | >f+++++++++ system/ps.txt 2026-08-24 15:30:02.867198 | instance | >f+++++++++ system/systemd-cgls.txt 2026-08-24 15:30:02.876355 | 2026-08-24 15:30:02.876424 | LOOP [helm-release-status : creating directory for helm release status] 2026-08-24 15:30:03.078559 | instance | changed: "values" 2026-08-24 15:30:03.274738 | instance | changed: "releases" 2026-08-24 15:30:03.288323 | 2026-08-24 15:30:03.288502 | TASK [helm-release-status : Gather get release status for helm charts] 2026-08-24 15:30:03.533814 | instance | /bin/bash: line 3: kubectl: command not found 2026-08-24 15:30:03.822043 | instance | ok: Runtime: 0:00:00.005447 2026-08-24 15:30:03.830724 | 2026-08-24 15:30:03.830863 | TASK [helm-release-status : Downloads logs to executor] 2026-08-24 15:30:04.368968 | instance | changed: 2026-08-24 15:30:04.369119 | instance | cd+++++++++ helm/ 2026-08-24 15:30:04.369149 | instance | cd+++++++++ helm/releases/ 2026-08-24 15:30:04.369171 | instance | cd+++++++++ helm/values/ 2026-08-24 15:30:04.379317 | 2026-08-24 15:30:04.379407 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-08-24 15:30:04.567918 | instance | changed 2026-08-24 15:30:04.586643 | 2026-08-24 15:30:04.586757 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-08-24 15:30:04.861156 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:04.861329 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:04.866252 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:04.867571 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:04.869133 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:04.870535 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:04.871325 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:04.872565 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:04.874295 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:04.875205 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:04.875391 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:04.876510 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:05.134728 | instance | ok: Runtime: 0:00:00.031410 2026-08-24 15:30:05.141840 | 2026-08-24 15:30:05.141994 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-08-24 15:30:05.356386 | instance | changed 2026-08-24 15:30:05.363997 | 2026-08-24 15:30:05.364099 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-08-24 15:30:05.571849 | instance | environment: line 5: kubectl: command not found 2026-08-24 15:30:05.572795 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:05.572973 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:05.573418 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:05.901331 | instance | ok: Runtime: 0:00:00.007682 2026-08-24 15:30:05.909325 | 2026-08-24 15:30:05.909538 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-08-24 15:30:06.461961 | instance | changed: 2026-08-24 15:30:06.462217 | instance | cd+++++++++ objects/ 2026-08-24 15:30:06.462258 | instance | cd+++++++++ objects/cluster/ 2026-08-24 15:30:06.462290 | instance | cd+++++++++ objects/namespaced/ 2026-08-24 15:30:06.481763 | 2026-08-24 15:30:06.481971 | TASK [gather-pod-logs : creating directory for pod logs] 2026-08-24 15:30:06.695309 | instance | changed 2026-08-24 15:30:06.702190 | 2026-08-24 15:30:06.702283 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-08-24 15:30:06.895784 | instance | changed 2026-08-24 15:30:06.900928 | 2026-08-24 15:30:06.901012 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-08-24 15:30:07.110416 | instance | environment: line 3: kubectl: command not found 2026-08-24 15:30:07.440387 | instance | ok: Runtime: 0:00:00.008653 2026-08-24 15:30:07.447385 | 2026-08-24 15:30:07.447537 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-08-24 15:30:07.947902 | instance | changed: 2026-08-24 15:30:07.948156 | instance | cd+++++++++ pod-logs/ 2026-08-24 15:30:07.948202 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-08-24 15:30:07.961780 | 2026-08-24 15:30:07.961915 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-08-24 15:30:08.160346 | instance | changed 2026-08-24 15:30:08.168004 | 2026-08-24 15:30:08.168200 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-08-24 15:30:08.403237 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-24 15:30:08.714455 | instance | ok: Runtime: 0:00:00.042341 2026-08-24 15:30:08.722166 | 2026-08-24 15:30:08.722270 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-08-24 15:30:08.925277 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-24 15:30:08.953400 | instance | ceph-mgr endpoints: 2026-08-24 15:30:09.258811 | instance | ok: Runtime: 0:00:00.035065 2026-08-24 15:30:09.266793 | 2026-08-24 15:30:09.266906 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-08-24 15:30:09.478449 | instance | /bin/bash: line 4: kubectl: command not found 2026-08-24 15:30:09.805073 | instance | ok: Runtime: 0:00:00.036947 2026-08-24 15:30:09.812511 | 2026-08-24 15:30:09.812637 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-08-24 15:30:10.348629 | instance | changed: cd+++++++++ prometheus/ 2026-08-24 15:30:10.358562 | 2026-08-24 15:30:10.358643 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-08-24 15:30:10.570936 | instance | changed 2026-08-24 15:30:10.580015 | 2026-08-24 15:30:10.580214 | TASK [gather-selenium-data : Get selenium data] 2026-08-24 15:30:10.862479 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-08-24 15:30:10.864165 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-08-24 15:30:11.123112 | instance | ERROR 2026-08-24 15:30:11.123291 | instance | { 2026-08-24 15:30:11.123335 | instance | "delta": "0:00:00.007668", 2026-08-24 15:30:11.123365 | instance | "end": "2026-08-24 15:30:10.864662", 2026-08-24 15:30:11.123392 | instance | "msg": "non-zero return code", 2026-08-24 15:30:11.123417 | instance | "rc": 1, 2026-08-24 15:30:11.123443 | instance | "start": "2026-08-24 15:30:10.856994" 2026-08-24 15:30:11.123807 | instance | } 2026-08-24 15:30:11.123981 | instance | ERROR: Ignoring Errors 2026-08-24 15:30:11.131800 | 2026-08-24 15:30:11.131922 | TASK [gather-selenium-data : Downloads logs to executor] 2026-08-24 15:30:11.623092 | instance | changed: cd+++++++++ selenium/ 2026-08-24 15:30:11.650613 | 2026-08-24 15:30:11.650737 | PLAY RECAP 2026-08-24 15:30:11.650813 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-08-24 15:30:11.650840 | 2026-08-24 15:30:11.800619 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-24 15:30:11.805520 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-24 15:30:12.495994 | 2026-08-24 15:30:12.496149 | PLAY [all] 2026-08-24 15:30:12.510172 | 2026-08-24 15:30:12.510335 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-24 15:30:12.556171 | instance | skipping: Conditional result was False 2026-08-24 15:30:12.563153 | 2026-08-24 15:30:12.563457 | TASK [fetch-output : Set log path for single node] 2026-08-24 15:30:12.604028 | instance | ok 2026-08-24 15:30:12.610327 | 2026-08-24 15:30:12.610420 | LOOP [fetch-output : Ensure local output dirs] 2026-08-24 15:30:13.096890 | instance -> localhost | ok: "/var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/work/logs" 2026-08-24 15:30:13.339668 | instance -> localhost | changed: "/var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/work/artifacts" 2026-08-24 15:30:13.582090 | instance -> localhost | changed: "/var/lib/zuul/builds/eff7567f391e47ee85622afe71e6ead5/work/docs" 2026-08-24 15:30:13.601325 | 2026-08-24 15:30:13.601435 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-24 15:30:14.273425 | instance | changed: .d..t...... ./ 2026-08-24 15:30:14.273623 | instance | changed: All items complete 2026-08-24 15:30:14.273651 | 2026-08-24 15:30:14.769092 | instance | changed: .d..t...... ./ 2026-08-24 15:30:15.271810 | instance | changed: .d..t...... ./ 2026-08-24 15:30:15.292197 | 2026-08-24 15:30:15.292319 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-24 15:30:15.903211 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.082300 2026-08-24 15:30:16.179027 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.010042 2026-08-24 15:30:16.199791 | 2026-08-24 15:30:16.200228 | PLAY [all] 2026-08-24 15:30:16.207018 | 2026-08-24 15:30:16.207086 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-24 15:30:16.630094 | instance | changed 2026-08-24 15:30:16.636929 | 2026-08-24 15:30:16.637001 | PLAY RECAP 2026-08-24 15:30:16.637085 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-24 15:30:16.637110 | 2026-08-24 15:30:16.840665 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-24 15:30:16.844745 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-24 15:30:19.200533 | 2026-08-24 15:30:19.200644 | PLAY [localhost] 2026-08-24 15:30:19.211314 | 2026-08-24 15:30:19.211400 | TASK [Generate Zuul manifest] 2026-08-24 15:30:19.234724 | localhost | ok 2026-08-24 15:30:20.204609 | 2026-08-24 15:30:20.204770 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-24 15:30:20.729611 | localhost | changed 2026-08-24 15:30:20.741634 | 2026-08-24 15:30:20.741722 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-24 15:30:20.783163 | localhost | ok 2026-08-24 15:30:20.793043 | 2026-08-24 15:30:20.793149 | TASK [Upload logs] 2026-08-24 15:30:20.815782 | localhost | ok 2026-08-24 15:30:20.944087 | 2026-08-24 15:30:20.944204 | TASK [Set zuul-log-path fact] 2026-08-24 15:30:20.965838 | localhost | ok 2026-08-24 15:30:20.978052 | 2026-08-24 15:30:20.978122 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-24 15:30:21.009037 | localhost | ok 2026-08-24 15:30:21.017870 | 2026-08-24 15:30:21.017944 | TASK [upload-logs : Create log directories] 2026-08-24 15:30:21.451274 | localhost | changed 2026-08-24 15:30:21.459114 | 2026-08-24 15:30:21.459257 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-24 15:30:21.953346 | localhost -> localhost | ok: Runtime: 0:00:00.050437 2026-08-24 15:30:21.961675 | 2026-08-24 15:30:21.961799 | TASK [upload-logs : Upload logs to log server] 2026-08-24 15:30:22.477826 | localhost | Output suppressed because no_log was given 2026-08-24 15:30:22.514282 | 2026-08-24 15:30:22.514402 | LOOP [upload-logs : Compress console log and json output] 2026-08-24 15:30:22.555408 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.563923 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.573795 | 2026-08-24 15:30:22.573930 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-24 15:30:22.613047 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.613675 | 2026-08-24 15:30:22.617637 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.629358 | 2026-08-24 15:30:22.629511 | LOOP [upload-logs : Upload console log and json output]