2026-07-25 08:14:19.386468 | Job console starting
2026-07-25 08:14:19.431273 | Updating git repos
2026-07-25 08:14:21.179685 | Cloning repos into workspace
2026-07-25 08:14:42.486717 | Restoring repo states
2026-07-25 08:14:49.920668 | Merging changes
2026-07-25 08:14:52.378699 | Checking out repos
2026-07-25 08:14:54.483460 | Preparing playbooks
2026-07-25 08:14:59.209824 | Running Ansible setup
2026-07-25 08:15:03.316326 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-25 08:15:04.012548 | 
2026-07-25 08:15:04.783460 | PLAY [localhost]
2026-07-25 08:15:04.801517 | 
2026-07-25 08:15:04.801619 | TASK [Gathering Facts]
2026-07-25 08:15:05.888941 | localhost | ok
2026-07-25 08:15:05.902030 | 
2026-07-25 08:15:05.902149 | TASK [Setup log path fact]
2026-07-25 08:15:05.922665 | localhost | ok
2026-07-25 08:15:05.935430 | 
2026-07-25 08:15:05.935498 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-25 08:15:05.965319 | localhost | ok
2026-07-25 08:15:05.982882 | 
2026-07-25 08:15:05.983087 | TASK [emit-job-header : Print job information]
2026-07-25 08:15:06.026498 | # Job Information
2026-07-25 08:15:06.026762 | Ansible Version: 2.16.18
2026-07-25 08:15:06.026813 | Job: atmosphere-molecule-aio-ovn-selective
2026-07-25 08:15:06.026847 | Pipeline: check
2026-07-25 08:15:06.026877 | Executor: 2d72f0692154
2026-07-25 08:15:06.026906 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4163
2026-07-25 08:15:06.026939 | Event ID: 8a225780-8800-11f1-889c-efcecb22fe13
2026-07-25 08:15:06.031539 | 
2026-07-25 08:15:06.031654 | LOOP [emit-job-header : Print node information]
2026-07-25 08:15:06.127495 | localhost | ok:
2026-07-25 08:15:06.127802 | localhost | # Node Information
2026-07-25 08:15:06.127863 | localhost | Inventory Hostname: instance
2026-07-25 08:15:06.127910 | localhost | Hostname: np0000186787
2026-07-25 08:15:06.127954 | localhost | Username: zuul
2026-07-25 08:15:06.128003 | localhost | Distro: Ubuntu 22.04
2026-07-25 08:15:06.128046 | localhost | Provider: yul1
2026-07-25 08:15:06.128089 | localhost | Region: ca-ymq-1
2026-07-25 08:15:06.128131 | localhost | Label: ubuntu-jammy-16
2026-07-25 08:15:06.128172 | localhost | Product Name: OpenStack Nova
2026-07-25 08:15:06.128213 | localhost | Interface IP: 199.204.45.77
2026-07-25 08:15:06.139727 | 
2026-07-25 08:15:06.139880 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-25 08:15:06.525330 | localhost -> localhost | changed
2026-07-25 08:15:06.530771 | 
2026-07-25 08:15:06.530873 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-25 08:15:07.552834 | localhost -> localhost | changed
2026-07-25 08:15:07.566220 | 
2026-07-25 08:15:07.566328 | PLAY [all]
2026-07-25 08:15:07.576182 | 
2026-07-25 08:15:07.576301 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-25 08:15:07.978101 | instance -> localhost | ok
2026-07-25 08:15:07.989090 | 
2026-07-25 08:15:07.989206 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-25 08:15:08.023618 | instance | ok
2026-07-25 08:15:08.037301 | instance | included: /var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-25 08:15:08.046361 | 
2026-07-25 08:15:08.046534 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-25 08:15:09.651895 | instance -> localhost | Generating public/private rsa key pair.
2026-07-25 08:15:09.652122 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/a5ebd2deb77842f8a56a4c450806a74a_id_rsa
2026-07-25 08:15:09.652164 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/a5ebd2deb77842f8a56a4c450806a74a_id_rsa.pub
2026-07-25 08:15:09.652196 | instance -> localhost | The key fingerprint is:
2026-07-25 08:15:09.652226 | instance -> localhost | SHA256:R56JnszdOYkXG0HYXY8FNDTSL4JyApbuOCE/xZtzvQU zuul-build-sshkey
2026-07-25 08:15:09.652268 | instance -> localhost | The key's randomart image is:
2026-07-25 08:15:09.652298 | instance -> localhost | +---[RSA 3072]----+
2026-07-25 08:15:09.652335 | instance -> localhost | |       .   oo=*oo|
2026-07-25 08:15:09.652366 | instance -> localhost | |      +   ....o=.|
2026-07-25 08:15:09.652395 | instance -> localhost | |     + .  ... ...|
2026-07-25 08:15:09.652424 | instance -> localhost | |  . . + o+Eo... .|
2026-07-25 08:15:09.652453 | instance -> localhost | |   o = oS==.o. . |
2026-07-25 08:15:09.652480 | instance -> localhost | |    = =+.+.o.*   |
2026-07-25 08:15:09.652508 | instance -> localhost | |     o o= ooB    |
2026-07-25 08:15:09.652534 | instance -> localhost | |          .. .   |
2026-07-25 08:15:09.652565 | instance -> localhost | |                 |
2026-07-25 08:15:09.652621 | instance -> localhost | +----[SHA256]-----+
2026-07-25 08:15:09.652688 | instance -> localhost | ok: Runtime: 0:00:01.004068
2026-07-25 08:15:09.663679 | 
2026-07-25 08:15:09.663814 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-25 08:15:09.704052 | instance | ok
2026-07-25 08:15:09.717688 | instance | included: /var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-25 08:15:09.726084 | 
2026-07-25 08:15:09.726151 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-25 08:15:09.752771 | instance | skipping: Conditional result was False
2026-07-25 08:15:09.763006 | 
2026-07-25 08:15:09.763134 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-25 08:15:10.296735 | instance | changed
2026-07-25 08:15:10.306707 | 
2026-07-25 08:15:10.306776 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-25 08:15:10.551593 | instance | ok
2026-07-25 08:15:10.556544 | 
2026-07-25 08:15:10.556607 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-25 08:15:11.016863 | instance | changed
2026-07-25 08:15:11.336710 | 
2026-07-25 08:15:11.336940 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-25 08:15:11.807945 | instance | changed
2026-07-25 08:15:11.815201 | 
2026-07-25 08:15:11.815294 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-25 08:15:11.840843 | instance | skipping: Conditional result was False
2026-07-25 08:15:11.848044 | 
2026-07-25 08:15:11.848129 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-25 08:15:12.228208 | instance -> localhost | changed
2026-07-25 08:15:12.245297 | 
2026-07-25 08:15:12.245422 | TASK [add-build-sshkey : Add back temp key]
2026-07-25 08:15:12.595575 | instance -> localhost | Identity added: /var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/a5ebd2deb77842f8a56a4c450806a74a_id_rsa (zuul-build-sshkey)
2026-07-25 08:15:12.595926 | instance -> localhost | ok: Runtime: 0:00:00.050934
2026-07-25 08:15:12.603317 | 
2026-07-25 08:15:12.603378 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-25 08:15:12.870542 | instance | ok
2026-07-25 08:15:12.879470 | 
2026-07-25 08:15:12.879562 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-25 08:15:12.905269 | instance | skipping: Conditional result was False
2026-07-25 08:15:12.922600 | 
2026-07-25 08:15:12.922719 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-25 08:15:13.212560 | instance | ok
2026-07-25 08:15:13.354610 | 
2026-07-25 08:15:13.354752 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-25 08:15:17.852355 | instance | Output suppressed because no_log was given
2026-07-25 08:15:17.865481 | 
2026-07-25 08:15:17.865588 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-25 08:15:18.062884 | instance | ok: "logs"
2026-07-25 08:15:18.063450 | instance | ok: All items complete
2026-07-25 08:15:18.063495 | 
2026-07-25 08:15:18.213295 | instance | ok: "artifacts"
2026-07-25 08:15:18.372275 | instance | ok: "docs"
2026-07-25 08:15:18.385548 | 
2026-07-25 08:15:18.385635 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-25 08:15:18.576556 | instance | changed: "logs"
2026-07-25 08:15:19.048909 | instance | changed: "artifacts"
2026-07-25 08:15:19.053952 | instance | changed: "docs"
2026-07-25 08:15:19.068141 | 
2026-07-25 08:15:19.068260 | PLAY RECAP
2026-07-25 08:15:19.068322 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-25 08:15:19.068367 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 08:15:19.068398 | 
2026-07-25 08:15:19.218820 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-25 08:15:19.230099 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-25 08:15:19.859933 | 
2026-07-25 08:15:19.860080 | PLAY [all]
2026-07-25 08:15:19.871885 | 
2026-07-25 08:15:19.871995 | TASK [setup-uv : Extract archive]
2026-07-25 08:15:22.144763 | instance | changed
2026-07-25 08:15:22.348415 | 
2026-07-25 08:15:22.348536 | TASK [setup-uv : Print version]
2026-07-25 08:15:22.677183 | instance | uv 0.8.13
2026-07-25 08:15:22.884805 | instance | ok: Runtime: 0:00:00.011054
2026-07-25 08:15:22.898829 | 
2026-07-25 08:15:22.898915 | TASK [Install Ansible collection siblings]
2026-07-25 08:15:31.949026 | instance | changed:
2026-07-25 08:15:31.949227 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-25 08:15:31.949278 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-25 08:15:31.949314 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-25 08:15:31.949343 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-25 08:15:31.949371 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-25 08:15:31.949405 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-25 08:15:31.949434 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-25 08:15:31.949465 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-25 08:15:31.949492 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-25 08:15:31.949518 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-25 08:15:31.949545 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-25 08:15:31.949571 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-25 08:15:31.949597 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-25 08:15:31.949624 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-25 08:15:31.949650 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-25 08:15:31.949677 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-25 08:15:31.949704 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-25 08:15:31.949730 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-25 08:15:31.949755 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-25 08:15:31.949781 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-25 08:15:31.949811 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-25 08:15:31.949837 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-25 08:15:31.949865 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-25 08:15:31.949902 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-25 08:15:31.949929 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-25 08:15:31.949954 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-25 08:15:31.949980 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-25 08:15:31.950005 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-25 08:15:31.950030 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-25 08:15:31.950056 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-25 08:15:31.950083 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-25 08:15:31.950110 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-25 08:15:31.950195 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-25 08:15:31.950224 | 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-25 08:15:31.950265 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.950299 | instance | Process install dependency map
2026-07-25 08:15:31.950326 | instance | Starting collection install process
2026-07-25 08:15:31.950351 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-25 08:15:31.950376 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-25 08:15:31.950403 | instance | ansible.netcommon:* was installed successfully
2026-07-25 08:15:31.950428 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-25 08:15:31.950453 | instance | Creating virtual environment at: .venv
2026-07-25 08:15:31.950479 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-25 08:15:31.950504 | instance | Downloading pydantic-core (2.0MiB)
2026-07-25 08:15:31.950529 | instance | Downloading pygments (1.2MiB)
2026-07-25 08:15:31.950560 | instance | Downloading openstacksdk (1.7MiB)
2026-07-25 08:15:31.950586 | instance | Downloading cryptography (4.2MiB)
2026-07-25 08:15:31.950612 | instance | Downloading netaddr (2.2MiB)
2026-07-25 08:15:31.950791 | instance | Downloading rjsonnet (1.2MiB)
2026-07-25 08:15:31.950832 | instance | Downloading ansible-core (2.1MiB)
2026-07-25 08:15:31.950860 | instance | Downloading kubernetes (1.9MiB)
2026-07-25 08:15:31.950887 | instance | Downloading setuptools (1.1MiB)
2026-07-25 08:15:31.950914 | instance |    Building pyperclip==1.9.0
2026-07-25 08:15:31.950944 | instance |  Downloading rjsonnet
2026-07-25 08:15:31.950970 | instance |  Downloading pydantic-core
2026-07-25 08:15:31.950996 | instance |  Downloading netaddr
2026-07-25 08:15:31.951022 | instance |  Downloading pygments
2026-07-25 08:15:31.951048 | instance |  Downloading cryptography
2026-07-25 08:15:31.951073 | instance |  Downloading setuptools
2026-07-25 08:15:31.951098 | instance |  Downloading kubernetes
2026-07-25 08:15:31.951123 | instance |  Downloading ansible-core
2026-07-25 08:15:31.951148 | instance |  Downloading openstacksdk
2026-07-25 08:15:31.951174 | instance |       Built pyperclip==1.9.0
2026-07-25 08:15:31.951200 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-25 08:15:31.951226 | instance | Installed 83 packages in 39ms
2026-07-25 08:15:31.951258 | instance | Checked out ansible.posix to 1.6.0
2026-07-25 08:15:31.951295 | 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-25 08:15:31.951325 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.951351 | instance | Process install dependency map
2026-07-25 08:15:31.951387 | instance | Starting collection install process
2026-07-25 08:15:31.951415 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-25 08:15:31.951441 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-25 08:15:31.951466 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-25 08:15:31.951492 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.951519 | instance | 2.17.12
2026-07-25 08:15:31.951544 | instance | Checked out ansible.utils to v6.0.3
2026-07-25 08:15:31.951569 | 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-25 08:15:31.951595 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.951620 | instance | Process install dependency map
2026-07-25 08:15:31.951645 | instance | Starting collection install process
2026-07-25 08:15:31.951671 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-25 08:15:31.951696 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-25 08:15:31.951722 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-25 08:15:31.951749 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.951774 | instance | 2.17.12
2026-07-25 08:15:31.951799 | instance | Checked out atmosphere.common to v0.7.0
2026-07-25 08:15:31.951825 | 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-25 08:15:31.951851 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.951917 | instance | Process install dependency map
2026-07-25 08:15:31.951953 | instance | Starting collection install process
2026-07-25 08:15:31.951979 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-25 08:15:31.952005 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-25 08:15:31.952031 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-25 08:15:31.952056 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.952081 | instance | 2.17.12
2026-07-25 08:15:31.952106 | instance | Checked out community.crypto to 2.2.3
2026-07-25 08:15:31.952131 | 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-25 08:15:31.952157 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.952182 | instance | Process install dependency map
2026-07-25 08:15:31.952206 | instance | Starting collection install process
2026-07-25 08:15:31.952231 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-25 08:15:31.952263 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-25 08:15:31.952297 | instance | community.crypto:2.2.3 was installed successfully
2026-07-25 08:15:31.952324 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.952348 | instance | 2.17.12
2026-07-25 08:15:31.952373 | instance | Checked out community.general to 7.3.0
2026-07-25 08:15:31.952398 | 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-25 08:15:31.952431 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.952457 | instance | Process install dependency map
2026-07-25 08:15:31.952483 | instance | Starting collection install process
2026-07-25 08:15:31.952508 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-25 08:15:31.952534 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-25 08:15:31.952558 | instance | community.general:7.3.0 was installed successfully
2026-07-25 08:15:31.952584 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.952609 | instance | 2.17.12
2026-07-25 08:15:31.952635 | instance | Checked out community.mysql to 3.6.0
2026-07-25 08:15:31.952662 | 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-25 08:15:31.952688 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.952713 | instance | Process install dependency map
2026-07-25 08:15:31.952739 | instance | Starting collection install process
2026-07-25 08:15:31.952764 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-25 08:15:31.952788 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-25 08:15:31.952814 | instance | community.mysql:3.6.0 was installed successfully
2026-07-25 08:15:31.952839 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.952863 | instance | 2.17.12
2026-07-25 08:15:31.952888 | instance | Checked out kubernetes.core to 2.4.0
2026-07-25 08:15:31.952915 | 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-25 08:15:31.952940 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.952992 | instance | Process install dependency map
2026-07-25 08:15:31.953020 | instance | Starting collection install process
2026-07-25 08:15:31.953046 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-25 08:15:31.953072 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-25 08:15:31.953097 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-25 08:15:31.953122 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.953147 | instance | 2.17.12
2026-07-25 08:15:31.953172 | instance | Checked out openstack.cloud to 2.6.0
2026-07-25 08:15:31.953197 | 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-25 08:15:31.953222 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.953249 | instance | Process install dependency map
2026-07-25 08:15:31.953285 | instance | Starting collection install process
2026-07-25 08:15:31.953312 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-25 08:15:31.953337 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-25 08:15:31.953362 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-25 08:15:31.953386 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.953411 | instance | 2.17.12
2026-07-25 08:15:31.953443 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-25 08:15:31.953469 | 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-25 08:15:31.953495 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.953520 | instance | Process install dependency map
2026-07-25 08:15:31.953545 | instance | Starting collection install process
2026-07-25 08:15:31.953570 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-25 08:15:31.953597 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-25 08:15:31.953622 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-25 08:15:31.953648 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.953673 | instance | 2.17.12
2026-07-25 08:15:31.953698 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-25 08:15:31.953723 | 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-25 08:15:31.953748 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.953773 | instance | Process install dependency map
2026-07-25 08:15:31.953800 | instance | Starting collection install process
2026-07-25 08:15:31.953828 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-25 08:15:31.953855 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-25 08:15:31.953880 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-25 08:15:31.953904 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.953929 | instance | 2.17.12
2026-07-25 08:15:31.953954 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-25 08:15:31.953979 | 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-25 08:15:31.954004 | instance | Starting galaxy collection install process
2026-07-25 08:15:31.954029 | instance | Process install dependency map
2026-07-25 08:15:31.954054 | instance | Starting collection install process
2026-07-25 08:15:31.954079 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-25 08:15:31.954104 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-25 08:15:31.954129 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-25 08:15:31.954155 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 08:15:31.954180 | instance | 2.17.12
2026-07-25 08:15:31.962541 | 
2026-07-25 08:15:31.962590 | PLAY RECAP
2026-07-25 08:15:31.962637 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 08:15:31.962659 | 
2026-07-25 08:15:32.107413 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-25 08:15:32.122268 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-25 08:15:32.765433 | 
2026-07-25 08:15:32.765589 | PLAY [all]
2026-07-25 08:15:32.777388 | 
2026-07-25 08:15:32.777464 | TASK [Install "jq" for log collection]
2026-07-25 08:15:53.263266 | instance | changed
2026-07-25 08:15:53.967069 | 
2026-07-25 08:15:53.967253 | PLAY RECAP
2026-07-25 08:15:53.967370 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 08:15:53.967499 | 
2026-07-25 08:15:54.160329 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-25 08:15:54.176643 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-25 08:15:54.823579 | 
2026-07-25 08:15:54.823713 | PLAY [Prepare Molecule dependencies]
2026-07-25 08:15:54.837170 | 
2026-07-25 08:15:54.837326 | TASK [Install jq for log collection]
2026-07-25 08:15:57.192313 | instance | ok
2026-07-25 08:15:58.551611 | 
2026-07-25 08:15:58.552529 | PLAY [Install Go for the Atmosphere deployment binary]
2026-07-25 08:15:58.562595 | 
2026-07-25 08:15:58.562689 | TASK [ensure-go : Check installed go version]
2026-07-25 08:15:59.100875 | instance | ok: ERROR (ignored)
2026-07-25 08:15:59.110122 | instance | {
2026-07-25 08:15:59.110192 | instance |   "failed_when_result": false,
2026-07-25 08:15:59.110230 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-07-25 08:15:59.110265 | instance |   "rc": 2
2026-07-25 08:15:59.110295 | instance | }
2026-07-25 08:15:59.117672 | 
2026-07-25 08:15:59.117774 | TASK [ensure-go : Skip if correct version of go is installed]
2026-07-25 08:15:59.191857 | instance | ok
2026-07-25 08:15:59.201496 | instance | included: /var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-07-25 08:15:59.206554 | 
2026-07-25 08:15:59.206622 | TASK [ensure-go : Create temp directory]
2026-07-25 08:15:59.517140 | instance | changed
2026-07-25 08:15:59.524321 | 
2026-07-25 08:15:59.524392 | TASK [ensure-go : Get archive checksum]
2026-07-25 08:16:00.089035 | instance | ok: OK (64 bytes)
2026-07-25 08:16:00.095227 | 
2026-07-25 08:16:00.095298 | TASK [ensure-go : Download go archive]
2026-07-25 08:16:01.431578 | instance | changed: OK (78559214 bytes)
2026-07-25 08:16:01.437225 | 
2026-07-25 08:16:01.437296 | TASK [ensure-go : Install go]
2026-07-25 08:16:07.230785 | instance | changed
2026-07-25 08:16:07.240231 | 
2026-07-25 08:16:07.240309 | PLAY [Prepare selective Molecule execution plan]
2026-07-25 08:16:07.249192 | 
2026-07-25 08:16:07.249282 | TASK [Build atmosphere binary]
2026-07-25 08:16:07.523975 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-07-25 08:16:07.527097 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-07-25 08:16:07.528593 | instance | go: downloading golang.org/x/sync v0.18.0
2026-07-25 08:16:07.811108 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-07-25 08:16:14.881113 | instance | ok: Runtime: 0:00:06.845959
2026-07-25 08:16:15.183424 | 
2026-07-25 08:16:15.184157 | TASK [Generate selective CI plan]
2026-07-25 08:16:15.755660 | instance | ok: Runtime: 0:00:00.027835
2026-07-25 08:16:15.762837 | 
2026-07-25 08:16:15.762916 | TASK [Explain selective CI plan]
2026-07-25 08:16:15.991465 | instance | CI plan mode: selective
2026-07-25 08:16:15.991666 | instance | Changed files:
2026-07-25 08:16:15.991679 | instance |   A	roles/keystone/.ci-selective-test
2026-07-25 08:16:15.991690 | instance | Matches:
2026-07-25 08:16:15.991702 | instance |   roles/keystone/.ci-selective-test: component:keystone (targets) -> keystone
2026-07-25 08:16:15.991712 | instance | Direct targets: keystone
2026-07-25 08:16:15.991724 | instance | Deployment roots: keystone
2026-07-25 08:16:15.991734 | instance | Verification profiles: identity, keycloak-federation
2026-07-25 08:16:15.991744 | instance | Variant openvswitch (11 components): cert-manager, cluster-issuer, csi, ingress-nginx, keycloak, keystone, kubernetes, memcached, percona-xtradb-cluster, percona-xtradb-cluster-operator, rabbitmq-cluster-operator
2026-07-25 08:16:15.991757 | instance | Molecule jobs:
2026-07-25 08:16:15.991766 | instance |   aio-openvswitch: run (11 components) - executes the openvswitch deployment variant
2026-07-25 08:16:15.991775 | instance |   aio-ovn: skip - plan has no ovn deployment variant
2026-07-25 08:16:15.991784 | instance |   csi-local-path-provisioner: skip - none of the job's verification profiles were requested
2026-07-25 08:16:15.991793 | instance |   csi-rbd: skip - none of the job's verification profiles were requested
2026-07-25 08:16:15.991803 | instance |   keycloak: run - requested verification profiles: keycloak-federation
2026-07-25 08:16:16.345051 | instance | ok: Runtime: 0:00:00.022474
2026-07-25 08:16:16.350939 | 
2026-07-25 08:16:16.351020 | TASK [Print selective CI plan]
2026-07-25 08:16:16.386929 | instance | ok
2026-07-25 08:16:16.391693 | 
2026-07-25 08:16:16.391766 | TASK [Report conservative fallback]
2026-07-25 08:16:16.417665 | instance | skipping: Conditional result was False
2026-07-25 08:16:16.427940 | 
2026-07-25 08:16:16.428052 | TASK [Create selective CI plan log directory]
2026-07-25 08:16:16.757349 | instance | changed
2026-07-25 08:16:16.764353 | 
2026-07-25 08:16:16.764422 | TASK [Publish selective CI plan with job logs]
2026-07-25 08:16:17.174508 | instance | changed
2026-07-25 08:16:17.176556 | 
2026-07-25 08:16:17.176622 | PLAY RECAP
2026-07-25 08:16:17.176687 | instance | ok: 13 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-25 08:16:17.176718 | 
2026-07-25 08:16:17.354872 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-25 08:16:17.550822 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-25 08:16:18.239979 | 
2026-07-25 08:16:25.234744 | PLAY [Run selected Molecule deployment and verification]
2026-07-25 08:16:25.256924 | 
2026-07-25 08:16:25.257159 | TASK [Check for selective CI plan]
2026-07-25 08:16:25.628863 | instance | ok
2026-07-25 08:16:25.638879 | 
2026-07-25 08:16:25.639019 | TASK [Validate selective CI plan]
2026-07-25 08:16:26.175376 | instance | ok: Runtime: 0:00:00.031327
2026-07-25 08:16:26.184201 | 
2026-07-25 08:16:26.184453 | TASK [Resolve selective CI plan availability]
2026-07-25 08:16:26.235018 | instance | ok
2026-07-25 08:16:26.239569 | 
2026-07-25 08:16:26.239642 | TASK [Read selective CI plan]
2026-07-25 08:16:26.581681 | instance | ok
2026-07-25 08:16:26.587334 | 
2026-07-25 08:16:26.587434 | TASK [Load selective CI plan or full fallback]
2026-07-25 08:16:26.653178 | instance | ok
2026-07-25 08:16:26.661651 | 
2026-07-25 08:16:26.661717 | TASK [Find this job in the selective CI plan]
2026-07-25 08:16:26.710633 | instance | ok
2026-07-25 08:16:26.715040 | 
2026-07-25 08:16:26.715102 | TASK [Resolve this job or use full fallback]
2026-07-25 08:16:26.769326 | instance | ok
2026-07-25 08:16:26.773764 | 
2026-07-25 08:16:26.773832 | TASK [Resolve deployment and verification inputs]
2026-07-25 08:16:26.866629 | instance | ok
2026-07-25 08:16:26.920730 | 
2026-07-25 08:16:26.920915 | TASK [Resolve deployment tags]
2026-07-25 08:16:26.974098 | instance | ok
2026-07-25 08:16:26.981374 | 
2026-07-25 08:16:26.981467 | TASK [Report Molecule job decision]
2026-07-25 08:16:27.015163 | instance | ok: {'job': 'aio-ovn', 'mode': 'selective', 'run': False, 'reason': 'plan has no ovn deployment variant', 'network_backend': 'ovn', 'components': [], 'verification_profiles': ['identity', 'keycloak-federation']}
2026-07-25 08:16:27.022679 | 
2026-07-25 08:16:27.022767 | TASK [Copy inventory file for Zuul]
2026-07-25 08:16:27.049321 | instance | skipping: Conditional result was False
2026-07-25 08:16:27.061544 | 
2026-07-25 08:16:27.061772 | TASK [Switch ansible_host to private IP]
2026-07-25 08:16:27.088468 | instance | skipping: Conditional result was False
2026-07-25 08:16:27.107619 | 
2026-07-25 08:16:27.107931 | TASK [Prepare Molecule scenario]
2026-07-25 08:16:27.639941 | instance | skipping: Conditional result was False
2026-07-25 08:16:27.802714 | 
2026-07-25 08:16:27.802827 | TASK [Build deployment command]
2026-07-25 08:16:27.827375 | instance | skipping: Conditional result was False
2026-07-25 08:16:27.834268 | 
2026-07-25 08:16:27.834366 | TASK [Deploy with parallel orchestrator]
2026-07-25 08:16:28.363083 | instance | skipping: Conditional result was False
2026-07-25 08:16:28.373560 | 
2026-07-25 08:16:28.373665 | TASK [Re-deploy to verify idempotent execution]
2026-07-25 08:16:28.902515 | instance | skipping: Conditional result was False
2026-07-25 08:16:30.625534 | 
2026-07-25 08:16:30.625701 | TASK [Add IP address to br-ex]
2026-07-25 08:16:31.153666 | instance | skipping: Conditional result was False
2026-07-25 08:16:31.164945 | 
2026-07-25 08:16:31.165037 | TASK [Set br-ex interface up]
2026-07-25 08:16:31.691187 | instance | skipping: Conditional result was False
2026-07-25 08:16:31.699822 | 
2026-07-25 08:16:31.699976 | TASK [Verify Molecule scenario]
2026-07-25 08:16:32.229134 | instance | skipping: Conditional result was False
2026-07-25 08:16:32.232611 | 
2026-07-25 08:16:32.232739 | PLAY RECAP
2026-07-25 08:16:32.232865 | instance | ok: 10 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2026-07-25 08:16:32.232933 | 
2026-07-25 08:16:32.406022 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-25 08:16:32.584387 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-25 08:16:33.237851 | 
2026-07-25 08:16:33.237992 | PLAY [all]
2026-07-25 08:16:33.252531 | 
2026-07-25 08:16:33.252637 | TASK [gather-host-logs : creating directory for system status]
2026-07-25 08:16:33.593236 | instance | ok
2026-07-25 08:16:33.937835 | 
2026-07-25 08:16:33.938093 | TASK [gather-host-logs : Get logs for each host]
2026-07-25 08:16:34.318793 | instance | + systemd-cgls --full --all --no-pager
2026-07-25 08:16:34.331102 | instance | + ip addr
2026-07-25 08:16:34.332489 | instance | + ip route
2026-07-25 08:16:34.333574 | instance | + lsblk
2026-07-25 08:16:34.336118 | instance | + mount
2026-07-25 08:16:34.337404 | instance | + docker images
2026-07-25 08:16:34.337728 | instance | /bin/bash: line 7: docker: command not found
2026-07-25 08:16:34.337900 | instance | + brctl show
2026-07-25 08:16:34.338208 | instance | /bin/bash: line 8: brctl: command not found
2026-07-25 08:16:34.338382 | instance | + ps aux --sort=-%mem
2026-07-25 08:16:34.353984 | instance | + dpkg -l
2026-07-25 08:16:34.364675 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-25 08:16:34.365256 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-25 08:16:34.365375 | instance | /bin/bash: line 11: docker: command not found
2026-07-25 08:16:34.365764 | instance | + '[' '!' -z '' ']'
2026-07-25 08:16:34.481640 | instance | ok: Runtime: 0:00:00.052752
2026-07-25 08:16:34.487987 | 
2026-07-25 08:16:34.488063 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-25 08:16:35.180885 | instance | ERROR
2026-07-25 08:16:35.181191 | instance | {
2026-07-25 08:16:35.181237 | instance |   "msg": "Warning: Permanently added '199.204.45.77' (ED25519) to the list of known hosts.\r\nrsync: [generator] chown \"/var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/logs/instance/system\" failed: Invalid argument (22)\nrsync: [receiver] chown \"/var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/logs/instance/system/.ci-plan.json.MBGjhP\" failed: Invalid argument (22)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [generator=3.2.7]\n",
2026-07-25 08:16:35.181271 | instance |   "rc": 23
2026-07-25 08:16:35.181299 | instance | }
2026-07-25 08:16:35.181337 | instance | ERROR: Ignoring Errors
2026-07-25 08:16:35.191800 | 
2026-07-25 08:16:35.191869 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-25 08:16:35.392360 | instance | changed: "values"
2026-07-25 08:16:35.563187 | instance | changed: "releases"
2026-07-25 08:16:35.682039 | 
2026-07-25 08:16:35.682175 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-25 08:16:35.956247 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-25 08:16:36.218325 | instance | ok: Runtime: 0:00:00.005716
2026-07-25 08:16:36.224807 | 
2026-07-25 08:16:36.224888 | TASK [helm-release-status : Downloads logs to executor]
2026-07-25 08:16:36.702823 | instance | changed:
2026-07-25 08:16:36.703026 | instance | cd+++++++++ helm/
2026-07-25 08:16:36.703066 | instance | cd+++++++++ helm/releases/
2026-07-25 08:16:36.703097 | instance | cd+++++++++ helm/values/
2026-07-25 08:16:36.713844 | 
2026-07-25 08:16:36.713910 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-25 08:16:36.924534 | instance | changed
2026-07-25 08:16:36.931163 | 
2026-07-25 08:16:36.931300 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-25 08:16:37.148458 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 08:16:37.148881 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 08:16:37.153685 | instance | environment: line 1: kubectl: command not found
2026-07-25 08:16:37.154765 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 08:16:37.157470 | instance | environment: line 1: kubectl: command not found
2026-07-25 08:16:37.159000 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 08:16:37.160187 | instance | environment: line 1: kubectl: command not found
2026-07-25 08:16:37.161905 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 08:16:37.162568 | instance | environment: line 1: kubectl: command not found
2026-07-25 08:16:37.163842 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 08:16:37.164496 | instance | environment: line 1: kubectl: command not found
2026-07-25 08:16:37.165924 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 08:16:37.464553 | instance | ok: Runtime: 0:00:00.027842
2026-07-25 08:16:37.469930 | 
2026-07-25 08:16:37.469996 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-25 08:16:37.679401 | instance | changed
2026-07-25 08:16:37.687557 | 
2026-07-25 08:16:37.687633 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-25 08:16:37.897523 | instance | environment: line 5: kubectl: command not found
2026-07-25 08:16:37.899061 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 08:16:37.899427 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 08:16:37.899441 | instance |
2026-07-25 08:16:38.222196 | instance | ok: Runtime: 0:00:00.011252
2026-07-25 08:16:38.228943 | 
2026-07-25 08:16:38.229050 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-25 08:16:38.728943 | instance | changed:
2026-07-25 08:16:38.729159 | instance | cd+++++++++ objects/
2026-07-25 08:16:38.729197 | instance | cd+++++++++ objects/cluster/
2026-07-25 08:16:38.729228 | instance | cd+++++++++ objects/namespaced/
2026-07-25 08:16:38.741858 | 
2026-07-25 08:16:38.741962 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-25 08:16:38.940016 | instance | changed
2026-07-25 08:16:38.948282 | 
2026-07-25 08:16:38.948350 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-25 08:16:39.143609 | instance | changed
2026-07-25 08:16:39.574131 | 
2026-07-25 08:16:39.574317 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-25 08:16:39.791265 | instance | environment: line 3: kubectl: command not found
2026-07-25 08:16:40.113413 | instance | ok: Runtime: 0:00:00.008916
2026-07-25 08:16:40.118587 | 
2026-07-25 08:16:40.118652 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-25 08:16:40.629958 | instance | changed:
2026-07-25 08:16:40.630231 | instance | cd+++++++++ pod-logs/
2026-07-25 08:16:40.630292 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-25 08:16:40.642345 | 
2026-07-25 08:16:40.642411 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-25 08:16:40.835950 | instance | changed
2026-07-25 08:16:40.841602 | 
2026-07-25 08:16:40.841671 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-25 08:16:41.044089 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-25 08:16:41.379755 | instance | ok: Runtime: 0:00:00.035660
2026-07-25 08:16:41.385868 | 
2026-07-25 08:16:41.385970 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-25 08:16:41.608743 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-25 08:16:41.640684 | instance | ceph-mgr endpoints:
2026-07-25 08:16:42.668172 | instance | ok: Runtime: 0:00:00.039745
2026-07-25 08:16:42.674280 | 
2026-07-25 08:16:42.674352 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-25 08:16:42.939304 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-25 08:16:43.261293 | instance | ok: Runtime: 0:00:00.036068
2026-07-25 08:16:43.510488 | 
2026-07-25 08:16:43.510613 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-25 08:16:44.029969 | instance | changed: cd+++++++++ prometheus/
2026-07-25 08:16:44.043302 | 
2026-07-25 08:16:44.043369 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-25 08:16:44.236808 | instance | changed
2026-07-25 08:16:44.242972 | 
2026-07-25 08:16:44.243044 | TASK [gather-selenium-data : Get selenium data]
2026-07-25 08:16:44.453957 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-25 08:16:44.455511 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-25 08:16:44.775936 | instance | ERROR
2026-07-25 08:16:44.776110 | instance | {
2026-07-25 08:16:44.776202 | instance |   "delta": "0:00:00.007060",
2026-07-25 08:16:44.776251 | instance |   "end": "2026-07-25 08:16:44.455872",
2026-07-25 08:16:44.776284 | instance |   "msg": "non-zero return code",
2026-07-25 08:16:44.776310 | instance |   "rc": 1,
2026-07-25 08:16:44.776339 | instance |   "start": "2026-07-25 08:16:44.448812"
2026-07-25 08:16:44.776364 | instance | }
2026-07-25 08:16:44.776399 | instance | ERROR: Ignoring Errors
2026-07-25 08:16:44.783073 | 
2026-07-25 08:16:44.783139 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-25 08:16:45.282883 | instance | changed: cd+++++++++ selenium/
2026-07-25 08:16:45.289490 | 
2026-07-25 08:16:45.289543 | PLAY RECAP
2026-07-25 08:16:45.289588 | instance | ok: 23 changed: 21 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-07-25 08:16:45.289609 | 
2026-07-25 08:16:45.424017 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-25 08:16:45.435866 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-25 08:16:46.096976 | 
2026-07-25 08:16:46.097168 | PLAY [all]
2026-07-25 08:16:46.111111 | 
2026-07-25 08:16:46.111242 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-25 08:16:46.158236 | instance | skipping: Conditional result was False
2026-07-25 08:16:46.169891 | 
2026-07-25 08:16:46.170100 | TASK [fetch-output : Set log path for single node]
2026-07-25 08:16:46.215086 | instance | ok
2026-07-25 08:16:46.221453 | 
2026-07-25 08:16:46.221534 | LOOP [fetch-output : Ensure local output dirs]
2026-07-25 08:16:47.124690 | instance -> localhost | ok: "/var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/logs"
2026-07-25 08:16:47.345510 | instance -> localhost | changed: "/var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/artifacts"
2026-07-25 08:16:47.631083 | instance -> localhost | changed: "/var/lib/zuul/builds/a5ebd2deb77842f8a56a4c450806a74a/work/docs"
2026-07-25 08:16:47.657660 | 
2026-07-25 08:16:47.657801 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-25 08:16:48.287421 | instance | changed: .d..t...... ./
2026-07-25 08:16:48.287620 | instance | changed: All items complete
2026-07-25 08:16:48.287648 | 
2026-07-25 08:16:48.755968 | instance | changed: .d..t...... ./
2026-07-25 08:16:49.207993 | instance | changed: .d..t...... ./
2026-07-25 08:16:49.228905 | 
2026-07-25 08:16:49.229102 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-25 08:16:49.787556 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.139479
2026-07-25 08:16:50.040842 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006910
2026-07-25 08:16:50.057643 | 
2026-07-25 08:16:50.057760 | PLAY [all]
2026-07-25 08:16:50.063689 | 
2026-07-25 08:16:50.063757 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-25 08:16:50.475160 | instance | changed
2026-07-25 08:16:50.482288 | 
2026-07-25 08:16:50.482339 | PLAY RECAP
2026-07-25 08:16:50.482387 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-25 08:16:50.482409 | 
2026-07-25 08:16:50.638028 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-25 08:16:50.652429 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-25 08:16:51.314922 | 
2026-07-25 08:16:51.315212 | PLAY [localhost]
2026-07-25 08:16:51.324996 | 
2026-07-25 08:16:51.325069 | TASK [Generate Zuul manifest]
2026-07-25 08:16:51.348511 | localhost | ok
2026-07-25 08:16:51.365733 | 
2026-07-25 08:16:51.365815 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-25 08:16:51.895384 | localhost | changed
2026-07-25 08:16:52.192767 | 
2026-07-25 08:16:52.192989 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-25 08:16:52.237514 | localhost | ok
2026-07-25 08:16:52.244530 | 
2026-07-25 08:16:52.244600 | TASK [Upload logs]
2026-07-25 08:16:52.270979 | localhost | ok
2026-07-25 08:16:52.613239 | 
2026-07-25 08:16:52.613410 | TASK [Set zuul-log-path fact]
2026-07-25 08:16:52.635273 | localhost | ok
2026-07-25 08:16:52.648946 | 
2026-07-25 08:16:52.649079 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-25 08:16:52.682002 | localhost | ok
2026-07-25 08:16:52.690616 | 
2026-07-25 08:16:52.690688 | TASK [upload-logs : Create log directories]
2026-07-25 08:16:53.068155 | localhost | changed
2026-07-25 08:16:53.075875 | 
2026-07-25 08:16:53.075955 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-25 08:16:53.442666 | localhost -> localhost | ok: Runtime: 0:00:00.005713
2026-07-25 08:16:53.448129 | 
2026-07-25 08:16:53.448213 | TASK [upload-logs : Upload logs to log server]
2026-07-25 08:16:53.875870 | localhost | Output suppressed because no_log was given
2026-07-25 08:16:53.881069 | 
2026-07-25 08:16:53.881163 | LOOP [upload-logs : Compress console log and json output]
2026-07-25 08:16:53.929748 | localhost | skipping: Conditional result was False
2026-07-25 08:16:53.937250 | localhost | skipping: Conditional result was False
2026-07-25 08:16:53.956137 | 
2026-07-25 08:16:53.956280 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-25 08:16:53.999551 | localhost | skipping: Conditional result was False
2026-07-25 08:16:53.999966 | 
2026-07-25 08:16:54.003296 | localhost | skipping: Conditional result was False
2026-07-25 08:16:54.022294 | 
2026-07-25 08:16:54.022490 | LOOP [upload-logs : Upload console log and json output]