2026-02-08 16:50:49.349470 | Job console starting
2026-02-08 16:50:49.360385 | Updating git repos
2026-02-08 16:50:49.410990 | Cloning repos into workspace
2026-02-08 16:50:49.477655 | Restoring repo states
2026-02-08 16:50:49.491582 | Merging changes
2026-02-08 16:50:50.262753 | Checking out repos
2026-02-08 16:50:50.388474 | Preparing playbooks
2026-02-08 16:50:52.593899 | Running Ansible setup
2026-02-08 16:50:55.717970 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-08 16:50:56.283270 | 
2026-02-08 16:50:56.284129 | PLAY [localhost]
2026-02-08 16:50:56.291421 | 
2026-02-08 16:50:56.291490 | TASK [Gathering Facts]
2026-02-08 16:50:57.159704 | localhost | ok
2026-02-08 16:50:57.171369 | 
2026-02-08 16:50:57.171460 | TASK [Setup log path fact]
2026-02-08 16:50:57.191441 | localhost | ok
2026-02-08 16:50:57.203523 | 
2026-02-08 16:50:57.203588 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-08 16:50:57.230869 | localhost | ok
2026-02-08 16:50:57.237799 | 
2026-02-08 16:50:57.237863 | TASK [emit-job-header : Print job information]
2026-02-08 16:50:57.276387 | # Job Information
2026-02-08 16:50:57.276624 | Ansible Version: 2.16.15
2026-02-08 16:50:57.276668 | Job: atmosphere-molecule-csi-rbd
2026-02-08 16:50:57.276699 | Pipeline: check
2026-02-08 16:50:57.276726 | Executor: 3a2793d2bd32
2026-02-08 16:50:57.276774 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3564
2026-02-08 16:50:57.276851 | Event ID: 2adf8a10-050e-11f1-9fc9-295ae6d7e0f6
2026-02-08 16:50:57.285256 | 
2026-02-08 16:50:57.285351 | LOOP [emit-job-header : Print node information]
2026-02-08 16:50:57.376170 | localhost | ok:
2026-02-08 16:50:57.377557 | localhost | # Node Information
2026-02-08 16:50:57.377611 | localhost | Inventory Hostname: instance
2026-02-08 16:50:57.377645 | localhost | Hostname: np0000155052
2026-02-08 16:50:57.377674 | localhost | Username: zuul
2026-02-08 16:50:57.377705 | localhost | Distro: Ubuntu 22.04
2026-02-08 16:50:57.377733 | localhost | Provider: yul1
2026-02-08 16:50:57.377760 | localhost | Region: ca-ymq-1
2026-02-08 16:50:57.377787 | localhost | Label: ubuntu-jammy
2026-02-08 16:50:57.377813 | localhost | Product Name: OpenStack Nova
2026-02-08 16:50:57.377839 | localhost | Interface IP: 162.253.55.227
2026-02-08 16:50:57.397478 | 
2026-02-08 16:50:57.397644 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-08 16:50:57.788325 | localhost -> localhost | changed
2026-02-08 16:50:57.798690 | 
2026-02-08 16:50:57.798927 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-08 16:50:58.614147 | localhost -> localhost | changed
2026-02-08 16:50:58.625715 | 
2026-02-08 16:50:58.625811 | PLAY [all]
2026-02-08 16:50:58.634237 | 
2026-02-08 16:50:58.634313 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-08 16:50:58.861027 | instance -> localhost | ok
2026-02-08 16:50:58.869721 | 
2026-02-08 16:50:58.869793 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-08 16:50:58.901123 | instance | ok
2026-02-08 16:50:58.916708 | instance | included: /var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-08 16:50:58.922249 | 
2026-02-08 16:50:58.922313 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-08 16:51:00.533764 | instance -> localhost | Generating public/private rsa key pair.
2026-02-08 16:51:00.533997 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/work/3fb2da1f1ed04b3d9b2ae5b6980bdb82_id_rsa
2026-02-08 16:51:00.534043 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/work/3fb2da1f1ed04b3d9b2ae5b6980bdb82_id_rsa.pub
2026-02-08 16:51:00.534078 | instance -> localhost | The key fingerprint is:
2026-02-08 16:51:00.534110 | instance -> localhost | SHA256:yQtMxKc6L8QV54Lrj2px5He7hjm1P4e3bmo90IHXWWc zuul-build-sshkey
2026-02-08 16:51:00.534154 | instance -> localhost | The key's randomart image is:
2026-02-08 16:51:00.534186 | instance -> localhost | +---[RSA 3072]----+
2026-02-08 16:51:00.534249 | instance -> localhost | |     ..          |
2026-02-08 16:51:00.534284 | instance -> localhost | |     .o o       E|
2026-02-08 16:51:00.534315 | instance -> localhost | |     ..*   . . +.|
2026-02-08 16:51:00.534345 | instance -> localhost | |    oo+.... o o  |
2026-02-08 16:51:00.534375 | instance -> localhost | |   + +o.S  o .   |
2026-02-08 16:51:00.534403 | instance -> localhost | |  . O ..o.. .    |
2026-02-08 16:51:00.534432 | instance -> localhost | |   = + =.o +     |
2026-02-08 16:51:00.534460 | instance -> localhost | |  . o.= + + *    |
2026-02-08 16:51:00.534492 | instance -> localhost | | ....o.o.+oBoo   |
2026-02-08 16:51:00.534522 | instance -> localhost | +----[SHA256]-----+
2026-02-08 16:51:00.534590 | instance -> localhost | ok: Runtime: 0:00:01.219974
2026-02-08 16:51:00.541002 | 
2026-02-08 16:51:00.541072 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-08 16:51:00.574007 | instance | ok
2026-02-08 16:51:00.583540 | instance | included: /var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-08 16:51:00.590749 | 
2026-02-08 16:51:00.590809 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-08 16:51:00.614268 | instance | skipping: Conditional result was False
2026-02-08 16:51:00.625906 | 
2026-02-08 16:51:00.626157 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-08 16:51:01.097142 | instance | changed
2026-02-08 16:51:01.102536 | 
2026-02-08 16:51:01.102605 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-08 16:51:01.281309 | instance | ok
2026-02-08 16:51:01.288412 | 
2026-02-08 16:51:01.288483 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-08 16:51:01.719499 | instance | changed
2026-02-08 16:51:01.724857 | 
2026-02-08 16:51:01.724927 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-08 16:51:02.155362 | instance | changed
2026-02-08 16:51:02.161738 | 
2026-02-08 16:51:02.161811 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-08 16:51:02.187099 | instance | skipping: Conditional result was False
2026-02-08 16:51:02.198403 | 
2026-02-08 16:51:02.198466 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-08 16:51:02.599601 | instance -> localhost | changed
2026-02-08 16:51:02.614555 | 
2026-02-08 16:51:02.614715 | TASK [add-build-sshkey : Add back temp key]
2026-02-08 16:51:02.917018 | instance -> localhost | Identity added: /var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/work/3fb2da1f1ed04b3d9b2ae5b6980bdb82_id_rsa (zuul-build-sshkey)
2026-02-08 16:51:02.917352 | instance -> localhost | ok: Runtime: 0:00:00.014100
2026-02-08 16:51:02.922644 | 
2026-02-08 16:51:02.922709 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-08 16:51:03.253333 | instance | ok
2026-02-08 16:51:03.259982 | 
2026-02-08 16:51:03.260047 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-08 16:51:03.284718 | instance | skipping: Conditional result was False
2026-02-08 16:51:03.299360 | 
2026-02-08 16:51:03.299425 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-08 16:51:03.634643 | instance | ok
2026-02-08 16:51:03.640371 | 
2026-02-08 16:51:03.640435 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-08 16:51:05.167855 | instance | Output suppressed because no_log was given
2026-02-08 16:51:05.176378 | 
2026-02-08 16:51:05.176439 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-08 16:51:05.356226 | instance | ok: "logs"
2026-02-08 16:51:05.356550 | instance | ok: All items complete
2026-02-08 16:51:05.356579 | 
2026-02-08 16:51:05.499721 | instance | ok: "artifacts"
2026-02-08 16:51:05.657029 | instance | ok: "docs"
2026-02-08 16:51:05.670001 | 
2026-02-08 16:51:05.670145 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-08 16:51:05.850377 | instance | changed: "logs"
2026-02-08 16:51:05.997846 | instance | changed: "artifacts"
2026-02-08 16:51:06.158052 | instance | changed: "docs"
2026-02-08 16:51:06.177999 | 
2026-02-08 16:51:06.178145 | PLAY RECAP
2026-02-08 16:51:06.178195 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-08 16:51:06.178224 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-08 16:51:06.178244 | 
2026-02-08 16:51:06.337752 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-08 16:51:06.341407 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-08 16:51:06.919187 | 
2026-02-08 16:51:06.919323 | PLAY [all]
2026-02-08 16:51:06.930656 | 
2026-02-08 16:51:06.930732 | TASK [setup-uv : Extract archive]
2026-02-08 16:51:09.092467 | instance | changed
2026-02-08 16:51:09.101436 | 
2026-02-08 16:51:09.101545 | TASK [setup-uv : Print version]
2026-02-08 16:51:09.362975 | instance | uv 0.8.13
2026-02-08 16:51:09.637997 | instance | ok: Runtime: 0:00:00.056732
2026-02-08 16:51:09.646475 | 
2026-02-08 16:51:09.646583 | PLAY RECAP
2026-02-08 16:51:09.646647 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-08 16:51:09.646682 | 
2026-02-08 16:51:09.779605 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-08 16:51:09.781600 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-02-08 16:51:10.335536 | 
2026-02-08 16:51:10.335659 | PLAY [all]
2026-02-08 16:51:10.346611 | 
2026-02-08 16:51:10.346681 | TASK [Install "jq" for log collection]
2026-02-08 16:51:19.901063 | instance | changed
2026-02-08 16:51:19.904354 | 
2026-02-08 16:51:19.904469 | PLAY RECAP
2026-02-08 16:51:19.904541 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-08 16:51:19.904611 | 
2026-02-08 16:51:20.024334 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-02-08 16:51:20.026648 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-08 16:51:20.610437 | 
2026-02-08 16:51:20.611004 | PLAY [all]
2026-02-08 16:51:20.622118 | 
2026-02-08 16:51:20.622189 | TASK [Copy inventory file for Zuul]
2026-02-08 16:51:21.460748 | instance | changed
2026-02-08 16:51:21.469233 | 
2026-02-08 16:51:21.469341 | TASK [Switch "ansible_host" to private IP]
2026-02-08 16:51:21.727129 | instance | changed: 1 replacements made
2026-02-08 16:51:21.734359 | 
2026-02-08 16:51:21.734431 | TASK [Run Molecule scenario]
2026-02-08 16:51:22.024882 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-02-08 16:51:22.025048 | instance | Creating virtual environment at: .venv
2026-02-08 16:51:22.059412 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-08 16:51:22.097295 | instance | Downloading pygments (1.2MiB)
2026-02-08 16:51:22.099825 | instance | Downloading setuptools (1.1MiB)
2026-02-08 16:51:22.102177 | instance | Downloading openstacksdk (1.7MiB)
2026-02-08 16:51:22.102526 | instance | Downloading rjsonnet (1.2MiB)
2026-02-08 16:51:22.102979 | instance | Downloading netaddr (2.2MiB)
2026-02-08 16:51:22.103544 | instance | Downloading kubernetes (1.9MiB)
2026-02-08 16:51:22.103824 | instance | Downloading ansible-core (2.1MiB)
2026-02-08 16:51:22.121450 | instance | Downloading cryptography (4.2MiB)
2026-02-08 16:51:22.395154 | instance |    Building pyperclip==1.9.0
2026-02-08 16:51:22.450264 | instance |  Downloading rjsonnet
2026-02-08 16:51:22.609195 | instance |  Downloading netaddr
2026-02-08 16:51:22.624903 | instance |  Downloading pygments
2026-02-08 16:51:22.633953 | instance |  Downloading cryptography
2026-02-08 16:51:22.680475 | instance |  Downloading setuptools
2026-02-08 16:51:22.768888 | instance |  Downloading kubernetes
2026-02-08 16:51:22.822293 | instance |  Downloading ansible-core
2026-02-08 16:51:22.867353 | instance |  Downloading openstacksdk
2026-02-08 16:51:23.227001 | instance |       Built pyperclip==1.9.0
2026-02-08 16:51:23.563164 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-08 16:51:23.828696 | instance | Installed 79 packages in 264ms
2026-02-08 16:51:24.716172 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-02-08 16:51:25.350314 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-02-08 16:51:25.350450 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-02-08 16:51:35.731099 | instance | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv community.crypto:2.2.3
2026-02-08 16:51:35.731168 | instance | ERROR    Command ansible-galaxy collection install -vvv community.crypto:2.2.3, returned 250 code:
2026-02-08 16:51:35.731174 | instance | Starting galaxy collection install process
2026-02-08 16:51:35.731179 | instance | Process install dependency map
2026-02-08 16:51:35.731183 | instance | the full traceback was:
2026-02-08 16:51:35.731188 | instance |
2026-02-08 16:51:35.731192 | instance | Traceback (most recent call last):
2026-02-08 16:51:35.731196 | 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-08 16:51:35.731201 | instance |     exit_code = cli.run()
2026-02-08 16:51:35.731205 | 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-08 16:51:35.731210 | instance |     return context.CLIARGS()
2026-02-08 16:51:35.731214 | 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-08 16:51:35.731218 | instance |     return wrapped_method(*args, **kwargs)
2026-02-08 16:51:35.731222 | 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-08 16:51:35.731226 | instance |     self._execute_install_collection(
2026-02-08 16:51:35.731230 | 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-08 16:51:35.731235 | instance |     install_collections(
2026-02-08 16:51:35.731239 | 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-08 16:51:35.731243 | instance |     dependency_map = _resolve_depenency_map(
2026-02-08 16:51:35.731247 | 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-08 16:51:35.731252 | instance |     return collection_dep_resolver.resolve(
2026-02-08 16:51:35.731256 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-08 16:51:35.731271 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-08 16:51:35.731276 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-08 16:51:35.731280 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-08 16:51:35.731284 | 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-08 16:51:35.731288 | instance |     matches = self._p.find_matches(
2026-02-08 16:51:35.731292 | 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-08 16:51:35.731296 | instance |     match for match in self._find_matches(list(requirements))
2026-02-08 16:51:35.731300 | 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-08 16:51:35.731304 | instance |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-02-08 16:51:35.731311 | 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-08 16:51:35.731316 | instance |     return set(
2026-02-08 16:51:35.731319 | 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-08 16:51:35.731323 | instance |     return set(
2026-02-08 16:51:35.731327 | 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-08 16:51:35.731331 | instance |     raise last_error
2026-02-08 16:51:35.731335 | 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-08 16:51:35.731339 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-08 16:51:35.731343 | 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-08 16:51:35.731347 | instance |     return method(self, *args, **kwargs)
2026-02-08 16:51:35.731351 | 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-08 16:51:35.731355 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-08 16:51:35.731367 | 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-08 16:51:35.731371 | instance |     return call_retryable_function()
2026-02-08 16:51:35.731375 | 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-08 16:51:35.731379 | instance |     res = path_cache
2026-02-08 16:51:35.731383 | instance | KeyError: 'results'
2026-02-08 16:51:35.731387 | instance |
2026-02-08 16:51:35.731391 | instance | ansible-galaxy
2026-02-08 16:51:35.731395 | instance |   config file = None
2026-02-08 16:51:35.731399 | instance |   configured module search path =
2026-02-08 16:51:35.731402 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-08 16:51:35.731407 | 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-08 16:51:35.731418 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-08 16:51:35.731423 | instance |   python version = 3.10.12 (main, Jan  8 2026, 06:52:19)  (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-02-08 16:51:35.731427 | instance |   jinja version = 3.1.6
2026-02-08 16:51:35.731431 | instance |   libyaml = True
2026-02-08 16:51:35.731434 | instance | No config file found; using defaults
2026-02-08 16:51:35.731438 | instance | : Collection ansible.netcommon does not support Ansible version
2026-02-08 16:51:35.731442 | instance | 2.17.12
2026-02-08 16:51:35.731446 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-08 16:51:35.731450 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-08 16:51:35.731454 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-08 16:51:35.731458 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-08 16:51:35.731462 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-08 16:51:35.731466 | instance | unexpected error when getting available versions of collection
2026-02-08 16:51:35.731470 | instance | community.crypto: 'results'
2026-02-08 16:51:35.731474 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-08 16:51:35.731478 | instance |
2026-02-08 16:51:35.731482 | instance | Traceback (most recent call last):
2026-02-08 16:51:35.731486 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-02-08 16:51:35.731490 | instance |     sys.exit(main())
2026-02-08 16:51:35.731494 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-02-08 16:51:35.731498 | instance |     return self.main(*args, **kwargs)
2026-02-08 16:51:35.731502 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-02-08 16:51:35.731540 | instance |     rv = self.invoke(ctx)
2026-02-08 16:51:35.731751 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-02-08 16:51:35.732254 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-02-08 16:51:35.732431 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-02-08 16:51:35.732870 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-02-08 16:51:35.733082 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-02-08 16:51:35.733414 | instance |     return callback(*args, **kwargs)
2026-02-08 16:51:35.733598 | 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-08 16:51:35.734154 | instance |     return func(*args, **kwargs)
2026-02-08 16:51:35.734583 | 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-08 16:51:35.735089 | instance |     return f(get_current_context(), *args, **kwargs)
2026-02-08 16:51:35.735553 | 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-08 16:51:35.736091 | instance |     return func(ctx)
2026-02-08 16:51:35.736447 | 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-08 16:51:35.736939 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-02-08 16:51:35.737298 | 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-08 16:51:35.737736 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-02-08 16:51:35.738040 | 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-08 16:51:35.738579 | instance |     scenario.config.runtime.prepare_environment(
2026-02-08 16:51:35.738743 | 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-08 16:51:35.738988 | instance |     self.install_collection(
2026-02-08 16:51:35.739135 | 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-08 16:51:35.739348 | instance |     raise InvalidPrerequisiteError(msg)
2026-02-08 16:51:35.741210 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.crypto:2.2.3, returned 250 code:
2026-02-08 16:51:35.741218 | instance | Starting galaxy collection install process
2026-02-08 16:51:35.741224 | instance | Process install dependency map
2026-02-08 16:51:35.741229 | instance | the full traceback was:
2026-02-08 16:51:35.741235 | instance |
2026-02-08 16:51:35.741240 | instance | Traceback (most recent call last):
2026-02-08 16:51:35.741246 | 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-08 16:51:35.741251 | instance |     exit_code = cli.run()
2026-02-08 16:51:35.741256 | 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-08 16:51:35.741262 | instance |     return context.CLIARGS['func']()
2026-02-08 16:51:35.741267 | 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-08 16:51:35.741273 | instance |     return wrapped_method(*args, **kwargs)
2026-02-08 16:51:35.741278 | 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-08 16:51:35.741283 | instance |     self._execute_install_collection(
2026-02-08 16:51:35.741289 | 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-08 16:51:35.741294 | instance |     install_collections(
2026-02-08 16:51:35.741300 | 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-08 16:51:35.741305 | instance |     dependency_map = _resolve_depenency_map(
2026-02-08 16:51:35.741310 | 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-08 16:51:35.741316 | instance |     return collection_dep_resolver.resolve(
2026-02-08 16:51:35.741321 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-08 16:51:35.741327 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-08 16:51:35.741332 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-08 16:51:35.741338 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-08 16:51:35.741343 | 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-08 16:51:35.741349 | instance |     matches = self._p.find_matches(
2026-02-08 16:51:35.741354 | 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-08 16:51:35.741359 | instance |     match for match in self._find_matches(list(requirements[identifier]))
2026-02-08 16:51:35.741365 | 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-08 16:51:35.741376 | instance |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-02-08 16:51:35.741382 | 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-08 16:51:35.741387 | instance |     return set(
2026-02-08 16:51:35.741396 | 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-08 16:51:35.741401 | instance |     return set(
2026-02-08 16:51:35.741407 | 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-08 16:51:35.741412 | instance |     raise last_error
2026-02-08 16:51:35.741418 | 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-08 16:51:35.741424 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-08 16:51:35.741429 | 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-08 16:51:35.741435 | instance |     return method(self, *args, **kwargs)
2026-02-08 16:51:35.741440 | 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-08 16:51:35.741446 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-08 16:51:35.741456 | 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-08 16:51:35.741462 | instance |     return call_retryable_function()
2026-02-08 16:51:35.741467 | 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-08 16:51:35.741472 | instance |     res = path_cache['results']
2026-02-08 16:51:35.741478 | instance | KeyError: 'results'
2026-02-08 16:51:35.741483 | instance |
2026-02-08 16:51:35.741488 | instance | ansible-galaxy [core 2.17.12]
2026-02-08 16:51:35.741494 | instance |   config file = None
2026-02-08 16:51:35.741499 | instance |   configured module search path = ['/home/zuul/src/github.com/vexxhost/atmosphere/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-02-08 16:51:35.741505 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-08 16:51:35.741510 | 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-08 16:51:35.741516 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-08 16:51:35.741522 | instance |   python version = 3.10.12 (main, Jan  8 2026, 06:52:19) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-02-08 16:51:35.741527 | instance |   jinja version = 3.1.6
2026-02-08 16:51:35.741532 | instance |   libyaml = True
2026-02-08 16:51:35.741538 | instance | No config file found; using defaults
2026-02-08 16:51:35.741543 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-02-08 16:51:35.741548 | instance | 2.17.12
2026-02-08 16:51:35.741554 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-08 16:51:35.741563 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-08 16:51:35.741569 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-08 16:51:35.741574 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-08 16:51:35.741579 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-08 16:51:35.741585 | instance | unexpected error when getting available versions of collection
2026-02-08 16:51:35.741590 | instance | community.crypto: 'results'
2026-02-08 16:51:35.741596 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-08 16:51:35.741601 | instance |
2026-02-08 16:51:36.316821 | instance | ERROR
2026-02-08 16:51:36.317135 | instance | {
2026-02-08 16:51:36.317166 | instance |   "delta": "0:00:13.844576",
2026-02-08 16:51:36.317187 | instance |   "end": "2026-02-08 16:51:35.808909",
2026-02-08 16:51:36.317206 | instance |   "msg": "non-zero return code",
2026-02-08 16:51:36.317224 | instance |   "rc": 1,
2026-02-08 16:51:36.317246 | instance |   "start": "2026-02-08 16:51:21.964333"
2026-02-08 16:51:36.317264 | instance | }
failure
2026-02-08 16:51:36.320207 | 
2026-02-08 16:51:36.320255 | PLAY RECAP
2026-02-08 16:51:36.320302 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-08 16:51:36.320324 | 
2026-02-08 16:51:36.435082 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-08 16:51:36.439946 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-02-08 16:51:37.014927 | 
2026-02-08 16:51:37.015068 | PLAY [all]
2026-02-08 16:51:37.029272 | 
2026-02-08 16:51:37.029350 | TASK [gather-host-logs : creating directory for system status]
2026-02-08 16:51:37.378062 | instance | changed
2026-02-08 16:51:37.385988 | 
2026-02-08 16:51:37.386083 | TASK [gather-host-logs : Get logs for each host]
2026-02-08 16:51:37.705243 | instance | + systemd-cgls --full --all --no-pager
2026-02-08 16:51:37.710946 | instance | + ip addr
2026-02-08 16:51:37.712021 | instance | + ip route
2026-02-08 16:51:37.712934 | instance | + lsblk
2026-02-08 16:51:37.714689 | instance | + mount
2026-02-08 16:51:37.715727 | instance | + docker images
2026-02-08 16:51:37.715963 | instance | /bin/bash: line 7: docker: command not found
2026-02-08 16:51:37.716112 | instance | + brctl show
2026-02-08 16:51:37.716366 | instance | /bin/bash: line 8: brctl: command not found
2026-02-08 16:51:37.716513 | instance | + ps aux --sort=-%mem
2026-02-08 16:51:37.725617 | instance | + dpkg -l
2026-02-08 16:51:37.732248 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-02-08 16:51:37.732493 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-02-08 16:51:37.732547 | instance | /bin/bash: line 11: docker: command not found
2026-02-08 16:51:37.732769 | instance | + '[' '!' -z '' ']'
2026-02-08 16:51:37.923176 | instance | ok: Runtime: 0:00:00.031229
2026-02-08 16:51:37.930844 | 
2026-02-08 16:51:37.930917 | TASK [gather-host-logs : Downloads logs to executor]
2026-02-08 16:51:38.554223 | instance | changed:
2026-02-08 16:51:38.554399 | instance | created directory /var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/work/logs/instance
2026-02-08 16:51:38.554434 | instance | cd+++++++++ system/
2026-02-08 16:51:38.554456 | instance | >f+++++++++ system/brctl-show.txt
2026-02-08 16:51:38.554477 | instance | >f+++++++++ system/docker-images.txt
2026-02-08 16:51:38.554497 | instance | >f+++++++++ system/ip-addr.txt
2026-02-08 16:51:38.554518 | instance | >f+++++++++ system/ip-route.txt
2026-02-08 16:51:38.554539 | instance | >f+++++++++ system/lsblk.txt
2026-02-08 16:51:38.554559 | instance | >f+++++++++ system/mount.txt
2026-02-08 16:51:38.554579 | instance | >f+++++++++ system/packages.txt
2026-02-08 16:51:38.554598 | instance | >f+++++++++ system/ps.txt
2026-02-08 16:51:38.554617 | instance | >f+++++++++ system/systemd-cgls.txt
2026-02-08 16:51:38.564014 | 
2026-02-08 16:51:38.564082 | LOOP [helm-release-status : creating directory for helm release status]
2026-02-08 16:51:38.754896 | instance | changed: "values"
2026-02-08 16:51:38.916342 | instance | changed: "releases"
2026-02-08 16:51:38.933175 | 
2026-02-08 16:51:38.933378 | TASK [helm-release-status : Gather get release status for helm charts]
2026-02-08 16:51:39.131816 | instance | /bin/bash: line 3: kubectl: command not found
2026-02-08 16:51:39.473293 | instance | ok: Runtime: 0:00:00.005376
2026-02-08 16:51:39.482616 | 
2026-02-08 16:51:39.482690 | TASK [helm-release-status : Downloads logs to executor]
2026-02-08 16:51:39.934367 | instance | changed:
2026-02-08 16:51:39.934603 | instance | cd+++++++++ helm/
2026-02-08 16:51:39.934642 | instance | cd+++++++++ helm/releases/
2026-02-08 16:51:39.934673 | instance | cd+++++++++ helm/values/
2026-02-08 16:51:39.947121 | 
2026-02-08 16:51:39.947196 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-02-08 16:51:40.135299 | instance | changed
2026-02-08 16:51:40.140410 | 
2026-02-08 16:51:40.140495 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-02-08 16:51:40.330740 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-08 16:51:40.330954 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-08 16:51:40.335731 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-08 16:51:40.335954 | instance | environment: line 1: kubectl: command not found
2026-02-08 16:51:40.338722 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-08 16:51:40.339467 | instance | environment: line 1: kubectl: command not found
2026-02-08 16:51:40.341637 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-08 16:51:40.342444 | instance | environment: line 1: kubectl: command not found
2026-02-08 16:51:40.344471 | instance | environment: line 1: kubectl: command not found
2026-02-08 16:51:40.344994 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-08 16:51:40.346270 | instance | environment: line 1: kubectl: command not found
2026-02-08 16:51:40.347083 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-08 16:51:40.677807 | instance | ok: Runtime: 0:00:00.025337
2026-02-08 16:51:40.685362 | 
2026-02-08 16:51:40.685456 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-02-08 16:51:40.872799 | instance | changed
2026-02-08 16:51:40.878604 | 
2026-02-08 16:51:40.878679 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-02-08 16:51:41.108078 | instance | environment: line 5: kubectl: command not found
2026-02-08 16:51:41.108802 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-08 16:51:41.109325 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-08 16:51:41.110502 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-08 16:51:41.459904 | instance | ok: Runtime: 0:00:00.008616
2026-02-08 16:51:41.468886 | 
2026-02-08 16:51:41.468971 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-02-08 16:51:41.939047 | instance | changed:
2026-02-08 16:51:41.939267 | instance | cd+++++++++ objects/
2026-02-08 16:51:41.939304 | instance | cd+++++++++ objects/cluster/
2026-02-08 16:51:41.939332 | instance | cd+++++++++ objects/namespaced/
2026-02-08 16:51:41.949861 | 
2026-02-08 16:51:41.949924 | TASK [gather-pod-logs : creating directory for pod logs]
2026-02-08 16:51:42.147239 | instance | changed
2026-02-08 16:51:42.152087 | 
2026-02-08 16:51:42.152151 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-02-08 16:51:42.336199 | instance | changed
2026-02-08 16:51:42.344001 | 
2026-02-08 16:51:42.344073 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-02-08 16:51:42.533914 | instance | environment: line 3: kubectl: command not found
2026-02-08 16:51:42.880950 | instance | ok: Runtime: 0:00:00.007745
2026-02-08 16:51:42.886972 | 
2026-02-08 16:51:42.887037 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-02-08 16:51:43.335869 | instance | changed:
2026-02-08 16:51:43.336092 | instance | cd+++++++++ pod-logs/
2026-02-08 16:51:43.336132 | instance | cd+++++++++ pod-logs/failed-pods/
2026-02-08 16:51:43.346305 | 
2026-02-08 16:51:43.346369 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-02-08 16:51:43.533697 | instance | changed
2026-02-08 16:51:43.539815 | 
2026-02-08 16:51:43.539885 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-02-08 16:51:43.730942 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-08 16:51:44.074330 | instance | ok: Runtime: 0:00:00.031647
2026-02-08 16:51:44.080660 | 
2026-02-08 16:51:44.080728 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-02-08 16:51:44.272241 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-08 16:51:44.301091 | instance | ceph-mgr endpoints:
2026-02-08 16:51:44.611480 | instance | ok: Runtime: 0:00:00.034369
2026-02-08 16:51:44.618356 | 
2026-02-08 16:51:44.618424 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-02-08 16:51:44.807556 | instance | /bin/bash: line 4: kubectl: command not found
2026-02-08 16:51:45.153811 | instance | ok: Runtime: 0:00:00.030193
2026-02-08 16:51:45.159073 | 
2026-02-08 16:51:45.159137 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-02-08 16:51:45.627667 | instance | changed: cd+++++++++ prometheus/
2026-02-08 16:51:45.639135 | 
2026-02-08 16:51:45.639260 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-02-08 16:51:46.300802 | instance | changed
2026-02-08 16:51:46.306987 | 
2026-02-08 16:51:46.307068 | TASK [gather-selenium-data : Get selenium data]
2026-02-08 16:51:46.499034 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-02-08 16:51:46.500661 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-02-08 16:51:46.843084 | instance | ERROR
2026-02-08 16:51:46.843420 | instance | {
2026-02-08 16:51:46.843489 | instance |   "delta": "0:00:00.006093",
2026-02-08 16:51:46.843539 | instance |   "end": "2026-02-08 16:51:46.500948",
2026-02-08 16:51:46.843582 | instance |   "msg": "non-zero return code",
2026-02-08 16:51:46.843651 | instance |   "rc": 1,
2026-02-08 16:51:46.843695 | instance |   "start": "2026-02-08 16:51:46.494855"
2026-02-08 16:51:46.843736 | instance | }
2026-02-08 16:51:46.843788 | instance | ERROR: Ignoring Errors
2026-02-08 16:51:46.849471 | 
2026-02-08 16:51:46.849536 | TASK [gather-selenium-data : Downloads logs to executor]
2026-02-08 16:51:47.321705 | instance | changed: cd+++++++++ selenium/
2026-02-08 16:51:47.329007 | 
2026-02-08 16:51:47.329057 | PLAY RECAP
2026-02-08 16:51:47.329106 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-02-08 16:51:47.329127 | 
2026-02-08 16:51:47.450989 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-02-08 16:51:47.453160 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-08 16:51:48.043417 | 
2026-02-08 16:51:48.043564 | PLAY [all]
2026-02-08 16:51:48.054950 | 
2026-02-08 16:51:48.055023 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-08 16:51:48.101245 | instance | skipping: Conditional result was False
2026-02-08 16:51:48.112199 | 
2026-02-08 16:51:48.112277 | TASK [fetch-output : Set log path for single node]
2026-02-08 16:51:48.154913 | instance | ok
2026-02-08 16:51:48.160822 | 
2026-02-08 16:51:48.160890 | LOOP [fetch-output : Ensure local output dirs]
2026-02-08 16:51:48.557069 | instance -> localhost | ok: "/var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/work/logs"
2026-02-08 16:51:48.760401 | instance -> localhost | changed: "/var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/work/artifacts"
2026-02-08 16:51:48.962058 | instance -> localhost | changed: "/var/lib/zuul/builds/3fb2da1f1ed04b3d9b2ae5b6980bdb82/work/docs"
2026-02-08 16:51:48.979396 | 
2026-02-08 16:51:48.979587 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-08 16:51:49.589579 | instance | changed: .d..t...... ./
2026-02-08 16:51:49.589843 | instance | changed: All items complete
2026-02-08 16:51:49.589883 | 
2026-02-08 16:51:50.014406 | instance | changed: .d..t...... ./
2026-02-08 16:51:50.435563 | instance | changed: .d..t...... ./
2026-02-08 16:51:50.458960 | 
2026-02-08 16:51:50.459099 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-08 16:51:50.884656 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007712
2026-02-08 16:51:51.125294 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008140
2026-02-08 16:51:51.146164 | 
2026-02-08 16:51:51.146330 | PLAY [all]
2026-02-08 16:51:51.152464 | 
2026-02-08 16:51:51.152536 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-08 16:51:51.572606 | instance | changed
2026-02-08 16:51:51.578315 | 
2026-02-08 16:51:51.578365 | PLAY RECAP
2026-02-08 16:51:51.578418 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-08 16:51:51.578441 | 
2026-02-08 16:51:51.729909 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-08 16:51:51.733931 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-08 16:51:52.310920 | 
2026-02-08 16:51:52.311056 | PLAY [localhost]
2026-02-08 16:51:52.321122 | 
2026-02-08 16:51:52.321196 | TASK [Generate Zuul manifest]
2026-02-08 16:51:52.342082 | localhost | ok
2026-02-08 16:51:52.364259 | 
2026-02-08 16:51:52.364449 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-08 16:51:52.670246 | localhost | changed
2026-02-08 16:51:52.682562 | 
2026-02-08 16:51:52.682669 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-08 16:51:52.713221 | localhost | ok
2026-02-08 16:51:52.721626 | 
2026-02-08 16:51:52.721701 | TASK [Upload logs]
2026-02-08 16:51:52.742528 | localhost | ok
2026-02-08 16:51:52.801275 | 
2026-02-08 16:51:52.801420 | TASK [Set zuul-log-path fact]
2026-02-08 16:51:52.824752 | localhost | ok
2026-02-08 16:51:52.843941 | 
2026-02-08 16:51:52.844110 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-08 16:51:52.874144 | localhost | ok
2026-02-08 16:51:52.882136 | 
2026-02-08 16:51:52.882205 | TASK [upload-logs : Create log directories]
2026-02-08 16:51:53.267519 | localhost | changed
2026-02-08 16:51:53.272913 | 
2026-02-08 16:51:53.273016 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-08 16:51:53.669296 | localhost -> localhost | ok: Runtime: 0:00:00.005486
2026-02-08 16:51:53.677717 | 
2026-02-08 16:51:53.677981 | TASK [upload-logs : Upload logs to log server]
2026-02-08 16:51:54.182202 | localhost | Output suppressed because no_log was given
2026-02-08 16:51:54.185914 | 
2026-02-08 16:51:54.185979 | LOOP [upload-logs : Compress console log and json output]
2026-02-08 16:51:54.232542 | localhost | skipping: Conditional result was False
2026-02-08 16:51:54.238505 | localhost | skipping: Conditional result was False
2026-02-08 16:51:54.250713 | 
2026-02-08 16:51:54.250986 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-08 16:51:54.296007 | localhost | skipping: Conditional result was False
2026-02-08 16:51:54.296595 | 
2026-02-08 16:51:54.299241 | localhost | skipping: Conditional result was False
2026-02-08 16:51:54.316583 | 
2026-02-08 16:51:54.316732 | LOOP [upload-logs : Upload console log and json output]