2026-07-21 13:02:36.651884 | Job console starting
2026-07-21 13:02:36.665916 | Updating git repos
2026-07-21 13:02:38.022152 | Cloning repos into workspace
2026-07-21 13:06:26.835734 | Restoring repo states
2026-07-21 13:06:32.737580 | Merging changes
2026-07-21 13:06:37.958865 | Checking out repos
2026-07-21 13:07:29.174906 | Preparing playbooks
2026-07-21 13:07:56.571045 | Running Ansible setup
2026-07-21 13:08:02.152063 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-21 13:08:02.912799 | 
2026-07-21 13:08:02.950615 | PLAY [localhost]
2026-07-21 13:08:02.973494 | 
2026-07-21 13:08:02.973728 | TASK [Gathering Facts]
2026-07-21 13:08:04.175573 | localhost | ok
2026-07-21 13:08:04.187066 | 
2026-07-21 13:08:04.187254 | TASK [Setup log path fact]
2026-07-21 13:08:04.227556 | localhost | ok
2026-07-21 13:08:04.242333 | 
2026-07-21 13:08:04.242465 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-21 13:08:04.273371 | localhost | ok
2026-07-21 13:08:04.283326 | 
2026-07-21 13:08:04.283421 | TASK [emit-job-header : Print job information]
2026-07-21 13:08:04.324514 | # Job Information
2026-07-21 13:08:04.324707 | Ansible Version: 2.16.18
2026-07-21 13:08:04.324755 | Job: atmosphere-molecule-keycloak
2026-07-21 13:08:04.324803 | Pipeline: check
2026-07-21 13:08:04.324836 | Executor: 2d72f0692154
2026-07-21 13:08:04.324942 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-07-21 13:08:04.325033 | Event ID: dffe7f30-8503-11f1-8114-ba7402f72bea
2026-07-21 13:08:04.329533 | 
2026-07-21 13:08:04.565420 | LOOP [emit-job-header : Print node information]
2026-07-21 13:08:04.683955 | localhost | ok:
2026-07-21 13:08:04.684192 | localhost | # Node Information
2026-07-21 13:08:04.684233 | localhost | Inventory Hostname: instance
2026-07-21 13:08:04.684263 | localhost | Hostname: np0000186059
2026-07-21 13:08:04.684291 | localhost | Username: zuul
2026-07-21 13:08:04.684323 | localhost | Distro: Ubuntu 22.04
2026-07-21 13:08:04.684353 | localhost | Provider: yul1
2026-07-21 13:08:04.684382 | localhost | Region: ca-ymq-1
2026-07-21 13:08:04.684412 | localhost | Label: ubuntu-jammy
2026-07-21 13:08:04.684442 | localhost | Product Name: OpenStack Nova
2026-07-21 13:08:04.684471 | localhost | Interface IP: 199.19.213.40
2026-07-21 13:08:04.704735 | 
2026-07-21 13:08:04.704977 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-21 13:08:06.512675 | localhost -> localhost | changed
2026-07-21 13:08:06.522169 | 
2026-07-21 13:08:06.522244 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-21 13:08:08.420935 | localhost -> localhost | changed
2026-07-21 13:08:08.429561 | 
2026-07-21 13:08:08.429712 | PLAY [all]
2026-07-21 13:08:08.439606 | 
2026-07-21 13:08:08.439777 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-21 13:08:09.064490 | instance -> localhost | ok
2026-07-21 13:08:09.070911 | 
2026-07-21 13:08:09.071049 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-21 13:08:09.100444 | instance | ok
2026-07-21 13:08:09.115132 | instance | included: /var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-21 13:08:09.121972 | 
2026-07-21 13:08:09.122035 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-21 13:08:10.962304 | instance -> localhost | Generating public/private rsa key pair.
2026-07-21 13:08:10.962501 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/f5c696e7586243c892871d59a352bb32_id_rsa
2026-07-21 13:08:10.962534 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/f5c696e7586243c892871d59a352bb32_id_rsa.pub
2026-07-21 13:08:10.962557 | instance -> localhost | The key fingerprint is:
2026-07-21 13:08:10.962580 | instance -> localhost | SHA256:p104DQ1iaYdDQdOwOcpi8OXR+GyVx32FByv00jaCL84 zuul-build-sshkey
2026-07-21 13:08:10.962615 | instance -> localhost | The key's randomart image is:
2026-07-21 13:08:10.962645 | instance -> localhost | +---[RSA 3072]----+
2026-07-21 13:08:10.962678 | instance -> localhost | |       oO*. . .o.|
2026-07-21 13:08:10.962703 | instance -> localhost | |       +==oB +..o|
2026-07-21 13:08:10.962724 | instance -> localhost | |  .   +.=o= B B..|
2026-07-21 13:08:10.962746 | instance -> localhost | |   o + = o * = o |
2026-07-21 13:08:10.962772 | instance -> localhost | |    + + S = +    |
2026-07-21 13:08:10.962799 | instance -> localhost | |   . . . * +     |
2026-07-21 13:08:10.962821 | instance -> localhost | |        . E      |
2026-07-21 13:08:10.962842 | instance -> localhost | |                 |
2026-07-21 13:08:10.962865 | instance -> localhost | |                 |
2026-07-21 13:08:10.962886 | instance -> localhost | +----[SHA256]-----+
2026-07-21 13:08:10.962948 | instance -> localhost | ok: Runtime: 0:00:00.399139
2026-07-21 13:08:10.970995 | 
2026-07-21 13:08:10.971122 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-21 13:08:11.002824 | instance | ok
2026-07-21 13:08:11.384109 | instance | included: /var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-21 13:08:11.397019 | 
2026-07-21 13:08:11.397104 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-21 13:08:11.420702 | instance | skipping: Conditional result was False
2026-07-21 13:08:11.431891 | 
2026-07-21 13:08:11.431989 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-21 13:08:11.874789 | instance | changed
2026-07-21 13:08:11.880937 | 
2026-07-21 13:08:11.881056 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-21 13:08:12.071390 | instance | ok
2026-07-21 13:08:12.077581 | 
2026-07-21 13:08:12.077654 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-21 13:08:12.585912 | instance | changed
2026-07-21 13:08:12.592456 | 
2026-07-21 13:08:12.592592 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-21 13:08:13.066923 | instance | changed
2026-07-21 13:08:13.071970 | 
2026-07-21 13:08:13.072040 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-21 13:08:13.095907 | instance | skipping: Conditional result was False
2026-07-21 13:08:13.106088 | 
2026-07-21 13:08:13.106220 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-21 13:08:13.926201 | instance -> localhost | changed
2026-07-21 13:08:13.997703 | 
2026-07-21 13:08:13.997850 | TASK [add-build-sshkey : Add back temp key]
2026-07-21 13:08:14.393133 | instance -> localhost | Identity added: /var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/f5c696e7586243c892871d59a352bb32_id_rsa (zuul-build-sshkey)
2026-07-21 13:08:14.393300 | instance -> localhost | ok: Runtime: 0:00:00.015945
2026-07-21 13:08:14.399106 | 
2026-07-21 13:08:14.399178 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-21 13:08:14.696982 | instance | ok
2026-07-21 13:08:15.007064 | 
2026-07-21 13:08:15.007216 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-21 13:08:15.062291 | instance | skipping: Conditional result was False
2026-07-21 13:08:15.138720 | 
2026-07-21 13:08:15.138938 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-21 13:08:15.474908 | instance | ok
2026-07-21 13:08:15.566917 | 
2026-07-21 13:08:15.567249 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-21 13:11:42.320490 | instance | Output suppressed because no_log was given
2026-07-21 13:11:42.330240 | 
2026-07-21 13:11:42.330362 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-21 13:11:42.722026 | instance | ok: "logs"
2026-07-21 13:11:42.722277 | instance | ok: All items complete
2026-07-21 13:11:42.722313 | 
2026-07-21 13:11:42.870805 | instance | ok: "artifacts"
2026-07-21 13:11:43.031952 | instance | ok: "docs"
2026-07-21 13:11:43.044098 | 
2026-07-21 13:11:43.044259 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-21 13:11:43.249126 | instance | changed: "logs"
2026-07-21 13:11:43.403800 | instance | changed: "artifacts"
2026-07-21 13:11:43.561223 | instance | changed: "docs"
2026-07-21 13:11:43.584292 | 
2026-07-21 13:11:43.584385 | PLAY RECAP
2026-07-21 13:11:43.584429 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-21 13:11:43.584459 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-21 13:11:43.584480 | 
2026-07-21 13:11:43.764617 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-21 13:11:43.777196 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-21 13:11:44.692674 | 
2026-07-21 13:11:44.692969 | PLAY [all]
2026-07-21 13:11:44.709694 | 
2026-07-21 13:11:44.710129 | TASK [setup-uv : Extract archive]
2026-07-21 13:11:47.104298 | instance | changed
2026-07-21 13:11:47.113701 | 
2026-07-21 13:11:47.113892 | TASK [setup-uv : Print version]
2026-07-21 13:11:47.451483 | instance | uv 0.8.13
2026-07-21 13:11:47.662621 | instance | ok: Runtime: 0:00:00.008469
2026-07-21 13:11:47.674698 | 
2026-07-21 13:11:47.674781 | TASK [Install Ansible collection siblings]
2026-07-21 13:11:56.713493 | instance | changed:
2026-07-21 13:11:56.713709 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-21 13:11:56.713759 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-21 13:11:56.713797 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-21 13:11:56.713831 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-21 13:11:56.713864 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-21 13:11:56.713906 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-21 13:11:56.713945 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-21 13:11:56.713985 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-21 13:11:56.714023 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-21 13:11:56.714059 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-21 13:11:56.714093 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-21 13:11:56.714125 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-21 13:11:56.714157 | instance | Resolved ansible.netcommon to v8.6.0 for >=1.2.0
2026-07-21 13:11:56.714187 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-21 13:11:56.714218 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=3.0.0
2026-07-21 13:11:56.714249 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-21 13:11:56.714280 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-21 13:11:56.714311 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-21 13:11:56.714339 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-21 13:11:56.714369 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-07-21 13:11:56.714399 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-07-21 13:11:56.714428 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.1.2
2026-07-21 13:11:56.714457 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-21 13:11:56.714503 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=3.0.0,>=6.0.0
2026-07-21 13:11:56.714537 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-21 13:11:56.714567 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-21 13:11:56.714598 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-21 13:11:56.714629 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=3.0.0,>=6.0.0
2026-07-21 13:11:56.714662 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-21 13:11:56.714695 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-21 13:11:56.714730 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-21 13:11:56.714763 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-21 13:11:56.714797 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-21 13:11:56.714831 | instance | Checked out ansible.netcommon to v8.6.0
2026-07-21 13:11:56.714864 | 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-21 13:11:56.714905 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.714940 | instance | Process install dependency map
2026-07-21 13:11:56.714971 | instance | Starting collection install process
2026-07-21 13:11:56.715006 | instance | Installing 'ansible.netcommon:8.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-21 13:11:56.715042 | instance | Created collection for ansible.netcommon:8.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-21 13:11:56.715079 | instance | ansible.netcommon:8.6.0 was installed successfully
2026-07-21 13:11:56.715115 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-21 13:11:56.715150 | instance | Creating virtual environment at: .venv
2026-07-21 13:11:56.715186 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-21 13:11:56.715221 | instance | Downloading setuptools (1.1MiB)
2026-07-21 13:11:56.715258 | instance | Downloading rjsonnet (1.2MiB)
2026-07-21 13:11:56.715296 | instance | Downloading kubernetes (1.9MiB)
2026-07-21 13:11:56.715329 | instance | Downloading cryptography (4.2MiB)
2026-07-21 13:11:56.715365 | instance | Downloading openstacksdk (1.7MiB)
2026-07-21 13:11:56.716457 | instance | Downloading ansible-core (2.1MiB)
2026-07-21 13:11:56.716500 | instance | Downloading pygments (1.2MiB)
2026-07-21 13:11:56.716539 | instance | Downloading netaddr (2.2MiB)
2026-07-21 13:11:56.716575 | instance |    Building pyperclip==1.9.0
2026-07-21 13:11:56.716610 | instance |  Downloading rjsonnet
2026-07-21 13:11:56.716642 | instance |  Downloading netaddr
2026-07-21 13:11:56.716677 | instance |  Downloading pygments
2026-07-21 13:11:56.716714 | instance |  Downloading cryptography
2026-07-21 13:11:56.716751 | instance |  Downloading setuptools
2026-07-21 13:11:56.716787 | instance |  Downloading kubernetes
2026-07-21 13:11:56.716823 | instance |  Downloading ansible-core
2026-07-21 13:11:56.716858 | instance |  Downloading openstacksdk
2026-07-21 13:11:56.716894 | instance |       Built pyperclip==1.9.0
2026-07-21 13:11:56.716931 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-21 13:11:56.716990 | instance | Installed 79 packages in 36ms
2026-07-21 13:11:56.717029 | instance | Checked out ansible.posix to 1.6.0
2026-07-21 13:11:56.717063 | 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-21 13:11:56.717096 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.717130 | instance | Process install dependency map
2026-07-21 13:11:56.717171 | instance | Starting collection install process
2026-07-21 13:11:56.717202 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-21 13:11:56.717235 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-21 13:11:56.717265 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-21 13:11:56.717294 | instance | Checked out ansible.utils to v6.0.3
2026-07-21 13:11:56.717325 | 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-21 13:11:56.717354 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.717382 | instance | Process install dependency map
2026-07-21 13:11:56.717409 | instance | Starting collection install process
2026-07-21 13:11:56.717436 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-21 13:11:56.717464 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-21 13:11:56.717496 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-21 13:11:56.717531 | instance | Checked out atmosphere.common to v0.6.0
2026-07-21 13:11:56.717565 | 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-21 13:11:56.717599 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.717632 | instance | Process install dependency map
2026-07-21 13:11:56.717665 | instance | Starting collection install process
2026-07-21 13:11:56.717698 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-21 13:11:56.717732 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-21 13:11:56.717765 | instance | atmosphere.common:0.6.0 was installed successfully
2026-07-21 13:11:56.717798 | instance | Checked out community.crypto to 2.2.3
2026-07-21 13:11:56.717831 | 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-21 13:11:56.717863 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.717895 | instance | Process install dependency map
2026-07-21 13:11:56.717928 | instance | Starting collection install process
2026-07-21 13:11:56.717962 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-21 13:11:56.717994 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-21 13:11:56.718027 | instance | community.crypto:2.2.3 was installed successfully
2026-07-21 13:11:56.718059 | instance | Checked out community.general to 7.3.0
2026-07-21 13:11:56.718093 | 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-21 13:11:56.718126 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.718158 | instance | Process install dependency map
2026-07-21 13:11:56.718190 | instance | Starting collection install process
2026-07-21 13:11:56.718223 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-21 13:11:56.718256 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-21 13:11:56.718288 | instance | community.general:7.3.0 was installed successfully
2026-07-21 13:11:56.718333 | instance | Checked out community.mysql to 3.6.0
2026-07-21 13:11:56.718368 | 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-21 13:11:56.718402 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.718435 | instance | Process install dependency map
2026-07-21 13:11:56.718467 | instance | Starting collection install process
2026-07-21 13:11:56.718497 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-21 13:11:56.718524 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-21 13:11:56.718551 | instance | community.mysql:3.6.0 was installed successfully
2026-07-21 13:11:56.718577 | instance | Checked out kubernetes.core to 2.4.0
2026-07-21 13:11:56.718602 | 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-21 13:11:56.718627 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.718651 | instance | Process install dependency map
2026-07-21 13:11:56.718680 | instance | Starting collection install process
2026-07-21 13:11:56.718706 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-21 13:11:56.718730 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-21 13:11:56.718754 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-21 13:11:56.718779 | instance | Checked out openstack.cloud to 1.7.0
2026-07-21 13:11:56.718802 | 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-21 13:11:56.718826 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.718852 | instance | Process install dependency map
2026-07-21 13:11:56.718876 | instance | Starting collection install process
2026-07-21 13:11:56.718899 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-21 13:11:56.718922 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-21 13:11:56.718947 | instance | openstack.cloud:1.7.0 was installed successfully
2026-07-21 13:11:56.718969 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-21 13:11:56.718992 | 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-21 13:11:56.719015 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.719034 | instance | Process install dependency map
2026-07-21 13:11:56.719052 | instance | Starting collection install process
2026-07-21 13:11:56.719070 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-21 13:11:56.719088 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-21 13:11:56.719106 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-21 13:11:56.719123 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-21 13:11:56.719141 | 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-21 13:11:56.719160 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.719183 | instance | Process install dependency map
2026-07-21 13:11:56.719202 | instance | Starting collection install process
2026-07-21 13:11:56.719220 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-21 13:11:56.719238 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-21 13:11:56.719256 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-21 13:11:56.719273 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-21 13:11:56.719291 | 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-21 13:11:56.719309 | instance | Starting galaxy collection install process
2026-07-21 13:11:56.719326 | instance | Process install dependency map
2026-07-21 13:11:56.719344 | instance | Starting collection install process
2026-07-21 13:11:56.719363 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-21 13:11:56.719381 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-21 13:11:56.719399 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-21 13:11:56.728455 | 
2026-07-21 13:11:56.728541 | PLAY RECAP
2026-07-21 13:11:56.728588 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-21 13:11:56.728610 | 
2026-07-21 13:11:56.882173 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-21 13:11:57.043551 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-21 13:11:57.801500 | 
2026-07-21 13:11:57.801642 | PLAY [all]
2026-07-21 13:11:57.814064 | 
2026-07-21 13:11:57.814170 | TASK [Install "jq" for log collection]
2026-07-21 13:12:31.337917 | instance | changed
2026-07-21 13:12:31.340495 | 
2026-07-21 13:12:31.340575 | PLAY RECAP
2026-07-21 13:12:31.340643 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-21 13:12:31.340710 | 
2026-07-21 13:12:31.463583 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-21 13:12:31.469059 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-21 13:12:32.141995 | 
2026-07-21 13:12:32.142125 | PLAY [all]
2026-07-21 13:12:32.154449 | 
2026-07-21 13:12:32.154554 | LOOP [Check inventory directories]
2026-07-21 13:12:32.647591 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-07-21 13:12:32.795477 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/keycloak/inventory"
2026-07-21 13:12:32.941922 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/keycloak/inventory"
2026-07-21 13:12:32.958160 | 
2026-07-21 13:12:32.958341 | LOOP [Copy inventory file for Zuul]
2026-07-21 13:12:33.917773 | instance | changed:
2026-07-21 13:12:33.917946 | instance | {
2026-07-21 13:12:33.917975 | instance |   "ansible_loop_var": "item",
2026-07-21 13:12:33.917998 | instance |   "changed": false,
2026-07-21 13:12:33.918018 | instance |   "failed": false,
2026-07-21 13:12:33.918041 | instance |   "invocation": {
2026-07-21 13:12:33.918060 | instance |     "module_args": {
2026-07-21 13:12:33.918078 | instance |       "checksum_algorithm": "sha1",
2026-07-21 13:12:33.918096 | instance |       "follow": true,
2026-07-21 13:12:33.918114 | instance |       "get_attributes": true,
2026-07-21 13:12:33.918133 | instance |       "get_checksum": true,
2026-07-21 13:12:33.918152 | instance |       "get_mime": true,
2026-07-21 13:12:33.918170 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-07-21 13:12:33.918203 | instance |     }
2026-07-21 13:12:33.918222 | instance |   },
2026-07-21 13:12:33.918240 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-07-21 13:12:33.918257 | instance |   "stat": {
2026-07-21 13:12:33.918275 | instance |     "atime": 1784639509.8255427,
2026-07-21 13:12:33.918293 | instance |     "attr_flags": "e",
2026-07-21 13:12:33.918311 | instance |     "attributes": [
2026-07-21 13:12:33.918328 | instance |       "extents"
2026-07-21 13:12:33.918346 | instance |     ],
2026-07-21 13:12:33.918363 | instance |     "block_size": 4096,
2026-07-21 13:12:33.918380 | instance |     "blocks": 8,
2026-07-21 13:12:33.918398 | instance |     "charset": "unknown",
2026-07-21 13:12:33.918417 | instance |     "ctime": 1784639508.9575224,
2026-07-21 13:12:33.918435 | instance |     "dev": 64513,
2026-07-21 13:12:33.918452 | instance |     "device_type": 0,
2026-07-21 13:12:33.918469 | instance |     "executable": true,
2026-07-21 13:12:33.918486 | instance |     "exists": true,
2026-07-21 13:12:33.918503 | instance |     "gid": 1000,
2026-07-21 13:12:33.918521 | instance |     "gr_name": "zuul",
2026-07-21 13:12:33.918538 | instance |     "inode": 1046334,
2026-07-21 13:12:33.918555 | instance |     "isblk": false,
2026-07-21 13:12:33.918573 | instance |     "ischr": false,
2026-07-21 13:12:33.918590 | instance |     "isdir": true,
2026-07-21 13:12:33.918608 | instance |     "isfifo": false,
2026-07-21 13:12:33.918625 | instance |     "isgid": false,
2026-07-21 13:12:33.918642 | instance |     "islnk": false,
2026-07-21 13:12:33.918659 | instance |     "isreg": false,
2026-07-21 13:12:33.918676 | instance |     "issock": false,
2026-07-21 13:12:33.918693 | instance |     "isuid": false,
2026-07-21 13:12:33.918710 | instance |     "mimetype": "unknown",
2026-07-21 13:12:33.918727 | instance |     "mode": "0755",
2026-07-21 13:12:33.918744 | instance |     "mtime": 1784639508.9575224,
2026-07-21 13:12:33.918761 | instance |     "nlink": 22,
2026-07-21 13:12:33.918778 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-07-21 13:12:33.918795 | instance |     "pw_name": "zuul",
2026-07-21 13:12:33.918813 | instance |     "readable": true,
2026-07-21 13:12:33.918831 | instance |     "rgrp": true,
2026-07-21 13:12:33.918849 | instance |     "roth": true,
2026-07-21 13:12:33.918866 | instance |     "rusr": true,
2026-07-21 13:12:33.918884 | instance |     "size": 4096,
2026-07-21 13:12:33.918901 | instance |     "uid": 1000,
2026-07-21 13:12:33.918918 | instance |     "version": "4247489298",
2026-07-21 13:12:33.918935 | instance |     "wgrp": false,
2026-07-21 13:12:33.918972 | instance |     "woth": false,
2026-07-21 13:12:33.918990 | instance |     "writeable": true,
2026-07-21 13:12:33.919007 | instance |     "wusr": true,
2026-07-21 13:12:33.919025 | instance |     "xgrp": true,
2026-07-21 13:12:33.919042 | instance |     "xoth": true,
2026-07-21 13:12:33.919058 | instance |     "xusr": true
2026-07-21 13:12:33.919075 | instance |   }
2026-07-21 13:12:33.919092 | instance | }
2026-07-21 13:12:33.919122 | instance | ok: All items complete
2026-07-21 13:12:33.919143 | 
2026-07-21 13:12:33.920346 | instance | skipping: Conditional result was False
2026-07-21 13:12:33.920939 | instance | skipping: Conditional result was False
2026-07-21 13:12:33.937496 | 
2026-07-21 13:12:33.937656 | LOOP [Switch "ansible_host" to private IP]
2026-07-21 13:12:34.267794 | instance | changed: 1 replacements made
2026-07-21 13:12:34.269700 | instance | skipping: Conditional result was False
2026-07-21 13:12:34.270699 | instance | skipping: Conditional result was False
2026-07-21 13:12:34.285113 | 
2026-07-21 13:12:34.285262 | TASK [Run Molecule scenario]
2026-07-21 13:12:35.197568 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-07-21 13:12:35.571449 | instance | INFO     [keycloak > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-21 13:12:35.571548 | instance | INFO     [keycloak > prerun] Performing prerun with role_name_check=0...
2026-07-21 13:17:14.444114 | [Zuul] Log Stream did not terminate
2026-07-21 13:17:14.451964 | instance | ERROR
2026-07-21 13:17:14.452220 | instance | {
2026-07-21 13:17:14.452283 | instance |   "msg": "Data could not be sent to remote host \"199.19.213.40\". Make sure this host can be reached over ssh: ssh: connect to host 199.19.213.40 port 22: No route to host\r\n",
2026-07-21 13:17:14.452317 | instance |   "unreachable": true
2026-07-21 13:17:14.452347 | instance | }
unreachable
2026-07-21 13:17:14.454228 | 
2026-07-21 13:17:14.454298 | PLAY RECAP
2026-07-21 13:17:14.454359 | instance | ok: 3 changed: 2 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-21 13:17:14.454389 | 
2026-07-21 13:17:14.597322 | RUN END RESULT_UNREACHABLE: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-21 13:17:14.602244 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-21 13:17:16.018374 | 
2026-07-21 13:17:16.018549 | PLAY [all]
2026-07-21 13:17:16.034759 | 
2026-07-21 13:17:16.034900 | TASK [gather-host-logs : creating directory for system status]
2026-07-21 13:17:27.642471 | instance | ERROR
2026-07-21 13:17:27.642775 | instance | {
2026-07-21 13:17:27.642814 | instance |   "msg": "Data could not be sent to remote host \"199.19.213.40\". Make sure this host can be reached over ssh: ssh: connect to host 199.19.213.40 port 22: No route to host\r\n",
2026-07-21 13:17:27.642845 | instance |   "unreachable": true
2026-07-21 13:17:27.642895 | instance | }
unreachable
2026-07-21 13:17:27.643840 | 
2026-07-21 13:17:27.643888 | PLAY RECAP
2026-07-21 13:17:27.643933 | instance | ok: 0 changed: 0 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-21 13:17:27.643956 | 
2026-07-21 13:17:27.778739 | POST-RUN END RESULT_UNREACHABLE: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-21 13:17:27.781538 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-21 13:17:28.413349 | 
2026-07-21 13:17:28.413505 | PLAY [all]
2026-07-21 13:17:28.430183 | 
2026-07-21 13:17:28.430356 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-21 13:17:28.475050 | instance | skipping: Conditional result was False
2026-07-21 13:17:28.483186 | 
2026-07-21 13:17:28.483355 | TASK [fetch-output : Set log path for single node]
2026-07-21 13:17:28.527720 | instance | ok
2026-07-21 13:17:28.535720 | 
2026-07-21 13:17:28.535857 | LOOP [fetch-output : Ensure local output dirs]
2026-07-21 13:17:28.919740 | instance -> localhost | ok: "/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/logs"
2026-07-21 13:17:29.153728 | instance -> localhost | changed: "/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/artifacts"
2026-07-21 13:17:29.382053 | instance -> localhost | changed: "/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/docs"
2026-07-21 13:17:29.395158 | 
2026-07-21 13:17:29.395424 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-21 13:17:33.938322 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/logs', 'src': 'logs'}
2026-07-21 13:17:35.607996 | instance | {
2026-07-21 13:17:35.608160 | instance |   "ansible_loop_var": "zj_output",
2026-07-21 13:17:35.608222 | instance |   "msg": "ssh: connect to host 199.19.213.40 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: error in rsync protocol data stream (code 12) at io.c(232) [Receiver=3.2.7]\n",
2026-07-21 13:17:35.608275 | instance |   "rc": 12,
2026-07-21 13:17:35.608319 | instance |   "zj_output": {
2026-07-21 13:17:35.608359 | instance |     "dest": "/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/logs",
2026-07-21 13:17:35.608399 | instance |     "src": "logs"
2026-07-21 13:17:35.608439 | instance |   }
2026-07-21 13:17:35.608478 | instance | }
2026-07-21 13:17:35.608588 | instance | changed: All items complete
2026-07-21 13:17:35.608640 | 
2026-07-21 13:17:37.074592 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/artifacts', 'src': 'artifacts'}
2026-07-21 13:17:37.074855 | instance | {
2026-07-21 13:17:37.074899 | instance |   "ansible_loop_var": "zj_output",
2026-07-21 13:17:37.074929 | instance |   "msg": "ssh: connect to host 199.19.213.40 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n",
2026-07-21 13:17:37.074957 | instance |   "rc": 255,
2026-07-21 13:17:37.074983 | instance |   "zj_output": {
2026-07-21 13:17:37.075009 | instance |     "dest": "/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/artifacts",
2026-07-21 13:17:37.075038 | instance |     "src": "artifacts"
2026-07-21 13:17:37.075064 | instance |   }
2026-07-21 13:17:37.075089 | instance | }
2026-07-21 13:17:40.147702 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/docs', 'src': 'docs'}
2026-07-21 13:17:40.148022 | instance | {
2026-07-21 13:17:40.148101 | instance |   "ansible_loop_var": "zj_output",
2026-07-21 13:17:40.148159 | instance |   "msg": "ssh: connect to host 199.19.213.40 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n",
2026-07-21 13:17:40.148213 | instance |   "rc": 255,
2026-07-21 13:17:40.148263 | instance |   "zj_output": {
2026-07-21 13:17:40.148456 | instance |     "dest": "/var/lib/zuul/builds/f5c696e7586243c892871d59a352bb32/work/docs",
2026-07-21 13:17:40.148534 | instance |     "src": "docs"
2026-07-21 13:17:40.148591 | instance |   }
2026-07-21 13:17:40.148644 | instance | }
failure
2026-07-21 13:17:40.162834 | 
2026-07-21 13:17:40.162890 | PLAY RECAP
2026-07-21 13:17:40.162935 | instance | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-07-21 13:17:40.162957 | 
2026-07-21 13:17:40.295963 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-21 13:17:40.301259 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-21 13:17:41.758111 | 
2026-07-21 13:17:41.758279 | PLAY [localhost]
2026-07-21 13:17:41.768902 | 
2026-07-21 13:17:41.769017 | TASK [Generate Zuul manifest]
2026-07-21 13:17:41.789960 | localhost | ok
2026-07-21 13:17:44.138814 | 
2026-07-21 13:17:44.140021 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-21 13:17:44.639993 | localhost | changed
2026-07-21 13:17:44.652102 | 
2026-07-21 13:17:44.652226 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-21 13:17:44.774308 | localhost | ok
2026-07-21 13:17:44.784053 | 
2026-07-21 13:17:44.784139 | TASK [Upload logs]
2026-07-21 13:17:44.803774 | localhost | ok
2026-07-21 13:17:44.889833 | 
2026-07-21 13:17:44.889958 | TASK [Set zuul-log-path fact]
2026-07-21 13:17:44.908876 | localhost | ok
2026-07-21 13:17:44.934488 | 
2026-07-21 13:17:44.934646 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-21 13:17:44.964303 | localhost | ok
2026-07-21 13:17:44.976400 | 
2026-07-21 13:17:44.976519 | TASK [upload-logs : Create log directories]
2026-07-21 13:17:45.423167 | localhost | changed
2026-07-21 13:17:45.466368 | 
2026-07-21 13:17:45.466515 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-21 13:17:45.891799 | localhost -> localhost | ok: Runtime: 0:00:00.003862
2026-07-21 13:17:45.900696 | 
2026-07-21 13:17:45.900889 | TASK [upload-logs : Upload logs to log server]
2026-07-21 13:17:46.387512 | localhost | Output suppressed because no_log was given
2026-07-21 13:17:46.393888 | 
2026-07-21 13:17:46.394030 | LOOP [upload-logs : Compress console log and json output]
2026-07-21 13:17:46.439282 | localhost | skipping: Conditional result was False
2026-07-21 13:17:46.451889 | localhost | skipping: Conditional result was False
2026-07-21 13:17:46.471164 | 
2026-07-21 13:17:46.471312 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-21 13:17:46.512539 | localhost | skipping: Conditional result was False
2026-07-21 13:17:46.512868 | 
2026-07-21 13:17:46.517747 | localhost | skipping: Conditional result was False
2026-07-21 13:17:46.531439 | 
2026-07-21 13:17:46.531576 | LOOP [upload-logs : Upload console log and json output]