2026-03-14 01:21:55.406455 | Job console starting 2026-03-14 01:21:55.483525 | Updating git repos 2026-03-14 01:21:55.555700 | Cloning repos into workspace 2026-03-14 01:21:55.981460 | Restoring repo states 2026-03-14 01:21:56.007469 | Merging changes 2026-03-14 01:21:57.447911 | Checking out repos 2026-03-14 01:21:57.622472 | Preparing playbooks 2026-03-14 01:22:01.312715 | Running Ansible setup 2026-03-14 01:22:04.924911 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-14 01:22:05.615132 | 2026-03-14 01:22:05.615295 | PLAY [localhost] 2026-03-14 01:22:05.623903 | 2026-03-14 01:22:05.623978 | TASK [Gathering Facts] 2026-03-14 01:22:06.705779 | localhost | ok 2026-03-14 01:22:06.713948 | 2026-03-14 01:22:06.714025 | TASK [Setup log path fact] 2026-03-14 01:22:06.732252 | localhost | ok 2026-03-14 01:22:06.743183 | 2026-03-14 01:22:06.743248 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 01:22:06.770164 | localhost | ok 2026-03-14 01:22:06.775929 | 2026-03-14 01:22:06.775992 | TASK [emit-job-header : Print job information] 2026-03-14 01:22:06.814391 | # Job Information 2026-03-14 01:22:06.814507 | Ansible Version: 2.16.16 2026-03-14 01:22:06.814539 | Job: atmosphere-molecule-aio-openvswitch 2026-03-14 01:22:06.814561 | Pipeline: check 2026-03-14 01:22:06.814580 | Executor: 0a8996d2b663 2026-03-14 01:22:06.814600 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3734 2026-03-14 01:22:06.814626 | Event ID: ff8a0020-1f43-11f1-861c-ebc756cb43a7 2026-03-14 01:22:06.817277 | 2026-03-14 01:22:06.817340 | LOOP [emit-job-header : Print node information] 2026-03-14 01:22:06.923371 | localhost | ok: 2026-03-14 01:22:06.925072 | localhost | # Node Information 2026-03-14 01:22:06.925123 | localhost | Inventory Hostname: instance 2026-03-14 01:22:06.925153 | localhost | Hostname: np0000158769 2026-03-14 01:22:06.925177 | localhost | Username: zuul 2026-03-14 01:22:06.925206 | localhost | Distro: Ubuntu 22.04 2026-03-14 01:22:06.925233 | localhost | Provider: yul1 2026-03-14 01:22:06.925255 | localhost | Region: ca-ymq-1 2026-03-14 01:22:06.925277 | localhost | Label: ubuntu-jammy-16 2026-03-14 01:22:06.925300 | localhost | Product Name: OpenStack Nova 2026-03-14 01:22:06.925321 | localhost | Interface IP: 199.204.45.72 2026-03-14 01:22:06.947645 | 2026-03-14 01:22:06.949077 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-14 01:22:07.349103 | localhost -> localhost | changed 2026-03-14 01:22:07.360440 | 2026-03-14 01:22:07.360548 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-14 01:22:08.208395 | localhost -> localhost | changed 2026-03-14 01:22:08.218064 | 2026-03-14 01:22:08.218139 | PLAY [all] 2026-03-14 01:22:08.227609 | 2026-03-14 01:22:08.227676 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-14 01:22:08.465400 | instance -> localhost | ok 2026-03-14 01:22:08.478161 | 2026-03-14 01:22:08.478285 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-14 01:22:08.510843 | instance | ok 2026-03-14 01:22:08.523886 | instance | included: /var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-14 01:22:08.529395 | 2026-03-14 01:22:08.529452 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-14 01:22:09.514713 | instance -> localhost | Generating public/private rsa key pair. 2026-03-14 01:22:09.514887 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/work/3f39dc51a13541d6a79168b3c66a3dec_id_rsa 2026-03-14 01:22:09.514918 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/work/3f39dc51a13541d6a79168b3c66a3dec_id_rsa.pub 2026-03-14 01:22:09.514941 | instance -> localhost | The key fingerprint is: 2026-03-14 01:22:09.514964 | instance -> localhost | SHA256:mKyztcTNLpAn0JsgMOK3kmEX8qXAQpRehdOrZPkyR8w zuul-build-sshkey 2026-03-14 01:22:09.515000 | instance -> localhost | The key's randomart image is: 2026-03-14 01:22:09.515022 | instance -> localhost | +---[RSA 3072]----+ 2026-03-14 01:22:09.515052 | instance -> localhost | |+o. +. | 2026-03-14 01:22:09.515074 | instance -> localhost | |== = o | 2026-03-14 01:22:09.515095 | instance -> localhost | |*.=.O . | 2026-03-14 01:22:09.515413 | instance -> localhost | |.=oO.E o | 2026-03-14 01:22:09.515462 | instance -> localhost | |..Bo=++ S | 2026-03-14 01:22:09.515488 | instance -> localhost | | o =*=.o | 2026-03-14 01:22:09.515509 | instance -> localhost | | . *++ o | 2026-03-14 01:22:09.515531 | instance -> localhost | | =.o | 2026-03-14 01:22:09.515553 | instance -> localhost | | . ... | 2026-03-14 01:22:09.515574 | instance -> localhost | +----[SHA256]-----+ 2026-03-14 01:22:09.515624 | instance -> localhost | ok: Runtime: 0:00:00.533818 2026-03-14 01:22:09.523747 | 2026-03-14 01:22:09.523818 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-14 01:22:09.559451 | instance | ok 2026-03-14 01:22:09.570360 | instance | included: /var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-14 01:22:09.580179 | 2026-03-14 01:22:09.580270 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-14 01:22:09.606475 | instance | skipping: Conditional result was False 2026-03-14 01:22:09.623767 | 2026-03-14 01:22:09.623852 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-14 01:22:10.058115 | instance | changed 2026-03-14 01:22:10.065121 | 2026-03-14 01:22:10.065211 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-14 01:22:10.258008 | instance | ok 2026-03-14 01:22:10.265269 | 2026-03-14 01:22:10.265360 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-14 01:22:10.734462 | instance | changed 2026-03-14 01:22:10.881788 | 2026-03-14 01:22:10.881946 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-14 01:22:11.357888 | instance | changed 2026-03-14 01:22:11.363790 | 2026-03-14 01:22:11.368794 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-14 01:22:11.394286 | instance | skipping: Conditional result was False 2026-03-14 01:22:11.414664 | 2026-03-14 01:22:11.414869 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-14 01:22:11.811815 | instance -> localhost | changed 2026-03-14 01:22:11.864224 | 2026-03-14 01:22:11.865851 | TASK [add-build-sshkey : Add back temp key] 2026-03-14 01:22:12.358226 | instance -> localhost | Identity added: /var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/work/3f39dc51a13541d6a79168b3c66a3dec_id_rsa (zuul-build-sshkey) 2026-03-14 01:22:12.358471 | instance -> localhost | ok: Runtime: 0:00:00.014641 2026-03-14 01:22:12.367754 | 2026-03-14 01:22:12.367822 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-14 01:22:12.669542 | instance | ok 2026-03-14 01:22:12.675317 | 2026-03-14 01:22:12.675386 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-14 01:22:12.702099 | instance | skipping: Conditional result was False 2026-03-14 01:22:12.726319 | 2026-03-14 01:22:12.726460 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-14 01:22:13.028254 | instance | ok 2026-03-14 01:22:13.041061 | 2026-03-14 01:22:13.041281 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-14 01:22:14.569540 | instance | Output suppressed because no_log was given 2026-03-14 01:22:14.578567 | 2026-03-14 01:22:14.578638 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-14 01:22:14.765047 | instance | ok: "logs" 2026-03-14 01:22:14.798412 | instance | ok: All items complete 2026-03-14 01:22:14.798504 | 2026-03-14 01:22:14.925041 | instance | ok: "artifacts" 2026-03-14 01:22:15.082057 | instance | ok: "docs" 2026-03-14 01:22:15.143817 | 2026-03-14 01:22:15.144022 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-14 01:22:15.341964 | instance | changed: "logs" 2026-03-14 01:22:15.497781 | instance | changed: "artifacts" 2026-03-14 01:22:15.649785 | instance | changed: "docs" 2026-03-14 01:22:15.673137 | 2026-03-14 01:22:15.687575 | PLAY RECAP 2026-03-14 01:22:15.687649 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-14 01:22:15.687683 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 01:22:15.687703 | 2026-03-14 01:22:15.885759 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-14 01:22:15.892783 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-14 01:22:16.491440 | 2026-03-14 01:22:16.491562 | PLAY [all] 2026-03-14 01:22:16.503989 | 2026-03-14 01:22:16.504064 | TASK [setup-uv : Extract archive] 2026-03-14 01:22:18.714017 | instance | changed 2026-03-14 01:22:18.722479 | 2026-03-14 01:22:18.722835 | TASK [setup-uv : Print version] 2026-03-14 01:22:19.135988 | instance | uv 0.8.13 2026-03-14 01:22:19.428995 | instance | ok: Runtime: 0:00:00.012700 2026-03-14 01:22:19.437751 | 2026-03-14 01:22:19.437854 | PLAY RECAP 2026-03-14 01:22:19.437931 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 01:22:19.437998 | 2026-03-14 01:22:19.618270 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-14 01:22:19.625964 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1] 2026-03-14 01:22:20.226714 | 2026-03-14 01:22:20.226839 | PLAY [all] 2026-03-14 01:22:20.238745 | 2026-03-14 01:22:20.238823 | TASK [Install "jq" for log collection] 2026-03-14 01:22:28.542302 | instance | changed 2026-03-14 01:22:28.544661 | 2026-03-14 01:22:28.544762 | PLAY RECAP 2026-03-14 01:22:28.544853 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 01:22:28.544946 | 2026-03-14 01:22:28.730469 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.1] 2026-03-14 01:22:28.764651 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-14 01:22:29.363756 | 2026-03-14 01:22:29.363885 | PLAY [all] 2026-03-14 01:22:29.375228 | 2026-03-14 01:22:29.375303 | TASK [Copy inventory file for Zuul] 2026-03-14 01:22:30.224023 | instance | changed 2026-03-14 01:22:30.229673 | 2026-03-14 01:22:30.229749 | TASK [Switch "ansible_host" to private IP] 2026-03-14 01:22:30.547589 | instance | changed: 1 replacements made 2026-03-14 01:22:30.554844 | 2026-03-14 01:22:30.555018 | TASK [Run Molecule scenario] 2026-03-14 01:22:31.024987 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-03-14 01:22:31.025140 | instance | Creating virtual environment at: .venv 2026-03-14 01:22:31.051530 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-03-14 01:22:31.190326 | instance | Downloading rjsonnet (1.2MiB) 2026-03-14 01:22:31.190447 | instance | Downloading setuptools (1.1MiB) 2026-03-14 01:22:31.192982 | instance | Downloading pygments (1.2MiB) 2026-03-14 01:22:31.193898 | instance | Downloading cryptography (4.2MiB) 2026-03-14 01:22:31.194487 | instance | Downloading kubernetes (1.9MiB) 2026-03-14 01:22:31.195354 | instance | Downloading netaddr (2.2MiB) 2026-03-14 01:22:31.195592 | instance | Downloading openstacksdk (1.7MiB) 2026-03-14 01:22:31.195810 | instance | Downloading ansible-core (2.1MiB) 2026-03-14 01:22:31.463714 | instance | Building pyperclip==1.9.0 2026-03-14 01:22:31.503121 | instance | Downloading rjsonnet 2026-03-14 01:22:31.593923 | instance | Downloading pygments 2026-03-14 01:22:31.640270 | instance | Downloading netaddr 2026-03-14 01:22:31.673314 | instance | Downloading cryptography 2026-03-14 01:22:31.674751 | instance | Downloading setuptools 2026-03-14 01:22:31.730785 | instance | Downloading kubernetes 2026-03-14 01:22:31.759815 | instance | Downloading ansible-core 2026-03-14 01:22:31.792915 | instance | Downloading openstacksdk 2026-03-14 01:22:32.157011 | instance | Built pyperclip==1.9.0 2026-03-14 01:22:32.391188 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-03-14 01:22:32.430638 | instance | Installed 79 packages in 37ms 2026-03-14 01:22:33.080873 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-03-14 01:22:33.678913 | instance | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-14 01:22:33.678976 | instance | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-03-14 01:22:58.045185 | instance | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv kubernetes.core:2.4.0 2026-03-14 01:22:58.045340 | instance | ERROR Command ansible-galaxy collection install -vvv kubernetes.core:2.4.0, returned 250 code: 2026-03-14 01:22:58.045353 | instance | Starting galaxy collection install process 2026-03-14 01:22:58.045363 | instance | Process install dependency map 2026-03-14 01:22:58.045372 | instance | the full traceback was: 2026-03-14 01:22:58.045381 | instance | 2026-03-14 01:22:58.045391 | instance | Traceback (most recent call last): 2026-03-14 01:22:58.045400 | 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-03-14 01:22:58.045410 | instance | exit_code = cli.run() 2026-03-14 01:22:58.045418 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-03-14 01:22:58.045427 | instance | return context.CLIARGS() 2026-03-14 01:22:58.045436 | 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-03-14 01:22:58.045445 | instance | return wrapped_method(*args, **kwargs) 2026-03-14 01:22:58.045454 | 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-03-14 01:22:58.045463 | instance | self._execute_install_collection( 2026-03-14 01:22:58.045472 | 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-03-14 01:22:58.045482 | instance | install_collections( 2026-03-14 01:22:58.045491 | 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-03-14 01:22:58.045499 | instance | dependency_map = _resolve_depenency_map( 2026-03-14 01:22:58.045508 | 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-03-14 01:22:58.045518 | instance | return collection_dep_resolver.resolve( 2026-03-14 01:22:58.045527 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-03-14 01:22:58.045563 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-03-14 01:22:58.045572 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 427, in resolve 2026-03-14 01:22:58.045581 | instance | failure_causes = self._attempt_to_pin_criterion(name) 2026-03-14 01:22:58.045590 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion 2026-03-14 01:22:58.045599 | instance | criteria = self._get_updated_criteria(candidate) 2026-03-14 01:22:58.045608 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria 2026-03-14 01:22:58.045618 | instance | for requirement in self._p.get_dependencies(candidate=candidate): 2026-03-14 01:22:58.045629 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies 2026-03-14 01:22:58.045639 | instance | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-03-14 01:22:58.045650 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 176, in get_collection_dependencies 2026-03-14 01:22:58.045660 | instance | get_collection_version_metadata(collection_candidate). 2026-03-14 01:22:58.045670 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 161, in get_collection_version_metadata 2026-03-14 01:22:58.045693 | instance | raise last_err 2026-03-14 01:22:58.045704 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 128, in get_collection_version_metadata 2026-03-14 01:22:58.045715 | instance | version_metadata = api.get_collection_version_metadata( 2026-03-14 01:22:58.045725 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-03-14 01:22:58.045735 | instance | return method(self, *args, **kwargs) 2026-03-14 01:22:58.045745 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 816, in get_collection_version_metadata 2026-03-14 01:22:58.045755 | instance | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-03-14 01:22:58.045770 | 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-03-14 01:22:58.045781 | instance | return call_retryable_function() 2026-03-14 01:22:58.045791 | 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-03-14 01:22:58.045801 | instance | res = path_cache 2026-03-14 01:22:58.045811 | instance | KeyError: 'results' 2026-03-14 01:22:58.045821 | instance | 2026-03-14 01:22:58.045831 | instance | ansible-galaxy 2026-03-14 01:22:58.045841 | instance | config file = None 2026-03-14 01:22:58.045859 | instance | configured module search path = 2026-03-14 01:22:58.045869 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-03-14 01:22:58.045880 | 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-03-14 01:22:58.045890 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-03-14 01:22:58.045905 | instance | python version = 3.10.12 (main, Mar 3 2026, 11:56:32) (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-03-14 01:22:58.045915 | instance | jinja version = 3.1.6 2026-03-14 01:22:58.045923 | instance | libyaml = True 2026-03-14 01:22:58.045932 | instance | No config file found; using defaults 2026-03-14 01:22:58.045941 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-03-14 01:22:58.045950 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-03-14 01:22:58.045958 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-03-14 01:22:58.045967 | instance | : Collection ansible.netcommon does not support Ansible version 2026-03-14 01:22:58.045976 | instance | 2.17.12 2026-03-14 01:22:58.045985 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-03-14 01:22:58.045994 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-03-14 01:22:58.046002 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-03-14 01:22:58.046011 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-03-14 01:22:58.046020 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-14 01:22:58.046028 | instance | unexpected error when getting available versions of collection kubernetes.core: 2026-03-14 01:22:58.046037 | instance | 'results' 2026-03-14 01:22:58.046046 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-03-14 01:22:58.046055 | instance | 2026-03-14 01:22:58.046066 | instance | Traceback (most recent call last): 2026-03-14 01:22:58.046417 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in 2026-03-14 01:22:58.046896 | instance | sys.exit(main()) 2026-03-14 01:22:58.047272 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__ 2026-03-14 01:22:58.047963 | instance | return self.main(*args, **kwargs) 2026-03-14 01:22:58.048254 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main 2026-03-14 01:22:58.048881 | instance | rv = self.invoke(ctx) 2026-03-14 01:22:58.049166 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke 2026-03-14 01:22:58.049915 | instance | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-03-14 01:22:58.050193 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke 2026-03-14 01:22:58.050779 | instance | return ctx.invoke(self.callback, **ctx.params) 2026-03-14 01:22:58.051001 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke 2026-03-14 01:22:58.051553 | instance | return callback(*args, **kwargs) 2026-03-14 01:22:58.051818 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-03-14 01:22:58.052295 | instance | return func(*args, **kwargs) 2026-03-14 01:22:58.052571 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func 2026-03-14 01:22:58.052912 | instance | return f(get_current_context(), *args, **kwargs) 2026-03-14 01:22:58.053194 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-03-14 01:22:58.053649 | instance | return func(ctx) 2026-03-14 01:22:58.053909 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test 2026-03-14 01:22:58.054302 | instance | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-03-14 01:22:58.054599 | 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-03-14 01:22:58.055032 | instance | _run_scenarios(scenarios, command_args, default_config) 2026-03-14 01:22:58.055387 | 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-03-14 01:22:58.055802 | instance | scenario.config.runtime.prepare_environment( 2026-03-14 01:22:58.056078 | 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-03-14 01:22:58.056571 | instance | self.install_collection( 2026-03-14 01:22:58.056850 | 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-03-14 01:22:58.057247 | instance | raise InvalidPrerequisiteError(msg) 2026-03-14 01:22:58.060413 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv kubernetes.core:2.4.0, returned 250 code: 2026-03-14 01:22:58.060446 | instance | Starting galaxy collection install process 2026-03-14 01:22:58.060457 | instance | Process install dependency map 2026-03-14 01:22:58.060466 | instance | the full traceback was: 2026-03-14 01:22:58.060475 | instance | 2026-03-14 01:22:58.060484 | instance | Traceback (most recent call last): 2026-03-14 01:22:58.060493 | 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-03-14 01:22:58.060502 | instance | exit_code = cli.run() 2026-03-14 01:22:58.060510 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-03-14 01:22:58.060519 | instance | return context.CLIARGS['func']() 2026-03-14 01:22:58.060528 | 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-03-14 01:22:58.060537 | instance | return wrapped_method(*args, **kwargs) 2026-03-14 01:22:58.060546 | 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-03-14 01:22:58.060554 | instance | self._execute_install_collection( 2026-03-14 01:22:58.060563 | 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-03-14 01:22:58.060572 | instance | install_collections( 2026-03-14 01:22:58.060581 | 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-03-14 01:22:58.060589 | instance | dependency_map = _resolve_depenency_map( 2026-03-14 01:22:58.060598 | 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-03-14 01:22:58.060608 | instance | return collection_dep_resolver.resolve( 2026-03-14 01:22:58.060616 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-03-14 01:22:58.060625 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-03-14 01:22:58.060634 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 427, in resolve 2026-03-14 01:22:58.060643 | instance | failure_causes = self._attempt_to_pin_criterion(name) 2026-03-14 01:22:58.060652 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion 2026-03-14 01:22:58.060660 | instance | criteria = self._get_updated_criteria(candidate) 2026-03-14 01:22:58.060669 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria 2026-03-14 01:22:58.060688 | instance | for requirement in self._p.get_dependencies(candidate=candidate): 2026-03-14 01:22:58.060698 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies 2026-03-14 01:22:58.060707 | instance | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-03-14 01:22:58.060715 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 176, in get_collection_dependencies 2026-03-14 01:22:58.060724 | instance | get_collection_version_metadata(collection_candidate). 2026-03-14 01:22:58.060738 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 161, in get_collection_version_metadata 2026-03-14 01:22:58.060748 | instance | raise last_err 2026-03-14 01:22:58.060757 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 128, in get_collection_version_metadata 2026-03-14 01:22:58.060765 | instance | version_metadata = api.get_collection_version_metadata( 2026-03-14 01:22:58.060774 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-03-14 01:22:58.060783 | instance | return method(self, *args, **kwargs) 2026-03-14 01:22:58.060792 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 816, in get_collection_version_metadata 2026-03-14 01:22:58.060801 | instance | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-03-14 01:22:58.060810 | 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-03-14 01:22:58.060818 | instance | return call_retryable_function() 2026-03-14 01:22:58.060827 | 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-03-14 01:22:58.060836 | instance | res = path_cache['results'] 2026-03-14 01:22:58.060851 | instance | KeyError: 'results' 2026-03-14 01:22:58.060860 | instance | 2026-03-14 01:22:58.060869 | instance | ansible-galaxy [core 2.17.12] 2026-03-14 01:22:58.060878 | instance | config file = None 2026-03-14 01:22:58.060887 | instance | configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-03-14 01:22:58.060896 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-03-14 01:22:58.060906 | 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-03-14 01:22:58.060916 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-03-14 01:22:58.060925 | instance | python version = 3.10.12 (main, Mar 3 2026, 11:56:32) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-03-14 01:22:58.060934 | instance | jinja version = 3.1.6 2026-03-14 01:22:58.060943 | instance | libyaml = True 2026-03-14 01:22:58.060951 | instance | No config file found; using defaults 2026-03-14 01:22:58.060960 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-03-14 01:22:58.060969 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-03-14 01:22:58.060978 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-03-14 01:22:58.060993 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-03-14 01:22:58.061002 | instance | 2.17.12 2026-03-14 01:22:58.061011 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-03-14 01:22:58.061020 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-03-14 01:22:58.061028 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-03-14 01:22:58.061037 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-03-14 01:22:58.061046 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-14 01:22:58.061055 | instance | unexpected error when getting available versions of collection kubernetes.core: 2026-03-14 01:22:58.061064 | instance | 'results' 2026-03-14 01:22:58.061073 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-03-14 01:22:58.061081 | instance | 2026-03-14 01:22:58.622980 | instance | ERROR 2026-03-14 01:22:58.657854 | instance | { 2026-03-14 01:22:58.657969 | instance | "delta": "0:00:27.170689", 2026-03-14 01:22:58.658024 | instance | "end": "2026-03-14 01:22:58.135415", 2026-03-14 01:22:58.658069 | instance | "msg": "non-zero return code", 2026-03-14 01:22:58.658117 | instance | "rc": 1, 2026-03-14 01:22:58.658165 | instance | "start": "2026-03-14 01:22:30.964726" 2026-03-14 01:22:58.658206 | instance | } failure 2026-03-14 01:22:58.666693 | 2026-03-14 01:22:58.666803 | PLAY RECAP 2026-03-14 01:22:58.666898 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-14 01:22:58.666924 | 2026-03-14 01:22:58.857706 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-14 01:22:58.866298 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1] 2026-03-14 01:22:59.513469 | 2026-03-14 01:22:59.514389 | PLAY [all] 2026-03-14 01:22:59.530981 | 2026-03-14 01:22:59.531193 | TASK [gather-host-logs : creating directory for system status] 2026-03-14 01:22:59.881733 | instance | changed 2026-03-14 01:22:59.890470 | 2026-03-14 01:22:59.890636 | TASK [gather-host-logs : Get logs for each host] 2026-03-14 01:23:00.245145 | instance | + systemd-cgls --full --all --no-pager 2026-03-14 01:23:00.258032 | instance | + ip addr 2026-03-14 01:23:00.261050 | instance | + ip route 2026-03-14 01:23:00.263212 | instance | + lsblk 2026-03-14 01:23:00.267841 | instance | + mount 2026-03-14 01:23:00.270011 | instance | + docker images 2026-03-14 01:23:00.270400 | instance | /bin/bash: line 7: docker: command not found 2026-03-14 01:23:00.270649 | instance | + brctl show 2026-03-14 01:23:00.271054 | instance | /bin/bash: line 8: brctl: command not found 2026-03-14 01:23:00.271256 | instance | + ps aux --sort=-%mem 2026-03-14 01:23:00.284145 | instance | + dpkg -l 2026-03-14 01:23:00.289709 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-03-14 01:23:00.290102 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-03-14 01:23:00.290146 | instance | /bin/bash: line 11: docker: command not found 2026-03-14 01:23:00.290378 | instance | + '[' '!' -z '' ']' 2026-03-14 01:23:00.431925 | instance | ok: Runtime: 0:00:00.050265 2026-03-14 01:23:00.439866 | 2026-03-14 01:23:00.439942 | TASK [gather-host-logs : Downloads logs to executor] 2026-03-14 01:23:01.129791 | instance | changed: 2026-03-14 01:23:01.130005 | instance | created directory /var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/work/logs/instance 2026-03-14 01:23:01.130044 | instance | cd+++++++++ system/ 2026-03-14 01:23:01.130075 | instance | >f+++++++++ system/brctl-show.txt 2026-03-14 01:23:01.130103 | instance | >f+++++++++ system/docker-images.txt 2026-03-14 01:23:01.130129 | instance | >f+++++++++ system/ip-addr.txt 2026-03-14 01:23:01.130159 | instance | >f+++++++++ system/ip-route.txt 2026-03-14 01:23:01.130187 | instance | >f+++++++++ system/lsblk.txt 2026-03-14 01:23:01.130213 | instance | >f+++++++++ system/mount.txt 2026-03-14 01:23:01.130242 | instance | >f+++++++++ system/packages.txt 2026-03-14 01:23:01.130269 | instance | >f+++++++++ system/ps.txt 2026-03-14 01:23:01.130295 | instance | >f+++++++++ system/systemd-cgls.txt 2026-03-14 01:23:01.142040 | 2026-03-14 01:23:01.142108 | LOOP [helm-release-status : creating directory for helm release status] 2026-03-14 01:23:01.341800 | instance | changed: "values" 2026-03-14 01:23:01.511455 | instance | changed: "releases" 2026-03-14 01:23:01.525553 | 2026-03-14 01:23:01.525714 | TASK [helm-release-status : Gather get release status for helm charts] 2026-03-14 01:23:01.723825 | instance | /bin/bash: line 3: kubectl: command not found 2026-03-14 01:23:02.059002 | instance | ok: Runtime: 0:00:00.005726 2026-03-14 01:23:02.064196 | 2026-03-14 01:23:02.064293 | TASK [helm-release-status : Downloads logs to executor] 2026-03-14 01:23:02.612109 | instance | changed: 2026-03-14 01:23:02.612360 | instance | cd+++++++++ helm/ 2026-03-14 01:23:02.612389 | instance | cd+++++++++ helm/releases/ 2026-03-14 01:23:02.612410 | instance | cd+++++++++ helm/values/ 2026-03-14 01:23:02.657284 | 2026-03-14 01:23:02.657467 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-03-14 01:23:02.842529 | instance | changed 2026-03-14 01:23:02.849386 | 2026-03-14 01:23:02.849493 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-03-14 01:23:03.071257 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-14 01:23:03.071533 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-14 01:23:03.077129 | instance | environment: line 1: kubectl: command not found 2026-03-14 01:23:03.078537 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-14 01:23:03.079607 | instance | environment: line 1: kubectl: command not found 2026-03-14 01:23:03.081227 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-14 01:23:03.082265 | instance | environment: line 1: kubectl: command not found 2026-03-14 01:23:03.083775 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-14 01:23:03.084623 | instance | environment: line 1: kubectl: command not found 2026-03-14 01:23:03.085978 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-14 01:23:03.087054 | instance | environment: line 1: kubectl: command not found 2026-03-14 01:23:03.088416 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-14 01:23:03.387249 | instance | ok: Runtime: 0:00:00.026847 2026-03-14 01:23:03.394122 | 2026-03-14 01:23:03.394205 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-03-14 01:23:03.590843 | instance | changed 2026-03-14 01:23:03.598645 | 2026-03-14 01:23:03.598740 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-03-14 01:23:03.800073 | instance | environment: line 5: kubectl: command not found 2026-03-14 01:23:03.801289 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-14 01:23:03.801325 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-14 01:23:03.801686 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-14 01:23:04.133148 | instance | ok: Runtime: 0:00:00.009175 2026-03-14 01:23:04.138213 | 2026-03-14 01:23:04.138277 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-03-14 01:23:04.717991 | instance | changed: 2026-03-14 01:23:04.718194 | instance | cd+++++++++ objects/ 2026-03-14 01:23:04.718234 | instance | cd+++++++++ objects/cluster/ 2026-03-14 01:23:04.718266 | instance | cd+++++++++ objects/namespaced/ 2026-03-14 01:23:04.730963 | 2026-03-14 01:23:04.747241 | TASK [gather-pod-logs : creating directory for pod logs] 2026-03-14 01:23:04.950269 | instance | changed 2026-03-14 01:23:04.955428 | 2026-03-14 01:23:04.955492 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-03-14 01:23:05.156615 | instance | changed 2026-03-14 01:23:05.162033 | 2026-03-14 01:23:05.162104 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-03-14 01:23:05.369675 | instance | environment: line 3: kubectl: command not found 2026-03-14 01:23:05.703355 | instance | ok: Runtime: 0:00:00.009571 2026-03-14 01:23:05.711657 | 2026-03-14 01:23:05.711724 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-03-14 01:23:06.193509 | instance | changed: 2026-03-14 01:23:06.193743 | instance | cd+++++++++ pod-logs/ 2026-03-14 01:23:06.193784 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-03-14 01:23:06.205106 | 2026-03-14 01:23:06.205183 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-03-14 01:23:06.392590 | instance | changed 2026-03-14 01:23:06.400196 | 2026-03-14 01:23:06.400316 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-03-14 01:23:06.609692 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-14 01:23:06.941752 | instance | ok: Runtime: 0:00:00.033312 2026-03-14 01:23:06.954350 | 2026-03-14 01:23:06.954462 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-03-14 01:23:07.267123 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-14 01:23:07.300051 | instance | ceph-mgr endpoints: 2026-03-14 01:23:07.514022 | instance | ok: Runtime: 0:00:00.038454 2026-03-14 01:23:07.520908 | 2026-03-14 01:23:07.520991 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-03-14 01:23:07.722385 | instance | /bin/bash: line 4: kubectl: command not found 2026-03-14 01:23:08.054342 | instance | ok: Runtime: 0:00:00.037392 2026-03-14 01:23:08.062812 | 2026-03-14 01:23:08.062967 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-03-14 01:23:08.851720 | instance | changed: cd+++++++++ prometheus/ 2026-03-14 01:23:08.906303 | 2026-03-14 01:23:08.906447 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-03-14 01:23:09.106639 | instance | changed 2026-03-14 01:23:09.113491 | 2026-03-14 01:23:09.113587 | TASK [gather-selenium-data : Get selenium data] 2026-03-14 01:23:09.321374 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-03-14 01:23:09.322880 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-03-14 01:23:09.648090 | instance | ERROR 2026-03-14 01:23:09.648284 | instance | { 2026-03-14 01:23:09.648319 | instance | "delta": "0:00:00.007233", 2026-03-14 01:23:09.648340 | instance | "end": "2026-03-14 01:23:09.323266", 2026-03-14 01:23:09.648359 | instance | "msg": "non-zero return code", 2026-03-14 01:23:09.648377 | instance | "rc": 1, 2026-03-14 01:23:09.648394 | instance | "start": "2026-03-14 01:23:09.316033" 2026-03-14 01:23:09.648412 | instance | } 2026-03-14 01:23:09.648435 | instance | ERROR: Ignoring Errors 2026-03-14 01:23:09.663419 | 2026-03-14 01:23:09.663485 | TASK [gather-selenium-data : Downloads logs to executor] 2026-03-14 01:23:10.185193 | instance | changed: cd+++++++++ selenium/ 2026-03-14 01:23:10.195170 | 2026-03-14 01:23:10.195225 | PLAY RECAP 2026-03-14 01:23:10.195271 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-03-14 01:23:10.195293 | 2026-03-14 01:23:10.314148 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.1] 2026-03-14 01:23:10.366728 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-14 01:23:11.030868 | 2026-03-14 01:23:11.085657 | PLAY [all] 2026-03-14 01:23:11.103159 | 2026-03-14 01:23:11.103247 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-14 01:23:11.148887 | instance | skipping: Conditional result was False 2026-03-14 01:23:11.159463 | 2026-03-14 01:23:11.159572 | TASK [fetch-output : Set log path for single node] 2026-03-14 01:23:11.204358 | instance | ok 2026-03-14 01:23:11.210880 | 2026-03-14 01:23:11.210980 | LOOP [fetch-output : Ensure local output dirs] 2026-03-14 01:23:11.746070 | instance -> localhost | ok: "/var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/work/logs" 2026-03-14 01:23:11.973458 | instance -> localhost | changed: "/var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/work/artifacts" 2026-03-14 01:23:12.203790 | instance -> localhost | changed: "/var/lib/zuul/builds/3f39dc51a13541d6a79168b3c66a3dec/work/docs" 2026-03-14 01:23:12.225748 | 2026-03-14 01:23:12.225888 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-14 01:23:12.881606 | instance | changed: .d..t...... ./ 2026-03-14 01:23:12.882431 | instance | changed: All items complete 2026-03-14 01:23:12.882476 | 2026-03-14 01:23:13.334654 | instance | changed: .d..t...... ./ 2026-03-14 01:23:13.819368 | instance | changed: .d..t...... ./ 2026-03-14 01:23:13.837625 | 2026-03-14 01:23:13.837751 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-14 01:23:14.279041 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006864 2026-03-14 01:23:14.501420 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008433 2026-03-14 01:23:14.509042 | 2026-03-14 01:23:14.509103 | PLAY [all] 2026-03-14 01:23:14.515632 | 2026-03-14 01:23:14.515709 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-14 01:23:14.933572 | instance | changed 2026-03-14 01:23:14.940349 | 2026-03-14 01:23:14.940402 | PLAY RECAP 2026-03-14 01:23:14.940446 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-14 01:23:14.940469 | 2026-03-14 01:23:15.061195 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-14 01:23:15.075610 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-14 01:23:15.703700 | 2026-03-14 01:23:15.725030 | PLAY [localhost] 2026-03-14 01:23:15.740997 | 2026-03-14 01:23:15.759396 | TASK [Generate Zuul manifest] 2026-03-14 01:23:15.780290 | localhost | ok 2026-03-14 01:23:15.798916 | 2026-03-14 01:23:15.799013 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-14 01:23:16.254613 | localhost | changed 2026-03-14 01:23:16.293123 | 2026-03-14 01:23:16.293234 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-14 01:23:16.407175 | localhost | ok 2026-03-14 01:23:16.416117 | 2026-03-14 01:23:16.416190 | TASK [Upload logs] 2026-03-14 01:23:16.438996 | localhost | ok 2026-03-14 01:23:16.585335 | 2026-03-14 01:23:16.644624 | TASK [Set zuul-log-path fact] 2026-03-14 01:23:16.681204 | localhost | ok 2026-03-14 01:23:16.695467 | 2026-03-14 01:23:16.695535 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 01:23:16.726808 | localhost | ok 2026-03-14 01:23:16.735038 | 2026-03-14 01:23:16.735101 | TASK [upload-logs : Create log directories] 2026-03-14 01:23:17.109734 | localhost | changed 2026-03-14 01:23:17.116282 | 2026-03-14 01:23:17.116387 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-14 01:23:17.507245 | localhost -> localhost | ok: Runtime: 0:00:00.007001 2026-03-14 01:23:17.514962 | 2026-03-14 01:23:17.515055 | TASK [upload-logs : Upload logs to log server] 2026-03-14 01:23:17.921485 | localhost | Output suppressed because no_log was given 2026-03-14 01:23:17.925513 | 2026-03-14 01:23:17.925764 | LOOP [upload-logs : Compress console log and json output] 2026-03-14 01:23:17.971532 | localhost | skipping: Conditional result was False 2026-03-14 01:23:17.980863 | localhost | skipping: Conditional result was False 2026-03-14 01:23:17.991709 | 2026-03-14 01:23:17.991858 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-14 01:23:18.034479 | localhost | skipping: Conditional result was False 2026-03-14 01:23:18.300855 | 2026-03-14 01:23:18.305541 | localhost | skipping: Conditional result was False 2026-03-14 01:23:18.328294 | 2026-03-14 01:23:18.328736 | LOOP [upload-logs : Upload console log and json output]