2026-02-27 20:20:39.555814 | Job console starting
2026-02-27 20:20:39.622667 | Updating git repos
2026-02-27 20:20:39.680615 | Cloning repos into workspace
2026-02-27 20:20:39.778584 | Restoring repo states
2026-02-27 20:20:39.907126 | Merging changes
2026-02-27 20:20:41.766697 | Checking out repos
2026-02-27 20:20:42.107721 | Preparing playbooks
2026-02-27 20:20:46.092814 | Running Ansible setup
2026-02-27 20:20:50.042575 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-27 20:20:50.732445 | 
2026-02-27 20:20:50.732596 | PLAY [localhost]
2026-02-27 20:20:50.741531 | 
2026-02-27 20:20:50.741644 | TASK [Gathering Facts]
2026-02-27 20:20:51.638164 | localhost | ok
2026-02-27 20:20:51.653812 | 
2026-02-27 20:20:51.653903 | TASK [Setup log path fact]
2026-02-27 20:20:51.677420 | localhost | ok
2026-02-27 20:20:51.691300 | 
2026-02-27 20:20:51.691385 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 20:20:51.724009 | localhost | ok
2026-02-27 20:20:51.734406 | 
2026-02-27 20:20:51.734482 | TASK [emit-job-header : Print job information]
2026-02-27 20:20:51.776643 | # Job Information
2026-02-27 20:20:51.786878 | Ansible Version: 2.16.16
2026-02-27 20:20:51.786984 | Job: atmosphere-molecule-aio-ovn
2026-02-27 20:20:51.787037 | Pipeline: check
2026-02-27 20:20:51.787083 | Executor: 0a8996d2b663
2026-02-27 20:20:51.787127 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3700
2026-02-27 20:20:51.787177 | Event ID: b0843bb0-1419-11f1-938a-b011ac622043
2026-02-27 20:20:51.793443 | 
2026-02-27 20:20:51.793579 | LOOP [emit-job-header : Print node information]
2026-02-27 20:20:51.887037 | localhost | ok:
2026-02-27 20:20:51.887248 | localhost | # Node Information
2026-02-27 20:20:51.887286 | localhost | Inventory Hostname: instance
2026-02-27 20:20:51.887315 | localhost | Hostname: np0000157385
2026-02-27 20:20:51.887341 | localhost | Username: zuul
2026-02-27 20:20:51.887371 | localhost | Distro: Ubuntu 22.04
2026-02-27 20:20:51.887398 | localhost | Provider: yul1
2026-02-27 20:20:51.887425 | localhost | Region: ca-ymq-1
2026-02-27 20:20:51.887450 | localhost | Label: ubuntu-jammy-16
2026-02-27 20:20:51.887475 | localhost | Product Name: OpenStack Nova
2026-02-27 20:20:51.887500 | localhost | Interface IP: 199.204.45.23
2026-02-27 20:20:51.902940 | 
2026-02-27 20:20:51.903088 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-27 20:20:52.338339 | localhost -> localhost | changed
2026-02-27 20:20:52.348751 | 
2026-02-27 20:20:52.348818 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-27 20:20:53.270680 | localhost -> localhost | changed
2026-02-27 20:20:53.276235 | 
2026-02-27 20:20:53.276289 | PLAY [all]
2026-02-27 20:20:53.284534 | 
2026-02-27 20:20:53.284597 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-27 20:20:53.550583 | instance -> localhost | ok
2026-02-27 20:20:53.556275 | 
2026-02-27 20:20:53.556343 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-27 20:20:53.588498 | instance | ok
2026-02-27 20:20:53.604770 | instance | included: /var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-27 20:20:53.610229 | 
2026-02-27 20:20:53.610291 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-27 20:20:54.343154 | instance -> localhost | Generating public/private rsa key pair.
2026-02-27 20:20:54.343326 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/work/c1d8d7d40d8c4611832c1f5d8da234aa_id_rsa
2026-02-27 20:20:54.343358 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/work/c1d8d7d40d8c4611832c1f5d8da234aa_id_rsa.pub
2026-02-27 20:20:54.343382 | instance -> localhost | The key fingerprint is:
2026-02-27 20:20:54.343404 | instance -> localhost | SHA256:OkH0ftEBH6EwngtWIpppX9dFa/bVx9Yi+6U6Xj6W1pE zuul-build-sshkey
2026-02-27 20:20:54.343440 | instance -> localhost | The key's randomart image is:
2026-02-27 20:20:54.343462 | instance -> localhost | +---[RSA 3072]----+
2026-02-27 20:20:54.343486 | instance -> localhost | |    . o + .o*.   |
2026-02-27 20:20:54.343507 | instance -> localhost | |   + o = = = + .o|
2026-02-27 20:20:54.343529 | instance -> localhost | |  =   = = + B . B|
2026-02-27 20:20:54.343550 | instance -> localhost | | . . + + . + + +.|
2026-02-27 20:20:54.343570 | instance -> localhost | |    . . S . . . o|
2026-02-27 20:20:54.343589 | instance -> localhost | |       o .   . E |
2026-02-27 20:20:54.343609 | instance -> localhost | |      o       +o.|
2026-02-27 20:20:54.343630 | instance -> localhost | |       .    .+= .|
2026-02-27 20:20:54.343653 | instance -> localhost | |           .o+.. |
2026-02-27 20:20:54.343674 | instance -> localhost | +----[SHA256]-----+
2026-02-27 20:20:54.343722 | instance -> localhost | ok: Runtime: 0:00:00.289521
2026-02-27 20:20:54.350020 | 
2026-02-27 20:20:54.350108 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-27 20:20:54.388469 | instance | ok
2026-02-27 20:20:54.406909 | instance | included: /var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-27 20:20:54.500349 | 
2026-02-27 20:20:54.500538 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-27 20:20:54.527178 | instance | skipping: Conditional result was False
2026-02-27 20:20:54.537328 | 
2026-02-27 20:20:54.537471 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-27 20:20:54.949834 | instance | changed
2026-02-27 20:20:54.954976 | 
2026-02-27 20:20:54.955039 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-27 20:20:55.140054 | instance | ok
2026-02-27 20:20:55.147223 | 
2026-02-27 20:20:55.147313 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-27 20:20:55.632414 | instance | changed
2026-02-27 20:20:55.639857 | 
2026-02-27 20:20:55.639947 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-27 20:20:56.099215 | instance | changed
2026-02-27 20:20:56.106439 | 
2026-02-27 20:20:56.106507 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-27 20:20:56.131893 | instance | skipping: Conditional result was False
2026-02-27 20:20:56.139354 | 
2026-02-27 20:20:56.139435 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-27 20:20:56.541972 | instance -> localhost | changed
2026-02-27 20:20:56.598902 | 
2026-02-27 20:20:56.599026 | TASK [add-build-sshkey : Add back temp key]
2026-02-27 20:20:57.201676 | instance -> localhost | Identity added: /var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/work/c1d8d7d40d8c4611832c1f5d8da234aa_id_rsa (zuul-build-sshkey)
2026-02-27 20:20:57.201837 | instance -> localhost | ok: Runtime: 0:00:00.014651
2026-02-27 20:20:57.208233 | 
2026-02-27 20:20:57.208293 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-27 20:20:57.457883 | instance | ok
2026-02-27 20:20:57.470683 | 
2026-02-27 20:20:57.471754 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-27 20:20:57.500539 | instance | skipping: Conditional result was False
2026-02-27 20:20:57.520112 | 
2026-02-27 20:20:57.553088 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-27 20:20:57.872874 | instance | ok
2026-02-27 20:20:57.881871 | 
2026-02-27 20:20:57.886410 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-27 20:20:59.801142 | instance | Output suppressed because no_log was given
2026-02-27 20:20:59.822083 | 
2026-02-27 20:20:59.822235 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-27 20:21:00.029026 | instance | ok: "logs"
2026-02-27 20:21:00.112655 | instance | ok: All items complete
2026-02-27 20:21:00.112788 | 
2026-02-27 20:21:00.191223 | instance | ok: "artifacts"
2026-02-27 20:21:00.384777 | instance | ok: "docs"
2026-02-27 20:21:00.405760 | 
2026-02-27 20:21:00.405931 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-27 20:21:00.607246 | instance | changed: "logs"
2026-02-27 20:21:00.765505 | instance | changed: "artifacts"
2026-02-27 20:21:00.934435 | instance | changed: "docs"
2026-02-27 20:21:00.951731 | 
2026-02-27 20:21:00.951861 | PLAY RECAP
2026-02-27 20:21:00.951917 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-27 20:21:00.951947 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 20:21:00.951972 | 
2026-02-27 20:21:01.108445 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-27 20:21:01.122687 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-27 20:21:01.757394 | 
2026-02-27 20:21:01.757989 | PLAY [all]
2026-02-27 20:21:01.770710 | 
2026-02-27 20:21:01.770788 | TASK [setup-uv : Extract archive]
2026-02-27 20:21:03.987007 | instance | changed
2026-02-27 20:21:03.995775 | 
2026-02-27 20:21:03.995857 | TASK [setup-uv : Print version]
2026-02-27 20:21:04.098590 | instance | uv 0.8.13
2026-02-27 20:21:04.541828 | instance | ok: Runtime: 0:00:00.010492
2026-02-27 20:21:04.550313 | 
2026-02-27 20:21:04.550383 | PLAY RECAP
2026-02-27 20:21:04.550444 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 20:21:04.550477 | 
2026-02-27 20:21:04.644718 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-27 20:21:04.651536 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1]
2026-02-27 20:21:05.245087 | 
2026-02-27 20:21:05.278953 | PLAY [all]
2026-02-27 20:21:05.290439 | 
2026-02-27 20:21:05.290515 | TASK [Install "jq" for log collection]
2026-02-27 20:21:14.491858 | instance | changed
2026-02-27 20:21:14.495375 | 
2026-02-27 20:21:14.495506 | PLAY RECAP
2026-02-27 20:21:14.495611 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 20:21:14.495719 | 
2026-02-27 20:21:14.673465 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1]
2026-02-27 20:21:14.679989 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-27 20:21:15.361846 | 
2026-02-27 20:21:15.361994 | PLAY [all]
2026-02-27 20:21:15.374587 | 
2026-02-27 20:21:15.374685 | TASK [Copy inventory file for Zuul]
2026-02-27 20:21:16.214694 | instance | changed
2026-02-27 20:21:16.220893 | 
2026-02-27 20:21:16.221002 | TASK [Switch "ansible_host" to private IP]
2026-02-27 20:21:16.574335 | instance | changed: 1 replacements made
2026-02-27 20:21:16.581365 | 
2026-02-27 20:21:16.581436 | TASK [Run Molecule scenario]
2026-02-27 20:21:16.959973 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-02-27 20:21:16.960276 | instance | Creating virtual environment at: .venv
2026-02-27 20:21:16.987707 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-27 20:21:17.004830 | instance | Downloading openstacksdk (1.7MiB)
2026-02-27 20:21:17.005128 | instance | Downloading rjsonnet (1.2MiB)
2026-02-27 20:21:17.005254 | instance | Downloading pygments (1.2MiB)
2026-02-27 20:21:17.017386 | instance | Downloading ansible-core (2.1MiB)
2026-02-27 20:21:17.018656 | instance | Downloading cryptography (4.2MiB)
2026-02-27 20:21:17.019539 | instance | Downloading netaddr (2.2MiB)
2026-02-27 20:21:17.019895 | instance | Downloading kubernetes (1.9MiB)
2026-02-27 20:21:17.020839 | instance | Downloading setuptools (1.1MiB)
2026-02-27 20:21:17.341326 | instance |    Building pyperclip==1.9.0
2026-02-27 20:21:17.349069 | instance |  Downloading rjsonnet
2026-02-27 20:21:17.503813 | instance |  Downloading netaddr
2026-02-27 20:21:17.523660 | instance |  Downloading pygments
2026-02-27 20:21:17.536248 | instance |  Downloading cryptography
2026-02-27 20:21:17.579801 | instance |  Downloading setuptools
2026-02-27 20:21:17.645131 | instance |  Downloading kubernetes
2026-02-27 20:21:17.685742 | instance |  Downloading ansible-core
2026-02-27 20:21:17.723542 | instance |  Downloading openstacksdk
2026-02-27 20:21:18.078268 | instance |       Built pyperclip==1.9.0
2026-02-27 20:21:18.299186 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-27 20:21:18.349284 | instance | Installed 79 packages in 48ms
2026-02-27 20:21:19.038614 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-02-27 20:21:19.659678 | instance | INFO     [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-02-27 20:21:19.660271 | instance | INFO     [aio > prerun] Performing prerun with role_name_check=0...
2026-02-27 20:21:42.080275 | instance | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv openstack.cloud:1.7.0
2026-02-27 20:21:42.080487 | instance | ERROR    Command ansible-galaxy collection install -vvv openstack.cloud:1.7.0, returned 250 code:
2026-02-27 20:21:42.080502 | instance | Starting galaxy collection install process
2026-02-27 20:21:42.080512 | instance | Process install dependency map
2026-02-27 20:21:42.080522 | instance | the full traceback was:
2026-02-27 20:21:42.080531 | instance |
2026-02-27 20:21:42.080541 | instance | Traceback (most recent call last):
2026-02-27 20:21:42.080550 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor
2026-02-27 20:21:42.080560 | instance |     exit_code = cli.run()
2026-02-27 20:21:42.080569 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run
2026-02-27 20:21:42.080579 | instance |     return context.CLIARGS()
2026-02-27 20:21:42.080588 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper
2026-02-27 20:21:42.080597 | instance |     return wrapped_method(*args, **kwargs)
2026-02-27 20:21:42.080606 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install
2026-02-27 20:21:42.080615 | instance |     self._execute_install_collection(
2026-02-27 20:21:42.080625 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection
2026-02-27 20:21:42.080636 | instance |     install_collections(
2026-02-27 20:21:42.080645 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections
2026-02-27 20:21:42.080655 | instance |     dependency_map = _resolve_depenency_map(
2026-02-27 20:21:42.080664 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map
2026-02-27 20:21:42.080674 | instance |     return collection_dep_resolver.resolve(
2026-02-27 20:21:42.080683 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-27 20:21:42.080733 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-27 20:21:42.080743 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-27 20:21:42.080752 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-27 20:21:42.080761 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-02-27 20:21:42.080770 | instance |     matches = self._p.find_matches(
2026-02-27 20:21:42.080779 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-02-27 20:21:42.080789 | instance |     match for match in self._find_matches(list(requirements))
2026-02-27 20:21:42.080798 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-02-27 20:21:42.080807 | instance |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-02-27 20:21:42.080816 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions
2026-02-27 20:21:42.080826 | instance |     return set(
2026-02-27 20:21:42.080835 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in <genexpr>
2026-02-27 20:21:42.080844 | instance |     return set(
2026-02-27 20:21:42.080853 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions
2026-02-27 20:21:42.080862 | instance |     raise last_error
2026-02-27 20:21:42.080871 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions
2026-02-27 20:21:42.080881 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-27 20:21:42.080890 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped
2026-02-27 20:21:42.080899 | instance |     return method(self, *args, **kwargs)
2026-02-27 20:21:42.080907 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions
2026-02-27 20:21:42.080917 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-27 20:21:42.080941 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function
2026-02-27 20:21:42.080950 | instance |     return call_retryable_function()
2026-02-27 20:21:42.080969 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy
2026-02-27 20:21:42.080978 | instance |     res = path_cache
2026-02-27 20:21:42.080987 | instance | KeyError: 'results'
2026-02-27 20:21:42.080996 | instance |
2026-02-27 20:21:42.081005 | instance | ansible-galaxy
2026-02-27 20:21:42.081014 | instance |   config file = None
2026-02-27 20:21:42.081023 | instance |   configured module search path =
2026-02-27 20:21:42.081032 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-27 20:21:42.081042 | instance |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages
2026-02-27 20:21:42.081060 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-27 20:21:42.081069 | instance |   python version = 3.10.12 (main, Jan 26 2026, 14:55:28)  (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-02-27 20:21:42.081078 | instance |   jinja version = 3.1.6
2026-02-27 20:21:42.081087 | instance |   libyaml = True
2026-02-27 20:21:42.081096 | instance | No config file found; using defaults
2026-02-27 20:21:42.081104 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-02-27 20:21:42.081113 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-02-27 20:21:42.081122 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-27 20:21:42.081131 | instance | : Collection ansible.netcommon does not support Ansible version
2026-02-27 20:21:42.081140 | instance | 2.17.12
2026-02-27 20:21:42.081149 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-27 20:21:42.081158 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-02-27 20:21:42.081167 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-27 20:21:42.081176 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-02-27 20:21:42.081185 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-27 20:21:42.081194 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-27 20:21:42.081203 | instance | unexpected error when getting available versions of collection openstack.cloud:
2026-02-27 20:21:42.081212 | instance | 'results'
2026-02-27 20:21:42.081221 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-27 20:21:42.081230 | instance |
2026-02-27 20:21:42.081239 | instance | Traceback (most recent call last):
2026-02-27 20:21:42.081251 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-02-27 20:21:42.081703 | instance |     sys.exit(main())
2026-02-27 20:21:42.081958 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-02-27 20:21:42.083560 | instance |     return self.main(*args, **kwargs)
2026-02-27 20:21:42.083993 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-02-27 20:21:42.084776 | instance |     rv = self.invoke(ctx)
2026-02-27 20:21:42.085093 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-02-27 20:21:42.085916 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-02-27 20:21:42.086220 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-02-27 20:21:42.086967 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-02-27 20:21:42.087381 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-02-27 20:21:42.087860 | instance |     return callback(*args, **kwargs)
2026-02-27 20:21:42.088041 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-02-27 20:21:42.088450 | instance |     return func(*args, **kwargs)
2026-02-27 20:21:42.088652 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func
2026-02-27 20:21:42.088905 | instance |     return f(get_current_context(), *args, **kwargs)
2026-02-27 20:21:42.089088 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-02-27 20:21:42.089373 | instance |     return func(ctx)
2026-02-27 20:21:42.089551 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test
2026-02-27 20:21:42.089814 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-02-27 20:21:42.089979 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-02-27 20:21:42.090229 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-02-27 20:21:42.090406 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-02-27 20:21:42.090668 | instance |     scenario.config.runtime.prepare_environment(
2026-02-27 20:21:42.090854 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 694, in prepare_environment
2026-02-27 20:21:42.091223 | instance |     self.install_collection(
2026-02-27 20:21:42.091400 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 552, in install_collection
2026-02-27 20:21:42.091692 | instance |     raise InvalidPrerequisiteError(msg)
2026-02-27 20:21:42.094430 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv openstack.cloud:1.7.0, returned 250 code:
2026-02-27 20:21:42.094447 | instance | Starting galaxy collection install process
2026-02-27 20:21:42.094456 | instance | Process install dependency map
2026-02-27 20:21:42.094465 | instance | the full traceback was:
2026-02-27 20:21:42.094474 | instance |
2026-02-27 20:21:42.094483 | instance | Traceback (most recent call last):
2026-02-27 20:21:42.094492 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor
2026-02-27 20:21:42.094501 | instance |     exit_code = cli.run()
2026-02-27 20:21:42.094510 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run
2026-02-27 20:21:42.094519 | instance |     return context.CLIARGS['func']()
2026-02-27 20:21:42.094528 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper
2026-02-27 20:21:42.094537 | instance |     return wrapped_method(*args, **kwargs)
2026-02-27 20:21:42.094546 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install
2026-02-27 20:21:42.094555 | instance |     self._execute_install_collection(
2026-02-27 20:21:42.094564 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection
2026-02-27 20:21:42.094573 | instance |     install_collections(
2026-02-27 20:21:42.094582 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections
2026-02-27 20:21:42.094591 | instance |     dependency_map = _resolve_depenency_map(
2026-02-27 20:21:42.094600 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map
2026-02-27 20:21:42.094610 | instance |     return collection_dep_resolver.resolve(
2026-02-27 20:21:42.094618 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-27 20:21:42.094627 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-27 20:21:42.094637 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-27 20:21:42.094645 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-27 20:21:42.094664 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-02-27 20:21:42.094673 | instance |     matches = self._p.find_matches(
2026-02-27 20:21:42.094682 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-02-27 20:21:42.094692 | instance |     match for match in self._find_matches(list(requirements[identifier]))
2026-02-27 20:21:42.094701 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-02-27 20:21:42.094711 | instance |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-02-27 20:21:42.094725 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions
2026-02-27 20:21:42.094735 | instance |     return set(
2026-02-27 20:21:42.094744 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in <genexpr>
2026-02-27 20:21:42.094754 | instance |     return set(
2026-02-27 20:21:42.094763 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions
2026-02-27 20:21:42.094772 | instance |     raise last_error
2026-02-27 20:21:42.094781 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions
2026-02-27 20:21:42.094790 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-27 20:21:42.094798 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped
2026-02-27 20:21:42.094807 | instance |     return method(self, *args, **kwargs)
2026-02-27 20:21:42.094816 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions
2026-02-27 20:21:42.094825 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-27 20:21:42.094844 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function
2026-02-27 20:21:42.094853 | instance |     return call_retryable_function()
2026-02-27 20:21:42.094862 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy
2026-02-27 20:21:42.094870 | instance |     res = path_cache['results']
2026-02-27 20:21:42.094879 | instance | KeyError: 'results'
2026-02-27 20:21:42.094888 | instance |
2026-02-27 20:21:42.094897 | instance | ansible-galaxy [core 2.17.12]
2026-02-27 20:21:42.094905 | instance |   config file = None
2026-02-27 20:21:42.094914 | instance |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-02-27 20:21:42.094923 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-27 20:21:42.094932 | instance |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages
2026-02-27 20:21:42.094942 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-27 20:21:42.094957 | instance |   python version = 3.10.12 (main, Jan 26 2026, 14:55:28) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-02-27 20:21:42.094966 | instance |   jinja version = 3.1.6
2026-02-27 20:21:42.094974 | instance |   libyaml = True
2026-02-27 20:21:42.094983 | instance | No config file found; using defaults
2026-02-27 20:21:42.094992 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-02-27 20:21:42.095001 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-02-27 20:21:42.095010 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-27 20:21:42.095018 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-02-27 20:21:42.095027 | instance | 2.17.12
2026-02-27 20:21:42.095036 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-27 20:21:42.095045 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-02-27 20:21:42.095054 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-27 20:21:42.095063 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-02-27 20:21:42.095072 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-27 20:21:42.095081 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-27 20:21:42.095117 | instance | unexpected error when getting available versions of collection openstack.cloud:
2026-02-27 20:21:42.095127 | instance | 'results'
2026-02-27 20:21:42.095135 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-27 20:21:42.095144 | instance |
2026-02-27 20:21:42.610574 | instance | ERROR
2026-02-27 20:21:42.610831 | instance | {
2026-02-27 20:21:42.610862 | instance |   "delta": "0:00:25.275544",
2026-02-27 20:21:42.610884 | instance |   "end": "2026-02-27 20:21:42.172296",
2026-02-27 20:21:42.610902 | instance |   "msg": "non-zero return code",
2026-02-27 20:21:42.610920 | instance |   "rc": 1,
2026-02-27 20:21:42.610949 | instance |   "start": "2026-02-27 20:21:16.896752"
2026-02-27 20:21:42.611000 | instance | }
failure
2026-02-27 20:21:42.617140 | 
2026-02-27 20:21:42.617192 | PLAY RECAP
2026-02-27 20:21:42.617241 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-27 20:21:42.617262 | 
2026-02-27 20:21:42.761353 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-27 20:21:42.773226 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1]
2026-02-27 20:21:43.439041 | 
2026-02-27 20:21:43.439203 | PLAY [all]
2026-02-27 20:21:43.456674 | 
2026-02-27 20:21:43.456771 | TASK [gather-host-logs : creating directory for system status]
2026-02-27 20:21:43.825182 | instance | changed
2026-02-27 20:21:43.833150 | 
2026-02-27 20:21:43.833248 | TASK [gather-host-logs : Get logs for each host]
2026-02-27 20:21:44.192971 | instance | + systemd-cgls --full --all --no-pager
2026-02-27 20:21:44.202379 | instance | + ip addr
2026-02-27 20:21:44.205290 | instance | + ip route
2026-02-27 20:21:44.207202 | instance | + lsblk
2026-02-27 20:21:44.211046 | instance | + mount
2026-02-27 20:21:44.212870 | instance | + docker images
2026-02-27 20:21:44.213304 | instance | /bin/bash: line 7: docker: command not found
2026-02-27 20:21:44.213542 | instance | + brctl show
2026-02-27 20:21:44.213988 | instance | /bin/bash: line 8: brctl: command not found
2026-02-27 20:21:44.214229 | instance | + ps aux --sort=-%mem
2026-02-27 20:21:44.228694 | instance | + dpkg -l
2026-02-27 20:21:44.240318 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-02-27 20:21:44.240761 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-02-27 20:21:44.240787 | instance | /bin/bash: line 11: docker: command not found
2026-02-27 20:21:44.241106 | instance | + '[' '!' -z '' ']'
2026-02-27 20:21:44.372094 | instance | ok: Runtime: 0:00:00.054995
2026-02-27 20:21:44.379045 | 
2026-02-27 20:21:44.379116 | TASK [gather-host-logs : Downloads logs to executor]
2026-02-27 20:21:45.001605 | instance | changed:
2026-02-27 20:21:45.001865 | instance | created directory /var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/work/logs/instance
2026-02-27 20:21:45.002065 | instance | cd+++++++++ system/
2026-02-27 20:21:45.002135 | instance | >f+++++++++ system/brctl-show.txt
2026-02-27 20:21:45.002184 | instance | >f+++++++++ system/docker-images.txt
2026-02-27 20:21:45.002227 | instance | >f+++++++++ system/ip-addr.txt
2026-02-27 20:21:45.002274 | instance | >f+++++++++ system/ip-route.txt
2026-02-27 20:21:45.002325 | instance | >f+++++++++ system/lsblk.txt
2026-02-27 20:21:45.002370 | instance | >f+++++++++ system/mount.txt
2026-02-27 20:21:45.002417 | instance | >f+++++++++ system/packages.txt
2026-02-27 20:21:45.002460 | instance | >f+++++++++ system/ps.txt
2026-02-27 20:21:45.002502 | instance | >f+++++++++ system/systemd-cgls.txt
2026-02-27 20:21:45.012941 | 
2026-02-27 20:21:45.013018 | LOOP [helm-release-status : creating directory for helm release status]
2026-02-27 20:21:45.245357 | instance | changed: "values"
2026-02-27 20:21:45.419751 | instance | changed: "releases"
2026-02-27 20:21:45.427906 | 
2026-02-27 20:21:45.428070 | TASK [helm-release-status : Gather get release status for helm charts]
2026-02-27 20:21:45.618298 | instance | /bin/bash: line 3: kubectl: command not found
2026-02-27 20:21:45.969067 | instance | ok: Runtime: 0:00:00.005388
2026-02-27 20:21:45.978074 | 
2026-02-27 20:21:45.978153 | TASK [helm-release-status : Downloads logs to executor]
2026-02-27 20:21:46.499686 | instance | changed:
2026-02-27 20:21:46.499844 | instance | cd+++++++++ helm/
2026-02-27 20:21:46.499872 | instance | cd+++++++++ helm/releases/
2026-02-27 20:21:46.499894 | instance | cd+++++++++ helm/values/
2026-02-27 20:21:46.539048 | 
2026-02-27 20:21:46.539198 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-02-27 20:21:46.745437 | instance | changed
2026-02-27 20:21:46.754154 | 
2026-02-27 20:21:46.754250 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-02-27 20:21:46.976941 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-27 20:21:46.977211 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-27 20:21:46.982432 | instance | environment: line 1: kubectl: command not found
2026-02-27 20:21:46.983643 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-27 20:21:46.983829 | instance | environment: line 1: kubectl: command not found
2026-02-27 20:21:46.985384 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-27 20:21:46.987392 | instance | environment: line 1: kubectl: command not found
2026-02-27 20:21:46.987980 | instance | environment: line 1: kubectl: command not found
2026-02-27 20:21:46.988543 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-27 20:21:46.989361 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-27 20:21:46.992090 | instance | environment: line 1: kubectl: command not found
2026-02-27 20:21:46.992869 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-27 20:21:47.297911 | instance | ok: Runtime: 0:00:00.028747
2026-02-27 20:21:47.304816 | 
2026-02-27 20:21:47.304902 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-02-27 20:21:47.521763 | instance | changed
2026-02-27 20:21:47.530465 | 
2026-02-27 20:21:47.530548 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-02-27 20:21:47.737442 | instance | environment: line 5: kubectl: command not found
2026-02-27 20:21:47.738296 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-27 20:21:47.739002 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-27 20:21:47.739096 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-27 20:21:48.066275 | instance | ok: Runtime: 0:00:00.008976
2026-02-27 20:21:48.077411 | 
2026-02-27 20:21:48.077546 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-02-27 20:21:48.580686 | instance | changed:
2026-02-27 20:21:48.580945 | instance | cd+++++++++ objects/
2026-02-27 20:21:48.581005 | instance | cd+++++++++ objects/cluster/
2026-02-27 20:21:48.581038 | instance | cd+++++++++ objects/namespaced/
2026-02-27 20:21:48.592867 | 
2026-02-27 20:21:48.593008 | TASK [gather-pod-logs : creating directory for pod logs]
2026-02-27 20:21:48.802479 | instance | changed
2026-02-27 20:21:48.810953 | 
2026-02-27 20:21:48.811038 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-02-27 20:21:49.010063 | instance | changed
2026-02-27 20:21:49.021623 | 
2026-02-27 20:21:49.021727 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-02-27 20:21:49.234537 | instance | environment: line 3: kubectl: command not found
2026-02-27 20:21:49.568661 | instance | ok: Runtime: 0:00:00.009918
2026-02-27 20:21:49.574753 | 
2026-02-27 20:21:49.574823 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-02-27 20:21:50.078620 | instance | changed:
2026-02-27 20:21:50.078821 | instance | cd+++++++++ pod-logs/
2026-02-27 20:21:50.078859 | instance | cd+++++++++ pod-logs/failed-pods/
2026-02-27 20:21:50.090403 | 
2026-02-27 20:21:50.090464 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-02-27 20:21:50.288328 | instance | changed
2026-02-27 20:21:50.299906 | 
2026-02-27 20:21:50.300221 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-02-27 20:21:50.509738 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-27 20:21:51.037176 | instance | ok: Runtime: 0:00:00.033105
2026-02-27 20:21:51.044221 | 
2026-02-27 20:21:51.044313 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-02-27 20:21:51.245836 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-27 20:21:51.272448 | instance | ceph-mgr endpoints:
2026-02-27 20:21:51.603235 | instance | ok: Runtime: 0:00:00.032853
2026-02-27 20:21:51.608110 | 
2026-02-27 20:21:51.608175 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-02-27 20:21:51.858007 | instance | /bin/bash: line 4: kubectl: command not found
2026-02-27 20:21:52.167983 | instance | ok: Runtime: 0:00:00.037433
2026-02-27 20:21:52.175673 | 
2026-02-27 20:21:52.175749 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-02-27 20:21:52.666344 | instance | changed: cd+++++++++ prometheus/
2026-02-27 20:21:52.732989 | 
2026-02-27 20:21:52.733159 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-02-27 20:21:52.937056 | instance | changed
2026-02-27 20:21:52.945930 | 
2026-02-27 20:21:52.946023 | TASK [gather-selenium-data : Get selenium data]
2026-02-27 20:21:53.163156 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-02-27 20:21:53.164703 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-02-27 20:21:53.486424 | instance | ERROR
2026-02-27 20:21:53.486921 | instance | {
2026-02-27 20:21:53.486989 | instance |   "delta": "0:00:00.006730",
2026-02-27 20:21:53.487024 | instance |   "end": "2026-02-27 20:21:53.165025",
2026-02-27 20:21:53.487051 | instance |   "msg": "non-zero return code",
2026-02-27 20:21:53.487077 | instance |   "rc": 1,
2026-02-27 20:21:53.487102 | instance |   "start": "2026-02-27 20:21:53.158295"
2026-02-27 20:21:53.487127 | instance | }
2026-02-27 20:21:53.487160 | instance | ERROR: Ignoring Errors
2026-02-27 20:21:53.494244 | 
2026-02-27 20:21:53.494336 | TASK [gather-selenium-data : Downloads logs to executor]
2026-02-27 20:21:54.011286 | instance | changed: cd+++++++++ selenium/
2026-02-27 20:21:54.018519 | 
2026-02-27 20:21:54.018578 | PLAY RECAP
2026-02-27 20:21:54.018630 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-02-27 20:21:54.018651 | 
2026-02-27 20:21:54.146239 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1]
2026-02-27 20:21:54.159266 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-27 20:21:54.827686 | 
2026-02-27 20:21:54.827867 | PLAY [all]
2026-02-27 20:21:54.840116 | 
2026-02-27 20:21:54.840208 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-27 20:21:54.886371 | instance | skipping: Conditional result was False
2026-02-27 20:21:54.916836 | 
2026-02-27 20:21:54.917208 | TASK [fetch-output : Set log path for single node]
2026-02-27 20:21:54.964028 | instance | ok
2026-02-27 20:21:54.970532 | 
2026-02-27 20:21:54.970603 | LOOP [fetch-output : Ensure local output dirs]
2026-02-27 20:21:55.526566 | instance -> localhost | ok: "/var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/work/logs"
2026-02-27 20:21:55.740217 | instance -> localhost | changed: "/var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/work/artifacts"
2026-02-27 20:21:55.962351 | instance -> localhost | changed: "/var/lib/zuul/builds/c1d8d7d40d8c4611832c1f5d8da234aa/work/docs"
2026-02-27 20:21:55.980725 | 
2026-02-27 20:21:55.980832 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-27 20:21:56.625681 | instance | changed: .d..t...... ./
2026-02-27 20:21:56.625925 | instance | changed: All items complete
2026-02-27 20:21:56.625964 | 
2026-02-27 20:21:57.078904 | instance | changed: .d..t...... ./
2026-02-27 20:21:57.534981 | instance | changed: .d..t...... ./
2026-02-27 20:21:57.560369 | 
2026-02-27 20:21:57.560706 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-27 20:21:58.005259 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.014448
2026-02-27 20:21:58.226882 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007863
2026-02-27 20:21:58.237842 | 
2026-02-27 20:21:58.238009 | PLAY [all]
2026-02-27 20:21:58.245429 | 
2026-02-27 20:21:58.245497 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-27 20:21:58.668723 | instance | changed
2026-02-27 20:21:58.675514 | 
2026-02-27 20:21:58.675566 | PLAY RECAP
2026-02-27 20:21:58.675618 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-27 20:21:58.675641 | 
2026-02-27 20:21:58.845243 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-27 20:21:58.856532 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-27 20:21:59.486779 | 
2026-02-27 20:21:59.486946 | PLAY [localhost]
2026-02-27 20:21:59.497423 | 
2026-02-27 20:21:59.497500 | TASK [Generate Zuul manifest]
2026-02-27 20:21:59.521163 | localhost | ok
2026-02-27 20:21:59.537358 | 
2026-02-27 20:21:59.537436 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-27 20:22:00.008546 | localhost | changed
2026-02-27 20:22:00.035589 | 
2026-02-27 20:22:00.035672 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-27 20:22:00.117974 | localhost | ok
2026-02-27 20:22:00.127006 | 
2026-02-27 20:22:00.127099 | TASK [Upload logs]
2026-02-27 20:22:00.147635 | localhost | ok
2026-02-27 20:22:00.282652 | 
2026-02-27 20:22:00.282821 | TASK [Set zuul-log-path fact]
2026-02-27 20:22:00.302735 | localhost | ok
2026-02-27 20:22:00.316010 | 
2026-02-27 20:22:00.316125 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 20:22:00.346173 | localhost | ok
2026-02-27 20:22:00.355891 | 
2026-02-27 20:22:00.355954 | TASK [upload-logs : Create log directories]
2026-02-27 20:22:00.713120 | localhost | changed
2026-02-27 20:22:00.719391 | 
2026-02-27 20:22:00.719486 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-27 20:22:01.093279 | localhost -> localhost | ok: Runtime: 0:00:00.005242
2026-02-27 20:22:01.099920 | 
2026-02-27 20:22:01.100012 | TASK [upload-logs : Upload logs to log server]
2026-02-27 20:22:01.514138 | localhost | Output suppressed because no_log was given
2026-02-27 20:22:01.519128 | 
2026-02-27 20:22:01.519216 | LOOP [upload-logs : Compress console log and json output]
2026-02-27 20:22:01.564111 | localhost | skipping: Conditional result was False
2026-02-27 20:22:01.572011 | localhost | skipping: Conditional result was False
2026-02-27 20:22:01.584254 | 
2026-02-27 20:22:01.584370 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-27 20:22:01.627436 | localhost | skipping: Conditional result was False
2026-02-27 20:22:01.627848 | 
2026-02-27 20:22:01.632989 | localhost | skipping: Conditional result was False
2026-02-27 20:22:01.651144 | 
2026-02-27 20:22:01.651407 | LOOP [upload-logs : Upload console log and json output]