2026-02-18 02:38:47.152297 | Job console starting
2026-02-18 02:38:47.166676 | Updating git repos
2026-02-18 02:38:47.214558 | Cloning repos into workspace
2026-02-18 02:38:47.283803 | Restoring repo states
2026-02-18 02:38:47.300175 | Merging changes
2026-02-18 02:38:48.333658 | Checking out repos
2026-02-18 02:38:48.443172 | Preparing playbooks
2026-02-18 02:38:50.667609 | Running Ansible setup
2026-02-18 02:38:53.956036 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-18 02:38:54.559141 | 
2026-02-18 02:38:54.559806 | PLAY [localhost]
2026-02-18 02:38:54.567805 | 
2026-02-18 02:38:54.567877 | TASK [Gathering Facts]
2026-02-18 02:38:55.447004 | localhost | ok
2026-02-18 02:38:55.457270 | 
2026-02-18 02:38:55.457397 | TASK [Setup log path fact]
2026-02-18 02:38:55.475645 | localhost | ok
2026-02-18 02:38:55.488051 | 
2026-02-18 02:38:55.488116 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-18 02:38:55.517263 | localhost | ok
2026-02-18 02:38:55.525182 | 
2026-02-18 02:38:55.525251 | TASK [emit-job-header : Print job information]
2026-02-18 02:38:55.565618 | # Job Information
2026-02-18 02:38:55.565848 | Ansible Version: 2.16.15
2026-02-18 02:38:55.565918 | Job: atmosphere-molecule-csi-rbd
2026-02-18 02:38:55.565965 | Pipeline: check
2026-02-18 02:38:55.566007 | Executor: 3a2793d2bd32
2026-02-18 02:38:55.566050 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3588
2026-02-18 02:38:55.566098 | Event ID: 61818800-0c72-11f1-8e96-77e277917c87
2026-02-18 02:38:55.569922 | 
2026-02-18 02:38:55.570011 | LOOP [emit-job-header : Print node information]
2026-02-18 02:38:55.665311 | localhost | ok:
2026-02-18 02:38:55.666083 | localhost | # Node Information
2026-02-18 02:38:55.666128 | localhost | Inventory Hostname: instance
2026-02-18 02:38:55.666159 | localhost | Hostname: np0000156281
2026-02-18 02:38:55.666186 | localhost | Username: zuul
2026-02-18 02:38:55.666215 | localhost | Distro: Ubuntu 22.04
2026-02-18 02:38:55.666242 | localhost | Provider: yul1
2026-02-18 02:38:55.666268 | localhost | Region: ca-ymq-1
2026-02-18 02:38:55.666294 | localhost | Label: ubuntu-jammy
2026-02-18 02:38:55.666320 | localhost | Product Name: OpenStack Nova
2026-02-18 02:38:55.666344 | localhost | Interface IP: 199.19.213.86
2026-02-18 02:38:55.677834 | 
2026-02-18 02:38:55.677982 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-18 02:38:56.108201 | localhost -> localhost | changed
2026-02-18 02:38:56.118123 | 
2026-02-18 02:38:56.118230 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-18 02:38:56.965847 | localhost -> localhost | changed
2026-02-18 02:38:56.974557 | 
2026-02-18 02:38:56.974648 | PLAY [all]
2026-02-18 02:38:56.982996 | 
2026-02-18 02:38:56.983062 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-18 02:38:57.252778 | instance -> localhost | ok
2026-02-18 02:38:57.265117 | 
2026-02-18 02:38:57.265219 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-18 02:38:57.297790 | instance | ok
2026-02-18 02:38:57.313692 | instance | included: /var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-18 02:38:57.319330 | 
2026-02-18 02:38:57.319388 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-18 02:38:58.481444 | instance -> localhost | Generating public/private rsa key pair.
2026-02-18 02:38:58.481731 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/work/eaaf809b4120440d97cee84d126d2f92_id_rsa
2026-02-18 02:38:58.481775 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/work/eaaf809b4120440d97cee84d126d2f92_id_rsa.pub
2026-02-18 02:38:58.481809 | instance -> localhost | The key fingerprint is:
2026-02-18 02:38:58.481841 | instance -> localhost | SHA256:8w4voXYv4t8dP3RJN9fs65lFHDd7oObLplyIM4Rials zuul-build-sshkey
2026-02-18 02:38:58.481894 | instance -> localhost | The key's randomart image is:
2026-02-18 02:38:58.481926 | instance -> localhost | +---[RSA 3072]----+
2026-02-18 02:38:58.481963 | instance -> localhost | |                 |
2026-02-18 02:38:58.481996 | instance -> localhost | |                 |
2026-02-18 02:38:58.482025 | instance -> localhost | |              .+o|
2026-02-18 02:38:58.482055 | instance -> localhost | |       .     . =X|
2026-02-18 02:38:58.482086 | instance -> localhost | |    o . S   o .oO|
2026-02-18 02:38:58.482114 | instance -> localhost | |   o . ..+ +  .o+|
2026-02-18 02:38:58.482142 | instance -> localhost | |  o E  .=.o +. .o|
2026-02-18 02:38:58.482170 | instance -> localhost | | . o  + +O +.+..+|
2026-02-18 02:38:58.482203 | instance -> localhost | |  .  o.+.+Bo+ o= |
2026-02-18 02:38:58.482232 | instance -> localhost | +----[SHA256]-----+
2026-02-18 02:38:58.482321 | instance -> localhost | ok: Runtime: 0:00:00.748625
2026-02-18 02:38:58.492167 | 
2026-02-18 02:38:58.492294 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-18 02:38:58.528101 | instance | ok
2026-02-18 02:38:58.536766 | instance | included: /var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-18 02:38:58.545162 | 
2026-02-18 02:38:58.545227 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-18 02:38:58.560773 | instance | skipping: Conditional result was False
2026-02-18 02:38:58.568229 | 
2026-02-18 02:38:58.568319 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-18 02:38:59.014290 | instance | changed
2026-02-18 02:38:59.023790 | 
2026-02-18 02:38:59.023881 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-18 02:38:59.216279 | instance | ok
2026-02-18 02:38:59.223122 | 
2026-02-18 02:38:59.223189 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-18 02:38:59.691041 | instance | changed
2026-02-18 02:38:59.699062 | 
2026-02-18 02:38:59.699153 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-18 02:39:00.164798 | instance | changed
2026-02-18 02:39:00.172191 | 
2026-02-18 02:39:00.172281 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-18 02:39:00.198006 | instance | skipping: Conditional result was False
2026-02-18 02:39:00.209224 | 
2026-02-18 02:39:00.209316 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-18 02:39:00.594260 | instance -> localhost | changed
2026-02-18 02:39:00.608448 | 
2026-02-18 02:39:00.608574 | TASK [add-build-sshkey : Add back temp key]
2026-02-18 02:39:00.906976 | instance -> localhost | Identity added: /var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/work/eaaf809b4120440d97cee84d126d2f92_id_rsa (zuul-build-sshkey)
2026-02-18 02:39:00.907291 | instance -> localhost | ok: Runtime: 0:00:00.016363
2026-02-18 02:39:00.914404 | 
2026-02-18 02:39:00.915345 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-18 02:39:01.247012 | instance | ok
2026-02-18 02:39:01.255909 | 
2026-02-18 02:39:01.256007 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-18 02:39:01.289855 | instance | skipping: Conditional result was False
2026-02-18 02:39:01.306475 | 
2026-02-18 02:39:01.306537 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-18 02:39:01.653999 | instance | ok
2026-02-18 02:39:01.662083 | 
2026-02-18 02:39:01.662176 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-18 02:39:03.109916 | instance | Output suppressed because no_log was given
2026-02-18 02:39:03.121092 | 
2026-02-18 02:39:03.121178 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-18 02:39:03.309650 | instance | ok: "logs"
2026-02-18 02:39:03.310709 | instance | ok: All items complete
2026-02-18 02:39:03.311212 | 
2026-02-18 02:39:03.466054 | instance | ok: "artifacts"
2026-02-18 02:39:03.635949 | instance | ok: "docs"
2026-02-18 02:39:03.655210 | 
2026-02-18 02:39:03.655383 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-18 02:39:03.868850 | instance | changed: "logs"
2026-02-18 02:39:04.034056 | instance | changed: "artifacts"
2026-02-18 02:39:04.192038 | instance | changed: "docs"
2026-02-18 02:39:04.213907 | 
2026-02-18 02:39:04.214065 | PLAY RECAP
2026-02-18 02:39:04.214135 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-18 02:39:04.214176 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 02:39:04.214208 | 
2026-02-18 02:39:04.354069 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-18 02:39:04.357916 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-18 02:39:04.906203 | 
2026-02-18 02:39:04.907023 | PLAY [all]
2026-02-18 02:39:04.918234 | 
2026-02-18 02:39:04.918312 | TASK [setup-uv : Extract archive]
2026-02-18 02:39:07.185510 | instance | changed
2026-02-18 02:39:07.194667 | 
2026-02-18 02:39:07.194764 | TASK [setup-uv : Print version]
2026-02-18 02:39:06.987688 | instance | uv 0.8.13
2026-02-18 02:39:07.738045 | instance | ok: Runtime: 0:00:00.012741
2026-02-18 02:39:07.744808 | 
2026-02-18 02:39:07.744869 | PLAY RECAP
2026-02-18 02:39:07.744923 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 02:39:07.744975 | 
2026-02-18 02:39:07.891826 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-18 02:39:07.897628 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1]
2026-02-18 02:39:08.468085 | 
2026-02-18 02:39:08.468216 | PLAY [all]
2026-02-18 02:39:08.479337 | 
2026-02-18 02:39:08.479413 | TASK [Install "jq" for log collection]
2026-02-18 02:39:20.532107 | instance | changed
2026-02-18 02:39:20.534617 | 
2026-02-18 02:39:20.534674 | PLAY RECAP
2026-02-18 02:39:20.534729 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 02:39:20.534782 | 
2026-02-18 02:39:20.677265 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1]
2026-02-18 02:39:20.729667 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-18 02:39:21.308343 | 
2026-02-18 02:39:21.322203 | PLAY [all]
2026-02-18 02:39:21.338261 | 
2026-02-18 02:39:21.338364 | TASK [Copy inventory file for Zuul]
2026-02-18 02:39:22.213684 | instance | changed
2026-02-18 02:39:22.224551 | 
2026-02-18 02:39:22.224663 | TASK [Switch "ansible_host" to private IP]
2026-02-18 02:39:22.499065 | instance | changed: 1 replacements made
2026-02-18 02:39:22.506303 | 
2026-02-18 02:39:22.506398 | TASK [Run Molecule scenario]
2026-02-18 02:39:22.921165 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-02-18 02:39:22.921398 | instance | Creating virtual environment at: .venv
2026-02-18 02:39:22.951779 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-18 02:39:22.999252 | instance | Downloading setuptools (1.1MiB)
2026-02-18 02:39:23.011765 | instance | Downloading pygments (1.2MiB)
2026-02-18 02:39:23.013846 | instance | Downloading netaddr (2.2MiB)
2026-02-18 02:39:23.014628 | instance | Downloading rjsonnet (1.2MiB)
2026-02-18 02:39:23.020221 | instance | Downloading cryptography (4.2MiB)
2026-02-18 02:39:23.020602 | instance | Downloading kubernetes (1.9MiB)
2026-02-18 02:39:23.021163 | instance | Downloading openstacksdk (1.7MiB)
2026-02-18 02:39:23.224397 | instance | Downloading ansible-core (2.1MiB)
2026-02-18 02:39:23.402173 | instance |  Downloading rjsonnet
2026-02-18 02:39:23.420270 | instance |    Building pyperclip==1.9.0
2026-02-18 02:39:23.596962 | instance |  Downloading netaddr
2026-02-18 02:39:23.624917 | instance |  Downloading pygments
2026-02-18 02:39:23.649886 | instance |  Downloading cryptography
2026-02-18 02:39:23.708341 | instance |  Downloading setuptools
2026-02-18 02:39:23.783766 | instance |  Downloading kubernetes
2026-02-18 02:39:23.849995 | instance |  Downloading ansible-core
2026-02-18 02:39:23.872597 | instance |  Downloading openstacksdk
2026-02-18 02:39:24.300399 | instance |       Built pyperclip==1.9.0
2026-02-18 02:39:24.576728 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-18 02:39:24.711146 | instance | Installed 79 packages in 132ms
2026-02-18 02:39:25.681554 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-02-18 02:39:26.250880 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-02-18 02:39:26.251006 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-02-18 02:39:34.603796 | instance | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.posix:1.6.0
2026-02-18 02:39:34.603966 | instance | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:1.6.0, returned 250 code:
2026-02-18 02:39:34.603975 | instance | Starting galaxy collection install process
2026-02-18 02:39:34.603980 | instance | Process install dependency map
2026-02-18 02:39:34.603986 | instance | the full traceback was:
2026-02-18 02:39:34.603991 | instance |
2026-02-18 02:39:34.603995 | instance | Traceback (most recent call last):
2026-02-18 02:39:34.604000 | 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-18 02:39:34.604006 | instance |     exit_code = cli.run()
2026-02-18 02:39:34.604010 | 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-18 02:39:34.604015 | instance |     return context.CLIARGS()
2026-02-18 02:39:34.604020 | 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-18 02:39:34.604024 | instance |     return wrapped_method(*args, **kwargs)
2026-02-18 02:39:34.604028 | 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-18 02:39:34.604033 | instance |     self._execute_install_collection(
2026-02-18 02:39:34.604037 | 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-18 02:39:34.604043 | instance |     install_collections(
2026-02-18 02:39:34.604047 | 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-18 02:39:34.604052 | instance |     dependency_map = _resolve_depenency_map(
2026-02-18 02:39:34.604056 | 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-18 02:39:34.604061 | instance |     return collection_dep_resolver.resolve(
2026-02-18 02:39:34.604066 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-18 02:39:34.604090 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-18 02:39:34.604094 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-18 02:39:34.604098 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-18 02:39:34.604103 | 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-18 02:39:34.604107 | instance |     matches = self._p.find_matches(
2026-02-18 02:39:34.604112 | 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-18 02:39:34.604116 | instance |     match for match in self._find_matches(list(requirements))
2026-02-18 02:39:34.604120 | 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-18 02:39:34.604124 | instance |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-02-18 02:39:34.604133 | 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-18 02:39:34.604138 | instance |     return set(
2026-02-18 02:39:34.604142 | 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-18 02:39:34.604146 | instance |     return set(
2026-02-18 02:39:34.604150 | 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-18 02:39:34.604154 | instance |     raise last_error
2026-02-18 02:39:34.604158 | 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-18 02:39:34.604163 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-18 02:39:34.604167 | 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-18 02:39:34.604171 | instance |     return method(self, *args, **kwargs)
2026-02-18 02:39:34.604175 | 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-18 02:39:34.604180 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-18 02:39:34.604184 | 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-18 02:39:34.604188 | instance |     return call_retryable_function()
2026-02-18 02:39:34.604195 | 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-18 02:39:34.604200 | instance |     res = path_cache
2026-02-18 02:39:34.604204 | instance | KeyError: 'results'
2026-02-18 02:39:34.604207 | instance |
2026-02-18 02:39:34.604211 | instance | ansible-galaxy
2026-02-18 02:39:34.604216 | instance |   config file = None
2026-02-18 02:39:34.604220 | instance |   configured module search path =
2026-02-18 02:39:34.604224 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-18 02:39:34.604229 | 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-18 02:39:34.604239 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-18 02:39:34.604243 | 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-18 02:39:34.604247 | instance |   jinja version = 3.1.6
2026-02-18 02:39:34.604251 | instance |   libyaml = True
2026-02-18 02:39:34.604255 | instance | No config file found; using defaults
2026-02-18 02:39:34.604259 | instance | : Collection ansible.netcommon does not support Ansible version
2026-02-18 02:39:34.604264 | instance | 2.17.12
2026-02-18 02:39:34.604268 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-18 02:39:34.604272 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-18 02:39:34.604276 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-18 02:39:34.604280 | instance | unexpected error when getting available versions of collection ansible.posix:
2026-02-18 02:39:34.604284 | instance | 'results'
2026-02-18 02:39:34.604288 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-18 02:39:34.604292 | instance |
2026-02-18 02:39:34.604629 | instance | Traceback (most recent call last):
2026-02-18 02:39:34.604973 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-02-18 02:39:34.605402 | instance |     sys.exit(main())
2026-02-18 02:39:34.605706 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-02-18 02:39:34.606384 | instance |     return self.main(*args, **kwargs)
2026-02-18 02:39:34.606666 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-02-18 02:39:34.607355 | instance |     rv = self.invoke(ctx)
2026-02-18 02:39:34.607643 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-02-18 02:39:34.608398 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-02-18 02:39:34.608570 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-02-18 02:39:34.608951 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-02-18 02:39:34.609136 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-02-18 02:39:34.609456 | instance |     return callback(*args, **kwargs)
2026-02-18 02:39:34.609636 | 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-18 02:39:34.609938 | instance |     return func(*args, **kwargs)
2026-02-18 02:39:34.610118 | 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-18 02:39:34.610358 | instance |     return f(get_current_context(), *args, **kwargs)
2026-02-18 02:39:34.610524 | 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-18 02:39:34.610799 | instance |     return func(ctx)
2026-02-18 02:39:34.610971 | 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-18 02:39:34.611238 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-02-18 02:39:34.611420 | 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-18 02:39:34.611686 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-02-18 02:39:34.611856 | 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-18 02:39:34.612134 | instance |     scenario.config.runtime.prepare_environment(
2026-02-18 02:39:34.612315 | 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-18 02:39:34.612650 | instance |     self.install_collection(
2026-02-18 02:39:34.612826 | 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-18 02:39:34.613119 | instance |     raise InvalidPrerequisiteError(msg)
2026-02-18 02:39:34.615467 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:1.6.0, returned 250 code:
2026-02-18 02:39:34.615482 | instance | Starting galaxy collection install process
2026-02-18 02:39:34.615492 | instance | Process install dependency map
2026-02-18 02:39:34.615500 | instance | the full traceback was:
2026-02-18 02:39:34.615509 | instance |
2026-02-18 02:39:34.615519 | instance | Traceback (most recent call last):
2026-02-18 02:39:34.615527 | 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-18 02:39:34.615536 | instance |     exit_code = cli.run()
2026-02-18 02:39:34.615546 | 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-18 02:39:34.615555 | instance |     return context.CLIARGS['func']()
2026-02-18 02:39:34.615564 | 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-18 02:39:34.615573 | instance |     return wrapped_method(*args, **kwargs)
2026-02-18 02:39:34.615582 | 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-18 02:39:34.615591 | instance |     self._execute_install_collection(
2026-02-18 02:39:34.615600 | 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-18 02:39:34.615609 | instance |     install_collections(
2026-02-18 02:39:34.615617 | 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-18 02:39:34.615626 | instance |     dependency_map = _resolve_depenency_map(
2026-02-18 02:39:34.615635 | 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-18 02:39:34.615644 | instance |     return collection_dep_resolver.resolve(
2026-02-18 02:39:34.615653 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-18 02:39:34.615662 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-18 02:39:34.615678 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-18 02:39:34.615687 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-18 02:39:34.615696 | 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-18 02:39:34.615705 | instance |     matches = self._p.find_matches(
2026-02-18 02:39:34.615715 | 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-18 02:39:34.615724 | instance |     match for match in self._find_matches(list(requirements[identifier]))
2026-02-18 02:39:34.615733 | 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-18 02:39:34.615743 | instance |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-02-18 02:39:34.615763 | 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-18 02:39:34.615773 | instance |     return set(
2026-02-18 02:39:34.615782 | 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-18 02:39:34.615791 | instance |     return set(
2026-02-18 02:39:34.615799 | 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-18 02:39:34.615808 | instance |     raise last_error
2026-02-18 02:39:34.615817 | 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-18 02:39:34.615826 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-18 02:39:34.615835 | 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-18 02:39:34.615844 | instance |     return method(self, *args, **kwargs)
2026-02-18 02:39:34.615853 | 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-18 02:39:34.615862 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-18 02:39:34.615941 | 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-18 02:39:34.615957 | instance |     return call_retryable_function()
2026-02-18 02:39:34.615969 | 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-18 02:39:34.615981 | instance |     res = path_cache['results']
2026-02-18 02:39:34.615990 | instance | KeyError: 'results'
2026-02-18 02:39:34.615998 | instance |
2026-02-18 02:39:34.616007 | instance | ansible-galaxy [core 2.17.12]
2026-02-18 02:39:34.616016 | instance |   config file = None
2026-02-18 02:39:34.616025 | instance |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-02-18 02:39:34.616034 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-18 02:39:34.616044 | 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-18 02:39:34.616054 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-18 02:39:34.616063 | 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-18 02:39:34.616072 | instance |   jinja version = 3.1.6
2026-02-18 02:39:34.616081 | instance |   libyaml = True
2026-02-18 02:39:34.616089 | instance | No config file found; using defaults
2026-02-18 02:39:34.616098 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-02-18 02:39:34.616107 | instance | 2.17.12
2026-02-18 02:39:34.616116 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-18 02:39:34.616125 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-18 02:39:34.616134 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-18 02:39:34.616142 | instance | unexpected error when getting available versions of collection ansible.posix:
2026-02-18 02:39:34.616159 | instance | 'results'
2026-02-18 02:39:34.616168 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-18 02:39:34.616177 | instance |
2026-02-18 02:39:35.066842 | instance | ERROR
2026-02-18 02:39:35.067148 | instance | {
2026-02-18 02:39:35.067181 | instance |   "delta": "0:00:11.827963",
2026-02-18 02:39:35.067204 | instance |   "end": "2026-02-18 02:39:34.674938",
2026-02-18 02:39:35.067241 | instance |   "msg": "non-zero return code",
2026-02-18 02:39:35.067262 | instance |   "rc": 1,
2026-02-18 02:39:35.067286 | instance |   "start": "2026-02-18 02:39:22.846975"
2026-02-18 02:39:35.067307 | instance | }
failure
2026-02-18 02:39:35.071743 | 
2026-02-18 02:39:35.071804 | PLAY RECAP
2026-02-18 02:39:35.071854 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-18 02:39:35.071876 | 
2026-02-18 02:39:35.206970 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-18 02:39:35.373570 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1]
2026-02-18 02:39:36.004429 | 
2026-02-18 02:39:36.004566 | PLAY [all]
2026-02-18 02:39:36.018968 | 
2026-02-18 02:39:36.034458 | TASK [gather-host-logs : creating directory for system status]
2026-02-18 02:39:36.387713 | instance | changed
2026-02-18 02:39:36.393130 | 
2026-02-18 02:39:36.393233 | TASK [gather-host-logs : Get logs for each host]
2026-02-18 02:39:36.752326 | instance | + systemd-cgls --full --all --no-pager
2026-02-18 02:39:36.758443 | instance | + ip addr
2026-02-18 02:39:36.760021 | instance | + ip route
2026-02-18 02:39:36.761367 | instance | + lsblk
2026-02-18 02:39:36.764027 | instance | + mount
2026-02-18 02:39:36.766239 | instance | + docker images
2026-02-18 02:39:36.766532 | instance | /bin/bash: line 7: docker: command not found
2026-02-18 02:39:36.766743 | instance | + brctl show
2026-02-18 02:39:36.767020 | instance | /bin/bash: line 8: brctl: command not found
2026-02-18 02:39:36.767264 | instance | + ps aux --sort=-%mem
2026-02-18 02:39:36.775502 | instance | + dpkg -l
2026-02-18 02:39:36.784052 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-02-18 02:39:36.784444 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-02-18 02:39:36.784505 | instance | /bin/bash: line 11: docker: command not found
2026-02-18 02:39:36.784798 | instance | + '[' '!' -z '' ']'
2026-02-18 02:39:36.930489 | instance | ok: Runtime: 0:00:00.037087
2026-02-18 02:39:36.938581 | 
2026-02-18 02:39:36.938652 | TASK [gather-host-logs : Downloads logs to executor]
2026-02-18 02:39:37.618738 | instance | changed:
2026-02-18 02:39:37.618930 | instance | created directory /var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/work/logs/instance
2026-02-18 02:39:37.618970 | instance | cd+++++++++ system/
2026-02-18 02:39:37.619000 | instance | >f+++++++++ system/brctl-show.txt
2026-02-18 02:39:37.619028 | instance | >f+++++++++ system/docker-images.txt
2026-02-18 02:39:37.619055 | instance | >f+++++++++ system/ip-addr.txt
2026-02-18 02:39:37.619084 | instance | >f+++++++++ system/ip-route.txt
2026-02-18 02:39:37.619119 | instance | >f+++++++++ system/lsblk.txt
2026-02-18 02:39:37.619147 | instance | >f+++++++++ system/mount.txt
2026-02-18 02:39:37.619176 | instance | >f+++++++++ system/packages.txt
2026-02-18 02:39:37.619202 | instance | >f+++++++++ system/ps.txt
2026-02-18 02:39:37.619228 | instance | >f+++++++++ system/systemd-cgls.txt
2026-02-18 02:39:37.632151 | 
2026-02-18 02:39:37.632321 | LOOP [helm-release-status : creating directory for helm release status]
2026-02-18 02:39:37.812845 | instance | changed: "values"
2026-02-18 02:39:37.965552 | instance | changed: "releases"
2026-02-18 02:39:37.982621 | 
2026-02-18 02:39:37.982766 | TASK [helm-release-status : Gather get release status for helm charts]
2026-02-18 02:39:38.182375 | instance | /bin/bash: line 3: kubectl: command not found
2026-02-18 02:39:38.518633 | instance | ok: Runtime: 0:00:00.004909
2026-02-18 02:39:38.524970 | 
2026-02-18 02:39:38.525039 | TASK [helm-release-status : Downloads logs to executor]
2026-02-18 02:39:39.015902 | instance | changed:
2026-02-18 02:39:39.016244 | instance | cd+++++++++ helm/
2026-02-18 02:39:39.016310 | instance | cd+++++++++ helm/releases/
2026-02-18 02:39:39.016359 | instance | cd+++++++++ helm/values/
2026-02-18 02:39:39.025934 | 
2026-02-18 02:39:39.026006 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-02-18 02:39:39.229247 | instance | changed
2026-02-18 02:39:39.235858 | 
2026-02-18 02:39:39.235998 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-02-18 02:39:39.464697 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 02:39:39.464768 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 02:39:39.471428 | instance | environment: line 1: kubectl: command not found
2026-02-18 02:39:39.472855 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 02:39:39.473135 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 02:39:39.473430 | instance | environment: line 1: kubectl: command not found
2026-02-18 02:39:39.476448 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 02:39:39.476591 | instance | environment: line 1: kubectl: command not found
2026-02-18 02:39:39.477695 | instance | xargs: environment: line 1: kubectl: command not found
2026-02-18 02:39:39.477735 | instance | warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 02:39:39.479297 | instance | environment: line 1: kubectl: command not found
2026-02-18 02:39:39.479872 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 02:39:39.771720 | instance | ok: Runtime: 0:00:00.037899
2026-02-18 02:39:39.778859 | 
2026-02-18 02:39:39.778949 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-02-18 02:39:39.966372 | instance | changed
2026-02-18 02:39:39.973620 | 
2026-02-18 02:39:39.973726 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-02-18 02:39:40.228092 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 02:39:40.228878 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 02:39:40.229118 | instance | environment: line 5: kubectl: command not found
2026-02-18 02:39:40.229575 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 02:39:40.563269 | instance | ok: Runtime: 0:00:00.008355
2026-02-18 02:39:40.568744 | 
2026-02-18 02:39:40.568824 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-02-18 02:39:41.077968 | instance | changed:
2026-02-18 02:39:41.078178 | instance | cd+++++++++ objects/
2026-02-18 02:39:41.078218 | instance | cd+++++++++ objects/cluster/
2026-02-18 02:39:41.078271 | instance | cd+++++++++ objects/namespaced/
2026-02-18 02:39:41.089529 | 
2026-02-18 02:39:41.089599 | TASK [gather-pod-logs : creating directory for pod logs]
2026-02-18 02:39:41.284421 | instance | changed
2026-02-18 02:39:41.291540 | 
2026-02-18 02:39:41.291618 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-02-18 02:39:41.494463 | instance | changed
2026-02-18 02:39:41.500595 | 
2026-02-18 02:39:41.500690 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-02-18 02:39:41.707444 | instance | environment: line 3: kubectl: command not found
2026-02-18 02:39:42.035906 | instance | ok: Runtime: 0:00:00.009370
2026-02-18 02:39:42.042679 | 
2026-02-18 02:39:42.042769 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-02-18 02:39:42.552468 | instance | changed:
2026-02-18 02:39:42.552689 | instance | cd+++++++++ pod-logs/
2026-02-18 02:39:42.552729 | instance | cd+++++++++ pod-logs/failed-pods/
2026-02-18 02:39:42.564474 | 
2026-02-18 02:39:42.564540 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-02-18 02:39:42.754176 | instance | changed
2026-02-18 02:39:42.760081 | 
2026-02-18 02:39:42.760152 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-02-18 02:39:42.972110 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-18 02:39:43.301737 | instance | ok: Runtime: 0:00:00.032674
2026-02-18 02:39:43.306737 | 
2026-02-18 02:39:43.306808 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-02-18 02:39:43.513964 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-18 02:39:43.538634 | instance | ceph-mgr endpoints:
2026-02-18 02:39:43.842304 | instance | ok: Runtime: 0:00:00.028895
2026-02-18 02:39:43.849265 | 
2026-02-18 02:39:43.849341 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-02-18 02:39:44.054561 | instance | /bin/bash: line 4: kubectl: command not found
2026-02-18 02:39:44.384623 | instance | ok: Runtime: 0:00:00.027017
2026-02-18 02:39:44.391819 | 
2026-02-18 02:39:44.391888 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-02-18 02:39:44.905244 | instance | changed: cd+++++++++ prometheus/
2026-02-18 02:39:44.918586 | 
2026-02-18 02:39:44.918740 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-02-18 02:39:45.134518 | instance | changed
2026-02-18 02:39:45.142638 | 
2026-02-18 02:39:45.142735 | TASK [gather-selenium-data : Get selenium data]
2026-02-18 02:39:45.340323 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-02-18 02:39:45.340984 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-02-18 02:39:45.679250 | instance | ERROR
2026-02-18 02:39:45.679605 | instance | {
2026-02-18 02:39:45.679727 | instance |   "delta": "0:00:00.003819",
2026-02-18 02:39:45.679777 | instance |   "end": "2026-02-18 02:39:45.341236",
2026-02-18 02:39:45.679818 | instance |   "msg": "non-zero return code",
2026-02-18 02:39:45.679857 | instance |   "rc": 1,
2026-02-18 02:39:45.679896 | instance |   "start": "2026-02-18 02:39:45.337417"
2026-02-18 02:39:45.679934 | instance | }
2026-02-18 02:39:45.679984 | instance | ERROR: Ignoring Errors
2026-02-18 02:39:45.685096 | 
2026-02-18 02:39:45.685166 | TASK [gather-selenium-data : Downloads logs to executor]
2026-02-18 02:39:46.179209 | instance | changed: cd+++++++++ selenium/
2026-02-18 02:39:46.186309 | 
2026-02-18 02:39:46.186368 | PLAY RECAP
2026-02-18 02:39:46.186422 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-02-18 02:39:46.186450 | 
2026-02-18 02:39:46.325331 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1]
2026-02-18 02:39:46.329434 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-18 02:39:46.921591 | 
2026-02-18 02:39:46.941812 | PLAY [all]
2026-02-18 02:39:46.958522 | 
2026-02-18 02:39:46.958646 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-18 02:39:47.004255 | instance | skipping: Conditional result was False
2026-02-18 02:39:47.016916 | 
2026-02-18 02:39:47.017136 | TASK [fetch-output : Set log path for single node]
2026-02-18 02:39:47.063490 | instance | ok
2026-02-18 02:39:47.070283 | 
2026-02-18 02:39:47.070383 | LOOP [fetch-output : Ensure local output dirs]
2026-02-18 02:39:47.455194 | instance -> localhost | ok: "/var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/work/logs"
2026-02-18 02:39:47.707611 | instance -> localhost | changed: "/var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/work/artifacts"
2026-02-18 02:39:47.973105 | instance -> localhost | changed: "/var/lib/zuul/builds/eaaf809b4120440d97cee84d126d2f92/work/docs"
2026-02-18 02:39:47.990804 | 
2026-02-18 02:39:47.990960 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-18 02:39:48.651612 | instance | changed: .d..t...... ./
2026-02-18 02:39:48.651894 | instance | changed: All items complete
2026-02-18 02:39:48.651944 | 
2026-02-18 02:39:49.097277 | instance | changed: .d..t...... ./
2026-02-18 02:39:49.568389 | instance | changed: .d..t...... ./
2026-02-18 02:39:49.582104 | 
2026-02-18 02:39:49.582289 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-18 02:39:50.018312 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008984
2026-02-18 02:39:50.265382 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007831
2026-02-18 02:39:50.285809 | 
2026-02-18 02:39:50.285934 | PLAY [all]
2026-02-18 02:39:50.292203 | 
2026-02-18 02:39:50.292271 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-18 02:39:50.722565 | instance | changed
2026-02-18 02:39:50.729880 | 
2026-02-18 02:39:50.729945 | PLAY RECAP
2026-02-18 02:39:50.730003 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-18 02:39:50.730034 | 
2026-02-18 02:39:50.840723 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-18 02:39:50.842932 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-18 02:39:51.439085 | 
2026-02-18 02:39:51.439217 | PLAY [localhost]
2026-02-18 02:39:51.449996 | 
2026-02-18 02:39:51.450095 | TASK [Generate Zuul manifest]
2026-02-18 02:39:51.472632 | localhost | ok
2026-02-18 02:39:51.490383 | 
2026-02-18 02:39:51.490501 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-18 02:39:51.828050 | localhost | changed
2026-02-18 02:39:51.842449 | 
2026-02-18 02:39:51.842525 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-18 02:39:51.879170 | localhost | ok
2026-02-18 02:39:51.886095 | 
2026-02-18 02:39:51.886158 | TASK [Upload logs]
2026-02-18 02:39:51.907730 | localhost | ok
2026-02-18 02:39:51.969766 | 
2026-02-18 02:39:51.969869 | TASK [Set zuul-log-path fact]
2026-02-18 02:39:51.992992 | localhost | ok
2026-02-18 02:39:52.006030 | 
2026-02-18 02:39:52.006111 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-18 02:39:52.039938 | localhost | ok
2026-02-18 02:39:52.049442 | 
2026-02-18 02:39:52.049520 | TASK [upload-logs : Create log directories]
2026-02-18 02:39:52.467795 | localhost | changed
2026-02-18 02:39:52.474504 | 
2026-02-18 02:39:52.474598 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-18 02:39:52.942778 | localhost -> localhost | ok: Runtime: 0:00:00.006527
2026-02-18 02:39:52.950140 | 
2026-02-18 02:39:52.950250 | TASK [upload-logs : Upload logs to log server]
2026-02-18 02:39:53.430543 | localhost | Output suppressed because no_log was given
2026-02-18 02:39:53.435008 | 
2026-02-18 02:39:53.435070 | LOOP [upload-logs : Compress console log and json output]
2026-02-18 02:39:53.478806 | localhost | skipping: Conditional result was False
2026-02-18 02:39:53.484339 | localhost | skipping: Conditional result was False
2026-02-18 02:39:53.498421 | 
2026-02-18 02:39:53.498595 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-18 02:39:53.538517 | localhost | skipping: Conditional result was False
2026-02-18 02:39:53.538929 | 
2026-02-18 02:39:53.541771 | localhost | skipping: Conditional result was False
2026-02-18 02:39:53.552019 | 
2026-02-18 02:39:53.552180 | LOOP [upload-logs : Upload console log and json output]