2026-02-18 05:10:42.857743 | Job console starting
2026-02-18 05:10:42.866851 | Updating git repos
2026-02-18 05:10:42.922441 | Cloning repos into workspace
2026-02-18 05:10:42.990435 | Restoring repo states
2026-02-18 05:10:43.010812 | Merging changes
2026-02-18 05:10:44.043193 | Checking out repos
2026-02-18 05:10:44.136494 | Preparing playbooks
2026-02-18 05:10:46.263681 | Running Ansible setup
2026-02-18 05:10:49.509761 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-18 05:10:50.107322 | 
2026-02-18 05:10:50.107467 | PLAY [localhost]
2026-02-18 05:10:50.115059 | 
2026-02-18 05:10:50.115127 | TASK [Gathering Facts]
2026-02-18 05:10:51.024435 | localhost | ok
2026-02-18 05:10:51.038605 | 
2026-02-18 05:10:51.038697 | TASK [Setup log path fact]
2026-02-18 05:10:51.060328 | localhost | ok
2026-02-18 05:10:51.075707 | 
2026-02-18 05:10:51.075780 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-18 05:10:51.103629 | localhost | ok
2026-02-18 05:10:51.111461 | 
2026-02-18 05:10:51.111550 | TASK [emit-job-header : Print job information]
2026-02-18 05:10:51.142086 | # Job Information
2026-02-18 05:10:51.142242 | Ansible Version: 2.16.15
2026-02-18 05:10:51.142285 | Job: atmosphere-molecule-csi-local-path-provisioner
2026-02-18 05:10:51.142316 | Pipeline: check
2026-02-18 05:10:51.142345 | Executor: 3a2793d2bd32
2026-02-18 05:10:51.142372 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3670
2026-02-18 05:10:51.142402 | Event ID: 168708f0-0c88-11f1-8abe-3cdb2ab418bc
2026-02-18 05:10:51.146124 | 
2026-02-18 05:10:51.146211 | LOOP [emit-job-header : Print node information]
2026-02-18 05:10:51.233435 | localhost | ok:
2026-02-18 05:10:51.233886 | localhost | # Node Information
2026-02-18 05:10:51.233960 | localhost | Inventory Hostname: instance
2026-02-18 05:10:51.234008 | localhost | Hostname: np0000156318
2026-02-18 05:10:51.234050 | localhost | Username: zuul
2026-02-18 05:10:51.234100 | localhost | Distro: Ubuntu 22.04
2026-02-18 05:10:51.234142 | localhost | Provider: yul1
2026-02-18 05:10:51.234184 | localhost | Region: ca-ymq-1
2026-02-18 05:10:51.234224 | localhost | Label: ubuntu-jammy
2026-02-18 05:10:51.234264 | localhost | Product Name: OpenStack Nova
2026-02-18 05:10:51.234304 | localhost | Interface IP: 199.204.45.35
2026-02-18 05:10:51.252061 | 
2026-02-18 05:10:51.252200 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-18 05:10:51.640448 | localhost -> localhost | changed
2026-02-18 05:10:51.650264 | 
2026-02-18 05:10:51.650362 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-18 05:10:52.515115 | localhost -> localhost | changed
2026-02-18 05:10:52.523848 | 
2026-02-18 05:10:52.523909 | PLAY [all]
2026-02-18 05:10:52.532262 | 
2026-02-18 05:10:52.532335 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-18 05:10:52.781555 | instance -> localhost | ok
2026-02-18 05:10:52.793223 | 
2026-02-18 05:10:52.793326 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-18 05:10:52.828800 | instance | ok
2026-02-18 05:10:52.846373 | instance | included: /var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-18 05:10:52.852322 | 
2026-02-18 05:10:52.852383 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-18 05:10:53.649334 | instance -> localhost | Generating public/private rsa key pair.
2026-02-18 05:10:53.649562 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/work/37dba1c7f7614d38b075dea482e9109c_id_rsa
2026-02-18 05:10:53.649603 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/work/37dba1c7f7614d38b075dea482e9109c_id_rsa.pub
2026-02-18 05:10:53.649635 | instance -> localhost | The key fingerprint is:
2026-02-18 05:10:53.649664 | instance -> localhost | SHA256:jztV1ZSE6y3DwR1tqg+j77gSVaR+ctqY5J5QzWVo72I zuul-build-sshkey
2026-02-18 05:10:53.649709 | instance -> localhost | The key's randomart image is:
2026-02-18 05:10:53.649739 | instance -> localhost | +---[RSA 3072]----+
2026-02-18 05:10:53.649774 | instance -> localhost | |           .. o++|
2026-02-18 05:10:53.649803 | instance -> localhost | |           ..o.o+|
2026-02-18 05:10:53.649832 | instance -> localhost | |          ..+.=o.|
2026-02-18 05:10:53.649859 | instance -> localhost | |         ..+.B.. |
2026-02-18 05:10:53.649887 | instance -> localhost | |        S.=.B.+  |
2026-02-18 05:10:53.649913 | instance -> localhost | |        .*.O+* . |
2026-02-18 05:10:53.649940 | instance -> localhost | |        oo*.E++  |
2026-02-18 05:10:53.649967 | instance -> localhost | |        o+.+ ..  |
2026-02-18 05:10:53.649997 | instance -> localhost | |        .o=+o    |
2026-02-18 05:10:53.650024 | instance -> localhost | +----[SHA256]-----+
2026-02-18 05:10:53.650091 | instance -> localhost | ok: Runtime: 0:00:00.406207
2026-02-18 05:10:53.662620 | 
2026-02-18 05:10:53.662697 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-18 05:10:53.706185 | instance | ok
2026-02-18 05:10:53.717863 | instance | included: /var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-18 05:10:53.731083 | 
2026-02-18 05:10:53.731171 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-18 05:10:53.759966 | instance | skipping: Conditional result was False
2026-02-18 05:10:53.767617 | 
2026-02-18 05:10:53.767699 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-18 05:10:54.216656 | instance | changed
2026-02-18 05:10:54.224053 | 
2026-02-18 05:10:54.224142 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-18 05:10:54.403234 | instance | ok
2026-02-18 05:10:54.409976 | 
2026-02-18 05:10:54.410045 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-18 05:10:54.870617 | instance | changed
2026-02-18 05:10:54.876915 | 
2026-02-18 05:10:54.876992 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-18 05:10:55.296865 | instance | changed
2026-02-18 05:10:55.301785 | 
2026-02-18 05:10:55.301844 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-18 05:10:55.325416 | instance | skipping: Conditional result was False
2026-02-18 05:10:55.337021 | 
2026-02-18 05:10:55.337124 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-18 05:10:55.704905 | instance -> localhost | changed
2026-02-18 05:10:55.720287 | 
2026-02-18 05:10:55.720359 | TASK [add-build-sshkey : Add back temp key]
2026-02-18 05:10:55.989084 | instance -> localhost | Identity added: /var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/work/37dba1c7f7614d38b075dea482e9109c_id_rsa (zuul-build-sshkey)
2026-02-18 05:10:55.989349 | instance -> localhost | ok: Runtime: 0:00:00.014475
2026-02-18 05:10:55.996063 | 
2026-02-18 05:10:55.996219 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-18 05:10:56.315240 | instance | ok
2026-02-18 05:10:56.322026 | 
2026-02-18 05:10:56.322092 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-18 05:10:56.347442 | instance | skipping: Conditional result was False
2026-02-18 05:10:56.363607 | 
2026-02-18 05:10:56.363701 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-18 05:10:56.689719 | instance | ok
2026-02-18 05:10:56.697566 | 
2026-02-18 05:10:56.697655 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-18 05:10:58.214867 | instance | Output suppressed because no_log was given
2026-02-18 05:10:58.223395 | 
2026-02-18 05:10:58.223459 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-18 05:10:58.399367 | instance | ok: "logs"
2026-02-18 05:10:58.399816 | instance | ok: All items complete
2026-02-18 05:10:58.399863 | 
2026-02-18 05:10:58.550254 | instance | ok: "artifacts"
2026-02-18 05:10:58.718787 | instance | ok: "docs"
2026-02-18 05:10:58.737071 | 
2026-02-18 05:10:58.737214 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-18 05:10:58.922179 | instance | changed: "logs"
2026-02-18 05:10:59.107970 | instance | changed: "artifacts"
2026-02-18 05:10:59.260467 | instance | changed: "docs"
2026-02-18 05:10:59.282458 | 
2026-02-18 05:10:59.282739 | PLAY RECAP
2026-02-18 05:10:59.282810 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-18 05:10:59.282852 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 05:10:59.282890 | 
2026-02-18 05:10:59.427923 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-18 05:10:59.431974 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-18 05:11:00.001938 | 
2026-02-18 05:11:00.002069 | PLAY [all]
2026-02-18 05:11:00.014074 | 
2026-02-18 05:11:00.014149 | TASK [setup-uv : Extract archive]
2026-02-18 05:11:02.220196 | instance | changed
2026-02-18 05:11:02.227567 | 
2026-02-18 05:11:02.227652 | TASK [setup-uv : Print version]
2026-02-18 05:11:01.721573 | instance | uv 0.8.13
2026-02-18 05:11:02.763422 | instance | ok: Runtime: 0:00:00.012481
2026-02-18 05:11:02.771916 | 
2026-02-18 05:11:02.771990 | PLAY RECAP
2026-02-18 05:11:02.772065 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 05:11:02.772102 | 
2026-02-18 05:11:02.892149 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-18 05:11:02.894997 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2025.2]
2026-02-18 05:11:03.483798 | 
2026-02-18 05:11:03.483941 | PLAY [all]
2026-02-18 05:11:03.495254 | 
2026-02-18 05:11:03.495330 | TASK [Install "jq" for log collection]
2026-02-18 05:11:13.260030 | instance | changed
2026-02-18 05:11:13.262184 | 
2026-02-18 05:11:13.262254 | PLAY RECAP
2026-02-18 05:11:13.262312 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-18 05:11:13.262368 | 
2026-02-18 05:11:13.373021 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2025.2]
2026-02-18 05:11:13.376875 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-18 05:11:13.941913 | 
2026-02-18 05:11:13.942040 | PLAY [all]
2026-02-18 05:11:13.953174 | 
2026-02-18 05:11:13.953248 | TASK [Copy inventory file for Zuul]
2026-02-18 05:11:14.778473 | instance | changed
2026-02-18 05:11:14.786666 | 
2026-02-18 05:11:14.786889 | TASK [Switch "ansible_host" to private IP]
2026-02-18 05:11:15.074349 | instance | changed: 1 replacements made
2026-02-18 05:11:15.081918 | 
2026-02-18 05:11:15.082005 | TASK [Run Molecule scenario]
2026-02-18 05:11:15.468384 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-02-18 05:11:15.468528 | instance | Creating virtual environment at: .venv
2026-02-18 05:11:15.508216 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-18 05:11:15.536183 | instance | Downloading netaddr (2.2MiB)
2026-02-18 05:11:15.544535 | instance | Downloading ansible-core (2.1MiB)
2026-02-18 05:11:15.544871 | instance | Downloading rjsonnet (1.2MiB)
2026-02-18 05:11:15.545355 | instance | Downloading kubernetes (1.9MiB)
2026-02-18 05:11:15.545661 | instance | Downloading openstacksdk (1.7MiB)
2026-02-18 05:11:15.545958 | instance | Downloading pygments (1.2MiB)
2026-02-18 05:11:15.546357 | instance | Downloading setuptools (1.1MiB)
2026-02-18 05:11:15.553192 | instance | Downloading cryptography (4.2MiB)
2026-02-18 05:11:15.857142 | instance |    Building pyperclip==1.9.0
2026-02-18 05:11:15.879091 | instance |  Downloading rjsonnet
2026-02-18 05:11:16.035726 | instance |  Downloading netaddr
2026-02-18 05:11:16.050634 | instance |  Downloading pygments
2026-02-18 05:11:16.073683 | instance |  Downloading cryptography
2026-02-18 05:11:16.097335 | instance |  Downloading setuptools
2026-02-18 05:11:16.164751 | instance |  Downloading kubernetes
2026-02-18 05:11:16.218297 | instance |  Downloading ansible-core
2026-02-18 05:11:16.231171 | instance |  Downloading openstacksdk
2026-02-18 05:11:16.607611 | instance |       Built pyperclip==1.9.0
2026-02-18 05:11:16.847935 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-18 05:11:16.909688 | instance | Installed 79 packages in 60ms
2026-02-18 05:11:17.527828 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-02-18 05:11:18.088751 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-02-18 05:11:18.088896 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-02-18 05:11:43.750143 | instance | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv openstack.cloud:>=2.0.0
2026-02-18 05:11:43.750200 | instance | ERROR    Command ansible-galaxy collection install -vvv openstack.cloud:>=2.0.0, returned 250 code:
2026-02-18 05:11:43.750206 | instance | Starting galaxy collection install process
2026-02-18 05:11:43.750211 | instance | Process install dependency map
2026-02-18 05:11:43.750216 | instance | the full traceback was:
2026-02-18 05:11:43.750221 | instance |
2026-02-18 05:11:43.750225 | instance | Traceback (most recent call last):
2026-02-18 05:11:43.750229 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor
2026-02-18 05:11:43.750234 | instance |     exit_code = cli.run()
2026-02-18 05:11:43.750238 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run
2026-02-18 05:11:43.750243 | instance |     return context.CLIARGS()
2026-02-18 05:11:43.750247 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper
2026-02-18 05:11:43.750251 | instance |     return wrapped_method(*args, **kwargs)
2026-02-18 05:11:43.750255 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install
2026-02-18 05:11:43.750259 | instance |     self._execute_install_collection(
2026-02-18 05:11:43.750263 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection
2026-02-18 05:11:43.750268 | instance |     install_collections(
2026-02-18 05:11:43.750272 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections
2026-02-18 05:11:43.750276 | instance |     dependency_map = _resolve_depenency_map(
2026-02-18 05:11:43.750281 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map
2026-02-18 05:11:43.750285 | instance |     return collection_dep_resolver.resolve(
2026-02-18 05:11:43.750289 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-18 05:11:43.750304 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-18 05:11:43.750309 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-18 05:11:43.750312 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-18 05:11:43.750317 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-02-18 05:11:43.750321 | instance |     matches = self._p.find_matches(
2026-02-18 05:11:43.750325 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-02-18 05:11:43.750329 | instance |     match for match in self._find_matches(list(requirements))
2026-02-18 05:11:43.750333 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-02-18 05:11:43.750338 | instance |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-02-18 05:11:43.750345 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions
2026-02-18 05:11:43.750349 | instance |     return set(
2026-02-18 05:11:43.750353 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in <genexpr>
2026-02-18 05:11:43.750357 | instance |     return set(
2026-02-18 05:11:43.750361 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions
2026-02-18 05:11:43.750365 | instance |     raise last_error
2026-02-18 05:11:43.750369 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions
2026-02-18 05:11:43.750373 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-18 05:11:43.750377 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped
2026-02-18 05:11:43.750381 | instance |     return method(self, *args, **kwargs)
2026-02-18 05:11:43.750385 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions
2026-02-18 05:11:43.750389 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-18 05:11:43.750400 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function
2026-02-18 05:11:43.750405 | instance |     return call_retryable_function()
2026-02-18 05:11:43.750409 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy
2026-02-18 05:11:43.750413 | instance |     res = path_cache
2026-02-18 05:11:43.750417 | instance | KeyError: 'results'
2026-02-18 05:11:43.750421 | instance |
2026-02-18 05:11:43.750424 | instance | ansible-galaxy
2026-02-18 05:11:43.750428 | instance |   config file = None
2026-02-18 05:11:43.750432 | instance |   configured module search path =
2026-02-18 05:11:43.750436 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-18 05:11:43.750441 | instance |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages
2026-02-18 05:11:43.750450 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-18 05:11:43.750454 | instance |   python version = 3.10.12 (main, Jan 26 2026, 14:55:28)  (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-02-18 05:11:43.750458 | instance |   jinja version = 3.1.6
2026-02-18 05:11:43.750462 | instance |   libyaml = True
2026-02-18 05:11:43.750466 | instance | No config file found; using defaults
2026-02-18 05:11:43.750470 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-02-18 05:11:43.750474 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-02-18 05:11:43.750478 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-02-18 05:11:43.750482 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-18 05:11:43.750486 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-18 05:11:43.750490 | instance | : Collection ansible.netcommon does not support Ansible version
2026-02-18 05:11:43.750494 | instance | 2.17.12
2026-02-18 05:11:43.750498 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-18 05:11:43.750502 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-02-18 05:11:43.750506 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-18 05:11:43.750510 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-18 05:11:43.750514 | instance | unexpected error when getting available versions of collection openstack.cloud:
2026-02-18 05:11:43.750518 | instance | 'results'
2026-02-18 05:11:43.750522 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-18 05:11:43.750526 | instance |
2026-02-18 05:11:43.750770 | instance | Traceback (most recent call last):
2026-02-18 05:11:43.751185 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-02-18 05:11:43.751705 | instance |     sys.exit(main())
2026-02-18 05:11:43.752103 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-02-18 05:11:43.753188 | instance |     return self.main(*args, **kwargs)
2026-02-18 05:11:43.753539 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-02-18 05:11:43.754353 | instance |     rv = self.invoke(ctx)
2026-02-18 05:11:43.754711 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-02-18 05:11:43.755556 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-02-18 05:11:43.755885 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-02-18 05:11:43.756666 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-02-18 05:11:43.756979 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-02-18 05:11:43.757500 | instance |     return callback(*args, **kwargs)
2026-02-18 05:11:43.757638 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-02-18 05:11:43.757883 | instance |     return func(*args, **kwargs)
2026-02-18 05:11:43.758025 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func
2026-02-18 05:11:43.758238 | instance |     return f(get_current_context(), *args, **kwargs)
2026-02-18 05:11:43.758376 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-02-18 05:11:43.758599 | instance |     return func(ctx)
2026-02-18 05:11:43.758736 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test
2026-02-18 05:11:43.758941 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-02-18 05:11:43.759068 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-02-18 05:11:43.759282 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-02-18 05:11:43.759415 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-02-18 05:11:43.759623 | instance |     scenario.config.runtime.prepare_environment(
2026-02-18 05:11:43.759752 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 694, in prepare_environment
2026-02-18 05:11:43.760053 | instance |     self.install_collection(
2026-02-18 05:11:43.760181 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 552, in install_collection
2026-02-18 05:11:43.760474 | instance |     raise InvalidPrerequisiteError(msg)
2026-02-18 05:11:43.762534 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv openstack.cloud:>=2.0.0, returned 250 code:
2026-02-18 05:11:43.762551 | instance | Starting galaxy collection install process
2026-02-18 05:11:43.762561 | instance | Process install dependency map
2026-02-18 05:11:43.762570 | instance | the full traceback was:
2026-02-18 05:11:43.762579 | instance |
2026-02-18 05:11:43.762588 | instance | Traceback (most recent call last):
2026-02-18 05:11:43.762597 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor
2026-02-18 05:11:43.762606 | instance |     exit_code = cli.run()
2026-02-18 05:11:43.762614 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run
2026-02-18 05:11:43.762623 | instance |     return context.CLIARGS['func']()
2026-02-18 05:11:43.762632 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper
2026-02-18 05:11:43.762641 | instance |     return wrapped_method(*args, **kwargs)
2026-02-18 05:11:43.762650 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install
2026-02-18 05:11:43.762659 | instance |     self._execute_install_collection(
2026-02-18 05:11:43.762667 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection
2026-02-18 05:11:43.762676 | instance |     install_collections(
2026-02-18 05:11:43.762693 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections
2026-02-18 05:11:43.762702 | instance |     dependency_map = _resolve_depenency_map(
2026-02-18 05:11:43.762711 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map
2026-02-18 05:11:43.762720 | instance |     return collection_dep_resolver.resolve(
2026-02-18 05:11:43.762728 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-02-18 05:11:43.762737 | instance |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-02-18 05:11:43.762746 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-02-18 05:11:43.762755 | instance |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-02-18 05:11:43.762773 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-02-18 05:11:43.762783 | instance |     matches = self._p.find_matches(
2026-02-18 05:11:43.762792 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-02-18 05:11:43.762801 | instance |     match for match in self._find_matches(list(requirements[identifier]))
2026-02-18 05:11:43.762810 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-02-18 05:11:43.762820 | instance |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-02-18 05:11:43.762830 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions
2026-02-18 05:11:43.762841 | instance |     return set(
2026-02-18 05:11:43.762852 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in <genexpr>
2026-02-18 05:11:43.762863 | instance |     return set(
2026-02-18 05:11:43.762872 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions
2026-02-18 05:11:43.762881 | instance |     raise last_error
2026-02-18 05:11:43.762890 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions
2026-02-18 05:11:43.762899 | instance |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-02-18 05:11:43.762907 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped
2026-02-18 05:11:43.762916 | instance |     return method(self, *args, **kwargs)
2026-02-18 05:11:43.762925 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions
2026-02-18 05:11:43.762934 | instance |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-02-18 05:11:43.762951 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function
2026-02-18 05:11:43.762960 | instance |     return call_retryable_function()
2026-02-18 05:11:43.762968 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy
2026-02-18 05:11:43.762977 | instance |     res = path_cache['results']
2026-02-18 05:11:43.762986 | instance | KeyError: 'results'
2026-02-18 05:11:43.762995 | instance |
2026-02-18 05:11:43.763005 | instance | ansible-galaxy [core 2.17.12]
2026-02-18 05:11:43.763016 | instance |   config file = None
2026-02-18 05:11:43.763025 | instance |   configured module search path = ['/home/zuul/src/github.com/vexxhost/atmosphere/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-02-18 05:11:43.763034 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-18 05:11:43.763044 | instance |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages
2026-02-18 05:11:43.763054 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-18 05:11:43.763069 | instance |   python version = 3.10.12 (main, Jan 26 2026, 14:55:28) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-02-18 05:11:43.763078 | instance |   jinja version = 3.1.6
2026-02-18 05:11:43.763087 | instance |   libyaml = True
2026-02-18 05:11:43.763096 | instance | No config file found; using defaults
2026-02-18 05:11:43.763104 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-02-18 05:11:43.763113 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-02-18 05:11:43.763122 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-02-18 05:11:43.763131 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-18 05:11:43.763140 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-18 05:11:43.763149 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-02-18 05:11:43.763158 | instance | 2.17.12
2026-02-18 05:11:43.763166 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-18 05:11:43.763175 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-02-18 05:11:43.763184 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-18 05:11:43.763197 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-02-18 05:11:43.763205 | instance | unexpected error when getting available versions of collection openstack.cloud:
2026-02-18 05:11:43.763214 | instance | 'results'
2026-02-18 05:11:43.763223 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-02-18 05:11:43.763232 | instance |
2026-02-18 05:11:44.170454 | instance | ERROR
2026-02-18 05:11:44.170851 | instance | {
2026-02-18 05:11:44.170917 | instance |   "delta": "0:00:28.459560",
2026-02-18 05:11:44.170962 | instance |   "end": "2026-02-18 05:11:43.874404",
2026-02-18 05:11:44.171003 | instance |   "msg": "non-zero return code",
2026-02-18 05:11:44.171043 | instance |   "rc": 1,
2026-02-18 05:11:44.171089 | instance |   "start": "2026-02-18 05:11:15.414844"
2026-02-18 05:11:44.171129 | instance | }
failure
2026-02-18 05:11:44.176000 | 
2026-02-18 05:11:44.176045 | PLAY RECAP
2026-02-18 05:11:44.176090 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-18 05:11:44.176111 | 
2026-02-18 05:11:44.373912 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-18 05:11:44.378218 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2025.2]
2026-02-18 05:11:45.005491 | 
2026-02-18 05:11:45.005628 | PLAY [all]
2026-02-18 05:11:45.020385 | 
2026-02-18 05:11:45.020489 | TASK [gather-host-logs : creating directory for system status]
2026-02-18 05:11:45.370013 | instance | changed
2026-02-18 05:11:45.375495 | 
2026-02-18 05:11:45.375579 | TASK [gather-host-logs : Get logs for each host]
2026-02-18 05:11:45.743601 | instance | + systemd-cgls --full --all --no-pager
2026-02-18 05:11:45.753664 | instance | + ip addr
2026-02-18 05:11:45.755496 | instance | + ip route
2026-02-18 05:11:45.756938 | instance | + lsblk
2026-02-18 05:11:45.759606 | instance | + mount
2026-02-18 05:11:45.760880 | instance | + docker images
2026-02-18 05:11:45.761277 | instance | /bin/bash: line 7: docker: command not found
2026-02-18 05:11:45.761522 | instance | + brctl show
2026-02-18 05:11:45.761878 | instance | /bin/bash: line 8: brctl: command not found
2026-02-18 05:11:45.762148 | instance | + ps aux --sort=-%mem
2026-02-18 05:11:45.769966 | instance | + dpkg -l
2026-02-18 05:11:45.776922 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-02-18 05:11:45.777326 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-02-18 05:11:45.777392 | instance | /bin/bash: line 11: docker: command not found
2026-02-18 05:11:45.777711 | instance | + '[' '!' -z '' ']'
2026-02-18 05:11:45.911751 | instance | ok: Runtime: 0:00:00.040699
2026-02-18 05:11:45.920446 | 
2026-02-18 05:11:45.920521 | TASK [gather-host-logs : Downloads logs to executor]
2026-02-18 05:11:46.561209 | instance | changed:
2026-02-18 05:11:46.561469 | instance | created directory /var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/work/logs/instance
2026-02-18 05:11:46.561501 | instance | cd+++++++++ system/
2026-02-18 05:11:46.561523 | instance | >f+++++++++ system/brctl-show.txt
2026-02-18 05:11:46.561544 | instance | >f+++++++++ system/docker-images.txt
2026-02-18 05:11:46.561565 | instance | >f+++++++++ system/ip-addr.txt
2026-02-18 05:11:46.561587 | instance | >f+++++++++ system/ip-route.txt
2026-02-18 05:11:46.561608 | instance | >f+++++++++ system/lsblk.txt
2026-02-18 05:11:46.561629 | instance | >f+++++++++ system/mount.txt
2026-02-18 05:11:46.561650 | instance | >f+++++++++ system/packages.txt
2026-02-18 05:11:46.561670 | instance | >f+++++++++ system/ps.txt
2026-02-18 05:11:46.561689 | instance | >f+++++++++ system/systemd-cgls.txt
2026-02-18 05:11:46.572656 | 
2026-02-18 05:11:46.572757 | LOOP [helm-release-status : creating directory for helm release status]
2026-02-18 05:11:46.770486 | instance | changed: "values"
2026-02-18 05:11:46.933713 | instance | changed: "releases"
2026-02-18 05:11:46.952344 | 
2026-02-18 05:11:46.952551 | TASK [helm-release-status : Gather get release status for helm charts]
2026-02-18 05:11:47.164640 | instance | /bin/bash: line 3: kubectl: command not found
2026-02-18 05:11:47.490216 | instance | ok: Runtime: 0:00:00.005747
2026-02-18 05:11:47.497342 | 
2026-02-18 05:11:47.497444 | TASK [helm-release-status : Downloads logs to executor]
2026-02-18 05:11:47.978475 | instance | changed:
2026-02-18 05:11:47.978663 | instance | cd+++++++++ helm/
2026-02-18 05:11:47.978689 | instance | cd+++++++++ helm/releases/
2026-02-18 05:11:47.978710 | instance | cd+++++++++ helm/values/
2026-02-18 05:11:47.987928 | 
2026-02-18 05:11:47.987998 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-02-18 05:11:48.204676 | instance | changed
2026-02-18 05:11:48.210527 | 
2026-02-18 05:11:48.210602 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-02-18 05:11:48.410029 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 05:11:48.410275 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 05:11:48.415029 | instance | environment: line 1: kubectl: command not found
2026-02-18 05:11:48.415747 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 05:11:48.417878 | instance | environment: line 1: kubectl: command not found
2026-02-18 05:11:48.418266 | instance | environment: line 1: kubectl: command not found
2026-02-18 05:11:48.419378 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 05:11:48.420514 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 05:11:48.422299 | instance | environment: line 1: kubectl: command not found
2026-02-18 05:11:48.423013 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 05:11:48.423488 | instance | environment: line 1: kubectl: command not found
2026-02-18 05:11:48.423793 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-18 05:11:48.747721 | instance | ok: Runtime: 0:00:00.022557
2026-02-18 05:11:48.753358 | 
2026-02-18 05:11:48.753424 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-02-18 05:11:48.969647 | instance | changed
2026-02-18 05:11:48.976182 | 
2026-02-18 05:11:48.976253 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-02-18 05:11:49.218810 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 05:11:49.219860 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 05:11:49.220117 | instance | environment: line 5: kubectl: command not found
2026-02-18 05:11:49.220298 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-18 05:11:49.550690 | instance | ok: Runtime: 0:00:00.009594
2026-02-18 05:11:49.557519 | 
2026-02-18 05:11:49.557586 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-02-18 05:11:50.058488 | instance | changed:
2026-02-18 05:11:50.058691 | instance | cd+++++++++ objects/
2026-02-18 05:11:50.058719 | instance | cd+++++++++ objects/cluster/
2026-02-18 05:11:50.058739 | instance | cd+++++++++ objects/namespaced/
2026-02-18 05:11:50.067481 | 
2026-02-18 05:11:50.067582 | TASK [gather-pod-logs : creating directory for pod logs]
2026-02-18 05:11:50.265502 | instance | changed
2026-02-18 05:11:50.271997 | 
2026-02-18 05:11:50.272077 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-02-18 05:11:50.457568 | instance | changed
2026-02-18 05:11:50.465095 | 
2026-02-18 05:11:50.465169 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-02-18 05:11:50.664809 | instance | environment: line 3: kubectl: command not found
2026-02-18 05:11:50.998373 | instance | ok: Runtime: 0:00:00.007480
2026-02-18 05:11:51.005408 | 
2026-02-18 05:11:51.005482 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-02-18 05:11:51.485589 | instance | changed:
2026-02-18 05:11:51.485836 | instance | cd+++++++++ pod-logs/
2026-02-18 05:11:51.485875 | instance | cd+++++++++ pod-logs/failed-pods/
2026-02-18 05:11:51.498220 | 
2026-02-18 05:11:51.498286 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-02-18 05:11:51.689012 | instance | changed
2026-02-18 05:11:51.695730 | 
2026-02-18 05:11:51.695798 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-02-18 05:11:51.910193 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-18 05:11:52.240896 | instance | ok: Runtime: 0:00:00.032486
2026-02-18 05:11:52.247644 | 
2026-02-18 05:11:52.247737 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-02-18 05:11:52.453701 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-18 05:11:52.479983 | instance | ceph-mgr endpoints:
2026-02-18 05:11:52.783542 | instance | ok: Runtime: 0:00:00.031851
2026-02-18 05:11:52.790287 | 
2026-02-18 05:11:52.790379 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-02-18 05:11:52.988704 | instance | /bin/bash: line 4: kubectl: command not found
2026-02-18 05:11:53.323212 | instance | ok: Runtime: 0:00:00.032490
2026-02-18 05:11:53.329531 | 
2026-02-18 05:11:53.329595 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-02-18 05:11:53.818418 | instance | changed: cd+++++++++ prometheus/
2026-02-18 05:11:53.830090 | 
2026-02-18 05:11:53.830152 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-02-18 05:11:54.028135 | instance | changed
2026-02-18 05:11:54.036142 | 
2026-02-18 05:11:54.036210 | TASK [gather-selenium-data : Get selenium data]
2026-02-18 05:11:54.235884 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-02-18 05:11:54.237522 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-02-18 05:11:54.571852 | instance | ERROR
2026-02-18 05:11:54.572159 | instance | {
2026-02-18 05:11:54.572221 | instance |   "delta": "0:00:00.007500",
2026-02-18 05:11:54.572254 | instance |   "end": "2026-02-18 05:11:54.237909",
2026-02-18 05:11:54.572280 | instance |   "msg": "non-zero return code",
2026-02-18 05:11:54.572304 | instance |   "rc": 1,
2026-02-18 05:11:54.572329 | instance |   "start": "2026-02-18 05:11:54.230409"
2026-02-18 05:11:54.572353 | instance | }
2026-02-18 05:11:54.572386 | instance | ERROR: Ignoring Errors
2026-02-18 05:11:54.579124 | 
2026-02-18 05:11:54.579213 | TASK [gather-selenium-data : Downloads logs to executor]
2026-02-18 05:11:55.047320 | instance | changed: cd+++++++++ selenium/
2026-02-18 05:11:55.055798 | 
2026-02-18 05:11:55.055851 | PLAY RECAP
2026-02-18 05:11:55.055901 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-02-18 05:11:55.055923 | 
2026-02-18 05:11:55.189021 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2025.2]
2026-02-18 05:11:55.193435 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-18 05:11:55.776329 | 
2026-02-18 05:11:55.776456 | PLAY [all]
2026-02-18 05:11:55.787629 | 
2026-02-18 05:11:55.787702 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-18 05:11:55.833014 | instance | skipping: Conditional result was False
2026-02-18 05:11:55.845220 | 
2026-02-18 05:11:55.845382 | TASK [fetch-output : Set log path for single node]
2026-02-18 05:11:55.880098 | instance | ok
2026-02-18 05:11:55.886195 | 
2026-02-18 05:11:55.886285 | LOOP [fetch-output : Ensure local output dirs]
2026-02-18 05:11:56.264424 | instance -> localhost | ok: "/var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/work/logs"
2026-02-18 05:11:56.466688 | instance -> localhost | changed: "/var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/work/artifacts"
2026-02-18 05:11:56.672238 | instance -> localhost | changed: "/var/lib/zuul/builds/37dba1c7f7614d38b075dea482e9109c/work/docs"
2026-02-18 05:11:56.693609 | 
2026-02-18 05:11:56.693787 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-18 05:11:57.339918 | instance | changed: .d..t...... ./
2026-02-18 05:11:57.340176 | instance | changed: All items complete
2026-02-18 05:11:57.340202 | 
2026-02-18 05:11:57.784215 | instance | changed: .d..t...... ./
2026-02-18 05:11:58.225115 | instance | changed: .d..t...... ./
2026-02-18 05:11:58.242448 | 
2026-02-18 05:11:58.242598 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-18 05:11:58.672603 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007387
2026-02-18 05:11:58.898941 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007393
2026-02-18 05:11:58.912764 | 
2026-02-18 05:11:58.912902 | PLAY [all]
2026-02-18 05:11:58.919766 | 
2026-02-18 05:11:58.919847 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-18 05:11:59.329557 | instance | changed
2026-02-18 05:11:59.338371 | 
2026-02-18 05:11:59.338421 | PLAY RECAP
2026-02-18 05:11:59.338467 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-18 05:11:59.338489 | 
2026-02-18 05:11:59.459353 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-18 05:11:59.463826 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-18 05:12:00.042971 | 
2026-02-18 05:12:00.043122 | PLAY [localhost]
2026-02-18 05:12:00.054147 | 
2026-02-18 05:12:00.054223 | TASK [Generate Zuul manifest]
2026-02-18 05:12:00.077142 | localhost | ok
2026-02-18 05:12:00.093790 | 
2026-02-18 05:12:00.093869 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-18 05:12:00.408702 | localhost | changed
2026-02-18 05:12:00.422073 | 
2026-02-18 05:12:00.422144 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-18 05:12:00.451743 | localhost | ok
2026-02-18 05:12:00.461169 | 
2026-02-18 05:12:00.461257 | TASK [Upload logs]
2026-02-18 05:12:00.492764 | localhost | ok
2026-02-18 05:12:00.553644 | 
2026-02-18 05:12:00.553769 | TASK [Set zuul-log-path fact]
2026-02-18 05:12:00.577227 | localhost | ok
2026-02-18 05:12:00.591415 | 
2026-02-18 05:12:00.591492 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-18 05:12:00.622286 | localhost | ok
2026-02-18 05:12:00.628942 | 
2026-02-18 05:12:00.629083 | TASK [upload-logs : Create log directories]
2026-02-18 05:12:01.030039 | localhost | changed
2026-02-18 05:12:01.039138 | 
2026-02-18 05:12:01.039215 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-18 05:12:01.464155 | localhost -> localhost | ok: Runtime: 0:00:00.006792
2026-02-18 05:12:01.471037 | 
2026-02-18 05:12:01.471125 | TASK [upload-logs : Upload logs to log server]
2026-02-18 05:12:01.918541 | localhost | Output suppressed because no_log was given
2026-02-18 05:12:01.923455 | 
2026-02-18 05:12:01.923539 | LOOP [upload-logs : Compress console log and json output]
2026-02-18 05:12:01.969457 | localhost | skipping: Conditional result was False
2026-02-18 05:12:01.975367 | localhost | skipping: Conditional result was False
2026-02-18 05:12:01.987557 | 
2026-02-18 05:12:01.987722 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-18 05:12:02.030906 | localhost | skipping: Conditional result was False
2026-02-18 05:12:02.031296 | 
2026-02-18 05:12:02.034057 | localhost | skipping: Conditional result was False
2026-02-18 05:12:02.052398 | 
2026-02-18 05:12:02.052524 | LOOP [upload-logs : Upload console log and json output]