2026-08-26 23:58:28.305108 | Job console starting
2026-08-26 23:58:28.312826 | Updating git repos
2026-08-26 23:58:33.746026 | Cloning repos into workspace
2026-08-26 23:58:49.476089 | Restoring repo states
2026-08-26 23:58:52.786871 | Merging changes
2026-08-26 23:58:54.220101 | Checking out repos
2026-08-26 23:58:57.055406 | Preparing playbooks
2026-08-26 23:59:01.619146 | Running Ansible setup
2026-08-26 23:59:07.120746 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 23:59:07.735704 | 
2026-08-26 23:59:07.735887 | PLAY [localhost]
2026-08-26 23:59:07.744429 | 
2026-08-26 23:59:07.744508 | TASK [Gathering Facts]
2026-08-26 23:59:08.614989 | localhost | ok
2026-08-26 23:59:08.622093 | 
2026-08-26 23:59:08.622176 | TASK [Setup log path fact]
2026-08-26 23:59:08.640786 | localhost | ok
2026-08-26 23:59:08.651026 | 
2026-08-26 23:59:08.651094 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 23:59:08.679144 | localhost | ok
2026-08-26 23:59:08.684705 | 
2026-08-26 23:59:08.684772 | TASK [emit-job-header : Print job information]
2026-08-26 23:59:08.728017 | # Job Information
2026-08-26 23:59:08.728165 | Ansible Version: 2.16.18
2026-08-26 23:59:08.728209 | Job: atmosphere-molecule-keycloak
2026-08-26 23:59:08.728239 | Pipeline: check
2026-08-26 23:59:08.728267 | Executor: 2d72f0692154
2026-08-26 23:59:08.728294 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-08-26 23:59:08.728330 | Event ID: 82ffc060-a1a9-11f1-9fc1-9d94a7497734
2026-08-26 23:59:08.731979 | 
2026-08-26 23:59:08.732061 | LOOP [emit-job-header : Print node information]
2026-08-26 23:59:08.828377 | localhost | ok:
2026-08-26 23:59:08.828635 | localhost | # Node Information
2026-08-26 23:59:08.828676 | localhost | Inventory Hostname: instance
2026-08-26 23:59:08.828706 | localhost | Hostname: np0000191321
2026-08-26 23:59:08.828735 | localhost | Username: zuul
2026-08-26 23:59:08.828766 | localhost | Distro: Ubuntu 22.04
2026-08-26 23:59:08.828794 | localhost | Provider: yul1
2026-08-26 23:59:08.828836 | localhost | Region: ca-ymq-1
2026-08-26 23:59:08.828872 | localhost | Label: ubuntu-jammy
2026-08-26 23:59:08.828905 | localhost | Product Name: OpenStack Nova
2026-08-26 23:59:08.828939 | localhost | Interface IP: 162.253.55.86
2026-08-26 23:59:08.840747 | 
2026-08-26 23:59:08.840899 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 23:59:09.210193 | localhost -> localhost | changed
2026-08-26 23:59:09.218974 | 
2026-08-26 23:59:09.219074 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 23:59:10.455944 | localhost -> localhost | changed
2026-08-26 23:59:10.465374 | 
2026-08-26 23:59:10.465466 | PLAY [all]
2026-08-26 23:59:10.473635 | 
2026-08-26 23:59:10.473707 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 23:59:10.690546 | instance -> localhost | ok
2026-08-26 23:59:10.701094 | 
2026-08-26 23:59:10.701188 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 23:59:10.732882 | instance | ok
2026-08-26 23:59:10.748340 | instance | included: /var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 23:59:10.753680 | 
2026-08-26 23:59:10.753742 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 23:59:11.536037 | instance -> localhost | Generating public/private rsa key pair.
2026-08-26 23:59:11.536314 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/work/2b825097626747ffae93ec53630a2a29_id_rsa
2026-08-26 23:59:11.536366 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/work/2b825097626747ffae93ec53630a2a29_id_rsa.pub
2026-08-26 23:59:11.536389 | instance -> localhost | The key fingerprint is:
2026-08-26 23:59:11.536410 | instance -> localhost | SHA256:SrSN7fIGJQFt918qXm30RgixAS6zaVBW8SoYi4oGQdk zuul-build-sshkey
2026-08-26 23:59:11.536441 | instance -> localhost | The key's randomart image is:
2026-08-26 23:59:11.536463 | instance -> localhost | +---[RSA 3072]----+
2026-08-26 23:59:11.536488 | instance -> localhost | | .o .o  o.+oo.   |
2026-08-26 23:59:11.536509 | instance -> localhost | |.. E  +o.. ..o   |
2026-08-26 23:59:11.536529 | instance -> localhost | |.    .+oo.. o. . |
2026-08-26 23:59:11.536548 | instance -> localhost | | .   o.X.=..  + .|
2026-08-26 23:59:11.536568 | instance -> localhost | |.   . *oS .. = o |
2026-08-26 23:59:11.536587 | instance -> localhost | |.. . ..+ .. + o o|
2026-08-26 23:59:11.536607 | instance -> localhost | |...   o... o . . |
2026-08-26 23:59:11.536626 | instance -> localhost | |.      o. .      |
2026-08-26 23:59:11.536649 | instance -> localhost | |       ..        |
2026-08-26 23:59:11.536669 | instance -> localhost | +----[SHA256]-----+
2026-08-26 23:59:11.536720 | instance -> localhost | ok: Runtime: 0:00:00.367849
2026-08-26 23:59:11.541752 | 
2026-08-26 23:59:11.541820 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 23:59:11.575912 | instance | ok
2026-08-26 23:59:11.584603 | instance | included: /var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 23:59:11.592810 | 
2026-08-26 23:59:11.592871 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 23:59:11.618221 | instance | skipping: Conditional result was False
2026-08-26 23:59:11.627450 | 
2026-08-26 23:59:11.627539 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 23:59:12.177673 | instance | changed
2026-08-26 23:59:12.186026 | 
2026-08-26 23:59:12.186125 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 23:59:12.396288 | instance | ok
2026-08-26 23:59:12.401368 | 
2026-08-26 23:59:12.401438 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 23:59:12.921427 | instance | changed
2026-08-26 23:59:12.928249 | 
2026-08-26 23:59:12.928344 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 23:59:13.443106 | instance | changed
2026-08-26 23:59:13.517830 | 
2026-08-26 23:59:13.517937 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 23:59:13.548112 | instance | skipping: Conditional result was False
2026-08-26 23:59:13.554716 | 
2026-08-26 23:59:13.554809 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 23:59:13.932992 | instance -> localhost | changed
2026-08-26 23:59:13.947456 | 
2026-08-26 23:59:13.947632 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 23:59:14.217594 | instance -> localhost | Identity added: /var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/work/2b825097626747ffae93ec53630a2a29_id_rsa (zuul-build-sshkey)
2026-08-26 23:59:14.218028 | instance -> localhost | ok: Runtime: 0:00:00.014127
2026-08-26 23:59:14.225922 | 
2026-08-26 23:59:14.225988 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 23:59:14.551887 | instance | ok
2026-08-26 23:59:14.557024 | 
2026-08-26 23:59:14.557100 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 23:59:14.582414 | instance | skipping: Conditional result was False
2026-08-26 23:59:14.597507 | 
2026-08-26 23:59:14.597635 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 23:59:14.978610 | instance | ok
2026-08-26 23:59:14.986205 | 
2026-08-26 23:59:14.986271 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 23:59:18.343889 | instance | Output suppressed because no_log was given
2026-08-26 23:59:18.354271 | 
2026-08-26 23:59:18.354342 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 23:59:18.565895 | instance | ok: "logs"
2026-08-26 23:59:18.566464 | instance | ok: All items complete
2026-08-26 23:59:18.566496 | 
2026-08-26 23:59:18.727048 | instance | ok: "artifacts"
2026-08-26 23:59:18.881047 | instance | ok: "docs"
2026-08-26 23:59:18.898476 | 
2026-08-26 23:59:18.898665 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 23:59:19.084755 | instance | changed: "logs"
2026-08-26 23:59:19.237884 | instance | changed: "artifacts"
2026-08-26 23:59:19.375968 | instance | changed: "docs"
2026-08-26 23:59:19.398760 | 
2026-08-26 23:59:19.398919 | PLAY RECAP
2026-08-26 23:59:19.398986 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 23:59:19.399028 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 23:59:19.399063 | 
2026-08-26 23:59:19.559680 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 23:59:19.563475 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 23:59:20.168472 | 
2026-08-26 23:59:20.168605 | PLAY [all]
2026-08-26 23:59:20.179825 | 
2026-08-26 23:59:20.179904 | TASK [setup-uv : Extract archive]
2026-08-26 23:59:26.386397 | instance | changed
2026-08-26 23:59:26.436884 | 
2026-08-26 23:59:26.437354 | TASK [setup-uv : Print version]
2026-08-26 23:59:26.864159 | instance | uv 0.8.13
2026-08-26 23:59:26.994811 | instance | ok: Runtime: 0:00:00.013714
2026-08-26 23:59:27.008556 | 
2026-08-26 23:59:27.008629 | TASK [Install Ansible collection siblings]
2026-08-26 23:59:36.266004 | instance | changed:
2026-08-26 23:59:36.266205 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-26 23:59:36.266234 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-26 23:59:36.266256 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-26 23:59:36.266276 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-26 23:59:36.266296 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-26 23:59:36.266319 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-26 23:59:36.266339 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-26 23:59:36.266361 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-26 23:59:36.266381 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-26 23:59:36.266400 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-26 23:59:36.266420 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-26 23:59:36.266439 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-26 23:59:36.266458 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-26 23:59:36.266476 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-26 23:59:36.266494 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-26 23:59:36.266513 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-26 23:59:36.266531 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-26 23:59:36.266549 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-26 23:59:36.266567 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-26 23:59:36.266584 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-26 23:59:36.266602 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-26 23:59:36.266620 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-26 23:59:36.266639 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-26 23:59:36.266667 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-26 23:59:36.266687 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-26 23:59:36.266705 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-26 23:59:36.266723 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-26 23:59:36.266741 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-26 23:59:36.266760 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-26 23:59:36.266778 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-26 23:59:36.266795 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-26 23:59:36.266814 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-26 23:59:36.266832 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-26 23:59:36.266850 | 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-26 23:59:36.266873 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.266892 | instance | Process install dependency map
2026-08-26 23:59:36.266909 | instance | Starting collection install process
2026-08-26 23:59:36.266927 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-26 23:59:36.266945 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-26 23:59:36.266963 | instance | ansible.netcommon:* was installed successfully
2026-08-26 23:59:36.266980 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-26 23:59:36.266998 | instance | Creating virtual environment at: .venv
2026-08-26 23:59:36.267015 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-26 23:59:36.267033 | instance | Downloading pygments (1.2MiB)
2026-08-26 23:59:36.267050 | instance | Downloading netaddr (2.2MiB)
2026-08-26 23:59:36.267072 | instance | Downloading setuptools (1.1MiB)
2026-08-26 23:59:36.267090 | instance | Downloading openstacksdk (1.7MiB)
2026-08-26 23:59:36.267108 | instance | Downloading rjsonnet (1.2MiB)
2026-08-26 23:59:36.267126 | instance | Downloading pydantic-core (2.0MiB)
2026-08-26 23:59:36.267144 | instance | Downloading ansible-core (2.1MiB)
2026-08-26 23:59:36.267162 | instance | Downloading cryptography (4.2MiB)
2026-08-26 23:59:36.267180 | instance | Downloading kubernetes (1.9MiB)
2026-08-26 23:59:36.267198 | instance |    Building pyperclip==1.9.0
2026-08-26 23:59:36.267217 | instance |  Downloading rjsonnet
2026-08-26 23:59:36.267236 | instance |  Downloading pydantic-core
2026-08-26 23:59:36.267254 | instance |  Downloading netaddr
2026-08-26 23:59:36.267273 | instance |  Downloading pygments
2026-08-26 23:59:36.267290 | instance |  Downloading cryptography
2026-08-26 23:59:36.267308 | instance |  Downloading setuptools
2026-08-26 23:59:36.267326 | instance |  Downloading kubernetes
2026-08-26 23:59:36.267344 | instance |  Downloading ansible-core
2026-08-26 23:59:36.267361 | instance |  Downloading openstacksdk
2026-08-26 23:59:36.267380 | instance |       Built pyperclip==1.9.0
2026-08-26 23:59:36.267400 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-26 23:59:36.267418 | instance | Installed 83 packages in 53ms
2026-08-26 23:59:36.267436 | instance | Checked out ansible.posix to 1.6.0
2026-08-26 23:59:36.267455 | 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-26 23:59:36.267473 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.267491 | instance | Process install dependency map
2026-08-26 23:59:36.267515 | instance | Starting collection install process
2026-08-26 23:59:36.267534 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-26 23:59:36.267552 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-26 23:59:36.267569 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-26 23:59:36.267587 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.267605 | instance | 2.17.12
2026-08-26 23:59:36.267623 | instance | Checked out ansible.utils to v6.1.0
2026-08-26 23:59:36.267640 | 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-26 23:59:36.267658 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.267676 | instance | Process install dependency map
2026-08-26 23:59:36.267693 | instance | Starting collection install process
2026-08-26 23:59:36.267711 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-26 23:59:36.267730 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-26 23:59:36.267748 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-26 23:59:36.267766 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.267784 | instance | 2.17.12
2026-08-26 23:59:36.267802 | instance | Checked out atmosphere.common to v0.7.0
2026-08-26 23:59:36.267820 | 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-26 23:59:36.267838 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.267855 | instance | Process install dependency map
2026-08-26 23:59:36.267873 | instance | Starting collection install process
2026-08-26 23:59:36.267890 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-26 23:59:36.267908 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-26 23:59:36.267926 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-26 23:59:36.267943 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.267960 | instance | 2.17.12
2026-08-26 23:59:36.267978 | instance | Checked out community.crypto to 2.2.3
2026-08-26 23:59:36.267995 | 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-26 23:59:36.268013 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.268030 | instance | Process install dependency map
2026-08-26 23:59:36.268047 | instance | Starting collection install process
2026-08-26 23:59:36.268064 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-26 23:59:36.268081 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-26 23:59:36.268099 | instance | community.crypto:2.2.3 was installed successfully
2026-08-26 23:59:36.268116 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.268133 | instance | 2.17.12
2026-08-26 23:59:36.268151 | instance | Checked out community.general to 7.3.0
2026-08-26 23:59:36.268169 | 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-26 23:59:36.268192 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.268210 | instance | Process install dependency map
2026-08-26 23:59:36.268228 | instance | Starting collection install process
2026-08-26 23:59:36.268246 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-26 23:59:36.268264 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-26 23:59:36.268282 | instance | community.general:7.3.0 was installed successfully
2026-08-26 23:59:36.268300 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.268318 | instance | 2.17.12
2026-08-26 23:59:36.268336 | instance | Checked out community.mysql to 3.6.0
2026-08-26 23:59:36.268355 | 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-26 23:59:36.268374 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.268392 | instance | Process install dependency map
2026-08-26 23:59:36.268409 | instance | Starting collection install process
2026-08-26 23:59:36.268427 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-26 23:59:36.268444 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-26 23:59:36.268462 | instance | community.mysql:3.6.0 was installed successfully
2026-08-26 23:59:36.268479 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.268496 | instance | 2.17.12
2026-08-26 23:59:36.268514 | instance | Checked out kubernetes.core to 2.4.0
2026-08-26 23:59:36.268532 | 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-26 23:59:36.268550 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.268568 | instance | Process install dependency map
2026-08-26 23:59:36.268586 | instance | Starting collection install process
2026-08-26 23:59:36.268604 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-26 23:59:36.268638 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-26 23:59:36.268658 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-26 23:59:36.268677 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.268694 | instance | 2.17.12
2026-08-26 23:59:36.268712 | instance | Checked out openstack.cloud to 2.6.0
2026-08-26 23:59:36.268729 | 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-26 23:59:36.268747 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.268764 | instance | Process install dependency map
2026-08-26 23:59:36.268782 | instance | Starting collection install process
2026-08-26 23:59:36.268799 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-26 23:59:36.268817 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-26 23:59:36.268834 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-26 23:59:36.268852 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.268868 | instance | 2.17.12
2026-08-26 23:59:36.268890 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-26 23:59:36.268908 | 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-26 23:59:36.268925 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.268943 | instance | Process install dependency map
2026-08-26 23:59:36.268960 | instance | Starting collection install process
2026-08-26 23:59:36.268995 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-26 23:59:36.269014 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-26 23:59:36.269032 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-26 23:59:36.269050 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.269067 | instance | 2.17.12
2026-08-26 23:59:36.269084 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-26 23:59:36.269101 | 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-26 23:59:36.269119 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.269136 | instance | Process install dependency map
2026-08-26 23:59:36.269154 | instance | Starting collection install process
2026-08-26 23:59:36.269171 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-26 23:59:36.269189 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-26 23:59:36.269207 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-26 23:59:36.269224 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.269242 | instance | 2.17.12
2026-08-26 23:59:36.269260 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-26 23:59:36.269277 | 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-26 23:59:36.269295 | instance | Starting galaxy collection install process
2026-08-26 23:59:36.269312 | instance | Process install dependency map
2026-08-26 23:59:36.269329 | instance | Starting collection install process
2026-08-26 23:59:36.269347 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-26 23:59:36.269364 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-26 23:59:36.269382 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-26 23:59:36.269400 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-26 23:59:36.269418 | instance | 2.17.12
2026-08-26 23:59:36.275204 | 
2026-08-26 23:59:36.275254 | PLAY RECAP
2026-08-26 23:59:36.275301 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 23:59:36.275322 | 
2026-08-26 23:59:36.428253 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 23:59:36.434430 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-26 23:59:37.754414 | 
2026-08-26 23:59:37.754557 | PLAY [all]
2026-08-26 23:59:37.766355 | 
2026-08-26 23:59:37.766425 | TASK [Install "jq" for log collection]
2026-08-26 23:59:53.224325 | instance | changed
2026-08-26 23:59:53.334870 | 
2026-08-26 23:59:53.335012 | PLAY RECAP
2026-08-26 23:59:53.335120 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 23:59:53.335240 | 
2026-08-26 23:59:53.481229 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-26 23:59:53.484767 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-26 23:59:54.174567 | 
2026-08-26 23:59:54.175000 | PLAY [all]
2026-08-26 23:59:54.187422 | 
2026-08-26 23:59:54.187492 | LOOP [Check inventory directories]
2026-08-26 23:59:54.548765 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-26 23:59:54.711885 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/keycloak/inventory"
2026-08-26 23:59:54.883937 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/keycloak/inventory"
2026-08-26 23:59:54.902962 | 
2026-08-26 23:59:54.903226 | LOOP [Copy inventory file for Zuul]
2026-08-26 23:59:55.738878 | instance | changed:
2026-08-26 23:59:55.739076 | instance | {
2026-08-26 23:59:55.739105 | instance |   "ansible_loop_var": "item",
2026-08-26 23:59:55.739128 | instance |   "changed": false,
2026-08-26 23:59:55.739148 | instance |   "failed": false,
2026-08-26 23:59:55.739170 | instance |   "invocation": {
2026-08-26 23:59:55.739188 | instance |     "module_args": {
2026-08-26 23:59:55.739206 | instance |       "checksum_algorithm": "sha1",
2026-08-26 23:59:55.739224 | instance |       "follow": true,
2026-08-26 23:59:55.739242 | instance |       "get_attributes": true,
2026-08-26 23:59:55.739260 | instance |       "get_checksum": true,
2026-08-26 23:59:55.739278 | instance |       "get_mime": true,
2026-08-26 23:59:55.739296 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-26 23:59:55.739314 | instance |     }
2026-08-26 23:59:55.739331 | instance |   },
2026-08-26 23:59:55.739348 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-26 23:59:55.739365 | instance |   "stat": {
2026-08-26 23:59:55.739382 | instance |     "atime": 1787788769.083304,
2026-08-26 23:59:55.739400 | instance |     "attr_flags": "e",
2026-08-26 23:59:55.739418 | instance |     "attributes": [
2026-08-26 23:59:55.739435 | instance |       "extents"
2026-08-26 23:59:55.739452 | instance |     ],
2026-08-26 23:59:55.739469 | instance |     "block_size": 4096,
2026-08-26 23:59:55.739486 | instance |     "blocks": 8,
2026-08-26 23:59:55.739504 | instance |     "charset": "unknown",
2026-08-26 23:59:55.739522 | instance |     "ctime": 1787788767.8913028,
2026-08-26 23:59:55.739540 | instance |     "dev": 64513,
2026-08-26 23:59:55.739557 | instance |     "device_type": 0,
2026-08-26 23:59:55.739574 | instance |     "executable": true,
2026-08-26 23:59:55.739592 | instance |     "exists": true,
2026-08-26 23:59:55.739608 | instance |     "gid": 1000,
2026-08-26 23:59:55.739626 | instance |     "gr_name": "zuul",
2026-08-26 23:59:55.739643 | instance |     "inode": 1047405,
2026-08-26 23:59:55.739660 | instance |     "isblk": false,
2026-08-26 23:59:55.739677 | instance |     "ischr": false,
2026-08-26 23:59:55.739694 | instance |     "isdir": true,
2026-08-26 23:59:55.739711 | instance |     "isfifo": false,
2026-08-26 23:59:55.739728 | instance |     "isgid": false,
2026-08-26 23:59:55.739744 | instance |     "islnk": false,
2026-08-26 23:59:55.739761 | instance |     "isreg": false,
2026-08-26 23:59:55.739777 | instance |     "issock": false,
2026-08-26 23:59:55.739794 | instance |     "isuid": false,
2026-08-26 23:59:55.739810 | instance |     "mimetype": "unknown",
2026-08-26 23:59:55.739827 | instance |     "mode": "0755",
2026-08-26 23:59:55.739844 | instance |     "mtime": 1787788767.8913028,
2026-08-26 23:59:55.739861 | instance |     "nlink": 23,
2026-08-26 23:59:55.739877 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-26 23:59:55.739894 | instance |     "pw_name": "zuul",
2026-08-26 23:59:55.739912 | instance |     "readable": true,
2026-08-26 23:59:55.739930 | instance |     "rgrp": true,
2026-08-26 23:59:55.739947 | instance |     "roth": true,
2026-08-26 23:59:55.739976 | instance |     "rusr": true,
2026-08-26 23:59:55.739994 | instance |     "size": 4096,
2026-08-26 23:59:55.740011 | instance |     "uid": 1000,
2026-08-26 23:59:55.740029 | instance |     "version": "2500779971",
2026-08-26 23:59:55.740046 | instance |     "wgrp": false,
2026-08-26 23:59:55.740064 | instance |     "woth": false,
2026-08-26 23:59:55.740082 | instance |     "writeable": true,
2026-08-26 23:59:55.740099 | instance |     "wusr": true,
2026-08-26 23:59:55.740116 | instance |     "xgrp": true,
2026-08-26 23:59:55.740133 | instance |     "xoth": true,
2026-08-26 23:59:55.740150 | instance |     "xusr": true
2026-08-26 23:59:55.740167 | instance |   }
2026-08-26 23:59:55.740184 | instance | }
2026-08-26 23:59:55.740218 | instance | ok: All items complete
2026-08-26 23:59:55.740239 | 
2026-08-26 23:59:55.741502 | instance | skipping: Conditional result was False
2026-08-26 23:59:55.742083 | instance | skipping: Conditional result was False
2026-08-26 23:59:55.759057 | 
2026-08-26 23:59:55.759276 | LOOP [Switch "ansible_host" to private IP]
2026-08-26 23:59:56.130561 | instance | changed: 1 replacements made
2026-08-26 23:59:56.131800 | instance | skipping: Conditional result was False
2026-08-26 23:59:56.132370 | instance | skipping: Conditional result was False
2026-08-26 23:59:56.145760 | 
2026-08-26 23:59:56.145918 | TASK [Run Molecule scenario]
2026-08-26 23:59:57.051506 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-26 23:59:57.444769 | instance | INFO     [keycloak > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-26 23:59:57.444825 | instance | INFO     [keycloak > prerun] Performing prerun with role_name_check=0...
2026-08-27 00:00:07.634099 | instance | INFO     [keycloak > dependency] Executing
2026-08-27 00:00:07.634505 | instance | WARNING  [keycloak > dependency] Missing roles requirements file: requirements.yml
2026-08-27 00:00:07.634891 | instance | WARNING  [keycloak > dependency] Missing collections requirements file: collections.yml
2026-08-27 00:00:07.635116 | instance | WARNING  [keycloak > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-27 00:00:07.649028 | instance | INFO     [keycloak > cleanup] Executing
2026-08-27 00:00:07.649625 | instance | WARNING  [keycloak > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-27 00:00:07.663638 | instance | INFO     [keycloak > destroy] Executing
2026-08-27 00:00:07.663768 | instance | WARNING  [keycloak > destroy] Skipping, '--destroy=never' requested.
2026-08-27 00:00:07.663909 | instance | INFO     [keycloak > destroy] Executed: Successful
2026-08-27 00:00:07.673050 | instance | INFO     [keycloak > syntax] Executing
2026-08-27 00:00:08.758028 | instance |
2026-08-27 00:00:08.758217 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/keycloak/converge.yml
2026-08-27 00:00:08.808729 | instance | INFO     [keycloak > syntax] Executed: Successful
2026-08-27 00:00:08.823354 | instance | INFO     [keycloak > create] Executing
2026-08-27 00:00:08.826721 | instance | WARNING  [keycloak > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-27 00:00:08.840417 | instance | INFO     [keycloak > prepare] Executing
2026-08-27 00:00:09.916204 | instance |
2026-08-27 00:00:09.916461 | instance | PLAY [Prepare] *****************************************************************
2026-08-27 00:00:09.916672 | instance |
2026-08-27 00:00:09.916904 | instance | TASK [Gathering Facts] *********************************************************
2026-08-27 00:00:09.917141 | instance | Thursday 27 August 2026  00:00:09 +0000 (0:00:00.023)       0:00:00.023 *******
2026-08-27 00:00:11.152364 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-27 00:00:11.152571 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-27 00:00:11.152815 | instance | interpreter could change the meaning of that path. See
2026-08-27 00:00:11.153042 | instance | https://docs.ansible.com/ansible-
2026-08-27 00:00:11.153275 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-27 00:00:11.163312 | instance | ok: [instance]
2026-08-27 00:00:11.163557 | instance |
2026-08-27 00:00:11.163798 | instance | TASK [Configure short hostname] ************************************************
2026-08-27 00:00:11.164034 | instance | Thursday 27 August 2026  00:00:11 +0000 (0:00:01.247)       0:00:01.270 *******
2026-08-27 00:00:29.001922 | instance | changed: [instance]
2026-08-27 00:00:29.002119 | instance |
2026-08-27 00:00:29.002384 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-27 00:00:29.002616 | instance | Thursday 27 August 2026  00:00:28 +0000 (0:00:17.838)       0:00:19.108 *******
2026-08-27 00:00:41.158789 | instance | fatal: [instance]: FAILED! => {"msg": "Timeout (12s) waiting for privilege escalation prompt: "}
2026-08-27 00:00:41.162827 | instance |
2026-08-27 00:00:41.163264 | instance | PLAY RECAP *********************************************************************
2026-08-27 00:00:41.163598 | instance | instance                   : ok=2    changed=1    unreachable=0    failed=1    skipped=0    rescued=0    ignored=0
2026-08-27 00:00:41.163879 | instance |
2026-08-27 00:00:41.164209 | instance | Thursday 27 August 2026  00:00:41 +0000 (0:00:12.159)       0:00:31.268 *******
2026-08-27 00:00:41.164495 | instance | ===============================================================================
2026-08-27 00:00:41.164784 | instance | Configure short hostname ----------------------------------------------- 17.84s
2026-08-27 00:00:41.165092 | instance | Ensure hostname inside hosts file -------------------------------------- 12.16s
2026-08-27 00:00:41.165424 | instance | Gathering Facts --------------------------------------------------------- 1.25s
2026-08-27 00:00:41.292994 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.keycloak/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/keycloak/prepare.yml
2026-08-27 00:00:41.293321 | instance | ERROR    [keycloak > prepare] Executed: Failed
2026-08-27 00:00:41.293516 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.keycloak/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/keycloak/prepare.yml
2026-08-27 00:00:41.750312 | instance | ERROR
2026-08-27 00:00:41.750569 | instance | {
2026-08-27 00:00:41.750621 | instance |   "delta": "0:00:44.873021",
2026-08-27 00:00:41.750657 | instance |   "end": "2026-08-27 00:00:41.378281",
2026-08-27 00:00:41.750723 | instance |   "msg": "non-zero return code",
2026-08-27 00:00:41.750756 | instance |   "rc": 2,
2026-08-27 00:00:41.750783 | instance |   "start": "2026-08-26 23:59:56.505260"
2026-08-27 00:00:41.750809 | instance | }
failure
2026-08-27 00:00:41.753341 | 
2026-08-27 00:00:41.753406 | PLAY RECAP
2026-08-27 00:00:41.753467 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-27 00:00:41.753498 | 
2026-08-27 00:00:41.899061 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-27 00:00:41.904549 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-27 00:00:42.535113 | 
2026-08-27 00:00:42.535337 | PLAY [all]
2026-08-27 00:00:42.549848 | 
2026-08-27 00:00:42.549928 | TASK [gather-host-logs : creating directory for system status]
2026-08-27 00:00:42.979507 | instance | changed
2026-08-27 00:00:42.985835 | 
2026-08-27 00:00:42.985917 | TASK [gather-host-logs : Get logs for each host]
2026-08-27 00:00:43.360805 | instance | + systemd-cgls --full --all --no-pager
2026-08-27 00:00:43.374167 | instance | + ip addr
2026-08-27 00:00:43.376473 | instance | + ip route
2026-08-27 00:00:43.378643 | instance | + lsblk
2026-08-27 00:00:43.382599 | instance | + mount
2026-08-27 00:00:43.384905 | instance | + docker images
2026-08-27 00:00:43.385391 | instance | /bin/bash: line 7: docker: command not found
2026-08-27 00:00:43.385648 | instance | + brctl show
2026-08-27 00:00:43.386257 | instance | /bin/bash: line 8: brctl: command not found
2026-08-27 00:00:43.386513 | instance | + ps aux --sort=-%mem
2026-08-27 00:00:43.402469 | instance | + dpkg -l
2026-08-27 00:00:43.413288 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-27 00:00:43.413843 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-27 00:00:43.413954 | instance | /bin/bash: line 11: docker: command not found
2026-08-27 00:00:43.414421 | instance | + '[' '!' -z '' ']'
2026-08-27 00:00:43.525975 | instance | ok: Runtime: 0:00:00.059338
2026-08-27 00:00:43.534477 | 
2026-08-27 00:00:43.534557 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-27 00:00:49.198813 | instance | changed:
2026-08-27 00:00:49.199038 | instance | created directory /var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/work/logs/instance
2026-08-27 00:00:49.199080 | instance | cd+++++++++ system/
2026-08-27 00:00:49.199111 | instance | >f+++++++++ system/brctl-show.txt
2026-08-27 00:00:49.199141 | instance | >f+++++++++ system/docker-images.txt
2026-08-27 00:00:49.199169 | instance | >f+++++++++ system/ip-addr.txt
2026-08-27 00:00:49.199200 | instance | >f+++++++++ system/ip-route.txt
2026-08-27 00:00:49.199228 | instance | >f+++++++++ system/lsblk.txt
2026-08-27 00:00:49.199255 | instance | >f+++++++++ system/mount.txt
2026-08-27 00:00:49.199284 | instance | >f+++++++++ system/packages.txt
2026-08-27 00:00:49.199311 | instance | >f+++++++++ system/ps.txt
2026-08-27 00:00:49.199338 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-27 00:00:49.209067 | 
2026-08-27 00:00:49.209139 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-27 00:00:52.483500 | instance | changed: "values"
2026-08-27 00:00:55.556225 | instance | changed: "releases"
2026-08-27 00:00:55.570654 | 
2026-08-27 00:00:55.571129 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-27 00:00:55.777494 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-27 00:00:56.108131 | instance | ok: Runtime: 0:00:00.005983
2026-08-27 00:00:56.114968 | 
2026-08-27 00:00:56.115043 | TASK [helm-release-status : Downloads logs to executor]
2026-08-27 00:01:01.066703 | instance | changed:
2026-08-27 00:01:01.067051 | instance | cd+++++++++ helm/
2026-08-27 00:01:01.067109 | instance | cd+++++++++ helm/releases/
2026-08-27 00:01:01.067140 | instance | cd+++++++++ helm/values/
2026-08-27 00:01:01.078302 | 
2026-08-27 00:01:01.078387 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-27 00:01:16.312347 | instance | changed
2026-08-27 00:01:16.319219 | 
2026-08-27 00:01:16.319324 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-27 00:01:17.416398 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-27 00:01:17.416578 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-27 00:01:17.422919 | instance | environment: line 1: kubectl: command not found
2026-08-27 00:01:17.424618 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-27 00:01:17.424846 | instance | environment: line 1: kubectl: command not found
2026-08-27 00:01:17.426425 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-27 00:01:17.428430 | instance | environment: line 1: kubectl: command not found
2026-08-27 00:01:17.430315 | instance | environment: line 1: kubectl: command not found
2026-08-27 00:01:17.430417 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-27 00:01:17.431606 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-27 00:01:17.433658 | instance | environment: line 1: kubectl: command not found
2026-08-27 00:01:17.434960 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-27 00:01:17.904148 | instance | ok: Runtime: 0:00:00.029227
2026-08-27 00:01:17.910350 | 
2026-08-27 00:01:17.910427 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-27 00:01:23.335730 | instance | changed
2026-08-27 00:01:23.341324 | 
2026-08-27 00:01:23.341399 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-27 00:01:26.634150 | instance | environment: line 5: kubectl: command not found
2026-08-27 00:01:26.634803 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-27 00:01:26.634838 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-27 00:01:26.635509 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-27 00:01:26.881593 | instance | ok: Runtime: 0:00:00.009210
2026-08-27 00:01:26.888223 | 
2026-08-27 00:01:26.888292 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-27 00:01:42.156467 | instance | changed:
2026-08-27 00:01:42.156707 | instance | cd+++++++++ objects/
2026-08-27 00:01:42.156748 | instance | cd+++++++++ objects/cluster/
2026-08-27 00:01:42.156784 | instance | cd+++++++++ objects/namespaced/
2026-08-27 00:01:42.168251 | 
2026-08-27 00:01:42.168328 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-27 00:01:48.518159 | instance | changed
2026-08-27 00:01:48.522988 | 
2026-08-27 00:01:48.523053 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-27 00:02:02.575518 | instance | changed
2026-08-27 00:02:02.582243 | 
2026-08-27 00:02:02.582339 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-27 00:02:08.683366 | instance | environment: line 3: kubectl: command not found
2026-08-27 00:02:09.129687 | instance | ok: Runtime: 0:00:00.009382
2026-08-27 00:02:09.136185 | 
2026-08-27 00:02:09.136271 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-27 00:02:09.633448 | instance | changed:
2026-08-27 00:02:09.633624 | instance | cd+++++++++ pod-logs/
2026-08-27 00:02:09.633652 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-27 00:02:09.642191 | 
2026-08-27 00:02:09.642255 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-27 00:02:15.758240 | instance | changed
2026-08-27 00:02:15.763357 | 
2026-08-27 00:02:15.763426 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-27 00:02:15.992226 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-27 00:02:16.302035 | instance | ok: Runtime: 0:00:00.052441
2026-08-27 00:02:16.308344 | 
2026-08-27 00:02:16.308410 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-27 00:02:26.539514 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-27 00:02:26.584041 | instance | ceph-mgr endpoints:
2026-08-27 00:02:26.862116 | instance | ok: Runtime: 0:00:00.051484
2026-08-27 00:02:26.867700 | 
2026-08-27 00:02:26.867771 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-27 00:02:29.158729 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-27 00:02:29.407504 | instance | ok: Runtime: 0:00:00.049815
2026-08-27 00:02:29.414553 | 
2026-08-27 00:02:29.414646 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-27 00:02:29.917304 | instance | changed: cd+++++++++ prometheus/
2026-08-27 00:02:29.926788 | 
2026-08-27 00:02:29.926857 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-27 00:02:32.505059 | instance | changed
2026-08-27 00:02:32.511603 | 
2026-08-27 00:02:32.511694 | TASK [gather-selenium-data : Get selenium data]
2026-08-27 00:02:35.846207 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-27 00:02:35.847665 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-27 00:02:36.094736 | instance | ERROR
2026-08-27 00:02:36.095076 | instance | {
2026-08-27 00:02:36.095126 | instance |   "delta": "0:00:00.006550",
2026-08-27 00:02:36.095157 | instance |   "end": "2026-08-27 00:02:35.847997",
2026-08-27 00:02:36.095184 | instance |   "msg": "non-zero return code",
2026-08-27 00:02:36.095210 | instance |   "rc": 1,
2026-08-27 00:02:36.095236 | instance |   "start": "2026-08-27 00:02:35.841447"
2026-08-27 00:02:36.095262 | instance | }
2026-08-27 00:02:36.095295 | instance | ERROR: Ignoring Errors
2026-08-27 00:02:36.101913 | 
2026-08-27 00:02:36.102003 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-27 00:02:40.746208 | instance | changed: cd+++++++++ selenium/
2026-08-27 00:02:40.754614 | 
2026-08-27 00:02:40.754668 | PLAY RECAP
2026-08-27 00:02:40.754717 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-27 00:02:40.754739 | 
2026-08-27 00:02:40.892378 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-27 00:02:40.899314 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-27 00:02:41.493082 | 
2026-08-27 00:02:41.493220 | PLAY [all]
2026-08-27 00:02:41.504098 | 
2026-08-27 00:02:41.504176 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-27 00:02:41.541121 | instance | skipping: Conditional result was False
2026-08-27 00:02:41.589358 | 
2026-08-27 00:02:41.589555 | TASK [fetch-output : Set log path for single node]
2026-08-27 00:02:41.636165 | instance | ok
2026-08-27 00:02:41.645084 | 
2026-08-27 00:02:41.645182 | LOOP [fetch-output : Ensure local output dirs]
2026-08-27 00:02:42.016397 | instance -> localhost | ok: "/var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/work/logs"
2026-08-27 00:02:42.218860 | instance -> localhost | changed: "/var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/work/artifacts"
2026-08-27 00:02:42.441810 | instance -> localhost | changed: "/var/lib/zuul/builds/2b825097626747ffae93ec53630a2a29/work/docs"
2026-08-27 00:02:42.462643 | 
2026-08-27 00:02:42.462793 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-27 00:02:43.157345 | instance | changed: .d..t...... ./
2026-08-27 00:02:43.157614 | instance | changed: All items complete
2026-08-27 00:02:43.157652 | 
2026-08-27 00:02:43.624085 | instance | changed: .d..t...... ./
2026-08-27 00:02:44.087953 | instance | changed: .d..t...... ./
2026-08-27 00:02:44.112057 | 
2026-08-27 00:02:44.112132 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-27 00:02:44.513346 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007813
2026-08-27 00:02:44.746281 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008134
2026-08-27 00:02:44.756325 | 
2026-08-27 00:02:44.756457 | PLAY [all]
2026-08-27 00:02:44.762676 | 
2026-08-27 00:02:44.762742 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-27 00:02:45.211364 | instance | changed
2026-08-27 00:02:45.217892 | 
2026-08-27 00:02:45.217945 | PLAY RECAP
2026-08-27 00:02:45.217993 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-27 00:02:45.218031 | 
2026-08-27 00:02:45.412340 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-27 00:02:45.419548 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-27 00:02:45.998157 | 
2026-08-27 00:02:45.998314 | PLAY [localhost]
2026-08-27 00:02:46.008821 | 
2026-08-27 00:02:46.008894 | TASK [Generate Zuul manifest]
2026-08-27 00:02:46.027961 | localhost | ok
2026-08-27 00:02:46.042797 | 
2026-08-27 00:02:46.042874 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-27 00:02:46.419307 | localhost | changed
2026-08-27 00:02:46.432684 | 
2026-08-27 00:02:46.432766 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-27 00:02:46.464092 | localhost | ok
2026-08-27 00:02:46.473556 | 
2026-08-27 00:02:46.473675 | TASK [Upload logs]
2026-08-27 00:02:46.493841 | localhost | ok
2026-08-27 00:02:46.554723 | 
2026-08-27 00:02:46.554837 | TASK [Set zuul-log-path fact]
2026-08-27 00:02:46.585308 | localhost | ok
2026-08-27 00:02:46.599818 | 
2026-08-27 00:02:46.599897 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-27 00:02:46.628644 | localhost | ok
2026-08-27 00:02:46.636746 | 
2026-08-27 00:02:46.636841 | TASK [upload-logs : Create log directories]
2026-08-27 00:02:47.060684 | localhost | changed
2026-08-27 00:02:47.154536 | 
2026-08-27 00:02:47.154772 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-27 00:02:47.515281 | localhost -> localhost | ok: Runtime: 0:00:00.006320
2026-08-27 00:02:47.527281 | 
2026-08-27 00:02:47.527456 | TASK [upload-logs : Upload logs to log server]
2026-08-27 00:02:47.956106 | localhost | Output suppressed because no_log was given
2026-08-27 00:02:47.960423 | 
2026-08-27 00:02:47.960490 | LOOP [upload-logs : Compress console log and json output]
2026-08-27 00:02:48.065201 | localhost | skipping: Conditional result was False
2026-08-27 00:02:48.074219 | localhost | skipping: Conditional result was False
2026-08-27 00:02:48.081533 | 
2026-08-27 00:02:48.081683 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-27 00:02:48.128262 | localhost | skipping: Conditional result was False
2026-08-27 00:02:48.128651 | 
2026-08-27 00:02:48.133256 | localhost | skipping: Conditional result was False
2026-08-27 00:02:48.146287 | 
2026-08-27 00:02:48.146453 | LOOP [upload-logs : Upload console log and json output]