2026-07-29 13:28:56.703067 | Job console starting
2026-07-29 13:28:56.715925 | Updating git repos
2026-07-29 13:28:57.138922 | Cloning repos into workspace
2026-07-29 13:29:28.694728 | Restoring repo states
2026-07-29 13:29:39.571579 | Merging changes
2026-07-29 13:29:40.906932 | Checking out repos
2026-07-29 13:29:42.378528 | Preparing playbooks
2026-07-29 13:29:47.506199 | Running Ansible setup
2026-07-29 13:29:51.210724 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 13:29:51.936409 | 
2026-07-29 13:29:51.936604 | PLAY [localhost]
2026-07-29 13:29:51.948659 | 
2026-07-29 13:29:51.948820 | TASK [Gathering Facts]
2026-07-29 13:29:52.844616 | localhost | ok
2026-07-29 13:29:52.855314 | 
2026-07-29 13:29:52.855418 | TASK [Setup log path fact]
2026-07-29 13:29:52.873250 | localhost | ok
2026-07-29 13:29:52.884995 | 
2026-07-29 13:29:52.885127 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 13:29:52.913722 | localhost | ok
2026-07-29 13:29:52.921716 | 
2026-07-29 13:29:52.921831 | TASK [emit-job-header : Print job information]
2026-07-29 13:29:52.962851 | # Job Information
2026-07-29 13:29:52.963023 | Ansible Version: 2.16.18
2026-07-29 13:29:52.963070 | Job: atmosphere-molecule-keycloak-selective
2026-07-29 13:29:52.963102 | Pipeline: check
2026-07-29 13:29:52.963131 | Executor: 2d72f0692154
2026-07-29 13:29:52.963159 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4164
2026-07-29 13:29:52.963190 | Event ID: 0936d8f0-8b50-11f1-845b-672cdb40f1bd
2026-07-29 13:29:52.967211 | 
2026-07-29 13:29:52.967304 | LOOP [emit-job-header : Print node information]
2026-07-29 13:29:53.072340 | localhost | ok:
2026-07-29 13:29:53.072573 | localhost | # Node Information
2026-07-29 13:29:53.072635 | localhost | Inventory Hostname: instance
2026-07-29 13:29:53.072683 | localhost | Hostname: np0000187395
2026-07-29 13:29:53.072727 | localhost | Username: zuul
2026-07-29 13:29:53.072776 | localhost | Distro: Ubuntu 22.04
2026-07-29 13:29:53.072820 | localhost | Provider: yul1
2026-07-29 13:29:53.072862 | localhost | Region: ca-ymq-1
2026-07-29 13:29:53.072903 | localhost | Label: ubuntu-jammy
2026-07-29 13:29:53.072943 | localhost | Product Name: OpenStack Nova
2026-07-29 13:29:53.073021 | localhost | Interface IP: 199.204.45.240
2026-07-29 13:29:53.089328 | 
2026-07-29 13:29:53.089747 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-29 13:29:53.492854 | localhost -> localhost | changed
2026-07-29 13:29:53.503203 | 
2026-07-29 13:29:53.503356 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-29 13:29:54.470811 | localhost -> localhost | changed
2026-07-29 13:29:54.477442 | 
2026-07-29 13:29:54.477519 | PLAY [all]
2026-07-29 13:29:54.485860 | 
2026-07-29 13:29:54.485958 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-29 13:29:54.733318 | instance -> localhost | ok
2026-07-29 13:29:54.739570 | 
2026-07-29 13:29:54.739699 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-29 13:29:54.774257 | instance | ok
2026-07-29 13:29:54.790033 | instance | included: /var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-29 13:29:54.796885 | 
2026-07-29 13:29:54.796978 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-29 13:29:56.310643 | instance -> localhost | Generating public/private rsa key pair.
2026-07-29 13:29:56.310929 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/work/3595e5c98c204e4287d33586761709ae_id_rsa
2026-07-29 13:29:56.310979 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/work/3595e5c98c204e4287d33586761709ae_id_rsa.pub
2026-07-29 13:29:56.311012 | instance -> localhost | The key fingerprint is:
2026-07-29 13:29:56.311047 | instance -> localhost | SHA256:Sx3dx5jHcoet0zx1E4xArfPEyHwFcuh4BUgZQKQGiqs zuul-build-sshkey
2026-07-29 13:29:56.311110 | instance -> localhost | The key's randomart image is:
2026-07-29 13:29:56.311149 | instance -> localhost | +---[RSA 3072]----+
2026-07-29 13:29:56.311196 | instance -> localhost | |   .  o+oo===o+. |
2026-07-29 13:29:56.311233 | instance -> localhost | |. . . .  o oo= X.|
2026-07-29 13:29:56.311266 | instance -> localhost | |..   o    * * B.@|
2026-07-29 13:29:56.311297 | instance -> localhost | | .  .    o X + O=|
2026-07-29 13:29:56.311327 | instance -> localhost | |.       S o = o.o|
2026-07-29 13:29:56.311354 | instance -> localhost | |.      . .   . ..|
2026-07-29 13:29:56.311382 | instance -> localhost | |E       .        |
2026-07-29 13:29:56.311405 | instance -> localhost | |                 |
2026-07-29 13:29:56.311439 | instance -> localhost | |                 |
2026-07-29 13:29:56.311471 | instance -> localhost | +----[SHA256]-----+
2026-07-29 13:29:56.311561 | instance -> localhost | ok: Runtime: 0:00:01.089005
2026-07-29 13:29:56.318969 | 
2026-07-29 13:29:56.319115 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-29 13:29:56.355351 | instance | ok
2026-07-29 13:29:56.368809 | instance | included: /var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-29 13:29:56.377373 | 
2026-07-29 13:29:56.377480 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-29 13:29:56.405256 | instance | skipping: Conditional result was False
2026-07-29 13:29:56.414925 | 
2026-07-29 13:29:56.415093 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-29 13:29:56.877305 | instance | changed
2026-07-29 13:29:56.883417 | 
2026-07-29 13:29:56.883539 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-29 13:29:57.089533 | instance | ok
2026-07-29 13:29:57.098549 | 
2026-07-29 13:29:57.098684 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-29 13:29:57.605642 | instance | changed
2026-07-29 13:29:57.613348 | 
2026-07-29 13:29:57.613502 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-29 13:29:58.087075 | instance | changed
2026-07-29 13:29:58.094386 | 
2026-07-29 13:29:58.094540 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-29 13:29:58.119095 | instance | skipping: Conditional result was False
2026-07-29 13:29:58.129823 | 
2026-07-29 13:29:58.130035 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-29 13:29:58.585158 | instance -> localhost | changed
2026-07-29 13:29:58.600872 | 
2026-07-29 13:29:58.601015 | TASK [add-build-sshkey : Add back temp key]
2026-07-29 13:29:58.877499 | instance -> localhost | Identity added: /var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/work/3595e5c98c204e4287d33586761709ae_id_rsa (zuul-build-sshkey)
2026-07-29 13:29:58.877719 | instance -> localhost | ok: Runtime: 0:00:00.013736
2026-07-29 13:29:58.884906 | 
2026-07-29 13:29:58.885070 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-29 13:29:59.233338 | instance | ok
2026-07-29 13:29:59.240587 | 
2026-07-29 13:29:59.240684 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-29 13:29:59.264871 | instance | skipping: Conditional result was False
2026-07-29 13:29:59.303911 | 
2026-07-29 13:29:59.304027 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-29 13:29:59.603881 | instance | ok
2026-07-29 13:29:59.612682 | 
2026-07-29 13:29:59.612802 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-29 13:30:03.067753 | instance | Output suppressed because no_log was given
2026-07-29 13:30:03.077932 | 
2026-07-29 13:30:03.078081 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-29 13:30:03.273325 | instance | ok: "logs"
2026-07-29 13:30:03.273641 | instance | ok: All items complete
2026-07-29 13:30:03.273671 | 
2026-07-29 13:30:03.426144 | instance | ok: "artifacts"
2026-07-29 13:30:03.578864 | instance | ok: "docs"
2026-07-29 13:30:03.601218 | 
2026-07-29 13:30:03.601344 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-29 13:30:03.774745 | instance | changed: "logs"
2026-07-29 13:30:03.929274 | instance | changed: "artifacts"
2026-07-29 13:30:04.077847 | instance | changed: "docs"
2026-07-29 13:30:04.095995 | 
2026-07-29 13:30:04.096275 | PLAY RECAP
2026-07-29 13:30:04.096403 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-29 13:30:04.096471 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:30:04.096526 | 
2026-07-29 13:30:04.289676 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 13:30:04.295568 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 13:30:04.951335 | 
2026-07-29 13:30:04.951498 | PLAY [all]
2026-07-29 13:30:04.964079 | 
2026-07-29 13:30:04.964234 | TASK [setup-uv : Extract archive]
2026-07-29 13:30:07.261177 | instance | changed
2026-07-29 13:30:07.274371 | 
2026-07-29 13:30:07.274537 | TASK [setup-uv : Print version]
2026-07-29 13:30:07.658340 | instance | uv 0.8.13
2026-07-29 13:30:07.825045 | instance | ok: Runtime: 0:00:00.014120
2026-07-29 13:30:07.836672 | 
2026-07-29 13:30:07.836745 | TASK [Install Ansible collection siblings]
2026-07-29 13:30:16.873455 | instance | changed:
2026-07-29 13:30:16.873664 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-29 13:30:16.873708 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-29 13:30:16.873740 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-29 13:30:16.873769 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-29 13:30:16.873799 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-29 13:30:16.873836 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-29 13:30:16.873880 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-29 13:30:16.873927 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-29 13:30:16.873972 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-29 13:30:16.874006 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-29 13:30:16.874035 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-29 13:30:16.874063 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-29 13:30:16.874090 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-29 13:30:16.874117 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-29 13:30:16.874143 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-29 13:30:16.874211 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-29 13:30:16.874249 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-29 13:30:16.874289 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-29 13:30:16.874330 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-29 13:30:16.874372 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-29 13:30:16.874405 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-29 13:30:16.874433 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-29 13:30:16.874470 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 13:30:16.874529 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-29 13:30:16.874577 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-29 13:30:16.874619 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 13:30:16.874655 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 13:30:16.874690 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 13:30:16.874728 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-29 13:30:16.874768 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-29 13:30:16.874807 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 13:30:16.874850 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 13:30:16.874893 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-29 13:30:16.874936 | 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-07-29 13:30:16.874985 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.875017 | instance | Process install dependency map
2026-07-29 13:30:16.875044 | instance | Starting collection install process
2026-07-29 13:30:16.875071 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-29 13:30:16.875097 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-29 13:30:16.875125 | instance | ansible.netcommon:* was installed successfully
2026-07-29 13:30:16.875152 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-29 13:30:16.875178 | instance | Creating virtual environment at: .venv
2026-07-29 13:30:16.875205 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 13:30:16.875232 | instance | Downloading cryptography (4.2MiB)
2026-07-29 13:30:16.875259 | instance | Downloading rjsonnet (1.2MiB)
2026-07-29 13:30:16.875291 | instance | Downloading openstacksdk (1.7MiB)
2026-07-29 13:30:16.875319 | instance | Downloading kubernetes (1.9MiB)
2026-07-29 13:30:16.875346 | instance | Downloading ansible-core (2.1MiB)
2026-07-29 13:30:16.875380 | instance | Downloading setuptools (1.1MiB)
2026-07-29 13:30:16.875421 | instance | Downloading pydantic-core (2.0MiB)
2026-07-29 13:30:16.875463 | instance | Downloading netaddr (2.2MiB)
2026-07-29 13:30:16.875505 | instance | Downloading pygments (1.2MiB)
2026-07-29 13:30:16.875536 | instance |    Building pyperclip==1.9.0
2026-07-29 13:30:16.875567 | instance |  Downloading rjsonnet
2026-07-29 13:30:16.875595 | instance |  Downloading pydantic-core
2026-07-29 13:30:16.875623 | instance |  Downloading netaddr
2026-07-29 13:30:16.875654 | instance |  Downloading pygments
2026-07-29 13:30:16.875690 | instance |  Downloading cryptography
2026-07-29 13:30:16.875730 | instance |  Downloading setuptools
2026-07-29 13:30:16.875770 | instance |  Downloading kubernetes
2026-07-29 13:30:16.875810 | instance |  Downloading ansible-core
2026-07-29 13:30:16.875841 | instance |  Downloading openstacksdk
2026-07-29 13:30:16.875869 | instance |       Built pyperclip==1.9.0
2026-07-29 13:30:16.875895 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 13:30:16.875923 | instance | Installed 87 packages in 44ms
2026-07-29 13:30:16.875954 | instance | Checked out ansible.posix to 1.6.0
2026-07-29 13:30:16.875994 | 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-07-29 13:30:16.876036 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.876078 | instance | Process install dependency map
2026-07-29 13:30:16.876123 | instance | Starting collection install process
2026-07-29 13:30:16.876152 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-29 13:30:16.876179 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-29 13:30:16.876207 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-29 13:30:16.876244 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.876283 | instance | 2.17.12
2026-07-29 13:30:16.876324 | instance | Checked out ansible.utils to v6.0.3
2026-07-29 13:30:16.876363 | 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-07-29 13:30:16.876399 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.876428 | instance | Process install dependency map
2026-07-29 13:30:16.876455 | instance | Starting collection install process
2026-07-29 13:30:16.876482 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-29 13:30:16.876508 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-29 13:30:16.876534 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-29 13:30:16.876561 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.876586 | instance | 2.17.12
2026-07-29 13:30:16.876613 | instance | Checked out atmosphere.common to v0.7.0
2026-07-29 13:30:16.876638 | 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-07-29 13:30:16.876664 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.876689 | instance | Process install dependency map
2026-07-29 13:30:16.876719 | instance | Starting collection install process
2026-07-29 13:30:16.876756 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-29 13:30:16.876798 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-29 13:30:16.876840 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-29 13:30:16.876876 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.876904 | instance | 2.17.12
2026-07-29 13:30:16.876931 | instance | Checked out community.crypto to 2.2.3
2026-07-29 13:30:16.876984 | 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-07-29 13:30:16.877029 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.877070 | instance | Process install dependency map
2026-07-29 13:30:16.877111 | instance | Starting collection install process
2026-07-29 13:30:16.877144 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-29 13:30:16.877174 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-29 13:30:16.877211 | instance | community.crypto:2.2.3 was installed successfully
2026-07-29 13:30:16.877251 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.877293 | instance | 2.17.12
2026-07-29 13:30:16.877331 | instance | Checked out community.general to 7.3.0
2026-07-29 13:30:16.877369 | 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-07-29 13:30:16.877418 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.877461 | instance | Process install dependency map
2026-07-29 13:30:16.877499 | instance | Starting collection install process
2026-07-29 13:30:16.877541 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-29 13:30:16.877583 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-29 13:30:16.877622 | instance | community.general:7.3.0 was installed successfully
2026-07-29 13:30:16.877653 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.877681 | instance | 2.17.12
2026-07-29 13:30:16.877708 | instance | Checked out community.mysql to 3.6.0
2026-07-29 13:30:16.877737 | 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-07-29 13:30:16.877765 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.877792 | instance | Process install dependency map
2026-07-29 13:30:16.877818 | instance | Starting collection install process
2026-07-29 13:30:16.877844 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-29 13:30:16.877871 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-29 13:30:16.877897 | instance | community.mysql:3.6.0 was installed successfully
2026-07-29 13:30:16.877923 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.877952 | instance | 2.17.12
2026-07-29 13:30:16.877990 | instance | Checked out kubernetes.core to 2.4.0
2026-07-29 13:30:16.878033 | 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-07-29 13:30:16.878077 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.878111 | instance | Process install dependency map
2026-07-29 13:30:16.878138 | instance | Starting collection install process
2026-07-29 13:30:16.878191 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-29 13:30:16.878219 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-29 13:30:16.878247 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-29 13:30:16.878280 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.878317 | instance | 2.17.12
2026-07-29 13:30:16.878356 | instance | Checked out openstack.cloud to 2.6.0
2026-07-29 13:30:16.878397 | 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-07-29 13:30:16.878436 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.878465 | instance | Process install dependency map
2026-07-29 13:30:16.878491 | instance | Starting collection install process
2026-07-29 13:30:16.878523 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-29 13:30:16.878563 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-29 13:30:16.878606 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-29 13:30:16.878645 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.878676 | instance | 2.17.12
2026-07-29 13:30:16.878710 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-29 13:30:16.878747 | 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-07-29 13:30:16.878783 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.878813 | instance | Process install dependency map
2026-07-29 13:30:16.878839 | instance | Starting collection install process
2026-07-29 13:30:16.878863 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-29 13:30:16.878895 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-29 13:30:16.878928 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-29 13:30:16.878960 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.878990 | instance | 2.17.12
2026-07-29 13:30:16.879023 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-29 13:30:16.879055 | 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-07-29 13:30:16.879087 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.879120 | instance | Process install dependency map
2026-07-29 13:30:16.879159 | instance | Starting collection install process
2026-07-29 13:30:16.879201 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-29 13:30:16.879235 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-29 13:30:16.879267 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-29 13:30:16.879299 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.879333 | instance | 2.17.12
2026-07-29 13:30:16.879365 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-29 13:30:16.879397 | 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-07-29 13:30:16.879431 | instance | Starting galaxy collection install process
2026-07-29 13:30:16.879466 | instance | Process install dependency map
2026-07-29 13:30:16.879505 | instance | Starting collection install process
2026-07-29 13:30:16.879546 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-29 13:30:16.879586 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-29 13:30:16.879622 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-29 13:30:16.879656 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:30:16.879692 | instance | 2.17.12
2026-07-29 13:30:16.887843 | 
2026-07-29 13:30:16.887894 | PLAY RECAP
2026-07-29 13:30:16.887938 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:30:16.887960 | 
2026-07-29 13:30:17.018609 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 13:30:17.021217 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 13:30:17.620517 | 
2026-07-29 13:30:17.620645 | PLAY [all]
2026-07-29 13:30:17.633703 | 
2026-07-29 13:30:17.633834 | TASK [Install "jq" for log collection]
2026-07-29 13:31:00.281766 | instance | changed
2026-07-29 13:31:00.286077 | 
2026-07-29 13:31:00.286210 | PLAY RECAP
2026-07-29 13:31:00.286316 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:31:00.286489 | 
2026-07-29 13:31:00.412414 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 13:31:00.417968 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 13:31:01.140397 | 
2026-07-29 13:31:01.140581 | PLAY [Prepare selective Molecule plan]
2026-07-29 13:31:01.152797 | 
2026-07-29 13:31:01.152900 | TASK [Generate selective Molecule plan]
2026-07-29 13:31:01.698575 | instance | ok: Runtime: 0:00:00.168340
2026-07-29 13:31:01.703609 | 
2026-07-29 13:31:01.703762 | TASK [Render selective Molecule plan]
2026-07-29 13:31:01.992948 | instance | Selective Molecule plan: selective
2026-07-29 13:31:01.993213 | instance | Changed files:
2026-07-29 13:31:01.993228 | instance |   M roles/glance/README.md
2026-07-29 13:31:01.993239 | instance | Targets: glance
2026-07-29 13:31:01.993249 | instance | Job decisions:
2026-07-29 13:31:01.993262 | instance |   RUN  aio-openvswitch: selected by changed components
2026-07-29 13:31:01.993277 | instance |        components: ceph, ceph-provisioners, cert-manager, cluster-issuer, csi, glance, ingress-nginx, keycloak, keystone, kubernetes, memcached, percona-xtradb-cluster, percona-xtradb-cluster-operator, rabbitmq-cluster-operator
2026-07-29 13:31:01.993292 | instance |        Tempest include: ^tempest\.api\.image\.
2026-07-29 13:31:01.993301 | instance |   SKIP aio-ovn: no changed component requires this job
2026-07-29 13:31:01.993311 | instance |   SKIP csi-local-path-provisioner: no changed component requires this job
2026-07-29 13:31:01.993321 | instance |   SKIP csi-rbd: no changed component requires this job
2026-07-29 13:31:01.993330 | instance |   SKIP keycloak: no changed component requires this job
2026-07-29 13:31:02.239299 | instance | ok: Runtime: 0:00:00.098908
2026-07-29 13:31:02.241621 | 
2026-07-29 13:31:02.241699 | PLAY RECAP
2026-07-29 13:31:02.241764 | instance | ok: 2 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:31:02.241794 | 
2026-07-29 13:31:02.378736 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 13:31:02.383545 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 13:31:03.000465 | 
2026-07-29 13:31:03.000617 | PLAY [Run selected Molecule scenario]
2026-07-29 13:31:03.012042 | 
2026-07-29 13:31:03.012154 | TASK [Read selective Molecule plan]
2026-07-29 13:31:03.341305 | instance | ok
2026-07-29 13:31:03.347469 | 
2026-07-29 13:31:03.347567 | TASK [Resolve this job decision]
2026-07-29 13:31:03.404314 | instance | ok
2026-07-29 13:31:03.410817 | 
2026-07-29 13:31:03.410922 | TASK [Assert this job exists in the plan]
2026-07-29 13:31:03.439975 | instance | ok: All assertions passed
2026-07-29 13:31:03.444406 | 
2026-07-29 13:31:03.444487 | TASK [Resolve selected scenario inputs]
2026-07-29 13:31:03.484944 | instance | ok
2026-07-29 13:31:03.489773 | 
2026-07-29 13:31:03.489842 | TASK [Report this job decision]
2026-07-29 13:31:03.555573 | instance | ok:
2026-07-29 13:31:03.555813 | instance | Selective Molecule job: keycloak
2026-07-29 13:31:03.555874 | instance | Decision: SKIP
2026-07-29 13:31:03.555929 | instance | Reason: no changed component requires this job
2026-07-29 13:31:03.555982 | instance | Scenario: keycloak
2026-07-29 13:31:03.556028 | instance | Targets: (none)
2026-07-29 13:31:03.556073 | instance | Components: (full scenario)
2026-07-29 13:31:03.562329 | 
2026-07-29 13:31:03.562445 | LOOP [Check Molecule inventory directories]
2026-07-29 13:31:03.608591 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.610283 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.612092 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.625726 | 
2026-07-29 13:31:03.625908 | LOOP [Copy Zuul inventory for Molecule]
2026-07-29 13:31:03.666100 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.666470 | 
2026-07-29 13:31:03.667950 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.670715 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.682346 | 
2026-07-29 13:31:03.682579 | LOOP [Use private addresses in Molecule inventory]
2026-07-29 13:31:03.721069 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.721441 | 
2026-07-29 13:31:03.723211 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.725629 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.736575 | 
2026-07-29 13:31:03.736732 | TASK [Prepare Molecule command]
2026-07-29 13:31:03.783458 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.793613 | 
2026-07-29 13:31:03.793706 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-29 13:31:03.837850 | instance | skipping: Conditional result was False
2026-07-29 13:31:03.839322 | 
2026-07-29 13:31:03.839403 | PLAY RECAP
2026-07-29 13:31:03.839482 | instance | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-07-29 13:31:03.839534 | 
2026-07-29 13:31:03.963603 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 13:31:03.966133 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 13:31:04.590917 | 
2026-07-29 13:31:04.591141 | PLAY [Publish selective Molecule plan]
2026-07-29 13:31:04.605183 | 
2026-07-29 13:31:04.605337 | TASK [Check for selective Molecule plan]
2026-07-29 13:31:04.977918 | instance | ok
2026-07-29 13:31:04.987774 | 
2026-07-29 13:31:04.987934 | TASK [Create log directory]
2026-07-29 13:31:05.345133 | instance | changed
2026-07-29 13:31:05.353397 | 
2026-07-29 13:31:05.353499 | TASK [Copy selective Molecule plan to logs]
2026-07-29 13:31:05.783369 | instance | changed
2026-07-29 13:31:05.784898 | 
2026-07-29 13:31:05.784946 | PLAY RECAP
2026-07-29 13:31:05.785008 | instance | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:31:05.785031 | 
2026-07-29 13:31:05.886902 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 13:31:05.891197 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 13:31:06.550780 | 
2026-07-29 13:31:06.550939 | PLAY [all]
2026-07-29 13:31:06.565898 | 
2026-07-29 13:31:06.566034 | TASK [gather-host-logs : creating directory for system status]
2026-07-29 13:31:06.894392 | instance | changed
2026-07-29 13:31:06.902803 | 
2026-07-29 13:31:06.902898 | TASK [gather-host-logs : Get logs for each host]
2026-07-29 13:31:07.254180 | instance | + systemd-cgls --full --all --no-pager
2026-07-29 13:31:07.269148 | instance | + ip addr
2026-07-29 13:31:07.272409 | instance | + ip route
2026-07-29 13:31:07.273985 | instance | + lsblk
2026-07-29 13:31:07.276210 | instance | + mount
2026-07-29 13:31:07.277554 | instance | + docker images
2026-07-29 13:31:07.278014 | instance | /bin/bash: line 7: docker: command not found
2026-07-29 13:31:07.278238 | instance | + brctl show
2026-07-29 13:31:07.278715 | instance | /bin/bash: line 8: brctl: command not found
2026-07-29 13:31:07.278951 | instance | + ps aux --sort=-%mem
2026-07-29 13:31:07.290075 | instance | + dpkg -l
2026-07-29 13:31:07.296901 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-29 13:31:07.297293 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-29 13:31:07.297338 | instance | /bin/bash: line 11: docker: command not found
2026-07-29 13:31:07.297589 | instance | + '[' '!' -z '' ']'
2026-07-29 13:31:07.441046 | instance | ok: Runtime: 0:00:00.049274
2026-07-29 13:31:07.449305 | 
2026-07-29 13:31:07.449381 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-29 13:31:08.089953 | instance | changed:
2026-07-29 13:31:08.090197 | instance | created directory /var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/work/logs/instance
2026-07-29 13:31:08.090254 | instance | cd+++++++++ system/
2026-07-29 13:31:08.090304 | instance | >f+++++++++ system/brctl-show.txt
2026-07-29 13:31:08.090346 | instance | >f+++++++++ system/docker-images.txt
2026-07-29 13:31:08.090390 | instance | >f+++++++++ system/ip-addr.txt
2026-07-29 13:31:08.090433 | instance | >f+++++++++ system/ip-route.txt
2026-07-29 13:31:08.090477 | instance | >f+++++++++ system/lsblk.txt
2026-07-29 13:31:08.090515 | instance | >f+++++++++ system/mount.txt
2026-07-29 13:31:08.090561 | instance | >f+++++++++ system/packages.txt
2026-07-29 13:31:08.090600 | instance | >f+++++++++ system/ps.txt
2026-07-29 13:31:08.090640 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-29 13:31:08.102784 | 
2026-07-29 13:31:08.102904 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-29 13:31:08.305198 | instance | changed: "values"
2026-07-29 13:31:08.493953 | instance | changed: "releases"
2026-07-29 13:31:08.506120 | 
2026-07-29 13:31:08.506264 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-29 13:31:08.718121 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-29 13:31:09.045330 | instance | ok: Runtime: 0:00:00.006754
2026-07-29 13:31:09.054321 | 
2026-07-29 13:31:09.054488 | TASK [helm-release-status : Downloads logs to executor]
2026-07-29 13:31:09.551236 | instance | changed:
2026-07-29 13:31:09.551415 | instance | cd+++++++++ helm/
2026-07-29 13:31:09.551444 | instance | cd+++++++++ helm/releases/
2026-07-29 13:31:09.551468 | instance | cd+++++++++ helm/values/
2026-07-29 13:31:09.561682 | 
2026-07-29 13:31:09.561763 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-29 13:31:09.778611 | instance | changed
2026-07-29 13:31:09.783684 | 
2026-07-29 13:31:09.783757 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-29 13:31:10.037791 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:31:10.037870 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:31:10.042794 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:31:10.044177 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:31:10.045392 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:31:10.047284 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:31:10.047647 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:31:10.048806 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:31:10.050746 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:31:10.052816 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:31:10.053483 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:31:10.054872 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:31:10.354092 | instance | ok: Runtime: 0:00:00.028720
2026-07-29 13:31:10.361150 | 
2026-07-29 13:31:10.361222 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-29 13:31:10.556814 | instance | changed
2026-07-29 13:31:10.563118 | 
2026-07-29 13:31:10.563217 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-29 13:31:10.819280 | instance | environment: line 5: kubectl: command not found
2026-07-29 13:31:10.820055 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:31:10.820170 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:31:10.820623 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:31:11.104157 | instance | ok: Runtime: 0:00:00.009975
2026-07-29 13:31:11.111773 | 
2026-07-29 13:31:11.111943 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-29 13:31:11.706007 | instance | changed:
2026-07-29 13:31:11.706276 | instance | cd+++++++++ objects/
2026-07-29 13:31:11.706342 | instance | cd+++++++++ objects/cluster/
2026-07-29 13:31:11.706394 | instance | cd+++++++++ objects/namespaced/
2026-07-29 13:31:11.720612 | 
2026-07-29 13:31:11.720781 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-29 13:31:11.919654 | instance | changed
2026-07-29 13:31:11.928330 | 
2026-07-29 13:31:11.928493 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-29 13:31:12.125541 | instance | changed
2026-07-29 13:31:12.131459 | 
2026-07-29 13:31:12.131530 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-29 13:31:12.358049 | instance | environment: line 3: kubectl: command not found
2026-07-29 13:31:12.676493 | instance | ok: Runtime: 0:00:00.009202
2026-07-29 13:31:12.681819 | 
2026-07-29 13:31:12.681890 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-29 13:31:13.194632 | instance | changed:
2026-07-29 13:31:13.194790 | instance | cd+++++++++ pod-logs/
2026-07-29 13:31:13.194817 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-29 13:31:13.203922 | 
2026-07-29 13:31:13.203996 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-29 13:31:13.408052 | instance | changed
2026-07-29 13:31:13.415068 | 
2026-07-29 13:31:13.415165 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-29 13:31:13.635953 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 13:31:13.951700 | instance | ok: Runtime: 0:00:00.035131
2026-07-29 13:31:13.958616 | 
2026-07-29 13:31:13.958740 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-29 13:31:14.157891 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 13:31:14.185956 | instance | ceph-mgr endpoints:
2026-07-29 13:31:14.496405 | instance | ok: Runtime: 0:00:00.035767
2026-07-29 13:31:14.502073 | 
2026-07-29 13:31:14.502148 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-29 13:31:14.705996 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-29 13:31:15.037407 | instance | ok: Runtime: 0:00:00.039173
2026-07-29 13:31:15.044190 | 
2026-07-29 13:31:15.044278 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-29 13:31:15.554926 | instance | changed: cd+++++++++ prometheus/
2026-07-29 13:31:15.567639 | 
2026-07-29 13:31:15.567774 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-29 13:31:15.786086 | instance | changed
2026-07-29 13:31:15.791027 | 
2026-07-29 13:31:15.791124 | TASK [gather-selenium-data : Get selenium data]
2026-07-29 13:31:16.038053 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-29 13:31:16.039640 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-29 13:31:16.362548 | instance | ERROR
2026-07-29 13:31:16.362770 | instance | {
2026-07-29 13:31:16.362818 | instance |   "delta": "0:00:00.006703",
2026-07-29 13:31:16.362849 | instance |   "end": "2026-07-29 13:31:16.040010",
2026-07-29 13:31:16.362878 | instance |   "msg": "non-zero return code",
2026-07-29 13:31:16.362905 | instance |   "rc": 1,
2026-07-29 13:31:16.362932 | instance |   "start": "2026-07-29 13:31:16.033307"
2026-07-29 13:31:16.362958 | instance | }
2026-07-29 13:31:16.363007 | instance | ERROR: Ignoring Errors
2026-07-29 13:31:16.369503 | 
2026-07-29 13:31:16.369592 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-29 13:31:16.867549 | instance | changed: cd+++++++++ selenium/
2026-07-29 13:31:16.874760 | 
2026-07-29 13:31:16.874827 | PLAY RECAP
2026-07-29 13:31:16.874875 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-29 13:31:16.874899 | 
2026-07-29 13:31:17.006425 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 13:31:17.012884 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 13:31:17.631838 | 
2026-07-29 13:31:17.632002 | PLAY [all]
2026-07-29 13:31:17.645047 | 
2026-07-29 13:31:17.645197 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-29 13:31:17.689780 | instance | skipping: Conditional result was False
2026-07-29 13:31:17.697647 | 
2026-07-29 13:31:17.697815 | TASK [fetch-output : Set log path for single node]
2026-07-29 13:31:17.730623 | instance | ok
2026-07-29 13:31:17.737379 | 
2026-07-29 13:31:17.737495 | LOOP [fetch-output : Ensure local output dirs]
2026-07-29 13:31:18.124644 | instance -> localhost | ok: "/var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/work/logs"
2026-07-29 13:31:18.334733 | instance -> localhost | changed: "/var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/work/artifacts"
2026-07-29 13:31:18.575443 | instance -> localhost | changed: "/var/lib/zuul/builds/3595e5c98c204e4287d33586761709ae/work/docs"
2026-07-29 13:31:18.596327 | 
2026-07-29 13:31:18.596442 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-29 13:31:19.238756 | instance | changed: .d..t...... ./
2026-07-29 13:31:19.238974 | instance | changed: All items complete
2026-07-29 13:31:19.239014 | 
2026-07-29 13:31:19.689168 | instance | changed: .d..t...... ./
2026-07-29 13:31:20.164392 | instance | changed: .d..t...... ./
2026-07-29 13:31:20.180846 | 
2026-07-29 13:31:20.180945 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-29 13:31:20.589777 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.004831
2026-07-29 13:31:20.853906 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007377
2026-07-29 13:31:20.868905 | 
2026-07-29 13:31:20.869110 | PLAY [all]
2026-07-29 13:31:20.875665 | 
2026-07-29 13:31:20.875742 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-29 13:31:21.312483 | instance | changed
2026-07-29 13:31:21.318096 | 
2026-07-29 13:31:21.318147 | PLAY RECAP
2026-07-29 13:31:21.318191 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-29 13:31:21.318212 | 
2026-07-29 13:31:21.419241 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 13:31:21.425264 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-29 13:31:22.114854 | 
2026-07-29 13:31:22.114969 | PLAY [localhost]
2026-07-29 13:31:22.124534 | 
2026-07-29 13:31:22.124611 | TASK [Generate Zuul manifest]
2026-07-29 13:31:22.146586 | localhost | ok
2026-07-29 13:31:22.162180 | 
2026-07-29 13:31:22.162266 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-29 13:31:22.544434 | localhost | changed
2026-07-29 13:31:22.556089 | 
2026-07-29 13:31:22.556300 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-29 13:31:22.586091 | localhost | ok
2026-07-29 13:31:22.598211 | 
2026-07-29 13:31:22.598379 | TASK [Upload logs]
2026-07-29 13:31:22.621144 | localhost | ok
2026-07-29 13:31:22.681036 | 
2026-07-29 13:31:22.681168 | TASK [Set zuul-log-path fact]
2026-07-29 13:31:22.703165 | localhost | ok
2026-07-29 13:31:22.717690 | 
2026-07-29 13:31:22.717782 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 13:31:22.748158 | localhost | ok
2026-07-29 13:31:22.758261 | 
2026-07-29 13:31:22.758357 | TASK [upload-logs : Create log directories]
2026-07-29 13:31:23.199823 | localhost | changed
2026-07-29 13:31:23.206061 | 
2026-07-29 13:31:23.206126 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-29 13:31:23.572448 | localhost -> localhost | ok: Runtime: 0:00:00.004210
2026-07-29 13:31:23.579713 | 
2026-07-29 13:31:23.579812 | TASK [upload-logs : Upload logs to log server]
2026-07-29 13:31:24.007196 | localhost | Output suppressed because no_log was given
2026-07-29 13:31:24.013292 | 
2026-07-29 13:31:24.013392 | LOOP [upload-logs : Compress console log and json output]
2026-07-29 13:31:24.060593 | localhost | skipping: Conditional result was False
2026-07-29 13:31:24.073130 | localhost | skipping: Conditional result was False
2026-07-29 13:31:24.087141 | 
2026-07-29 13:31:24.087318 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-29 13:31:24.138856 | localhost | skipping: Conditional result was False
2026-07-29 13:31:24.139237 | 
2026-07-29 13:31:24.142702 | localhost | skipping: Conditional result was False
2026-07-29 13:31:24.152807 | 
2026-07-29 13:31:24.152972 | LOOP [upload-logs : Upload console log and json output]