2026-04-02 16:35:35.846097 | Job console starting
2026-04-02 16:35:36.079492 | Updating git repos
2026-04-02 16:35:36.308652 | Cloning repos into workspace
2026-04-02 16:35:37.094515 | Restoring repo states
2026-04-02 16:35:37.229520 | Merging changes
2026-04-02 16:35:39.346505 | Checking out repos
2026-04-02 16:35:39.651085 | Preparing playbooks
2026-04-02 16:35:50.938563 | Running Ansible setup
2026-04-02 16:35:54.787328 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-02 16:35:55.371313 | 
2026-04-02 16:35:55.386204 | PLAY [localhost]
2026-04-02 16:35:55.399340 | 
2026-04-02 16:35:55.399410 | TASK [Gathering Facts]
2026-04-02 16:35:56.331629 | localhost | ok
2026-04-02 16:35:56.342954 | 
2026-04-02 16:35:56.343088 | TASK [Setup log path fact]
2026-04-02 16:35:56.363308 | localhost | ok
2026-04-02 16:35:56.377917 | 
2026-04-02 16:35:56.378017 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-02 16:35:56.406800 | localhost | ok
2026-04-02 16:35:56.500729 | 
2026-04-02 16:35:56.500869 | TASK [emit-job-header : Print job information]
2026-04-02 16:35:56.540217 | # Job Information
2026-04-02 16:35:56.540432 | Ansible Version: 2.16.16
2026-04-02 16:35:56.540477 | Job: atmosphere-molecule-aio-openvswitch
2026-04-02 16:35:56.540506 | Pipeline: check
2026-04-02 16:35:56.540535 | Executor: 0a8996d2b663
2026-04-02 16:35:56.540567 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3697
2026-04-02 16:35:56.540598 | Event ID: 373276b0-2ead-11f1-8ade-187d56890396
2026-04-02 16:35:56.544279 | 
2026-04-02 16:35:56.544368 | LOOP [emit-job-header : Print node information]
2026-04-02 16:35:56.643812 | localhost | ok:
2026-04-02 16:35:56.726507 | localhost | # Node Information
2026-04-02 16:35:56.726660 | localhost | Inventory Hostname: instance
2026-04-02 16:35:56.726730 | localhost | Hostname: np0000163030
2026-04-02 16:35:56.726780 | localhost | Username: zuul
2026-04-02 16:35:56.726854 | localhost | Distro: Ubuntu 22.04
2026-04-02 16:35:56.726910 | localhost | Provider: yul1
2026-04-02 16:35:56.726962 | localhost | Region: ca-ymq-1
2026-04-02 16:35:56.727016 | localhost | Label: ubuntu-jammy-16
2026-04-02 16:35:56.727075 | localhost | Product Name: OpenStack Nova
2026-04-02 16:35:56.727119 | localhost | Interface IP: 199.19.213.29
2026-04-02 16:35:56.737703 | 
2026-04-02 16:35:56.737823 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-02 16:35:57.321797 | localhost -> localhost | changed
2026-04-02 16:35:57.339443 | 
2026-04-02 16:35:57.339551 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-02 16:35:58.302842 | localhost -> localhost | changed
2026-04-02 16:35:58.312194 | 
2026-04-02 16:35:58.312259 | PLAY [all]
2026-04-02 16:35:58.319929 | 
2026-04-02 16:35:58.319995 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-02 16:35:58.572852 | instance -> localhost | ok
2026-04-02 16:35:58.578942 | 
2026-04-02 16:35:58.579010 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-02 16:35:58.603090 | instance | ok
2026-04-02 16:35:58.616055 | instance | included: /var/lib/zuul/builds/579d792498c347b7aa1249422e103993/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-02 16:35:58.621819 | 
2026-04-02 16:35:58.621890 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-02 16:35:59.968737 | instance -> localhost | Generating public/private rsa key pair.
2026-04-02 16:35:59.968939 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/579d792498c347b7aa1249422e103993/work/579d792498c347b7aa1249422e103993_id_rsa
2026-04-02 16:35:59.969012 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/579d792498c347b7aa1249422e103993/work/579d792498c347b7aa1249422e103993_id_rsa.pub
2026-04-02 16:35:59.969048 | instance -> localhost | The key fingerprint is:
2026-04-02 16:35:59.969080 | instance -> localhost | SHA256:4k5qg4nRz4E6/jLiqTcqbApNiii6ZAxWGF1ox5tHgWQ zuul-build-sshkey
2026-04-02 16:35:59.969125 | instance -> localhost | The key's randomart image is:
2026-04-02 16:35:59.969157 | instance -> localhost | +---[RSA 3072]----+
2026-04-02 16:35:59.969194 | instance -> localhost | | .. =E...        |
2026-04-02 16:35:59.969236 | instance -> localhost | |  o+.+ .         |
2026-04-02 16:35:59.969267 | instance -> localhost | | .... +          |
2026-04-02 16:35:59.969297 | instance -> localhost | |  .  o .         |
2026-04-02 16:35:59.969326 | instance -> localhost | |.o..  o S        |
2026-04-02 16:35:59.969355 | instance -> localhost | |B+o .. .         |
2026-04-02 16:35:59.969384 | instance -> localhost | |BB.= .o          |
2026-04-02 16:35:59.969413 | instance -> localhost | |%=* =+           |
2026-04-02 16:35:59.969446 | instance -> localhost | |#O++...          |
2026-04-02 16:35:59.969497 | instance -> localhost | +----[SHA256]-----+
2026-04-02 16:35:59.969567 | instance -> localhost | ok: Runtime: 0:00:00.885583
2026-04-02 16:35:59.976437 | 
2026-04-02 16:35:59.976525 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-02 16:36:00.007473 | instance | ok
2026-04-02 16:36:00.018075 | instance | included: /var/lib/zuul/builds/579d792498c347b7aa1249422e103993/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-02 16:36:00.025487 | 
2026-04-02 16:36:00.025557 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-02 16:36:00.049201 | instance | skipping: Conditional result was False
2026-04-02 16:36:00.056709 | 
2026-04-02 16:36:00.056802 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-02 16:36:00.520630 | instance | changed
2026-04-02 16:36:00.525785 | 
2026-04-02 16:36:00.525854 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-02 16:36:00.712611 | instance | ok
2026-04-02 16:36:00.720001 | 
2026-04-02 16:36:00.720093 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-02 16:36:01.195504 | instance | changed
2026-04-02 16:36:01.201872 | 
2026-04-02 16:36:01.201967 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-02 16:36:01.659571 | instance | changed
2026-04-02 16:36:01.665627 | 
2026-04-02 16:36:01.665716 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-02 16:36:01.683966 | instance | skipping: Conditional result was False
2026-04-02 16:36:01.691123 | 
2026-04-02 16:36:01.691194 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-02 16:36:02.099465 | instance -> localhost | changed
2026-04-02 16:36:02.153448 | 
2026-04-02 16:36:02.153563 | TASK [add-build-sshkey : Add back temp key]
2026-04-02 16:36:02.436426 | instance -> localhost | Identity added: /var/lib/zuul/builds/579d792498c347b7aa1249422e103993/work/579d792498c347b7aa1249422e103993_id_rsa (zuul-build-sshkey)
2026-04-02 16:36:02.436593 | instance -> localhost | ok: Runtime: 0:00:00.015611
2026-04-02 16:36:02.442258 | 
2026-04-02 16:36:02.442345 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-02 16:36:02.728885 | instance | ok
2026-04-02 16:36:02.743022 | 
2026-04-02 16:36:02.808023 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-02 16:36:02.832351 | instance | skipping: Conditional result was False
2026-04-02 16:36:02.850302 | 
2026-04-02 16:36:02.850430 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-02 16:36:03.142134 | instance | ok
2026-04-02 16:36:03.148106 | 
2026-04-02 16:36:03.148194 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-02 16:36:04.728033 | instance | Output suppressed because no_log was given
2026-04-02 16:36:04.807713 | 
2026-04-02 16:36:04.807811 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-02 16:36:04.981745 | instance | ok: "logs"
2026-04-02 16:36:04.982029 | instance | ok: All items complete
2026-04-02 16:36:04.982064 | 
2026-04-02 16:36:05.132273 | instance | ok: "artifacts"
2026-04-02 16:36:05.298801 | instance | ok: "docs"
2026-04-02 16:36:05.310908 | 
2026-04-02 16:36:05.311035 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-02 16:36:05.494191 | instance | changed: "logs"
2026-04-02 16:36:05.646036 | instance | changed: "artifacts"
2026-04-02 16:36:05.793706 | instance | changed: "docs"
2026-04-02 16:36:05.916195 | 
2026-04-02 16:36:05.916278 | PLAY RECAP
2026-04-02 16:36:05.916323 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-02 16:36:05.916352 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-02 16:36:05.916374 | 
2026-04-02 16:36:06.036676 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-02 16:36:06.092661 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-02 16:36:06.746679 | 
2026-04-02 16:36:06.746826 | PLAY [all]
2026-04-02 16:36:06.759006 | 
2026-04-02 16:36:06.759118 | TASK [setup-uv : Extract archive]
2026-04-02 16:36:08.932361 | instance | changed
2026-04-02 16:36:08.947171 | 
2026-04-02 16:36:08.947339 | TASK [setup-uv : Print version]
2026-04-02 16:36:09.283378 | instance | uv 0.8.13
2026-04-02 16:36:09.541591 | instance | ok: Runtime: 0:00:00.010062
2026-04-02 16:36:09.548847 | 
2026-04-02 16:36:09.548898 | PLAY RECAP
2026-04-02 16:36:09.548943 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-02 16:36:09.548993 | 
2026-04-02 16:36:09.663151 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-02 16:36:09.780277 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-02 16:36:10.381932 | 
2026-04-02 16:36:10.382048 | PLAY [all]
2026-04-02 16:36:10.393768 | 
2026-04-02 16:36:10.393870 | TASK [Install "jq" for log collection]
2026-04-02 16:36:20.411971 | instance | changed
2026-04-02 16:36:20.413998 | 
2026-04-02 16:36:20.414073 | PLAY RECAP
2026-04-02 16:36:20.414137 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-02 16:36:20.414205 | 
2026-04-02 16:36:20.526359 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-02 16:36:20.573076 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-02 16:36:21.217608 | 
2026-04-02 16:36:21.217717 | PLAY [all]
2026-04-02 16:36:21.229501 | 
2026-04-02 16:36:21.229588 | TASK [Copy inventory file for Zuul]
2026-04-02 16:36:22.098743 | instance | changed
2026-04-02 16:36:22.104181 | 
2026-04-02 16:36:22.104265 | TASK [Switch "ansible_host" to private IP]
2026-04-02 16:36:22.420000 | instance | changed: 1 replacements made
2026-04-02 16:36:22.778530 | 
2026-04-02 16:36:22.778892 | TASK [Run Molecule scenario]
2026-04-02 16:36:23.181020 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-04-02 16:36:23.181147 | instance | Creating virtual environment at: .venv
2026-04-02 16:36:23.206196 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-04-02 16:36:23.220965 | instance | Downloading setuptools (1.1MiB)
2026-04-02 16:36:23.228599 | instance | Downloading pygments (1.2MiB)
2026-04-02 16:36:23.232119 | instance | Downloading kubernetes (1.9MiB)
2026-04-02 16:36:23.232479 | instance | Downloading openstacksdk (1.7MiB)
2026-04-02 16:36:23.232678 | instance | Downloading ansible-core (2.1MiB)
2026-04-02 16:36:23.232888 | instance | Downloading rjsonnet (1.2MiB)
2026-04-02 16:36:23.233103 | instance | Downloading cryptography (4.2MiB)
2026-04-02 16:36:23.233614 | instance | Downloading netaddr (2.2MiB)
2026-04-02 16:36:23.234077 | instance | Downloading pydantic-core (2.0MiB)
2026-04-02 16:36:23.552791 | instance |    Building pyperclip==1.9.0
2026-04-02 16:36:23.562030 | instance |  Downloading rjsonnet
2026-04-02 16:36:23.674020 | instance |  Downloading pydantic-core
2026-04-02 16:36:23.715358 | instance |  Downloading netaddr
2026-04-02 16:36:23.730819 | instance |  Downloading pygments
2026-04-02 16:36:23.745123 | instance |  Downloading cryptography
2026-04-02 16:36:23.783318 | instance |  Downloading setuptools
2026-04-02 16:36:23.845694 | instance |  Downloading kubernetes
2026-04-02 16:36:23.886713 | instance |  Downloading ansible-core
2026-04-02 16:36:23.923146 | instance |  Downloading openstacksdk
2026-04-02 16:36:24.292436 | instance |       Built pyperclip==1.9.0
2026-04-02 16:36:24.485897 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-04-02 16:36:24.532356 | instance | Installed 83 packages in 43ms
2026-04-02 16:36:25.194093 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-04-02 16:36:25.816111 | instance | INFO     [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-02 16:36:25.816195 | instance | INFO     [aio > prerun] Performing prerun with role_name_check=0...
2026-04-02 16:36:33.036979 | instance | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.posix:1.6.0
2026-04-02 16:36:33.037057 | instance | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:1.6.0, returned 250 code:
2026-04-02 16:36:33.037063 | instance | Starting galaxy collection install process
2026-04-02 16:36:33.037069 | instance | Process install dependency map
2026-04-02 16:36:33.037074 | instance | the full traceback was:
2026-04-02 16:36:33.037078 | instance |
2026-04-02 16:36:33.037083 | instance | Traceback (most recent call last):
2026-04-02 16:36:33.037087 | 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-04-02 16:36:33.037092 | instance |     exit_code = cli.run()
2026-04-02 16:36:33.037096 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run
2026-04-02 16:36:33.037100 | instance |     return context.CLIARGS()
2026-04-02 16:36:33.037104 | 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-04-02 16:36:33.037109 | instance |     return wrapped_method(*args, **kwargs)
2026-04-02 16:36:33.037113 | 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-04-02 16:36:33.037117 | instance |     self._execute_install_collection(
2026-04-02 16:36:33.037121 | 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-04-02 16:36:33.037126 | instance |     install_collections(
2026-04-02 16:36:33.037131 | 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-04-02 16:36:33.037135 | instance |     dependency_map = _resolve_depenency_map(
2026-04-02 16:36:33.037139 | 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-04-02 16:36:33.037156 | instance |     return collection_dep_resolver.resolve(
2026-04-02 16:36:33.037160 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-04-02 16:36:33.037164 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-04-02 16:36:33.037168 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-04-02 16:36:33.037172 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-04-02 16:36:33.037176 | 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-04-02 16:36:33.037180 | instance |     matches = self._p.find_matches(
2026-04-02 16:36:33.037184 | 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-04-02 16:36:33.037189 | instance |     match for match in self._find_matches(list(requirements))
2026-04-02 16:36:33.037193 | 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-04-02 16:36:33.037197 | instance |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-04-02 16:36:33.037201 | 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-04-02 16:36:33.037205 | instance |     return set(
2026-04-02 16:36:33.037209 | 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-04-02 16:36:33.037213 | instance |     return set(
2026-04-02 16:36:33.037217 | 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-04-02 16:36:33.037221 | instance |     raise last_error
2026-04-02 16:36:33.037229 | 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-04-02 16:36:33.037233 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-04-02 16:36:33.037237 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped
2026-04-02 16:36:33.037241 | instance |     return method(self, *args, **kwargs)
2026-04-02 16:36:33.037245 | 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-04-02 16:36:33.037249 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-04-02 16:36:33.037253 | 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-04-02 16:36:33.037257 | instance |     return call_retryable_function()
2026-04-02 16:36:33.037265 | 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-04-02 16:36:33.037269 | instance |     res = path_cache
2026-04-02 16:36:33.037273 | instance | KeyError: 'results'
2026-04-02 16:36:33.037277 | instance |
2026-04-02 16:36:33.037281 | instance | ansible-galaxy
2026-04-02 16:36:33.037285 | instance |   config file = None
2026-04-02 16:36:33.037289 | instance |   configured module search path =
2026-04-02 16:36:33.037293 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-04-02 16:36:33.037297 | 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-04-02 16:36:33.037351 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-04-02 16:36:33.037356 | instance |   python version = 3.10.12 (main, Mar  3 2026, 11:56:32)  (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-04-02 16:36:33.037360 | instance |   jinja version = 3.1.6
2026-04-02 16:36:33.037364 | instance |   libyaml = True
2026-04-02 16:36:33.037368 | instance | No config file found; using defaults
2026-04-02 16:36:33.037372 | instance | : Collection ansible.netcommon does not support Ansible version
2026-04-02 16:36:33.037376 | instance | 2.17.12
2026-04-02 16:36:33.037380 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-04-02 16:36:33.037384 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-04-02 16:36:33.037388 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-04-02 16:36:33.037392 | instance | unexpected error when getting available versions of collection ansible.posix:
2026-04-02 16:36:33.037396 | instance | 'results'
2026-04-02 16:36:33.037400 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-04-02 16:36:33.037404 | instance |
2026-04-02 16:36:33.037773 | instance | Traceback (most recent call last):
2026-04-02 16:36:33.038253 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-04-02 16:36:33.038888 | instance |     sys.exit(main())
2026-04-02 16:36:33.039311 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-04-02 16:36:33.040327 | instance |     return self.main(*args, **kwargs)
2026-04-02 16:36:33.040703 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-04-02 16:36:33.041514 | instance |     rv = self.invoke(ctx)
2026-04-02 16:36:33.041673 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-04-02 16:36:33.042179 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-04-02 16:36:33.042388 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-04-02 16:36:33.042779 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-04-02 16:36:33.042965 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-04-02 16:36:33.043307 | instance |     return callback(*args, **kwargs)
2026-04-02 16:36:33.043495 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-04-02 16:36:33.043788 | instance |     return func(*args, **kwargs)
2026-04-02 16:36:33.043973 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func
2026-04-02 16:36:33.044210 | instance |     return f(get_current_context(), *args, **kwargs)
2026-04-02 16:36:33.044367 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-04-02 16:36:33.044618 | instance |     return func(ctx)
2026-04-02 16:36:33.044794 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test
2026-04-02 16:36:33.045048 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-04-02 16:36:33.045229 | 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-04-02 16:36:33.045472 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-04-02 16:36:33.045647 | 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-04-02 16:36:33.045946 | instance |     scenario.config.runtime.prepare_environment(
2026-04-02 16:36:33.046119 | 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-04-02 16:36:33.046447 | instance |     self.install_collection(
2026-04-02 16:36:33.046630 | 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-04-02 16:36:33.046966 | instance |     raise InvalidPrerequisiteError(msg)
2026-04-02 16:36:33.049472 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:1.6.0, returned 250 code:
2026-04-02 16:36:33.049479 | instance | Starting galaxy collection install process
2026-04-02 16:36:33.049484 | instance | Process install dependency map
2026-04-02 16:36:33.049490 | instance | the full traceback was:
2026-04-02 16:36:33.049495 | instance |
2026-04-02 16:36:33.049501 | instance | Traceback (most recent call last):
2026-04-02 16:36:33.049506 | 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-04-02 16:36:33.049512 | instance |     exit_code = cli.run()
2026-04-02 16:36:33.049518 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run
2026-04-02 16:36:33.049523 | instance |     return context.CLIARGS['func']()
2026-04-02 16:36:33.049529 | 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-04-02 16:36:33.049534 | instance |     return wrapped_method(*args, **kwargs)
2026-04-02 16:36:33.049540 | 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-04-02 16:36:33.049545 | instance |     self._execute_install_collection(
2026-04-02 16:36:33.049550 | 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-04-02 16:36:33.049556 | instance |     install_collections(
2026-04-02 16:36:33.049562 | 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-04-02 16:36:33.049567 | instance |     dependency_map = _resolve_depenency_map(
2026-04-02 16:36:33.049572 | 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-04-02 16:36:33.049578 | instance |     return collection_dep_resolver.resolve(
2026-04-02 16:36:33.049584 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-04-02 16:36:33.049589 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-04-02 16:36:33.049595 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-04-02 16:36:33.049600 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-04-02 16:36:33.049606 | 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-04-02 16:36:33.049611 | instance |     matches = self._p.find_matches(
2026-04-02 16:36:33.049616 | 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-04-02 16:36:33.049622 | instance |     match for match in self._find_matches(list(requirements[identifier]))
2026-04-02 16:36:33.049628 | 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-04-02 16:36:33.049639 | instance |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-04-02 16:36:33.049645 | 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-04-02 16:36:33.049650 | instance |     return set(
2026-04-02 16:36:33.049656 | 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-04-02 16:36:33.049661 | instance |     return set(
2026-04-02 16:36:33.049667 | 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-04-02 16:36:33.049672 | instance |     raise last_error
2026-04-02 16:36:33.049680 | 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-04-02 16:36:33.049686 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-04-02 16:36:33.049692 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped
2026-04-02 16:36:33.049697 | instance |     return method(self, *args, **kwargs)
2026-04-02 16:36:33.049703 | 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-04-02 16:36:33.049708 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-04-02 16:36:33.049719 | 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-04-02 16:36:33.049736 | instance |     return call_retryable_function()
2026-04-02 16:36:33.049742 | 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-04-02 16:36:33.049747 | instance |     res = path_cache['results']
2026-04-02 16:36:33.049753 | instance | KeyError: 'results'
2026-04-02 16:36:33.049758 | instance |
2026-04-02 16:36:33.049764 | instance | ansible-galaxy [core 2.17.12]
2026-04-02 16:36:33.049769 | instance |   config file = None
2026-04-02 16:36:33.049775 | 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-04-02 16:36:33.049782 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-04-02 16:36:33.049788 | 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-04-02 16:36:33.049794 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-04-02 16:36:33.049799 | instance |   python version = 3.10.12 (main, Mar  3 2026, 11:56:32) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-04-02 16:36:33.049805 | instance |   jinja version = 3.1.6
2026-04-02 16:36:33.049810 | instance |   libyaml = True
2026-04-02 16:36:33.049816 | instance | No config file found; using defaults
2026-04-02 16:36:33.049822 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-04-02 16:36:33.049828 | instance | 2.17.12
2026-04-02 16:36:33.049834 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-04-02 16:36:33.049839 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-04-02 16:36:33.049845 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-04-02 16:36:33.049855 | instance | unexpected error when getting available versions of collection ansible.posix:
2026-04-02 16:36:33.049861 | instance | 'results'
2026-04-02 16:36:33.049866 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-04-02 16:36:33.049872 | instance |
2026-04-02 16:36:33.343343 | instance | ERROR
2026-04-02 16:36:33.343663 | instance | {
2026-04-02 16:36:33.343807 | instance |   "delta": "0:00:10.045905",
2026-04-02 16:36:33.343861 | instance |   "end": "2026-04-02 16:36:33.159534",
2026-04-02 16:36:33.343903 | instance |   "msg": "non-zero return code",
2026-04-02 16:36:33.343944 | instance |   "rc": 1,
2026-04-02 16:36:33.343988 | instance |   "start": "2026-04-02 16:36:23.113629"
2026-04-02 16:36:33.344028 | instance | }
failure
2026-04-02 16:36:33.351290 | 
2026-04-02 16:36:33.351352 | PLAY RECAP
2026-04-02 16:36:33.351399 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-02 16:36:33.351421 | 
2026-04-02 16:36:33.539865 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-02 16:36:33.624731 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-02 16:36:34.352833 | 
2026-04-02 16:36:34.353059 | PLAY [all]
2026-04-02 16:36:34.369703 | 
2026-04-02 16:36:34.470554 | TASK [gather-host-logs : creating directory for system status]
2026-04-02 16:36:34.832601 | instance | changed
2026-04-02 16:36:34.842825 | 
2026-04-02 16:36:34.842979 | TASK [gather-host-logs : Get logs for each host]
2026-04-02 16:36:35.189245 | instance | + systemd-cgls --full --all --no-pager
2026-04-02 16:36:35.202622 | instance | + ip addr
2026-04-02 16:36:35.204393 | instance | + ip route
2026-04-02 16:36:35.205884 | instance | + lsblk
2026-04-02 16:36:35.208133 | instance | + mount
2026-04-02 16:36:35.209556 | instance | + docker images
2026-04-02 16:36:35.210000 | instance | /bin/bash: line 7: docker: command not found
2026-04-02 16:36:35.210223 | instance | + brctl show
2026-04-02 16:36:35.210606 | instance | /bin/bash: line 8: brctl: command not found
2026-04-02 16:36:35.210843 | instance | + ps aux --sort=-%mem
2026-04-02 16:36:35.223838 | instance | + dpkg -l
2026-04-02 16:36:35.231712 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-02 16:36:35.232159 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-02 16:36:35.232190 | instance | /bin/bash: line 11: docker: command not found
2026-04-02 16:36:35.232550 | instance | + '[' '!' -z '' ']'
2026-04-02 16:36:35.463360 | instance | ok: Runtime: 0:00:00.049159
2026-04-02 16:36:35.540644 | 
2026-04-02 16:36:35.540835 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-02 16:36:36.235016 | instance | changed:
2026-04-02 16:36:36.235202 | instance | created directory /var/lib/zuul/builds/579d792498c347b7aa1249422e103993/work/logs/instance
2026-04-02 16:36:36.235244 | instance | cd+++++++++ system/
2026-04-02 16:36:36.235274 | instance | >f+++++++++ system/brctl-show.txt
2026-04-02 16:36:36.235311 | instance | >f+++++++++ system/docker-images.txt
2026-04-02 16:36:36.235341 | instance | >f+++++++++ system/ip-addr.txt
2026-04-02 16:36:36.235370 | instance | >f+++++++++ system/ip-route.txt
2026-04-02 16:36:36.235399 | instance | >f+++++++++ system/lsblk.txt
2026-04-02 16:36:36.235426 | instance | >f+++++++++ system/mount.txt
2026-04-02 16:36:36.235456 | instance | >f+++++++++ system/packages.txt
2026-04-02 16:36:36.235482 | instance | >f+++++++++ system/ps.txt
2026-04-02 16:36:36.235507 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-02 16:36:36.247216 | 
2026-04-02 16:36:36.247286 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-02 16:36:36.446856 | instance | changed: "values"
2026-04-02 16:36:36.636271 | instance | changed: "releases"
2026-04-02 16:36:36.711666 | 
2026-04-02 16:36:36.711837 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-02 16:36:36.920825 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-02 16:36:37.249256 | instance | ok: Runtime: 0:00:00.007511
2026-04-02 16:36:37.256782 | 
2026-04-02 16:36:37.256876 | TASK [helm-release-status : Downloads logs to executor]
2026-04-02 16:36:37.772804 | instance | changed:
2026-04-02 16:36:37.772972 | instance | cd+++++++++ helm/
2026-04-02 16:36:37.773002 | instance | cd+++++++++ helm/releases/
2026-04-02 16:36:37.773025 | instance | cd+++++++++ helm/values/
2026-04-02 16:36:37.782046 | 
2026-04-02 16:36:37.782112 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-02 16:36:37.991029 | instance | changed
2026-04-02 16:36:38.221576 | 
2026-04-02 16:36:38.221712 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-02 16:36:38.416251 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-02 16:36:38.416527 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-02 16:36:38.422291 | instance | environment: line 1: kubectl: command not found
2026-04-02 16:36:38.424709 | instance | xargs: environment: line 1: kubectl: command not found
2026-04-02 16:36:38.424823 | instance | warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-02 16:36:38.426069 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-02 16:36:38.427779 | instance | environment: line 1: kubectl: command not found
2026-04-02 16:36:38.428677 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-02 16:36:38.429376 | instance | environment: line 1: kubectl: command not found
2026-04-02 16:36:38.430203 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-02 16:36:38.432065 | instance | environment: line 1: kubectl: command not found
2026-04-02 16:36:38.433545 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-02 16:36:38.755011 | instance | ok: Runtime: 0:00:00.026884
2026-04-02 16:36:38.762124 | 
2026-04-02 16:36:38.762212 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-02 16:36:38.959229 | instance | changed
2026-04-02 16:36:39.173591 | 
2026-04-02 16:36:39.173727 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-02 16:36:39.424573 | instance | environment: line 5: kubectl: command not found
2026-04-02 16:36:39.425437 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-02 16:36:39.425509 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-02 16:36:39.427694 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-02 16:36:39.729068 | instance | ok: Runtime: 0:00:00.010483
2026-04-02 16:36:39.734642 | 
2026-04-02 16:36:39.734707 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-02 16:36:40.229302 | instance | changed:
2026-04-02 16:36:40.229553 | instance | cd+++++++++ objects/
2026-04-02 16:36:40.229595 | instance | cd+++++++++ objects/cluster/
2026-04-02 16:36:40.229625 | instance | cd+++++++++ objects/namespaced/
2026-04-02 16:36:40.239054 | 
2026-04-02 16:36:40.239124 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-02 16:36:40.434707 | instance | changed
2026-04-02 16:36:40.441813 | 
2026-04-02 16:36:40.441903 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-02 16:36:40.650147 | instance | changed
2026-04-02 16:36:40.750260 | 
2026-04-02 16:36:40.750456 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-02 16:36:40.965734 | instance | environment: line 3: kubectl: command not found
2026-04-02 16:36:41.371012 | instance | ok: Runtime: 0:00:00.009886
2026-04-02 16:36:41.399070 | 
2026-04-02 16:36:41.399137 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-02 16:36:41.912521 | instance | changed:
2026-04-02 16:36:41.912666 | instance | cd+++++++++ pod-logs/
2026-04-02 16:36:41.912694 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-02 16:36:41.922232 | 
2026-04-02 16:36:41.922294 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-02 16:36:42.125932 | instance | changed
2026-04-02 16:36:42.141612 | 
2026-04-02 16:36:42.141764 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-02 16:36:42.357324 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-02 16:36:42.724345 | instance | ok: Runtime: 0:00:00.037500
2026-04-02 16:36:42.731392 | 
2026-04-02 16:36:42.731489 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-02 16:36:42.949871 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-02 16:36:42.979729 | instance | ceph-mgr endpoints:
2026-04-02 16:36:43.265886 | instance | ok: Runtime: 0:00:00.036472
2026-04-02 16:36:43.274763 | 
2026-04-02 16:36:43.274880 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-02 16:36:43.502236 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-02 16:36:43.815058 | instance | ok: Runtime: 0:00:00.035701
2026-04-02 16:36:43.821316 | 
2026-04-02 16:36:43.821480 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-02 16:36:44.318633 | instance | changed: cd+++++++++ prometheus/
2026-04-02 16:36:44.345506 | 
2026-04-02 16:36:44.345611 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-02 16:36:44.590701 | instance | changed
2026-04-02 16:36:44.739112 | 
2026-04-02 16:36:44.739275 | TASK [gather-selenium-data : Get selenium data]
2026-04-02 16:36:44.954834 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-02 16:36:44.956350 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-02 16:36:45.404565 | instance | ERROR
2026-04-02 16:36:45.404856 | instance | {
2026-04-02 16:36:45.405025 | instance |   "delta": "0:00:00.007389",
2026-04-02 16:36:45.405086 | instance |   "end": "2026-04-02 16:36:44.956756",
2026-04-02 16:36:45.405130 | instance |   "msg": "non-zero return code",
2026-04-02 16:36:45.405170 | instance |   "rc": 1,
2026-04-02 16:36:45.405211 | instance |   "start": "2026-04-02 16:36:44.949367"
2026-04-02 16:36:45.405250 | instance | }
2026-04-02 16:36:45.405301 | instance | ERROR: Ignoring Errors
2026-04-02 16:36:45.412121 | 
2026-04-02 16:36:45.412211 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-02 16:36:45.908246 | instance | changed: cd+++++++++ selenium/
2026-04-02 16:36:45.914730 | 
2026-04-02 16:36:45.914783 | PLAY RECAP
2026-04-02 16:36:45.914831 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-02 16:36:45.914856 | 
2026-04-02 16:36:46.064385 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-02 16:36:46.082557 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-02 16:36:46.733508 | 
2026-04-02 16:36:46.733647 | PLAY [all]
2026-04-02 16:36:46.745611 | 
2026-04-02 16:36:46.745764 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-02 16:36:46.783450 | instance | skipping: Conditional result was False
2026-04-02 16:36:46.789474 | 
2026-04-02 16:36:46.789581 | TASK [fetch-output : Set log path for single node]
2026-04-02 16:36:46.832314 | instance | ok
2026-04-02 16:36:46.838157 | 
2026-04-02 16:36:46.992389 | LOOP [fetch-output : Ensure local output dirs]
2026-04-02 16:36:47.604169 | instance -> localhost | ok: "/var/lib/zuul/builds/579d792498c347b7aa1249422e103993/work/logs"
2026-04-02 16:36:47.930690 | instance -> localhost | changed: "/var/lib/zuul/builds/579d792498c347b7aa1249422e103993/work/artifacts"
2026-04-02 16:36:48.194494 | instance -> localhost | changed: "/var/lib/zuul/builds/579d792498c347b7aa1249422e103993/work/docs"
2026-04-02 16:36:48.209638 | 
2026-04-02 16:36:48.209756 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-02 16:36:48.878874 | instance | changed: .d..t...... ./
2026-04-02 16:36:48.879137 | instance | changed: All items complete
2026-04-02 16:36:48.879168 | 
2026-04-02 16:36:49.372883 | instance | changed: .d..t...... ./
2026-04-02 16:36:49.826899 | instance | changed: .d..t...... ./
2026-04-02 16:36:49.853879 | 
2026-04-02 16:36:49.854004 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-02 16:36:50.442139 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006860
2026-04-02 16:36:50.728112 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008299
2026-04-02 16:36:50.747457 | 
2026-04-02 16:36:50.747573 | PLAY [all]
2026-04-02 16:36:50.754861 | 
2026-04-02 16:36:50.754940 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-02 16:36:51.190806 | instance | changed
2026-04-02 16:36:51.196922 | 
2026-04-02 16:36:51.196991 | PLAY RECAP
2026-04-02 16:36:51.197039 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-02 16:36:51.197063 | 
2026-04-02 16:36:51.318233 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-02 16:36:51.500282 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-02 16:36:52.196080 | 
2026-04-02 16:36:52.196220 | PLAY [localhost]
2026-04-02 16:36:52.207002 | 
2026-04-02 16:36:52.207145 | TASK [Generate Zuul manifest]
2026-04-02 16:36:52.225652 | localhost | ok
2026-04-02 16:36:52.244665 | 
2026-04-02 16:36:52.244791 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-02 16:36:52.712566 | localhost | changed
2026-04-02 16:36:52.729628 | 
2026-04-02 16:36:52.729738 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-02 16:36:52.862564 | localhost | ok
2026-04-02 16:36:52.871610 | 
2026-04-02 16:36:52.871704 | TASK [Upload logs]
2026-04-02 16:36:52.902298 | localhost | ok
2026-04-02 16:36:53.006422 | 
2026-04-02 16:36:53.006531 | TASK [Set zuul-log-path fact]
2026-04-02 16:36:53.026503 | localhost | ok
2026-04-02 16:36:53.038819 | 
2026-04-02 16:36:53.038907 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-02 16:36:53.073465 | localhost | ok
2026-04-02 16:36:53.087369 | 
2026-04-02 16:36:53.087559 | TASK [upload-logs : Create log directories]
2026-04-02 16:36:53.583639 | localhost | changed
2026-04-02 16:36:53.670355 | 
2026-04-02 16:36:53.670532 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-02 16:36:54.143729 | localhost -> localhost | ok: Runtime: 0:00:00.091928
2026-04-02 16:36:54.150402 | 
2026-04-02 16:36:54.150469 | TASK [upload-logs : Upload logs to log server]
2026-04-02 16:36:54.724245 | localhost | Output suppressed because no_log was given
2026-04-02 16:36:54.729223 | 
2026-04-02 16:36:54.729314 | LOOP [upload-logs : Compress console log and json output]
2026-04-02 16:36:54.778820 | localhost | skipping: Conditional result was False
2026-04-02 16:36:54.925740 | localhost | skipping: Conditional result was False
2026-04-02 16:36:54.935783 | 
2026-04-02 16:36:54.936042 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-02 16:36:54.988283 | localhost | skipping: Conditional result was False
2026-04-02 16:36:54.988680 | 
2026-04-02 16:36:54.994424 | localhost | skipping: Conditional result was False
2026-04-02 16:36:55.011588 | 
2026-04-02 16:36:55.011790 | LOOP [upload-logs : Upload console log and json output]