2026-04-18 17:50:51.422236 | Job console starting
2026-04-18 17:50:51.432333 | Updating git repos
2026-04-18 17:50:51.496033 | Cloning repos into workspace
2026-04-18 17:50:52.007953 | Restoring repo states
2026-04-18 17:50:52.020860 | Merging changes
2026-04-18 17:50:53.551749 | Checking out repos
2026-04-18 17:50:53.604991 | Preparing playbooks
2026-04-18 17:50:55.731984 | Running Ansible setup
2026-04-18 17:50:59.546230 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 17:51:00.165924 | 
2026-04-18 17:51:00.166064 | PLAY [localhost]
2026-04-18 17:51:00.213570 | 
2026-04-18 17:51:00.213772 | TASK [Gathering Facts]
2026-04-18 17:51:01.161140 | localhost | ok
2026-04-18 17:51:01.171115 | 
2026-04-18 17:51:01.171247 | TASK [Setup log path fact]
2026-04-18 17:51:01.189370 | localhost | ok
2026-04-18 17:51:01.201244 | 
2026-04-18 17:51:01.201351 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 17:51:01.241249 | localhost | ok
2026-04-18 17:51:01.250050 | 
2026-04-18 17:51:01.250165 | TASK [emit-job-header : Print job information]
2026-04-18 17:51:01.289357 | # Job Information
2026-04-18 17:51:01.289549 | Ansible Version: 2.16.16
2026-04-18 17:51:01.289594 | Job: ansible-collection-kubernetes-molecule-aio-ubuntu-jammy
2026-04-18 17:51:01.289628 | Pipeline: check
2026-04-18 17:51:01.289658 | Executor: 0a8996d2b663
2026-04-18 17:51:01.289688 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/260
2026-04-18 17:51:01.289719 | Event ID: 63431510-3b4c-11f1-9dcc-f0b7af1855b2
2026-04-18 17:51:01.293725 | 
2026-04-18 17:51:01.293855 | LOOP [emit-job-header : Print node information]
2026-04-18 17:51:01.386758 | localhost | ok:
2026-04-18 17:51:01.386924 | localhost | # Node Information
2026-04-18 17:51:01.386954 | localhost | Inventory Hostname: controller
2026-04-18 17:51:01.386976 | localhost | Hostname: np0000167598
2026-04-18 17:51:01.386996 | localhost | Username: zuul
2026-04-18 17:51:01.387020 | localhost | Distro: Ubuntu 22.04
2026-04-18 17:51:01.387040 | localhost | Provider: yul1
2026-04-18 17:51:01.387059 | localhost | Region: ca-ymq-1
2026-04-18 17:51:01.387078 | localhost | Label: ubuntu-jammy
2026-04-18 17:51:01.387096 | localhost | Product Name: OpenStack Nova
2026-04-18 17:51:01.387114 | localhost | Interface IP: 199.19.213.146
2026-04-18 17:51:01.399418 | 
2026-04-18 17:51:01.399572 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-18 17:51:01.809875 | localhost -> localhost | changed
2026-04-18 17:51:01.816781 | 
2026-04-18 17:51:01.816891 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-18 17:51:03.816611 | localhost -> localhost | changed
2026-04-18 17:51:03.823278 | 
2026-04-18 17:51:03.823415 | PLAY [all]
2026-04-18 17:51:03.841730 | 
2026-04-18 17:51:03.841925 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-18 17:51:04.079413 | controller -> localhost | ok
2026-04-18 17:51:04.086808 | 
2026-04-18 17:51:04.086894 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-18 17:51:04.119116 | controller | ok
2026-04-18 17:51:04.137282 | controller | included: /var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-18 17:51:04.143402 | 
2026-04-18 17:51:04.143470 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-18 17:51:05.290506 | controller -> localhost | Generating public/private rsa key pair.
2026-04-18 17:51:05.290789 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/work/ab05088d6fb04fe9ac039cfea729f94f_id_rsa
2026-04-18 17:51:05.290822 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/work/ab05088d6fb04fe9ac039cfea729f94f_id_rsa.pub
2026-04-18 17:51:05.290846 | controller -> localhost | The key fingerprint is:
2026-04-18 17:51:05.290867 | controller -> localhost | SHA256:P5yBBvtcewz1Wy0WqB6OJSx41XH1g82ZcRQA/nkzLpg zuul-build-sshkey
2026-04-18 17:51:05.290902 | controller -> localhost | The key's randomart image is:
2026-04-18 17:51:05.290924 | controller -> localhost | +---[RSA 3072]----+
2026-04-18 17:51:05.290950 | controller -> localhost | |          ..ooo++|
2026-04-18 17:51:05.290973 | controller -> localhost | |         ..o .+.=|
2026-04-18 17:51:05.290995 | controller -> localhost | |      . . ..o..*.|
2026-04-18 17:51:05.291015 | controller -> localhost | |     . = . o....o|
2026-04-18 17:51:05.291034 | controller -> localhost | |    . + S B  o++o|
2026-04-18 17:51:05.291055 | controller -> localhost | |     . = X Bo.o+o|
2026-04-18 17:51:05.291074 | controller -> localhost | |        + OEo... |
2026-04-18 17:51:05.291094 | controller -> localhost | |           o  .  |
2026-04-18 17:51:05.291116 | controller -> localhost | |                 |
2026-04-18 17:51:05.291346 | controller -> localhost | +----[SHA256]-----+
2026-04-18 17:51:05.291407 | controller -> localhost | ok: Runtime: 0:00:00.702528
2026-04-18 17:51:05.297560 | 
2026-04-18 17:51:05.297836 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-18 17:51:05.327392 | controller | ok
2026-04-18 17:51:05.336940 | controller | included: /var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-18 17:51:05.346388 | 
2026-04-18 17:51:05.346489 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-18 17:51:05.371127 | controller | skipping: Conditional result was False
2026-04-18 17:51:05.381989 | 
2026-04-18 17:51:05.382084 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-18 17:51:05.812815 | controller | changed
2026-04-18 17:51:05.818965 | 
2026-04-18 17:51:05.819053 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-18 17:51:06.014518 | controller | ok
2026-04-18 17:51:06.021697 | 
2026-04-18 17:51:06.021770 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-18 17:51:06.540965 | controller | changed
2026-04-18 17:51:06.548106 | 
2026-04-18 17:51:06.548197 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-18 17:51:07.010426 | controller | changed
2026-04-18 17:51:07.613704 | 
2026-04-18 17:51:07.613850 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-18 17:51:07.638237 | controller | skipping: Conditional result was False
2026-04-18 17:51:07.649176 | 
2026-04-18 17:51:07.649318 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-18 17:51:08.258250 | controller -> localhost | changed
2026-04-18 17:51:08.273665 | 
2026-04-18 17:51:08.273775 | TASK [add-build-sshkey : Add back temp key]
2026-04-18 17:51:08.889347 | controller -> localhost | Identity added: /var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/work/ab05088d6fb04fe9ac039cfea729f94f_id_rsa (zuul-build-sshkey)
2026-04-18 17:51:08.889574 | controller -> localhost | ok: Runtime: 0:00:00.016105
2026-04-18 17:51:08.897151 | 
2026-04-18 17:51:08.897243 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-18 17:51:09.242129 | controller | ok
2026-04-18 17:51:09.246654 | 
2026-04-18 17:51:09.246723 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-18 17:51:09.270810 | controller | skipping: Conditional result was False
2026-04-18 17:51:09.286249 | 
2026-04-18 17:51:09.286336 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-18 17:51:09.684275 | controller | ok
2026-04-18 17:51:09.692295 | 
2026-04-18 17:51:09.692451 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-18 17:51:10.651712 | controller | Output suppressed because no_log was given
2026-04-18 17:51:10.661242 | 
2026-04-18 17:51:10.661326 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-18 17:51:10.855737 | controller | ok: "logs"
2026-04-18 17:51:10.856203 | controller | ok: All items complete
2026-04-18 17:51:10.856249 | 
2026-04-18 17:51:11.011110 | controller | ok: "artifacts"
2026-04-18 17:51:11.148720 | controller | ok: "docs"
2026-04-18 17:51:11.194617 | 
2026-04-18 17:51:11.194742 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-18 17:51:11.375106 | controller | changed: "logs"
2026-04-18 17:51:11.532293 | controller | changed: "artifacts"
2026-04-18 17:51:11.688329 | controller | changed: "docs"
2026-04-18 17:51:11.706876 | 
2026-04-18 17:51:11.706992 | PLAY RECAP
2026-04-18 17:51:11.707055 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-18 17:51:11.707096 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 17:51:11.707126 | 
2026-04-18 17:51:11.858408 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 17:51:11.860974 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-18 17:51:12.505919 | 
2026-04-18 17:51:12.506131 | PLAY [all]
2026-04-18 17:51:12.520881 | 
2026-04-18 17:51:12.521039 | TASK [setup-uv : Extract archive]
2026-04-18 17:51:14.829171 | controller | changed
2026-04-18 17:51:14.964216 | 
2026-04-18 17:51:14.964388 | TASK [setup-uv : Print version]
2026-04-18 17:51:15.304908 | controller | uv 0.8.13
2026-04-18 17:51:15.503144 | controller | ok: Runtime: 0:00:00.012330
2026-04-18 17:51:15.511297 | 
2026-04-18 17:51:15.511369 | PLAY RECAP
2026-04-18 17:51:15.511431 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 17:51:15.511470 | 
2026-04-18 17:51:15.635736 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-18 17:51:15.637158 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-04-18 17:51:16.235004 | 
2026-04-18 17:51:16.235126 | PLAY [all]
2026-04-18 17:51:16.245914 | 
2026-04-18 17:51:16.245995 | TASK [Update apt cache]
2026-04-18 17:51:23.093811 | controller | changed
2026-04-18 17:51:23.276265 | 
2026-04-18 17:51:23.276534 | TASK [Disable firewalld]
2026-04-18 17:51:23.302443 | controller | skipping: Conditional result was False
2026-04-18 17:51:23.313708 | 
2026-04-18 17:51:23.313852 | TASK [Install "jq" for log collection]
2026-04-18 17:51:26.254329 | controller | changed
2026-04-18 17:51:26.258172 | 
2026-04-18 17:51:26.258295 | PLAY RECAP
2026-04-18 17:51:26.258356 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-18 17:51:26.258387 | 
2026-04-18 17:51:26.390414 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-04-18 17:51:26.391949 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-18 17:51:27.072213 | 
2026-04-18 17:51:27.072536 | PLAY [all]
2026-04-18 17:51:27.084489 | 
2026-04-18 17:51:27.084592 | TASK [Copy inventory file for Zuul]
2026-04-18 17:51:27.924854 | controller | changed
2026-04-18 17:51:27.931353 | 
2026-04-18 17:51:27.931461 | TASK [Switch "ansible_host" to private IP]
2026-04-18 17:51:28.205228 | controller | changed: 1 replacements made
2026-04-18 17:51:28.212170 | 
2026-04-18 17:51:28.212244 | TASK [Run Molecule scenario]
2026-04-18 17:51:28.602728 | controller | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-04-18 17:51:28.602886 | controller | Creating virtual environment at: .venv
2026-04-18 17:51:28.881868 | controller | Downloading cryptography (4.3MiB)
2026-04-18 17:51:28.882595 | controller | Downloading ansible-core (2.1MiB)
2026-04-18 17:51:28.882769 | controller | Downloading pygments (1.2MiB)
2026-04-18 17:51:29.047087 | controller |  Downloading cryptography
2026-04-18 17:51:29.080058 | controller |  Downloading pygments
2026-04-18 17:51:29.198021 | controller |  Downloading ansible-core
2026-04-18 17:51:29.228109 | controller | Installed 39 packages in 29ms
2026-04-18 17:51:29.851542 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-04-18 17:51:29.851654 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-04-18 17:51:30.430793 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-04-18 17:51:30.430890 | controller | INFO     default scenario not found, disabling shared state.
2026-04-18 17:51:30.430904 | controller | INFO     [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-18 17:51:30.430982 | controller | INFO     [aio > prerun] Performing prerun with role_name_check=0...
2026-04-18 17:51:45.254670 | controller | INFO     [aio > dependency] Executing
2026-04-18 17:51:45.254810 | controller | WARNING  [aio > dependency] Missing roles requirements file: requirements.yml
2026-04-18 17:51:45.254998 | controller | WARNING  [aio > dependency] Missing collections requirements file: collections.yml
2026-04-18 17:51:45.255164 | controller | WARNING  [aio > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-04-18 17:51:45.263292 | controller | INFO     [aio > cleanup] Executing
2026-04-18 17:51:45.263607 | controller | WARNING  [aio > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-18 17:51:45.271326 | controller | INFO     [aio > destroy] Executing
2026-04-18 17:51:45.271373 | controller | WARNING  [aio > destroy] Skipping, '--destroy=never' requested.
2026-04-18 17:51:45.271463 | controller | INFO     [aio > destroy] Executed: Successful
2026-04-18 17:51:45.279138 | controller | INFO     [aio > syntax] Executing
2026-04-18 17:51:46.384499 | controller |
2026-04-18 17:51:46.384720 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/converge.yml
2026-04-18 17:51:46.434551 | controller | INFO     [aio > syntax] Executed: Successful
2026-04-18 17:51:46.448028 | controller | INFO     [aio > create] Executing
2026-04-18 17:51:46.450354 | controller | WARNING  [aio > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-18 17:51:46.457981 | controller | INFO     [aio > prepare] Executing
2026-04-18 17:51:47.226419 | controller |
2026-04-18 17:51:47.226518 | controller | PLAY [Prepare] *****************************************************************
2026-04-18 17:51:47.226561 | controller |
2026-04-18 17:51:47.226721 | controller | TASK [Gathering Facts] *********************************************************
2026-04-18 17:51:47.226895 | controller | Saturday 18 April 2026  17:51:47 +0000 (0:00:00.027)       0:00:00.027 ********
2026-04-18 17:51:48.346529 | controller | [WARNING]: Platform linux on host controller is using the discovered Python
2026-04-18 17:51:48.346820 | controller | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-04-18 17:51:48.347040 | controller | interpreter could change the meaning of that path. See
2026-04-18 17:51:48.347280 | controller | https://docs.ansible.com/ansible-
2026-04-18 17:51:48.347558 | controller | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-04-18 17:51:48.356773 | controller | ok: [controller]
2026-04-18 17:51:48.357090 | controller |
2026-04-18 17:51:48.357439 | controller | TASK [Install Debian packages] *************************************************
2026-04-18 17:51:48.357743 | controller | Saturday 18 April 2026  17:51:48 +0000 (0:00:01.130)       0:00:01.158 ********
2026-04-18 17:51:52.045998 | controller | ok: [controller]
2026-04-18 17:51:52.046162 | controller |
2026-04-18 17:51:52.046518 | controller | PLAY RECAP *********************************************************************
2026-04-18 17:51:52.046804 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-04-18 17:51:52.047035 | controller |
2026-04-18 17:51:52.047198 | controller |
2026-04-18 17:51:52.047342 | controller | TASKS RECAP ********************************************************************
2026-04-18 17:51:52.047502 | controller | Saturday 18 April 2026  17:51:52 +0000 (0:00:03.681)       0:00:04.840 ********
2026-04-18 17:51:52.047651 | controller | ===============================================================================
2026-04-18 17:51:52.047790 | controller | Install Debian packages ------------------------------------------------- 3.68s
2026-04-18 17:51:52.047943 | controller | Gathering Facts --------------------------------------------------------- 1.13s
2026-04-18 17:51:52.096742 | controller | INFO     [aio > prepare] Executed: Successful
2026-04-18 17:51:52.109402 | controller | INFO     [aio > converge] Executing
2026-04-18 17:51:53.112699 | controller |
2026-04-18 17:51:53.112819 | controller | PLAY [Configure Kubernetes VIP] ************************************************
2026-04-18 17:51:53.112832 | controller |
2026-04-18 17:51:53.112977 | controller | TASK [Gathering Facts] *********************************************************
2026-04-18 17:51:53.113143 | controller | Saturday 18 April 2026  17:51:53 +0000 (0:00:00.014)       0:00:00.014 ********
2026-04-18 17:51:54.061382 | controller | [WARNING]: Platform linux on host controller is using the discovered Python
2026-04-18 17:51:54.061619 | controller | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-04-18 17:51:54.061871 | controller | interpreter could change the meaning of that path. See
2026-04-18 17:51:54.062113 | controller | https://docs.ansible.com/ansible-
2026-04-18 17:51:54.062413 | controller | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-04-18 17:51:54.072114 | controller | ok: [controller]
2026-04-18 17:51:54.072250 | controller |
2026-04-18 17:51:54.072410 | controller | TASK [vexxhost.containers.directory : Create directory (/etc/kubernetes/manifests)] ***
2026-04-18 17:51:54.072560 | controller | Saturday 18 April 2026  17:51:54 +0000 (0:00:00.959)       0:00:00.974 ********
2026-04-18 17:51:54.369307 | controller | changed: [controller]
2026-04-18 17:51:54.369499 | controller |
2026-04-18 17:51:54.369752 | controller | TASK [vexxhost.kubernetes.kube_vip : Uninstall legacy HA stack] ****************
2026-04-18 17:51:54.369993 | controller | Saturday 18 April 2026  17:51:54 +0000 (0:00:00.296)       0:00:01.271 ********
2026-04-18 17:51:55.249558 | controller | ok: [controller] => (item=/etc/keepalived/keepalived.conf)
2026-04-18 17:51:55.249776 | controller | ok: [controller] => (item=/etc/keepalived/check_apiserver.sh)
2026-04-18 17:51:55.250023 | controller | ok: [controller] => (item=/etc/kubernetes/manifests/keepalived.yaml)
2026-04-18 17:51:55.250266 | controller | ok: [controller] => (item=/etc/haproxy/haproxy.cfg)
2026-04-18 17:51:55.250507 | controller | ok: [controller] => (item=/etc/kubernetes/manifests/haproxy.yaml)
2026-04-18 17:51:55.250725 | controller |
2026-04-18 17:51:55.251023 | controller | TASK [vexxhost.kubernetes.kube_vip : Switch API server to run on port 6443] ****
2026-04-18 17:51:55.251281 | controller | Saturday 18 April 2026  17:51:55 +0000 (0:00:00.880)       0:00:02.151 ********
2026-04-18 17:51:55.496039 | controller | failed: [controller] (item=/etc/kubernetes/manifests/kube-apiserver.yaml) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/manifests/kube-apiserver.yaml", "msg": "Path /etc/kubernetes/manifests/kube-apiserver.yaml does not exist !", "rc": 257}
2026-04-18 17:51:55.654574 | controller | failed: [controller] (item=/etc/kubernetes/controller-manager.conf) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/controller-manager.conf", "msg": "Path /etc/kubernetes/controller-manager.conf does not exist !", "rc": 257}
2026-04-18 17:51:55.812348 | controller | failed: [controller] (item=/etc/kubernetes/scheduler.conf) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/scheduler.conf", "msg": "Path /etc/kubernetes/scheduler.conf does not exist !", "rc": 257}
2026-04-18 17:51:55.823918 | controller | ...ignoring
2026-04-18 17:51:55.823999 | controller |
2026-04-18 17:51:55.824121 | controller | TASK [vexxhost.kubernetes.kube_vip : Check if super-admin.conf exists] *********
2026-04-18 17:51:55.824245 | controller | Saturday 18 April 2026  17:51:55 +0000 (0:00:00.574)       0:00:02.726 ********
2026-04-18 17:51:56.075844 | controller | ok: [controller]
2026-04-18 17:51:56.076191 | controller |
2026-04-18 17:51:56.076505 | controller | TASK [vexxhost.kubernetes.kube_vip : Check if kubeadm has already run] *********
2026-04-18 17:51:56.076862 | controller | Saturday 18 April 2026  17:51:56 +0000 (0:00:00.251)       0:00:02.977 ********
2026-04-18 17:51:56.256965 | controller | ok: [controller]
2026-04-18 17:51:56.257295 | controller |
2026-04-18 17:51:56.257598 | controller | TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path] ************
2026-04-18 17:51:56.257880 | controller | Saturday 18 April 2026  17:51:56 +0000 (0:00:00.181)       0:00:03.159 ********
2026-04-18 17:51:56.285654 | controller | ok: [controller]
2026-04-18 17:51:56.285936 | controller |
2026-04-18 17:51:56.286250 | controller | TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path (with super-admin.conf)] ***
2026-04-18 17:51:56.286529 | controller | Saturday 18 April 2026  17:51:56 +0000 (0:00:00.028)       0:00:03.187 ********
2026-04-18 17:51:56.319035 | controller | ok: [controller]
2026-04-18 17:51:56.319316 | controller |
2026-04-18 17:51:56.319712 | controller | TASK [vexxhost.kubernetes.kube_vip : Upload Kubernetes manifest] ***************
2026-04-18 17:51:56.319991 | controller | Saturday 18 April 2026  17:51:56 +0000 (0:00:00.033)       0:00:03.221 ********
2026-04-18 17:51:56.975589 | controller | ok: [controller]
2026-04-18 17:51:56.975703 | controller |
2026-04-18 17:51:56.975867 | controller | TASK [vexxhost.kubernetes.kube_vip : Ensure kube-vip configuration file] *******
2026-04-18 17:51:56.976067 | controller | Saturday 18 April 2026  17:51:56 +0000 (0:00:00.656)       0:00:03.877 ********
2026-04-18 17:51:57.213613 | controller | ok: [controller]
2026-04-18 17:51:57.213686 | controller |
2026-04-18 17:51:57.213754 | controller | TASK [vexxhost.kubernetes.kube_vip : Flush handlers] ***************************
2026-04-18 17:51:57.213865 | controller | Saturday 18 April 2026  17:51:57 +0000 (0:00:00.180)       0:00:04.058 ********
2026-04-18 17:51:57.213960 | controller |
2026-04-18 17:51:57.214068 | controller | PLAY [Install Kubernetes] ******************************************************
2026-04-18 17:51:57.214167 | controller |
2026-04-18 17:51:57.214275 | controller | TASK [Gathering Facts] *********************************************************
2026-04-18 17:51:57.214383 | controller | Saturday 18 April 2026  17:51:57 +0000 (0:00:00.057)       0:00:04.116 ********
2026-04-18 17:51:57.888465 | controller | ok: [controller]
2026-04-18 17:51:57.888695 | controller |
2026-04-18 17:51:57.888962 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-18 17:51:57.889271 | controller | Saturday 18 April 2026  17:51:57 +0000 (0:00:00.674)       0:00:04.790 ********
2026-04-18 17:51:58.086158 | controller | ok: [controller]
2026-04-18 17:51:58.086389 | controller |
2026-04-18 17:51:58.086706 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-18 17:51:58.087006 | controller | Saturday 18 April 2026  17:51:58 +0000 (0:00:00.197)       0:00:04.987 ********
2026-04-18 17:51:58.117505 | controller | skipping: [controller]
2026-04-18 17:51:58.117612 | controller |
2026-04-18 17:51:58.117718 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-04-18 17:51:58.117878 | controller | Saturday 18 April 2026  17:51:58 +0000 (0:00:00.032)       0:00:05.019 ********
2026-04-18 17:51:58.326497 | controller | changed: [controller]
2026-04-18 17:51:58.326673 | controller |
2026-04-18 17:51:58.326943 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-18 17:51:58.327205 | controller | Saturday 18 April 2026  17:51:58 +0000 (0:00:00.208)       0:00:05.228 ********
2026-04-18 17:51:58.400425 | controller | ok: [controller] => {
2026-04-18 17:51:58.400545 | controller |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-04-18 17:51:58.400834 | controller | }
2026-04-18 17:51:58.401152 | controller |
2026-04-18 17:51:58.401421 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-04-18 17:51:58.401670 | controller | Saturday 18 April 2026  17:51:58 +0000 (0:00:00.073)       0:00:05.302 ********
2026-04-18 17:52:20.122681 | controller | fatal: [controller]: FAILED! => {"attempts": 4, "censored": "the output has been hidden due to the fact that 'no_log: true' was specified for this result", "changed": false}
2026-04-18 17:52:20.129815 | controller | FAILED - RETRYING: [controller]: Download item (4 retries left).
2026-04-18 17:52:20.131039 | controller | FAILED - RETRYING: [controller]: Download item (3 retries left).
2026-04-18 17:52:20.131087 | controller | FAILED - RETRYING: [controller]: Download item (2 retries left).
2026-04-18 17:52:20.131097 | controller | FAILED - RETRYING: [controller]: Download item (1 retries left).
2026-04-18 17:52:20.131129 | controller |
2026-04-18 17:52:20.131157 | controller | PLAY RECAP *********************************************************************
2026-04-18 17:52:20.131168 | controller | controller                 : ok=14   changed=2    unreachable=0    failed=1    skipped=1    rescued=0    ignored=1
2026-04-18 17:52:20.131178 | controller |
2026-04-18 17:52:20.131187 | controller |
2026-04-18 17:52:20.131196 | controller | TASKS RECAP ********************************************************************
2026-04-18 17:52:20.131205 | controller | Saturday 18 April 2026  17:52:20 +0000 (0:00:21.724)       0:00:27.027 ********
2026-04-18 17:52:20.131214 | controller | ===============================================================================
2026-04-18 17:52:20.131223 | controller | vexxhost.containers.download_artifact : Download item ------------------ 21.72s
2026-04-18 17:52:20.131232 | controller | Gathering Facts --------------------------------------------------------- 0.96s
2026-04-18 17:52:20.131240 | controller | vexxhost.kubernetes.kube_vip : Uninstall legacy HA stack ---------------- 0.88s
2026-04-18 17:52:20.131249 | controller | Gathering Facts --------------------------------------------------------- 0.67s
2026-04-18 17:52:20.131258 | controller | vexxhost.kubernetes.kube_vip : Upload Kubernetes manifest --------------- 0.66s
2026-04-18 17:52:20.131267 | controller | vexxhost.kubernetes.kube_vip : Switch API server to run on port 6443 ---- 0.57s
2026-04-18 17:52:20.131275 | controller | vexxhost.containers.directory : Create directory (/etc/kubernetes/manifests) --- 0.30s
2026-04-18 17:52:20.131284 | controller | vexxhost.kubernetes.kube_vip : Check if super-admin.conf exists --------- 0.25s
2026-04-18 17:52:20.131293 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.21s
2026-04-18 17:52:20.131305 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.20s
2026-04-18 17:52:20.131315 | controller | vexxhost.kubernetes.kube_vip : Check if kubeadm has already run --------- 0.18s
2026-04-18 17:52:20.131324 | controller | vexxhost.kubernetes.kube_vip : Ensure kube-vip configuration file ------- 0.18s
2026-04-18 17:52:20.131333 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.07s
2026-04-18 17:52:20.131342 | controller | vexxhost.kubernetes.kube_vip : Flush handlers --------------------------- 0.06s
2026-04-18 17:52:20.131350 | controller | vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path (with super-admin.conf) --- 0.03s
2026-04-18 17:52:20.131359 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.03s
2026-04-18 17:52:20.131375 | controller | vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path ------------ 0.03s
2026-04-18 17:52:20.198786 | controller | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.Bs5j.aio/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/converge.yml
2026-04-18 17:52:20.198970 | controller | ERROR    [aio > converge] Executed: Failed
2026-04-18 17:52:20.199156 | controller | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.Bs5j.aio/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/converge.yml
2026-04-18 17:52:20.360913 | controller | ERROR
2026-04-18 17:52:20.361131 | controller | {
2026-04-18 17:52:20.361162 | controller |   "delta": "0:00:51.713171",
2026-04-18 17:52:20.361184 | controller |   "end": "2026-04-18 17:52:20.257510",
2026-04-18 17:52:20.361203 | controller |   "msg": "non-zero return code",
2026-04-18 17:52:20.361221 | controller |   "rc": 2,
2026-04-18 17:52:20.361242 | controller |   "start": "2026-04-18 17:51:28.544339"
2026-04-18 17:52:20.361260 | controller | }
failure
2026-04-18 17:52:20.364074 | 
2026-04-18 17:52:20.364123 | PLAY RECAP
2026-04-18 17:52:20.364171 | controller | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-18 17:52:20.364194 | 
2026-04-18 17:52:20.485092 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-18 17:52:20.486982 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-04-18 17:52:21.125818 | 
2026-04-18 17:52:21.125973 | PLAY [all]
2026-04-18 17:52:21.138468 | 
2026-04-18 17:52:21.138561 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-18 17:52:21.537423 | controller | changed
2026-04-18 17:52:21.543405 | 
2026-04-18 17:52:21.543500 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-18 17:52:21.902492 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-18 17:52:21.902881 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-18 17:52:21.905457 | controller | environment: line 1: kubectl: command not found
2026-04-18 17:52:21.906518 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-18 17:52:21.907541 | controller | environment: line 1: kubectl: command not found
2026-04-18 17:52:21.908615 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-18 17:52:21.909519 | controller | environment: line 1: kubectl: command not found
2026-04-18 17:52:21.910163 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-18 17:52:21.911830 | controller | environment: line 1: kubectl: command not found
2026-04-18 17:52:21.912752 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-18 17:52:21.914080 | controller | environment: line 1: kubectl: command not found
2026-04-18 17:52:21.915397 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-18 17:52:22.082205 | controller | ok: Runtime: 0:00:00.022595
2026-04-18 17:52:22.088086 | 
2026-04-18 17:52:22.088168 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-18 17:52:22.285675 | controller | changed
2026-04-18 17:52:22.293576 | 
2026-04-18 17:52:22.293664 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-18 17:52:22.498926 | controller | environment: line 5: kubectl: command not found
2026-04-18 17:52:22.499099 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-18 17:52:22.499958 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-18 17:52:22.499989 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-18 17:52:22.830089 | controller | ok: Runtime: 0:00:00.006764
2026-04-18 17:52:22.838063 | 
2026-04-18 17:52:22.838181 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-18 17:52:23.472856 | controller | changed:
2026-04-18 17:52:23.473035 | controller | created directory /var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/work/logs/controller
2026-04-18 17:52:23.473066 | controller | cd+++++++++ objects/
2026-04-18 17:52:23.473089 | controller | cd+++++++++ objects/cluster/
2026-04-18 17:52:23.473112 | controller | cd+++++++++ objects/namespaced/
2026-04-18 17:52:23.482129 | 
2026-04-18 17:52:23.482207 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-18 17:52:23.679416 | controller | changed
2026-04-18 17:52:23.684313 | 
2026-04-18 17:52:23.684380 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-18 17:52:23.879983 | controller | changed
2026-04-18 17:52:23.887666 | 
2026-04-18 17:52:23.887767 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-18 17:52:24.090226 | controller | environment: line 3: kubectl: command not found
2026-04-18 17:52:24.422290 | controller | ok: Runtime: 0:00:00.008286
2026-04-18 17:52:24.429127 | 
2026-04-18 17:52:24.429198 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-18 17:52:24.923787 | controller | changed:
2026-04-18 17:52:24.923944 | controller | cd+++++++++ pod-logs/
2026-04-18 17:52:24.923975 | controller | cd+++++++++ pod-logs/failed-pods/
2026-04-18 17:52:24.933825 | 
2026-04-18 17:52:24.933896 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-18 17:52:25.129954 | controller | changed: "values"
2026-04-18 17:52:25.297586 | controller | changed: "releases"
2026-04-18 17:52:25.317588 | 
2026-04-18 17:52:25.317786 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-18 17:52:25.604628 | controller | /bin/bash: line 3: kubectl: command not found
2026-04-18 17:52:25.908772 | controller | ok: Runtime: 0:00:00.006206
2026-04-18 17:52:25.916256 | 
2026-04-18 17:52:25.916338 | TASK [helm-release-status : Downloads logs to executor]
2026-04-18 17:52:26.426023 | controller | changed:
2026-04-18 17:52:26.426228 | controller | cd+++++++++ helm/
2026-04-18 17:52:26.426269 | controller | cd+++++++++ helm/releases/
2026-04-18 17:52:26.426306 | controller | cd+++++++++ helm/values/
2026-04-18 17:52:26.434157 | 
2026-04-18 17:52:26.434213 | PLAY RECAP
2026-04-18 17:52:26.434312 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 17:52:26.434342 | 
2026-04-18 17:52:26.558221 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-04-18 17:52:26.561344 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 17:52:27.183817 | 
2026-04-18 17:52:27.183957 | PLAY [all]
2026-04-18 17:52:27.196885 | 
2026-04-18 17:52:27.197151 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-18 17:52:27.242324 | controller | skipping: Conditional result was False
2026-04-18 17:52:27.251197 | 
2026-04-18 17:52:27.251345 | TASK [fetch-output : Set log path for single node]
2026-04-18 17:52:27.293618 | controller | ok
2026-04-18 17:52:27.299345 | 
2026-04-18 17:52:27.299417 | LOOP [fetch-output : Ensure local output dirs]
2026-04-18 17:52:27.703984 | controller -> localhost | ok: "/var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/work/logs"
2026-04-18 17:52:28.223837 | controller -> localhost | changed: "/var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/work/artifacts"
2026-04-18 17:52:28.438110 | controller -> localhost | changed: "/var/lib/zuul/builds/ab05088d6fb04fe9ac039cfea729f94f/work/docs"
2026-04-18 17:52:28.461543 | 
2026-04-18 17:52:28.461759 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-18 17:52:29.099104 | controller | changed: .d..t...... ./
2026-04-18 17:52:29.099344 | controller | changed: All items complete
2026-04-18 17:52:29.099387 | 
2026-04-18 17:52:29.545272 | controller | changed: .d..t...... ./
2026-04-18 17:52:29.988673 | controller | changed: .d..t...... ./
2026-04-18 17:52:30.015766 | 
2026-04-18 17:52:30.015917 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-18 17:52:30.437317 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008768
2026-04-18 17:52:30.673966 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007320
2026-04-18 17:52:30.694770 | 
2026-04-18 17:52:30.694900 | PLAY [all]
2026-04-18 17:52:30.703732 | 
2026-04-18 17:52:30.703810 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-18 17:52:31.146826 | controller | changed
2026-04-18 17:52:31.157604 | 
2026-04-18 17:52:31.157657 | PLAY RECAP
2026-04-18 17:52:31.157700 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-18 17:52:31.157722 | 
2026-04-18 17:52:31.275739 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 17:52:31.277874 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-18 17:52:31.877027 | 
2026-04-18 17:52:31.877234 | PLAY [localhost]
2026-04-18 17:52:31.889096 | 
2026-04-18 17:52:31.889265 | TASK [Generate Zuul manifest]
2026-04-18 17:52:31.913832 | localhost | ok
2026-04-18 17:52:31.935836 | 
2026-04-18 17:52:31.936004 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-18 17:52:32.265069 | localhost | changed
2026-04-18 17:52:32.277677 | 
2026-04-18 17:52:32.277755 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-18 17:52:32.309842 | localhost | ok
2026-04-18 17:52:32.319364 | 
2026-04-18 17:52:32.319470 | TASK [Upload logs]
2026-04-18 17:52:32.343065 | localhost | ok
2026-04-18 17:52:32.408131 | 
2026-04-18 17:52:32.408310 | TASK [Set zuul-log-path fact]
2026-04-18 17:52:32.430614 | localhost | ok
2026-04-18 17:52:32.446383 | 
2026-04-18 17:52:32.446480 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 17:52:32.476002 | localhost | ok
2026-04-18 17:52:32.485246 | 
2026-04-18 17:52:32.485333 | TASK [upload-logs : Create log directories]
2026-04-18 17:52:32.915513 | localhost | changed
2026-04-18 17:52:32.921245 | 
2026-04-18 17:52:32.921314 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-18 17:52:33.334949 | localhost -> localhost | ok: Runtime: 0:00:00.005108
2026-04-18 17:52:33.341859 | 
2026-04-18 17:52:33.341951 | TASK [upload-logs : Upload logs to log server]
2026-04-18 17:52:33.806107 | localhost | Output suppressed because no_log was given
2026-04-18 17:52:33.810169 | 
2026-04-18 17:52:33.810239 | LOOP [upload-logs : Compress console log and json output]
2026-04-18 17:52:33.856747 | localhost | skipping: Conditional result was False
2026-04-18 17:52:33.864319 | localhost | skipping: Conditional result was False
2026-04-18 17:52:33.876792 | 
2026-04-18 17:52:33.877131 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-18 17:52:33.919317 | localhost | skipping: Conditional result was False
2026-04-18 17:52:33.919804 | 
2026-04-18 17:52:33.923005 | localhost | skipping: Conditional result was False
2026-04-18 17:52:33.933253 | 
2026-04-18 17:52:33.933493 | LOOP [upload-logs : Upload console log and json output]