2026-05-14 01:00:57.151712 | Job console starting 2026-05-14 01:00:57.164658 | Updating git repos 2026-05-14 01:00:57.217153 | Cloning repos into workspace 2026-05-14 01:00:58.036696 | Restoring repo states 2026-05-14 01:00:58.051160 | Merging changes 2026-05-14 01:00:59.026133 | Checking out repos 2026-05-14 01:00:59.577764 | Preparing playbooks 2026-05-14 01:01:12.305263 | Running Ansible setup 2026-05-14 01:01:16.156894 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-14 01:01:16.742692 | 2026-05-14 01:01:16.742815 | PLAY [localhost] 2026-05-14 01:01:16.750150 | 2026-05-14 01:01:16.750218 | TASK [Gathering Facts] 2026-05-14 01:01:17.615987 | localhost | ok 2026-05-14 01:01:17.629700 | 2026-05-14 01:01:17.629790 | TASK [Setup log path fact] 2026-05-14 01:01:17.664659 | localhost | ok 2026-05-14 01:01:17.677787 | 2026-05-14 01:01:17.677859 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 01:01:17.708843 | localhost | ok 2026-05-14 01:01:17.716038 | 2026-05-14 01:01:17.716117 | TASK [emit-job-header : Print job information] 2026-05-14 01:01:17.757687 | # Job Information 2026-05-14 01:01:17.757953 | Ansible Version: 2.16.18 2026-05-14 01:01:17.758023 | Job: ansible-collection-kubernetes-molecule-cluster-api 2026-05-14 01:01:17.758072 | Pipeline: check 2026-05-14 01:01:17.758117 | Executor: 2d72f0692154 2026-05-14 01:01:17.758161 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/274 2026-05-14 01:01:17.758211 | Event ID: 3b767700-4f30-11f1-8df5-f59037ac2d5f 2026-05-14 01:01:17.764180 | 2026-05-14 01:01:17.764322 | LOOP [emit-job-header : Print node information] 2026-05-14 01:01:17.851130 | localhost | ok: 2026-05-14 01:01:17.851359 | localhost | # Node Information 2026-05-14 01:01:17.851401 | localhost | Inventory Hostname: controller 2026-05-14 01:01:17.851429 | localhost | Hostname: np0000173950 2026-05-14 01:01:17.851454 | localhost | Username: zuul 2026-05-14 01:01:17.851482 | localhost | Distro: Debian 13.4 2026-05-14 01:01:17.851507 | localhost | Provider: yul1 2026-05-14 01:01:17.851531 | localhost | Region: ca-ymq-1 2026-05-14 01:01:17.851555 | localhost | Label: debian-trixie 2026-05-14 01:01:17.851578 | localhost | Product Name: OpenStack Nova 2026-05-14 01:01:17.851601 | localhost | Interface IP: 199.204.45.38 2026-05-14 01:01:17.862457 | 2026-05-14 01:01:17.862602 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-14 01:01:18.231263 | localhost -> localhost | changed 2026-05-14 01:01:18.236302 | 2026-05-14 01:01:18.236368 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-14 01:01:19.120191 | localhost -> localhost | changed 2026-05-14 01:01:19.126341 | 2026-05-14 01:01:19.126396 | PLAY [all] 2026-05-14 01:01:19.132602 | 2026-05-14 01:01:19.132666 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-14 01:01:19.368174 | controller -> localhost | ok 2026-05-14 01:01:19.378340 | 2026-05-14 01:01:19.378481 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-14 01:01:19.411414 | controller | ok 2026-05-14 01:01:19.428990 | controller | included: /var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-14 01:01:19.433643 | 2026-05-14 01:01:19.433703 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-14 01:01:20.283001 | controller -> localhost | Generating public/private rsa key pair. 2026-05-14 01:01:20.283179 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/work/9ab3ef03d2244b5e8e0df0907913dfa8_id_rsa 2026-05-14 01:01:20.283210 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/work/9ab3ef03d2244b5e8e0df0907913dfa8_id_rsa.pub 2026-05-14 01:01:20.283233 | controller -> localhost | The key fingerprint is: 2026-05-14 01:01:20.283255 | controller -> localhost | SHA256:BfgyxqgY4hwc/t6IDC5OYzPZmXB02L/GVIYamuKKY0Y zuul-build-sshkey 2026-05-14 01:01:20.283290 | controller -> localhost | The key's randomart image is: 2026-05-14 01:01:20.283312 | controller -> localhost | +---[RSA 3072]----+ 2026-05-14 01:01:20.283341 | controller -> localhost | | .. | 2026-05-14 01:01:20.283362 | controller -> localhost | | . o . .. | 2026-05-14 01:01:20.283383 | controller -> localhost | |o .o * o o. | 2026-05-14 01:01:20.283403 | controller -> localhost | |o+. = O +. | 2026-05-14 01:01:20.283423 | controller -> localhost | |+=o= o =S | 2026-05-14 01:01:20.283443 | controller -> localhost | |+EB.o o . | 2026-05-14 01:01:20.283462 | controller -> localhost | |=Oo+o + | 2026-05-14 01:01:20.283482 | controller -> localhost | |B*+o .. | 2026-05-14 01:01:20.283504 | controller -> localhost | |Bo | 2026-05-14 01:01:20.283524 | controller -> localhost | +----[SHA256]-----+ 2026-05-14 01:01:20.283579 | controller -> localhost | ok: Runtime: 0:00:00.437760 2026-05-14 01:01:20.289226 | 2026-05-14 01:01:20.289288 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-14 01:01:20.339590 | controller | ok 2026-05-14 01:01:20.349197 | controller | included: /var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-14 01:01:20.361124 | 2026-05-14 01:01:20.361237 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-14 01:01:20.395700 | controller | skipping: Conditional result was False 2026-05-14 01:01:20.404945 | 2026-05-14 01:01:20.405137 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-14 01:01:20.939351 | controller | changed 2026-05-14 01:01:20.945080 | 2026-05-14 01:01:20.945172 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-14 01:01:21.156317 | controller | ok 2026-05-14 01:01:21.160686 | 2026-05-14 01:01:21.160762 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-14 01:01:21.692496 | controller | changed 2026-05-14 01:01:21.696671 | 2026-05-14 01:01:21.696735 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-14 01:01:22.232692 | controller | changed 2026-05-14 01:01:22.238633 | 2026-05-14 01:01:22.238701 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-14 01:01:22.263344 | controller | skipping: Conditional result was False 2026-05-14 01:01:22.270888 | 2026-05-14 01:01:22.270997 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-14 01:01:22.660443 | controller -> localhost | changed 2026-05-14 01:01:22.675059 | 2026-05-14 01:01:22.675152 | TASK [add-build-sshkey : Add back temp key] 2026-05-14 01:01:22.956189 | controller -> localhost | Identity added: /var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/work/9ab3ef03d2244b5e8e0df0907913dfa8_id_rsa (zuul-build-sshkey) 2026-05-14 01:01:22.956445 | controller -> localhost | ok: Runtime: 0:00:00.015178 2026-05-14 01:01:22.962364 | 2026-05-14 01:01:22.962451 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-14 01:01:23.315152 | controller | ok 2026-05-14 01:01:23.318995 | 2026-05-14 01:01:23.319061 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-14 01:01:23.343978 | controller | skipping: Conditional result was False 2026-05-14 01:01:23.357791 | 2026-05-14 01:01:23.357901 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-14 01:01:23.721091 | controller | ok 2026-05-14 01:01:23.725826 | 2026-05-14 01:01:23.725888 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-14 01:01:24.802294 | controller | Output suppressed because no_log was given 2026-05-14 01:01:24.810163 | 2026-05-14 01:01:24.810249 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-14 01:01:25.014419 | controller | ok: "logs" 2026-05-14 01:01:25.014819 | controller | ok: All items complete 2026-05-14 01:01:25.014879 | 2026-05-14 01:01:25.189646 | controller | ok: "artifacts" 2026-05-14 01:01:25.369961 | controller | ok: "docs" 2026-05-14 01:01:25.388456 | 2026-05-14 01:01:25.388634 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-14 01:01:25.602079 | controller | changed: "logs" 2026-05-14 01:01:25.781869 | controller | changed: "artifacts" 2026-05-14 01:01:25.972195 | controller | changed: "docs" 2026-05-14 01:01:25.986584 | 2026-05-14 01:01:25.986735 | PLAY RECAP 2026-05-14 01:01:25.986803 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-14 01:01:25.986843 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 01:01:25.986874 | 2026-05-14 01:01:26.126481 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-14 01:01:26.129522 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-14 01:01:26.808172 | 2026-05-14 01:01:26.808367 | PLAY [all] 2026-05-14 01:01:26.822338 | 2026-05-14 01:01:26.822485 | TASK [setup-uv : Extract archive] 2026-05-14 01:01:29.184434 | controller | changed 2026-05-14 01:01:29.191434 | 2026-05-14 01:01:29.191598 | TASK [setup-uv : Print version] 2026-05-14 01:01:29.552164 | controller | uv 0.8.13 2026-05-14 01:01:29.723594 | controller | ok: Runtime: 0:00:00.014426 2026-05-14 01:01:29.729780 | 2026-05-14 01:01:29.729829 | PLAY RECAP 2026-05-14 01:01:29.729876 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 01:01:29.729900 | 2026-05-14 01:01:29.857946 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-14 01:01:29.860876 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-05-14 01:01:30.488218 | 2026-05-14 01:01:30.488341 | PLAY [all] 2026-05-14 01:01:30.498227 | 2026-05-14 01:01:30.498305 | TASK [Update apt cache] 2026-05-14 01:01:35.011661 | controller | ok 2026-05-14 01:01:35.015761 | 2026-05-14 01:01:35.015835 | TASK [Disable firewalld] 2026-05-14 01:01:35.041182 | controller | skipping: Conditional result was False 2026-05-14 01:01:35.049570 | 2026-05-14 01:01:35.049668 | TASK [Install "jq" for log collection] 2026-05-14 01:01:37.092382 | controller | changed 2026-05-14 01:01:37.095641 | 2026-05-14 01:01:37.095688 | PLAY RECAP 2026-05-14 01:01:37.095731 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-14 01:01:37.095753 | 2026-05-14 01:01:37.206747 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-05-14 01:01:37.209819 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-14 01:01:37.782350 | 2026-05-14 01:01:37.782477 | PLAY [all] 2026-05-14 01:01:37.792468 | 2026-05-14 01:01:37.792541 | TASK [Copy inventory file for Zuul] 2026-05-14 01:01:38.712399 | controller | changed 2026-05-14 01:01:38.717357 | 2026-05-14 01:01:38.717457 | TASK [Switch "ansible_host" to private IP] 2026-05-14 01:01:39.054914 | controller | changed: 1 replacements made 2026-05-14 01:01:39.061463 | 2026-05-14 01:01:39.061558 | TASK [Run Molecule scenario] 2026-05-14 01:01:39.488357 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-05-14 01:01:39.488442 | controller | Creating virtual environment at: .venv 2026-05-14 01:01:40.182276 | controller | Downloading pygments (1.2MiB) 2026-05-14 01:01:40.212946 | controller | Downloading ansible-core (2.3MiB) 2026-05-14 01:01:40.213127 | controller | Downloading cryptography (4.3MiB) 2026-05-14 01:01:40.421957 | controller | Downloading cryptography 2026-05-14 01:01:40.436699 | controller | Downloading pygments 2026-05-14 01:01:40.629739 | controller | Downloading ansible-core 2026-05-14 01:01:40.677134 | controller | Installed 36 packages in 46ms 2026-05-14 01:01:41.340770 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-05-14 01:01:41.340833 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-05-14 01:01:42.271905 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-05-14 01:01:42.271961 | controller | INFO default scenario not found, disabling shared state. 2026-05-14 01:01:42.271980 | controller | INFO [cluster-api > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-05-14 01:01:42.272073 | controller | INFO [cluster-api > prerun] Performing prerun with role_name_check=0... 2026-05-14 01:02:20.420314 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2 2026-05-14 01:02:20.420377 | controller | ERROR Command ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2, returned 1 code: 2026-05-14 01:02:20.420408 | controller | Starting galaxy collection install process 2026-05-14 01:02:20.420416 | controller | Process install dependency map 2026-05-14 01:02:20.420424 | controller | Starting collection install process 2026-05-14 01:02:20.420433 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/kubernetes-core-6.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1478gmh8pc75/tmp81qlsll0/kubernetes-core-6.4.0-9f6_rnrv 2026-05-14 01:02:20.420441 | controller | 2026-05-14 01:02:20.420450 | controller | ansible-galaxy 2026-05-14 01:02:20.420457 | controller | config file = None 2026-05-14 01:02:20.420464 | controller | configured module search path = 2026-05-14 01:02:20.420472 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-05-14 01:02:20.420488 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages 2026-05-14 01:02:20.420499 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-05-14 01:02:20.420507 | controller | python version = 3.13.5 (main, Jun 25 2025, 18:55:22) (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-05-14 01:02:20.420514 | controller | jinja version = 3.1.6 2026-05-14 01:02:20.420521 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-05-14 01:02:20.420529 | controller | No config file found; using defaults 2026-05-14 01:02:20.420536 | controller | Found installed collection ansible.posix:2.1.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-05-14 01:02:20.420544 | controller | Found installed collection community.crypto:3.2.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-05-14 01:02:20.420551 | controller | Found installed collection community.general:12.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-05-14 01:02:20.420558 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-05-14 01:02:20.420570 | controller | : Failed to download collection tar from 'default': HTTP Error 503: Service Unavailable 2026-05-14 01:02:20.420578 | controller | 2026-05-14 01:02:20.424066 | controller | Traceback (most recent call last): 2026-05-14 01:02:20.427618 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-05-14 01:02:20.427646 | controller | sys.exit(main()) 2026-05-14 01:02:20.427654 | controller | ~~~~^^ 2026-05-14 01:02:20.427945 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1485, in __call__ 2026-05-14 01:02:20.427955 | controller | return self.main(*args, **kwargs) 2026-05-14 01:02:20.427962 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.428224 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1406, in main 2026-05-14 01:02:20.428233 | controller | rv = self.invoke(ctx) 2026-05-14 01:02:20.428539 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1873, in invoke 2026-05-14 01:02:20.428547 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-05-14 01:02:20.428555 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-05-14 01:02:20.428859 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1269, in invoke 2026-05-14 01:02:20.428868 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-05-14 01:02:20.428875 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.429120 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 824, in invoke 2026-05-14 01:02:20.429128 | controller | return callback(*args, **kwargs) 2026-05-14 01:02:20.429385 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-05-14 01:02:20.429393 | controller | return func(*args, **kwargs) 2026-05-14 01:02:20.429655 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func 2026-05-14 01:02:20.429663 | controller | return f(get_current_context(), *args, **kwargs) 2026-05-14 01:02:20.429962 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-05-14 01:02:20.429972 | controller | return func(ctx) 2026-05-14 01:02:20.430342 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test 2026-05-14 01:02:20.430352 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-05-14 01:02:20.430360 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.430653 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios 2026-05-14 01:02:20.430662 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-05-14 01:02:20.430669 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.431136 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios 2026-05-14 01:02:20.431145 | controller | scenario.config.runtime.prepare_environment( 2026-05-14 01:02:20.431153 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-05-14 01:02:20.431160 | controller | install_local=True, 2026-05-14 01:02:20.431167 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.431174 | controller | role_name_check=role_name_check, 2026-05-14 01:02:20.431181 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.431188 | controller | ) 2026-05-14 01:02:20.431195 | controller | ^ 2026-05-14 01:02:20.431353 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment 2026-05-14 01:02:20.431361 | controller | self.install_collection( 2026-05-14 01:02:20.431375 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-05-14 01:02:20.431383 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-05-14 01:02:20.431390 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.431397 | controller | destination=destination, 2026-05-14 01:02:20.431404 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-14 01:02:20.431412 | controller | ) 2026-05-14 01:02:20.431419 | controller | ^ 2026-05-14 01:02:20.431475 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection 2026-05-14 01:02:20.431483 | controller | raise InvalidPrerequisiteError(msg) 2026-05-14 01:02:20.432001 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2, returned 1 code: 2026-05-14 01:02:20.432009 | controller | Starting galaxy collection install process 2026-05-14 01:02:20.432016 | controller | Process install dependency map 2026-05-14 01:02:20.432024 | controller | Starting collection install process 2026-05-14 01:02:20.432031 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/kubernetes-core-6.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1478gmh8pc75/tmp81qlsll0/kubernetes-core-6.4.0-9f6_rnrv 2026-05-14 01:02:20.432039 | controller | 2026-05-14 01:02:20.432046 | controller | ansible-galaxy [core 2.20.1] 2026-05-14 01:02:20.432054 | controller | config file = None 2026-05-14 01:02:20.432061 | controller | configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-05-14 01:02:20.432069 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-05-14 01:02:20.432076 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages 2026-05-14 01:02:20.432085 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-05-14 01:02:20.432092 | controller | python version = 3.13.5 (main, Jun 25 2025, 18:55:22) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-05-14 01:02:20.432099 | controller | jinja version = 3.1.6 2026-05-14 01:02:20.432107 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-05-14 01:02:20.432114 | controller | No config file found; using defaults 2026-05-14 01:02:20.432121 | controller | Found installed collection ansible.posix:2.1.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-05-14 01:02:20.432128 | controller | Found installed collection community.crypto:3.2.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-05-14 01:02:20.432135 | controller | Found installed collection community.general:12.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-05-14 01:02:20.432142 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-05-14 01:02:20.432149 | controller | [ERROR]: Failed to download collection tar from 'default': HTTP Error 503: Service Unavailable 2026-05-14 01:02:20.432156 | controller | 2026-05-14 01:02:20.613939 | controller | ERROR 2026-05-14 01:02:20.614219 | controller | { 2026-05-14 01:02:20.614264 | controller | "delta": "0:00:41.119670", 2026-05-14 01:02:20.614294 | controller | "end": "2026-05-14 01:02:20.527658", 2026-05-14 01:02:20.614321 | controller | "msg": "non-zero return code", 2026-05-14 01:02:20.614347 | controller | "rc": 1, 2026-05-14 01:02:20.614376 | controller | "start": "2026-05-14 01:01:39.407988" 2026-05-14 01:02:20.614403 | controller | } failure 2026-05-14 01:02:20.618445 | 2026-05-14 01:02:20.618510 | PLAY RECAP 2026-05-14 01:02:20.618571 | controller | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-14 01:02:20.618602 | 2026-05-14 01:02:20.768606 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-14 01:02:20.770983 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-05-14 01:02:21.364116 | 2026-05-14 01:02:21.364253 | PLAY [all] 2026-05-14 01:02:21.375171 | 2026-05-14 01:02:21.375248 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-05-14 01:02:21.763297 | controller | changed 2026-05-14 01:02:21.768524 | 2026-05-14 01:02:21.768605 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-05-14 01:02:22.161314 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-14 01:02:22.161437 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-14 01:02:22.169335 | controller | environment: line 1: kubectl: command not found 2026-05-14 01:02:22.171475 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-14 01:02:22.171492 | controller | environment: line 1: kubectl: command not found 2026-05-14 01:02:22.172776 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-14 01:02:22.177149 | controller | environment: line 1: kubectl: command not found 2026-05-14 01:02:22.178293 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-14 01:02:22.179150 | controller | environment: line 1: kubectl: command not found 2026-05-14 01:02:22.181881 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-14 01:02:22.182348 | controller | environment: line 1: kubectl: command not found 2026-05-14 01:02:22.183536 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-14 01:02:22.308244 | controller | ok: Runtime: 0:00:00.037356 2026-05-14 01:02:22.314066 | 2026-05-14 01:02:22.314154 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-05-14 01:02:22.565522 | controller | changed 2026-05-14 01:02:22.569711 | 2026-05-14 01:02:22.569784 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-05-14 01:02:22.813944 | controller | environment: line 5: kubectl: command not found 2026-05-14 01:02:22.815445 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-14 01:02:22.815455 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-14 01:02:22.816188 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-14 01:02:23.100373 | controller | ok: Runtime: 0:00:00.013738 2026-05-14 01:02:23.107432 | 2026-05-14 01:02:23.107508 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-05-14 01:02:23.803641 | controller | changed: 2026-05-14 01:02:23.803814 | controller | created directory /var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/work/logs/controller 2026-05-14 01:02:23.803845 | controller | cd+++++++++ objects/ 2026-05-14 01:02:23.803868 | controller | cd+++++++++ objects/cluster/ 2026-05-14 01:02:23.803892 | controller | cd+++++++++ objects/namespaced/ 2026-05-14 01:02:23.810888 | 2026-05-14 01:02:23.810954 | TASK [gather-pod-logs : creating directory for pod logs] 2026-05-14 01:02:24.032454 | controller | changed 2026-05-14 01:02:24.036564 | 2026-05-14 01:02:24.036627 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-05-14 01:02:24.281947 | controller | changed 2026-05-14 01:02:24.288978 | 2026-05-14 01:02:24.289063 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-05-14 01:02:24.523345 | controller | environment: line 3: kubectl: command not found 2026-05-14 01:02:24.816610 | controller | ok: Runtime: 0:00:00.012242 2026-05-14 01:02:24.823169 | 2026-05-14 01:02:24.823288 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-05-14 01:02:25.373558 | controller | changed: 2026-05-14 01:02:25.373792 | controller | cd+++++++++ pod-logs/ 2026-05-14 01:02:25.373862 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-05-14 01:02:25.383220 | 2026-05-14 01:02:25.383285 | LOOP [helm-release-status : creating directory for helm release status] 2026-05-14 01:02:25.629471 | controller | changed: "values" 2026-05-14 01:02:25.827956 | controller | changed: "releases" 2026-05-14 01:02:25.836920 | 2026-05-14 01:02:25.837151 | TASK [helm-release-status : Gather get release status for helm charts] 2026-05-14 01:02:26.068322 | controller | /bin/bash: line 3: kubectl: command not found 2026-05-14 01:02:26.370862 | controller | ok: Runtime: 0:00:00.008681 2026-05-14 01:02:26.376555 | 2026-05-14 01:02:26.376643 | TASK [helm-release-status : Downloads logs to executor] 2026-05-14 01:02:26.923654 | controller | changed: 2026-05-14 01:02:26.923848 | controller | cd+++++++++ helm/ 2026-05-14 01:02:26.923888 | controller | cd+++++++++ helm/releases/ 2026-05-14 01:02:26.923922 | controller | cd+++++++++ helm/values/ 2026-05-14 01:02:26.930359 | 2026-05-14 01:02:26.930424 | PLAY RECAP 2026-05-14 01:02:26.930482 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 01:02:26.930514 | 2026-05-14 01:02:27.048514 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-05-14 01:02:27.051724 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 01:02:27.687096 | 2026-05-14 01:02:27.687247 | PLAY [all] 2026-05-14 01:02:27.697574 | 2026-05-14 01:02:27.697654 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-14 01:02:27.743586 | controller | skipping: Conditional result was False 2026-05-14 01:02:27.752460 | 2026-05-14 01:02:27.752641 | TASK [fetch-output : Set log path for single node] 2026-05-14 01:02:27.797010 | controller | ok 2026-05-14 01:02:27.805540 | 2026-05-14 01:02:27.805685 | LOOP [fetch-output : Ensure local output dirs] 2026-05-14 01:02:28.223019 | controller -> localhost | ok: "/var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/work/logs" 2026-05-14 01:02:28.451182 | controller -> localhost | changed: "/var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/work/artifacts" 2026-05-14 01:02:28.677355 | controller -> localhost | changed: "/var/lib/zuul/builds/9ab3ef03d2244b5e8e0df0907913dfa8/work/docs" 2026-05-14 01:02:28.692473 | 2026-05-14 01:02:28.692573 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-14 01:02:29.385943 | controller | changed: .d..t...... ./ 2026-05-14 01:02:29.386380 | controller | changed: All items complete 2026-05-14 01:02:29.386431 | 2026-05-14 01:02:29.882954 | controller | changed: .d..t...... ./ 2026-05-14 01:02:30.386992 | controller | changed: .d..t...... ./ 2026-05-14 01:02:30.402357 | 2026-05-14 01:02:30.402517 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-14 01:02:30.818767 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008163 2026-05-14 01:02:31.047205 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008480 2026-05-14 01:02:31.062631 | 2026-05-14 01:02:31.062782 | PLAY [all] 2026-05-14 01:02:31.068269 | 2026-05-14 01:02:31.068338 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-14 01:02:31.527195 | controller | changed 2026-05-14 01:02:31.532197 | 2026-05-14 01:02:31.532247 | PLAY RECAP 2026-05-14 01:02:31.532292 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-14 01:02:31.532314 | 2026-05-14 01:02:31.689050 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 01:02:31.692058 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-14 01:02:32.342676 | 2026-05-14 01:02:32.342863 | PLAY [localhost] 2026-05-14 01:02:32.354819 | 2026-05-14 01:02:32.354908 | TASK [Generate Zuul manifest] 2026-05-14 01:02:32.377368 | localhost | ok 2026-05-14 01:02:32.395854 | 2026-05-14 01:02:32.395964 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-14 01:02:32.751833 | localhost | changed 2026-05-14 01:02:32.767803 | 2026-05-14 01:02:32.767910 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-14 01:02:32.800477 | localhost | ok 2026-05-14 01:02:32.808646 | 2026-05-14 01:02:32.808717 | TASK [Upload logs] 2026-05-14 01:02:32.832012 | localhost | ok 2026-05-14 01:02:32.896784 | 2026-05-14 01:02:32.896981 | TASK [Set zuul-log-path fact] 2026-05-14 01:02:32.919697 | localhost | ok 2026-05-14 01:02:32.932740 | 2026-05-14 01:02:32.932813 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 01:02:32.965294 | localhost | ok 2026-05-14 01:02:32.975678 | 2026-05-14 01:02:32.975749 | TASK [upload-logs : Create log directories] 2026-05-14 01:02:33.384072 | localhost | changed 2026-05-14 01:02:33.391215 | 2026-05-14 01:02:33.391334 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-14 01:02:33.799498 | localhost -> localhost | ok: Runtime: 0:00:00.005726 2026-05-14 01:02:33.806569 | 2026-05-14 01:02:33.806648 | TASK [upload-logs : Upload logs to log server] 2026-05-14 01:02:34.266236 | localhost | Output suppressed because no_log was given 2026-05-14 01:02:34.270219 | 2026-05-14 01:02:34.270308 | LOOP [upload-logs : Compress console log and json output] 2026-05-14 01:02:34.319229 | localhost | skipping: Conditional result was False 2026-05-14 01:02:34.377873 | localhost | skipping: Conditional result was False 2026-05-14 01:02:34.396450 | 2026-05-14 01:02:34.396638 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-14 01:02:34.442662 | localhost | skipping: Conditional result was False 2026-05-14 01:02:34.443095 | 2026-05-14 01:02:34.447782 | localhost | skipping: Conditional result was False 2026-05-14 01:02:34.463448 | 2026-05-14 01:02:34.463664 | LOOP [upload-logs : Upload console log and json output]