2026-02-26 18:50:59.512803 | Job console starting
2026-02-26 18:50:59.527367 | Updating git repos
2026-02-26 18:50:59.604912 | Cloning repos into workspace
2026-02-26 18:50:59.655639 | Restoring repo states
2026-02-26 18:50:59.675516 | Merging changes
2026-02-26 18:51:00.575792 | Checking out repos
2026-02-26 18:51:00.763609 | Preparing playbooks
2026-02-26 18:51:03.116351 | Running Ansible setup
2026-02-26 18:51:06.407199 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-26 18:51:07.113513 | 
2026-02-26 18:51:07.113692 | PLAY [localhost]
2026-02-26 18:51:07.122192 | 
2026-02-26 18:51:07.122266 | TASK [Gathering Facts]
2026-02-26 18:51:07.955418 | localhost | ok
2026-02-26 18:51:07.963600 | 
2026-02-26 18:51:07.963676 | TASK [Setup log path fact]
2026-02-26 18:51:07.983350 | localhost | ok
2026-02-26 18:51:07.994939 | 
2026-02-26 18:51:07.995003 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 18:51:08.024880 | localhost | ok
2026-02-26 18:51:08.033515 | 
2026-02-26 18:51:08.033581 | TASK [emit-job-header : Print job information]
2026-02-26 18:51:08.062600 | # Job Information
2026-02-26 18:51:08.062840 | Ansible Version: 2.16.16
2026-02-26 18:51:08.062910 | Job: atmosphere-molecule-aio-openvswitch
2026-02-26 18:51:08.062958 | Pipeline: check
2026-02-26 18:51:08.063001 | Executor: 0a8996d2b663
2026-02-26 18:51:08.063044 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3683
2026-02-26 18:51:08.063094 | Event ID: f0b11630-1343-11f1-9906-2bb10b041db7
2026-02-26 18:51:08.066858 | 
2026-02-26 18:51:08.066925 | LOOP [emit-job-header : Print node information]
2026-02-26 18:51:08.149397 | localhost | ok:
2026-02-26 18:51:08.149645 | localhost | # Node Information
2026-02-26 18:51:08.149685 | localhost | Inventory Hostname: instance
2026-02-26 18:51:08.149715 | localhost | Hostname: np0000157233
2026-02-26 18:51:08.149742 | localhost | Username: zuul
2026-02-26 18:51:08.149772 | localhost | Distro: Ubuntu 22.04
2026-02-26 18:51:08.149800 | localhost | Provider: yul1
2026-02-26 18:51:08.149827 | localhost | Region: ca-ymq-1
2026-02-26 18:51:08.149853 | localhost | Label: ubuntu-jammy-16
2026-02-26 18:51:08.149879 | localhost | Product Name: OpenStack Nova
2026-02-26 18:51:08.149904 | localhost | Interface IP: 162.253.55.52
2026-02-26 18:51:08.161173 | 
2026-02-26 18:51:08.161358 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-26 18:51:08.519178 | localhost -> localhost | changed
2026-02-26 18:51:08.526378 | 
2026-02-26 18:51:08.526473 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-26 18:51:09.371699 | localhost -> localhost | changed
2026-02-26 18:51:09.380581 | 
2026-02-26 18:51:09.380653 | PLAY [all]
2026-02-26 18:51:09.389375 | 
2026-02-26 18:51:09.389435 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-26 18:51:09.606166 | instance -> localhost | ok
2026-02-26 18:51:09.614716 | 
2026-02-26 18:51:09.614815 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-26 18:51:09.649998 | instance | ok
2026-02-26 18:51:09.663813 | instance | included: /var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-26 18:51:09.670349 | 
2026-02-26 18:51:09.670433 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-26 18:51:10.400509 | instance -> localhost | Generating public/private rsa key pair.
2026-02-26 18:51:10.400769 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/work/00e1cdd0e2ef4e15b63bf7f9c29a29ae_id_rsa
2026-02-26 18:51:10.400834 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/work/00e1cdd0e2ef4e15b63bf7f9c29a29ae_id_rsa.pub
2026-02-26 18:51:10.400884 | instance -> localhost | The key fingerprint is:
2026-02-26 18:51:10.400931 | instance -> localhost | SHA256:utQLMf821vWr463ajDsjZs4yq4brYZcskD1bqxG0uu8 zuul-build-sshkey
2026-02-26 18:51:10.401028 | instance -> localhost | The key's randomart image is:
2026-02-26 18:51:10.401078 | instance -> localhost | +---[RSA 3072]----+
2026-02-26 18:51:10.401133 | instance -> localhost | |                 |
2026-02-26 18:51:10.401181 | instance -> localhost | |                 |
2026-02-26 18:51:10.401214 | instance -> localhost | |   .             |
2026-02-26 18:51:10.401242 | instance -> localhost | |  + .            |
2026-02-26 18:51:10.401270 | instance -> localhost | | o = .o S        |
2026-02-26 18:51:10.401297 | instance -> localhost | |  o * o*      .  |
2026-02-26 18:51:10.401323 | instance -> localhost | | . *.=+ o  . . . |
2026-02-26 18:51:10.401350 | instance -> localhost | |  o.*o =.*+o=.. .|
2026-02-26 18:51:10.401380 | instance -> localhost | | .+Eo.o.O=o=**+o.|
2026-02-26 18:51:10.401408 | instance -> localhost | +----[SHA256]-----+
2026-02-26 18:51:10.401470 | instance -> localhost | ok: Runtime: 0:00:00.351948
2026-02-26 18:51:10.408226 | 
2026-02-26 18:51:10.408309 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-26 18:51:10.441885 | instance | ok
2026-02-26 18:51:10.452659 | instance | included: /var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-26 18:51:10.459736 | 
2026-02-26 18:51:10.459792 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-26 18:51:10.483007 | instance | skipping: Conditional result was False
2026-02-26 18:51:10.491660 | 
2026-02-26 18:51:10.491745 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-26 18:51:10.929302 | instance | changed
2026-02-26 18:51:10.934179 | 
2026-02-26 18:51:10.934241 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-26 18:51:11.097927 | instance | ok
2026-02-26 18:51:11.105052 | 
2026-02-26 18:51:11.105148 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-26 18:51:11.565551 | instance | changed
2026-02-26 18:51:11.571680 | 
2026-02-26 18:51:11.571743 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-26 18:51:12.028315 | instance | changed
2026-02-26 18:51:12.035476 | 
2026-02-26 18:51:12.035562 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-26 18:51:12.059437 | instance | skipping: Conditional result was False
2026-02-26 18:51:12.070180 | 
2026-02-26 18:51:12.070287 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-26 18:51:12.396814 | instance -> localhost | changed
2026-02-26 18:51:12.437838 | 
2026-02-26 18:51:12.437951 | TASK [add-build-sshkey : Add back temp key]
2026-02-26 18:51:12.733701 | instance -> localhost | Identity added: /var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/work/00e1cdd0e2ef4e15b63bf7f9c29a29ae_id_rsa (zuul-build-sshkey)
2026-02-26 18:51:12.733886 | instance -> localhost | ok: Runtime: 0:00:00.013116
2026-02-26 18:51:12.742079 | 
2026-02-26 18:51:12.742158 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-26 18:51:13.009756 | instance | ok
2026-02-26 18:51:13.015111 | 
2026-02-26 18:51:13.015174 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-26 18:51:13.037648 | instance | skipping: Conditional result was False
2026-02-26 18:51:13.048122 | 
2026-02-26 18:51:13.048185 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-26 18:51:13.314202 | instance | ok
2026-02-26 18:51:13.322970 | 
2026-02-26 18:51:13.323053 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-26 18:51:14.769746 | instance | Output suppressed because no_log was given
2026-02-26 18:51:14.777968 | 
2026-02-26 18:51:14.778028 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-26 18:51:14.969975 | instance | ok: "logs"
2026-02-26 18:51:14.970188 | instance | ok: All items complete
2026-02-26 18:51:14.970216 | 
2026-02-26 18:51:15.113663 | instance | ok: "artifacts"
2026-02-26 18:51:15.300702 | instance | ok: "docs"
2026-02-26 18:51:15.316845 | 
2026-02-26 18:51:15.316992 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-26 18:51:15.496868 | instance | changed: "logs"
2026-02-26 18:51:15.645548 | instance | changed: "artifacts"
2026-02-26 18:51:15.795729 | instance | changed: "docs"
2026-02-26 18:51:15.819776 | 
2026-02-26 18:51:15.820452 | PLAY RECAP
2026-02-26 18:51:15.820530 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-26 18:51:15.820588 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 18:51:15.820616 | 
2026-02-26 18:51:15.933156 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-26 18:51:15.942391 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-26 18:51:16.525654 | 
2026-02-26 18:51:16.525760 | PLAY [all]
2026-02-26 18:51:16.538470 | 
2026-02-26 18:51:16.538692 | TASK [setup-uv : Extract archive]
2026-02-26 18:51:18.763467 | instance | changed
2026-02-26 18:51:18.771705 | 
2026-02-26 18:51:18.771780 | TASK [setup-uv : Print version]
2026-02-26 18:51:18.814212 | instance | uv 0.8.13
2026-02-26 18:51:19.306969 | instance | ok: Runtime: 0:00:00.012027
2026-02-26 18:51:19.315173 | 
2026-02-26 18:51:19.315222 | PLAY RECAP
2026-02-26 18:51:19.315267 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 18:51:19.315291 | 
2026-02-26 18:51:19.404068 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-26 18:51:19.415393 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1]
2026-02-26 18:51:19.990019 | 
2026-02-26 18:51:19.990119 | PLAY [all]
2026-02-26 18:51:20.001331 | 
2026-02-26 18:51:20.001431 | TASK [Install "jq" for log collection]
2026-02-26 18:51:31.597654 | instance | changed
2026-02-26 18:51:31.599720 | 
2026-02-26 18:51:31.599791 | PLAY RECAP
2026-02-26 18:51:31.599855 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 18:51:31.599917 | 
2026-02-26 18:51:31.698193 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1]
2026-02-26 18:51:31.710041 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-26 18:51:32.302448 | 
2026-02-26 18:51:32.302614 | PLAY [all]
2026-02-26 18:51:32.315449 | 
2026-02-26 18:51:32.315582 | TASK [Copy inventory file for Zuul]
2026-02-26 18:51:33.113387 | instance | changed
2026-02-26 18:51:33.118247 | 
2026-02-26 18:51:33.118319 | TASK [Switch "ansible_host" to private IP]
2026-02-26 18:51:33.426055 | instance | changed: 1 replacements made
2026-02-26 18:51:33.431297 | 
2026-02-26 18:51:33.431361 | TASK [Run Molecule scenario]
2026-02-26 18:51:33.709332 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-02-26 18:51:33.709444 | instance | Creating virtual environment at: .venv
2026-02-26 18:51:33.729539 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-26 18:51:33.790878 | instance | Downloading pygments (1.2MiB)
2026-02-26 18:51:33.792257 | instance | Downloading setuptools (1.1MiB)
2026-02-26 18:51:33.792514 | instance | Downloading rjsonnet (1.2MiB)
2026-02-26 18:51:33.794125 | instance | Downloading kubernetes (1.9MiB)
2026-02-26 18:51:33.796367 | instance | Downloading openstacksdk (1.7MiB)
2026-02-26 18:51:33.797122 | instance | Downloading ansible-core (2.1MiB)
2026-02-26 18:51:33.797685 | instance | Downloading cryptography (4.2MiB)
2026-02-26 18:51:33.797938 | instance | Downloading netaddr (2.2MiB)
2026-02-26 18:51:34.129927 | instance |    Building pyperclip==1.9.0
2026-02-26 18:51:34.160579 | instance |  Downloading rjsonnet
2026-02-26 18:51:34.298456 | instance |  Downloading pygments
2026-02-26 18:51:34.303099 | instance |  Downloading netaddr
2026-02-26 18:51:34.339106 | instance |  Downloading cryptography
2026-02-26 18:51:34.360530 | instance |  Downloading setuptools
2026-02-26 18:51:34.424384 | instance |  Downloading kubernetes
2026-02-26 18:51:34.460670 | instance |  Downloading ansible-core
2026-02-26 18:51:34.495105 | instance |  Downloading openstacksdk
2026-02-26 18:51:34.871122 | instance |       Built pyperclip==1.9.0
2026-02-26 18:51:35.075475 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-26 18:51:35.119715 | instance | Installed 79 packages in 41ms
2026-02-26 18:51:35.793731 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-02-26 18:51:36.499433 | instance | INFO     [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-02-26 18:51:36.499510 | instance | INFO     [aio > prerun] Performing prerun with role_name_check=0...
2026-02-26 18:51:43.736877 | instance | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.utils:>=2.9.0
2026-02-26 18:51:43.736965 | instance | ERROR    Command ansible-galaxy collection install -vvv ansible.utils:>=2.9.0, returned 250 code:
2026-02-26 18:51:43.736978 | instance | Starting galaxy collection install process
2026-02-26 18:51:43.736989 | instance | Process install dependency map
2026-02-26 18:51:43.736998 | instance | the full traceback was:
2026-02-26 18:51:43.737007 | instance |
2026-02-26 18:51:43.737016 | instance | Traceback (most recent call last):
2026-02-26 18:51:43.737026 | 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-26 18:51:43.737036 | instance |     exit_code = cli.run()
2026-02-26 18:51:43.737045 | 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-26 18:51:43.737054 | instance |     return context.CLIARGS()
2026-02-26 18:51:43.737063 | 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-26 18:51:43.737072 | instance |     return wrapped_method(*args, **kwargs)
2026-02-26 18:51:43.737081 | 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-26 18:51:43.737089 | instance |     self._execute_install_collection(
2026-02-26 18:51:43.737099 | 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-26 18:51:43.737108 | instance |     install_collections(
2026-02-26 18:51:43.737117 | 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-26 18:51:43.737126 | instance |     dependency_map = _resolve_depenency_map(
2026-02-26 18:51:43.737135 | 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-26 18:51:43.737145 | instance |     return collection_dep_resolver.resolve(
2026-02-26 18:51:43.737154 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-26 18:51:43.737181 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-26 18:51:43.737191 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-26 18:51:43.737200 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-26 18:51:43.737209 | 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-26 18:51:43.737218 | instance |     matches = self._p.find_matches(
2026-02-26 18:51:43.737227 | 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-26 18:51:43.737236 | instance |     match for match in self._find_matches(list(requirements))
2026-02-26 18:51:43.737247 | 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-26 18:51:43.737260 | instance |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-02-26 18:51:43.737273 | 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-26 18:51:43.737286 | instance |     return set(
2026-02-26 18:51:43.737299 | 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-26 18:51:43.737311 | instance |     return set(
2026-02-26 18:51:43.737325 | 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-26 18:51:43.737338 | instance |     raise last_error
2026-02-26 18:51:43.737351 | 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-26 18:51:43.737362 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-26 18:51:43.737374 | 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-26 18:51:43.737387 | instance |     return method(self, *args, **kwargs)
2026-02-26 18:51:43.737400 | 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-26 18:51:43.737413 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-26 18:51:43.737433 | 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-26 18:51:43.737445 | instance |     return call_retryable_function()
2026-02-26 18:51:43.737466 | 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-26 18:51:43.737475 | instance |     res = path_cache
2026-02-26 18:51:43.737484 | instance | KeyError: 'results'
2026-02-26 18:51:43.737537 | instance |
2026-02-26 18:51:43.737547 | instance | ansible-galaxy
2026-02-26 18:51:43.737556 | instance |   config file = None
2026-02-26 18:51:43.737564 | instance |   configured module search path =
2026-02-26 18:51:43.737573 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-26 18:51:43.737583 | 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-26 18:51:43.737602 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-26 18:51:43.737611 | 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-26 18:51:43.737620 | instance |   jinja version = 3.1.6
2026-02-26 18:51:43.737629 | instance |   libyaml = True
2026-02-26 18:51:43.737638 | instance | No config file found; using defaults
2026-02-26 18:51:43.737647 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-26 18:51:43.737671 | instance | : Collection ansible.netcommon does not support Ansible version
2026-02-26 18:51:43.737680 | instance | 2.17.12
2026-02-26 18:51:43.737689 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-26 18:51:43.737698 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-26 18:51:43.737707 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-26 18:51:43.737716 | instance | unexpected error when getting available versions of collection ansible.utils:
2026-02-26 18:51:43.737725 | instance | 'results'
2026-02-26 18:51:43.737734 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-26 18:51:43.737743 | instance |
2026-02-26 18:51:43.737755 | instance | Traceback (most recent call last):
2026-02-26 18:51:43.738120 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-02-26 18:51:43.738652 | instance |     sys.exit(main())
2026-02-26 18:51:43.738970 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-02-26 18:51:43.739633 | instance |     return self.main(*args, **kwargs)
2026-02-26 18:51:43.739916 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-02-26 18:51:43.740571 | instance |     rv = self.invoke(ctx)
2026-02-26 18:51:43.740845 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-02-26 18:51:43.741586 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-02-26 18:51:43.741863 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-02-26 18:51:43.742532 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-02-26 18:51:43.742809 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-02-26 18:51:43.743317 | instance |     return callback(*args, **kwargs)
2026-02-26 18:51:43.743605 | 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-26 18:51:43.744068 | instance |     return func(*args, **kwargs)
2026-02-26 18:51:43.744332 | 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-26 18:51:43.744677 | instance |     return f(get_current_context(), *args, **kwargs)
2026-02-26 18:51:43.744949 | 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-26 18:51:43.745400 | instance |     return func(ctx)
2026-02-26 18:51:43.745638 | 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-26 18:51:43.746084 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-02-26 18:51:43.746325 | 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-26 18:51:43.746519 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-02-26 18:51:43.746639 | 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-26 18:51:43.746832 | instance |     scenario.config.runtime.prepare_environment(
2026-02-26 18:51:43.746956 | 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-26 18:51:43.747194 | instance |     self.install_collection(
2026-02-26 18:51:43.747333 | 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-26 18:51:43.747555 | instance |     raise InvalidPrerequisiteError(msg)
2026-02-26 18:51:43.749451 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.utils:>=2.9.0, returned 250 code:
2026-02-26 18:51:43.749462 | instance | Starting galaxy collection install process
2026-02-26 18:51:43.749471 | instance | Process install dependency map
2026-02-26 18:51:43.749480 | instance | the full traceback was:
2026-02-26 18:51:43.749489 | instance |
2026-02-26 18:51:43.749497 | instance | Traceback (most recent call last):
2026-02-26 18:51:43.749506 | 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-26 18:51:43.749515 | instance |     exit_code = cli.run()
2026-02-26 18:51:43.749525 | 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-26 18:51:43.749534 | instance |     return context.CLIARGS['func']()
2026-02-26 18:51:43.749543 | 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-26 18:51:43.749551 | instance |     return wrapped_method(*args, **kwargs)
2026-02-26 18:51:43.749560 | 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-26 18:51:43.749569 | instance |     self._execute_install_collection(
2026-02-26 18:51:43.749578 | 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-26 18:51:43.749587 | instance |     install_collections(
2026-02-26 18:51:43.749596 | 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-26 18:51:43.749605 | instance |     dependency_map = _resolve_depenency_map(
2026-02-26 18:51:43.749613 | 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-26 18:51:43.749623 | instance |     return collection_dep_resolver.resolve(
2026-02-26 18:51:43.749631 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-26 18:51:43.749640 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-26 18:51:43.749650 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-26 18:51:43.749658 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-26 18:51:43.749668 | 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-26 18:51:43.749676 | instance |     matches = self._p.find_matches(
2026-02-26 18:51:43.749686 | 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-26 18:51:43.749695 | instance |     match for match in self._find_matches(list(requirements[identifier]))
2026-02-26 18:51:43.749705 | 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-26 18:51:43.749722 | instance |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-02-26 18:51:43.749731 | 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-26 18:51:43.749741 | instance |     return set(
2026-02-26 18:51:43.749750 | 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-26 18:51:43.749759 | instance |     return set(
2026-02-26 18:51:43.749768 | 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-26 18:51:43.749777 | instance |     raise last_error
2026-02-26 18:51:43.749791 | 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-26 18:51:43.749800 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-26 18:51:43.749809 | 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-26 18:51:43.749818 | instance |     return method(self, *args, **kwargs)
2026-02-26 18:51:43.749827 | 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-26 18:51:43.749836 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-26 18:51:43.749851 | 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-26 18:51:43.749861 | instance |     return call_retryable_function()
2026-02-26 18:51:43.749870 | 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-26 18:51:43.749878 | instance |     res = path_cache['results']
2026-02-26 18:51:43.749887 | instance | KeyError: 'results'
2026-02-26 18:51:43.749896 | instance |
2026-02-26 18:51:43.749905 | instance | ansible-galaxy [core 2.17.12]
2026-02-26 18:51:43.749914 | instance |   config file = None
2026-02-26 18:51:43.749922 | instance |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-02-26 18:51:43.749931 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-26 18:51:43.749941 | 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-26 18:51:43.749951 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-26 18:51:43.749966 | 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-26 18:51:43.749991 | instance |   jinja version = 3.1.6
2026-02-26 18:51:43.750000 | instance |   libyaml = True
2026-02-26 18:51:43.750009 | instance | No config file found; using defaults
2026-02-26 18:51:43.750018 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-26 18:51:43.750027 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-02-26 18:51:43.750036 | instance | 2.17.12
2026-02-26 18:51:43.750045 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-26 18:51:43.750060 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-26 18:51:43.750069 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-26 18:51:43.750079 | instance | unexpected error when getting available versions of collection ansible.utils:
2026-02-26 18:51:43.750088 | instance | 'results'
2026-02-26 18:51:43.750096 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-26 18:51:43.750105 | instance |
2026-02-26 18:51:43.969857 | instance | ERROR
2026-02-26 18:51:43.970080 | instance | {
2026-02-26 18:51:43.970121 | instance |   "delta": "0:00:10.247445",
2026-02-26 18:51:43.970151 | instance |   "end": "2026-02-26 18:51:43.886956",
2026-02-26 18:51:43.970178 | instance |   "msg": "non-zero return code",
2026-02-26 18:51:43.970203 | instance |   "rc": 1,
2026-02-26 18:51:43.970231 | instance |   "start": "2026-02-26 18:51:33.639511"
2026-02-26 18:51:43.970257 | instance | }
failure
2026-02-26 18:51:43.974038 | 
2026-02-26 18:51:43.974107 | PLAY RECAP
2026-02-26 18:51:43.974167 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-26 18:51:43.974198 | 
2026-02-26 18:51:44.084108 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-26 18:51:44.094185 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1]
2026-02-26 18:51:44.673977 | 
2026-02-26 18:51:44.674082 | PLAY [all]
2026-02-26 18:51:44.687164 | 
2026-02-26 18:51:44.687238 | TASK [gather-host-logs : creating directory for system status]
2026-02-26 18:51:45.012890 | instance | changed
2026-02-26 18:51:45.018525 | 
2026-02-26 18:51:45.018606 | TASK [gather-host-logs : Get logs for each host]
2026-02-26 18:51:45.363104 | instance | + systemd-cgls --full --all --no-pager
2026-02-26 18:51:45.376937 | instance | + ip addr
2026-02-26 18:51:45.379761 | instance | + ip route
2026-02-26 18:51:45.381878 | instance | + lsblk
2026-02-26 18:51:45.386607 | instance | + mount
2026-02-26 18:51:45.388856 | instance | + docker images
2026-02-26 18:51:45.389279 | instance | /bin/bash: line 7: docker: command not found
2026-02-26 18:51:45.389492 | instance | + brctl show
2026-02-26 18:51:45.389888 | instance | /bin/bash: line 8: brctl: command not found
2026-02-26 18:51:45.390090 | instance | + ps aux --sort=-%mem
2026-02-26 18:51:45.405969 | instance | + dpkg -l
2026-02-26 18:51:45.411580 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-02-26 18:51:45.412203 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-02-26 18:51:45.412301 | instance | /bin/bash: line 11: docker: command not found
2026-02-26 18:51:45.412675 | instance | + '[' '!' -z '' ']'
2026-02-26 18:51:45.552383 | instance | ok: Runtime: 0:00:00.055783
2026-02-26 18:51:45.560051 | 
2026-02-26 18:51:45.560116 | TASK [gather-host-logs : Downloads logs to executor]
2026-02-26 18:51:46.243296 | instance | changed:
2026-02-26 18:51:46.243955 | instance | created directory /var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/work/logs/instance
2026-02-26 18:51:46.244010 | instance | cd+++++++++ system/
2026-02-26 18:51:46.244042 | instance | >f+++++++++ system/brctl-show.txt
2026-02-26 18:51:46.244070 | instance | >f+++++++++ system/docker-images.txt
2026-02-26 18:51:46.244097 | instance | >f+++++++++ system/ip-addr.txt
2026-02-26 18:51:46.244127 | instance | >f+++++++++ system/ip-route.txt
2026-02-26 18:51:46.244155 | instance | >f+++++++++ system/lsblk.txt
2026-02-26 18:51:46.244182 | instance | >f+++++++++ system/mount.txt
2026-02-26 18:51:46.244211 | instance | >f+++++++++ system/packages.txt
2026-02-26 18:51:46.244238 | instance | >f+++++++++ system/ps.txt
2026-02-26 18:51:46.244264 | instance | >f+++++++++ system/systemd-cgls.txt
2026-02-26 18:51:46.253897 | 
2026-02-26 18:51:46.253961 | LOOP [helm-release-status : creating directory for helm release status]
2026-02-26 18:51:46.480847 | instance | changed: "values"
2026-02-26 18:51:46.663887 | instance | changed: "releases"
2026-02-26 18:51:46.677055 | 
2026-02-26 18:51:46.677173 | TASK [helm-release-status : Gather get release status for helm charts]
2026-02-26 18:51:46.873081 | instance | /bin/bash: line 3: kubectl: command not found
2026-02-26 18:51:47.213793 | instance | ok: Runtime: 0:00:00.005970
2026-02-26 18:51:47.220177 | 
2026-02-26 18:51:47.220286 | TASK [helm-release-status : Downloads logs to executor]
2026-02-26 18:51:47.743572 | instance | changed:
2026-02-26 18:51:47.743730 | instance | cd+++++++++ helm/
2026-02-26 18:51:47.743757 | instance | cd+++++++++ helm/releases/
2026-02-26 18:51:47.743778 | instance | cd+++++++++ helm/values/
2026-02-26 18:51:47.781119 | 
2026-02-26 18:51:47.781261 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-02-26 18:51:47.976677 | instance | changed
2026-02-26 18:51:47.983089 | 
2026-02-26 18:51:47.983155 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-02-26 18:51:48.169509 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-26 18:51:48.169607 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-26 18:51:48.174383 | instance | environment: line 1: kubectl: command not found
2026-02-26 18:51:48.175338 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-26 18:51:48.176867 | instance | environment: line 1: kubectl: command not found
2026-02-26 18:51:48.178624 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-26 18:51:48.179467 | instance | environment: line 1: kubectl: command not found
2026-02-26 18:51:48.180293 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-26 18:51:48.181945 | instance | environment: line 1: kubectl: command not found
2026-02-26 18:51:48.182872 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-26 18:51:48.184299 | instance | environment: line 1: kubectl: command not found
2026-02-26 18:51:48.184945 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-26 18:51:48.518560 | instance | ok: Runtime: 0:00:00.025838
2026-02-26 18:51:48.525320 | 
2026-02-26 18:51:48.525384 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-02-26 18:51:48.708145 | instance | changed
2026-02-26 18:51:48.714772 | 
2026-02-26 18:51:48.714839 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-02-26 18:51:48.902638 | instance | environment: line 5: kubectl: command not found
2026-02-26 18:51:48.903599 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-26 18:51:48.903749 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-26 18:51:48.903924 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-26 18:51:49.249060 | instance | ok: Runtime: 0:00:00.008697
2026-02-26 18:51:49.256413 | 
2026-02-26 18:51:49.256481 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-02-26 18:51:49.750153 | instance | changed:
2026-02-26 18:51:49.750355 | instance | cd+++++++++ objects/
2026-02-26 18:51:49.750395 | instance | cd+++++++++ objects/cluster/
2026-02-26 18:51:49.750426 | instance | cd+++++++++ objects/namespaced/
2026-02-26 18:51:49.760563 | 
2026-02-26 18:51:49.760636 | TASK [gather-pod-logs : creating directory for pod logs]
2026-02-26 18:51:49.959748 | instance | changed
2026-02-26 18:51:49.965410 | 
2026-02-26 18:51:49.965485 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-02-26 18:51:50.169973 | instance | changed
2026-02-26 18:51:50.176188 | 
2026-02-26 18:51:50.176265 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-02-26 18:51:50.357697 | instance | environment: line 3: kubectl: command not found
2026-02-26 18:51:50.710966 | instance | ok: Runtime: 0:00:00.007817
2026-02-26 18:51:50.717491 | 
2026-02-26 18:51:50.717563 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-02-26 18:51:51.185529 | instance | changed:
2026-02-26 18:51:51.185707 | instance | cd+++++++++ pod-logs/
2026-02-26 18:51:51.185746 | instance | cd+++++++++ pod-logs/failed-pods/
2026-02-26 18:51:51.196120 | 
2026-02-26 18:51:51.196187 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-02-26 18:51:51.379879 | instance | changed
2026-02-26 18:51:51.386853 | 
2026-02-26 18:51:51.386925 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-02-26 18:51:51.584457 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-26 18:51:51.923616 | instance | ok: Runtime: 0:00:00.038004
2026-02-26 18:51:51.930532 | 
2026-02-26 18:51:51.930599 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-02-26 18:51:52.119291 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-26 18:51:52.147944 | instance | ceph-mgr endpoints:
2026-02-26 18:51:52.463285 | instance | ok: Runtime: 0:00:00.034378
2026-02-26 18:51:52.469717 | 
2026-02-26 18:51:52.469807 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-02-26 18:51:52.666199 | instance | /bin/bash: line 4: kubectl: command not found
2026-02-26 18:51:52.998813 | instance | ok: Runtime: 0:00:00.036775
2026-02-26 18:51:53.003585 | 
2026-02-26 18:51:53.003705 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-02-26 18:51:53.482991 | instance | changed: cd+++++++++ prometheus/
2026-02-26 18:51:53.528846 | 
2026-02-26 18:51:53.528994 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-02-26 18:51:53.725181 | instance | changed
2026-02-26 18:51:53.730773 | 
2026-02-26 18:51:53.730858 | TASK [gather-selenium-data : Get selenium data]
2026-02-26 18:51:53.933410 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-02-26 18:51:53.934934 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-02-26 18:51:54.276144 | instance | ERROR
2026-02-26 18:51:54.276449 | instance | {
2026-02-26 18:51:54.276531 | instance |   "delta": "0:00:00.006687",
2026-02-26 18:51:54.276590 | instance |   "end": "2026-02-26 18:51:53.935274",
2026-02-26 18:51:54.276639 | instance |   "msg": "non-zero return code",
2026-02-26 18:51:54.276665 | instance |   "rc": 1,
2026-02-26 18:51:54.276688 | instance |   "start": "2026-02-26 18:51:53.928587"
2026-02-26 18:51:54.276710 | instance | }
2026-02-26 18:51:54.276733 | instance | ERROR: Ignoring Errors
2026-02-26 18:51:54.281598 | 
2026-02-26 18:51:54.281673 | TASK [gather-selenium-data : Downloads logs to executor]
2026-02-26 18:51:54.761735 | instance | changed: cd+++++++++ selenium/
2026-02-26 18:51:54.768024 | 
2026-02-26 18:51:54.768084 | PLAY RECAP
2026-02-26 18:51:54.768128 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-02-26 18:51:54.768150 | 
2026-02-26 18:51:54.866955 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1]
2026-02-26 18:51:54.874942 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-26 18:51:55.463796 | 
2026-02-26 18:51:55.464343 | PLAY [all]
2026-02-26 18:51:55.475460 | 
2026-02-26 18:51:55.475534 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 18:51:55.510957 | instance | skipping: Conditional result was False
2026-02-26 18:51:55.520279 | 
2026-02-26 18:51:55.520350 | TASK [fetch-output : Set log path for single node]
2026-02-26 18:51:55.562350 | instance | ok
2026-02-26 18:51:55.566787 | 
2026-02-26 18:51:55.566849 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 18:51:55.935093 | instance -> localhost | ok: "/var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/work/logs"
2026-02-26 18:51:56.131387 | instance -> localhost | changed: "/var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/work/artifacts"
2026-02-26 18:51:56.340266 | instance -> localhost | changed: "/var/lib/zuul/builds/00e1cdd0e2ef4e15b63bf7f9c29a29ae/work/docs"
2026-02-26 18:51:56.353643 | 
2026-02-26 18:51:56.353734 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 18:51:56.969428 | instance | changed: .d..t...... ./
2026-02-26 18:51:56.969617 | instance | changed: All items complete
2026-02-26 18:51:56.969645 | 
2026-02-26 18:51:57.409553 | instance | changed: .d..t...... ./
2026-02-26 18:51:57.861561 | instance | changed: .d..t...... ./
2026-02-26 18:51:57.889960 | 
2026-02-26 18:51:57.890092 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-26 18:51:58.278827 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007052
2026-02-26 18:51:58.483351 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006405
2026-02-26 18:51:58.503610 | 
2026-02-26 18:51:58.503830 | PLAY [all]
2026-02-26 18:51:58.515214 | 
2026-02-26 18:51:58.515304 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-26 18:51:58.893260 | instance | changed
2026-02-26 18:51:58.899768 | 
2026-02-26 18:51:58.899817 | PLAY RECAP
2026-02-26 18:51:58.899859 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-26 18:51:58.899880 | 
2026-02-26 18:51:59.001395 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-26 18:51:59.013189 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-26 18:51:59.627724 | 
2026-02-26 18:51:59.627852 | PLAY [localhost]
2026-02-26 18:51:59.637622 | 
2026-02-26 18:51:59.637695 | TASK [Generate Zuul manifest]
2026-02-26 18:51:59.657158 | localhost | ok
2026-02-26 18:51:59.674574 | 
2026-02-26 18:51:59.674660 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-26 18:52:00.010224 | localhost | changed
2026-02-26 18:52:00.020155 | 
2026-02-26 18:52:00.020251 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-26 18:52:00.051393 | localhost | ok
2026-02-26 18:52:00.059101 | 
2026-02-26 18:52:00.059163 | TASK [Upload logs]
2026-02-26 18:52:00.078949 | localhost | ok
2026-02-26 18:52:00.173584 | 
2026-02-26 18:52:00.173715 | TASK [Set zuul-log-path fact]
2026-02-26 18:52:00.192085 | localhost | ok
2026-02-26 18:52:00.205301 | 
2026-02-26 18:52:00.205373 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 18:52:00.235813 | localhost | ok
2026-02-26 18:52:00.245720 | 
2026-02-26 18:52:00.245802 | TASK [upload-logs : Create log directories]
2026-02-26 18:52:00.579886 | localhost | changed
2026-02-26 18:52:00.585986 | 
2026-02-26 18:52:00.586083 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-26 18:52:00.922110 | localhost -> localhost | ok: Runtime: 0:00:00.004683
2026-02-26 18:52:00.928452 | 
2026-02-26 18:52:00.928536 | TASK [upload-logs : Upload logs to log server]
2026-02-26 18:52:01.356159 | localhost | Output suppressed because no_log was given
2026-02-26 18:52:01.361111 | 
2026-02-26 18:52:01.361179 | LOOP [upload-logs : Compress console log and json output]
2026-02-26 18:52:01.412059 | localhost | skipping: Conditional result was False
2026-02-26 18:52:01.429685 | localhost | skipping: Conditional result was False
2026-02-26 18:52:01.445378 | 
2026-02-26 18:52:01.445537 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-26 18:52:01.489533 | localhost | skipping: Conditional result was False
2026-02-26 18:52:01.490005 | 
2026-02-26 18:52:01.493951 | localhost | skipping: Conditional result was False
2026-02-26 18:52:01.511356 | 
2026-02-26 18:52:01.511464 | LOOP [upload-logs : Upload console log and json output]