2026-03-31 00:17:22.056266 | Job console starting 2026-03-31 00:17:22.098465 | Updating git repos 2026-03-31 00:17:22.136950 | Cloning repos into workspace 2026-03-31 00:17:22.387829 | Restoring repo states 2026-03-31 00:17:22.401842 | Merging changes 2026-03-31 00:17:23.133632 | Checking out repos 2026-03-31 00:17:23.191535 | Preparing playbooks 2026-03-31 00:17:25.492174 | Running Ansible setup 2026-03-31 00:17:29.285588 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-31 00:17:29.885457 | 2026-03-31 00:17:29.885614 | PLAY [localhost] 2026-03-31 00:17:29.893617 | 2026-03-31 00:17:29.893696 | TASK [Gathering Facts] 2026-03-31 00:17:30.781872 | localhost | ok 2026-03-31 00:17:30.788862 | 2026-03-31 00:17:30.788941 | TASK [Setup log path fact] 2026-03-31 00:17:30.807848 | localhost | ok 2026-03-31 00:17:30.818174 | 2026-03-31 00:17:30.818243 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-31 00:17:30.868184 | localhost | ok 2026-03-31 00:17:30.874045 | 2026-03-31 00:17:30.874113 | TASK [emit-job-header : Print job information] 2026-03-31 00:17:30.912563 | # Job Information 2026-03-31 00:17:30.912683 | Ansible Version: 2.16.16 2026-03-31 00:17:30.912715 | Job: ansible-collection-kubernetes-molecule-helm 2026-03-31 00:17:30.912737 | Pipeline: check 2026-03-31 00:17:30.912758 | Executor: 0a8996d2b663 2026-03-31 00:17:30.912778 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/256 2026-03-31 00:17:30.912799 | Event ID: d453e320-2c96-11f1-911f-48ee5da28f41 2026-03-31 00:17:30.915510 | 2026-03-31 00:17:30.915574 | LOOP [emit-job-header : Print node information] 2026-03-31 00:17:31.003853 | localhost | ok: 2026-03-31 00:17:31.004054 | localhost | # Node Information 2026-03-31 00:17:31.004083 | localhost | Inventory Hostname: controller 2026-03-31 00:17:31.004104 | localhost | Hostname: np0000161770 2026-03-31 00:17:31.004124 | localhost | Username: zuul 2026-03-31 00:17:31.004148 | localhost | Distro: Debian 13.4 2026-03-31 00:17:31.004167 | localhost | Provider: yul1 2026-03-31 00:17:31.004186 | localhost | Region: ca-ymq-1 2026-03-31 00:17:31.004205 | localhost | Label: debian-trixie 2026-03-31 00:17:31.004223 | localhost | Product Name: OpenStack Nova 2026-03-31 00:17:31.004242 | localhost | Interface IP: 199.204.45.248 2026-03-31 00:17:31.019271 | 2026-03-31 00:17:31.019583 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-31 00:17:31.428878 | localhost -> localhost | changed 2026-03-31 00:17:31.434603 | 2026-03-31 00:17:31.434680 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-31 00:17:32.249680 | localhost -> localhost | changed 2026-03-31 00:17:32.259759 | 2026-03-31 00:17:32.259839 | PLAY [all] 2026-03-31 00:17:32.267852 | 2026-03-31 00:17:32.267923 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-31 00:17:32.495404 | controller -> localhost | ok 2026-03-31 00:17:32.504447 | 2026-03-31 00:17:32.504532 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-31 00:17:32.539740 | controller | ok 2026-03-31 00:17:32.551905 | controller | included: /var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-31 00:17:32.556511 | 2026-03-31 00:17:32.556569 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-31 00:17:33.251364 | controller -> localhost | Generating public/private rsa key pair. 2026-03-31 00:17:33.251563 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/work/821b528d3d484658b0cc1131eb67e5fa_id_rsa 2026-03-31 00:17:33.251593 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/work/821b528d3d484658b0cc1131eb67e5fa_id_rsa.pub 2026-03-31 00:17:33.251616 | controller -> localhost | The key fingerprint is: 2026-03-31 00:17:33.251638 | controller -> localhost | SHA256:QEFOpqAr+RrSQC6kHOO07/CFnafHng1ItCIELaviU78 zuul-build-sshkey 2026-03-31 00:17:33.251679 | controller -> localhost | The key's randomart image is: 2026-03-31 00:17:33.251701 | controller -> localhost | +---[RSA 3072]----+ 2026-03-31 00:17:33.251730 | controller -> localhost | |... .*. | 2026-03-31 00:17:33.251752 | controller -> localhost | |o... * | 2026-03-31 00:17:33.251773 | controller -> localhost | |.X ..o | 2026-03-31 00:17:33.251793 | controller -> localhost | |@o+ . .. | 2026-03-31 00:17:33.251813 | controller -> localhost | |O* . o S | 2026-03-31 00:17:33.251833 | controller -> localhost | |=+o.= o | 2026-03-31 00:17:33.251853 | controller -> localhost | |=.+o.=.o | 2026-03-31 00:17:33.251874 | controller -> localhost | |.=+ ..oo+ | 2026-03-31 00:17:33.251897 | controller -> localhost | |. .o E+o . | 2026-03-31 00:17:33.251919 | controller -> localhost | +----[SHA256]-----+ 2026-03-31 00:17:33.251968 | controller -> localhost | ok: Runtime: 0:00:00.259947 2026-03-31 00:17:33.256291 | 2026-03-31 00:17:33.256360 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-31 00:17:33.289128 | controller | ok 2026-03-31 00:17:33.297507 | controller | included: /var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-31 00:17:33.304407 | 2026-03-31 00:17:33.304467 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-31 00:17:33.329281 | controller | skipping: Conditional result was False 2026-03-31 00:17:33.353332 | 2026-03-31 00:17:33.353399 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-31 00:17:33.877860 | controller | changed 2026-03-31 00:17:33.882067 | 2026-03-31 00:17:33.882132 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-31 00:17:34.096190 | controller | ok 2026-03-31 00:17:34.103204 | 2026-03-31 00:17:34.103292 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-31 00:17:34.640734 | controller | changed 2026-03-31 00:17:34.644874 | 2026-03-31 00:17:34.644938 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-31 00:17:35.176865 | controller | changed 2026-03-31 00:17:35.182658 | 2026-03-31 00:17:35.182733 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-31 00:17:35.210042 | controller | skipping: Conditional result was False 2026-03-31 00:17:35.215513 | 2026-03-31 00:17:35.215593 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-31 00:17:35.594858 | controller -> localhost | changed 2026-03-31 00:17:35.603193 | 2026-03-31 00:17:35.603259 | TASK [add-build-sshkey : Add back temp key] 2026-03-31 00:17:35.865176 | controller -> localhost | Identity added: /var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/work/821b528d3d484658b0cc1131eb67e5fa_id_rsa (zuul-build-sshkey) 2026-03-31 00:17:35.865408 | controller -> localhost | ok: Runtime: 0:00:00.010332 2026-03-31 00:17:35.869713 | 2026-03-31 00:17:35.869796 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-31 00:17:36.236835 | controller | ok 2026-03-31 00:17:36.242046 | 2026-03-31 00:17:36.242135 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-31 00:17:36.268603 | controller | skipping: Conditional result was False 2026-03-31 00:17:36.281692 | 2026-03-31 00:17:36.281780 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-31 00:17:36.668138 | controller | ok 2026-03-31 00:17:36.674455 | 2026-03-31 00:17:36.674522 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-31 00:17:37.686812 | controller | Output suppressed because no_log was given 2026-03-31 00:17:37.694083 | 2026-03-31 00:17:37.694149 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-31 00:17:37.925841 | controller | ok: "logs" 2026-03-31 00:17:37.926180 | controller | ok: All items complete 2026-03-31 00:17:37.926220 | 2026-03-31 00:17:38.123420 | controller | ok: "artifacts" 2026-03-31 00:17:38.304673 | controller | ok: "docs" 2026-03-31 00:17:38.324566 | 2026-03-31 00:17:38.324733 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-31 00:17:38.541436 | controller | changed: "logs" 2026-03-31 00:17:38.748458 | controller | changed: "artifacts" 2026-03-31 00:17:38.944529 | controller | changed: "docs" 2026-03-31 00:17:38.964183 | 2026-03-31 00:17:38.964318 | PLAY RECAP 2026-03-31 00:17:38.964366 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-31 00:17:38.964395 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-31 00:17:38.964417 | 2026-03-31 00:17:39.156300 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-31 00:17:39.158973 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-31 00:17:39.743760 | 2026-03-31 00:17:39.743898 | PLAY [all] 2026-03-31 00:17:39.754294 | 2026-03-31 00:17:39.754374 | TASK [setup-uv : Extract archive] 2026-03-31 00:17:43.215189 | controller | changed 2026-03-31 00:17:43.225215 | 2026-03-31 00:17:43.225417 | TASK [setup-uv : Print version] 2026-03-31 00:17:43.689719 | controller | uv 0.8.13 2026-03-31 00:17:43.760574 | controller | ok: Runtime: 0:00:00.014141 2026-03-31 00:17:43.767578 | 2026-03-31 00:17:43.767660 | PLAY RECAP 2026-03-31 00:17:43.767725 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-31 00:17:43.767759 | 2026-03-31 00:17:43.895452 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-31 00:17:43.896843 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-03-31 00:17:44.481975 | 2026-03-31 00:17:44.482113 | PLAY [all] 2026-03-31 00:17:44.492361 | 2026-03-31 00:17:44.492461 | TASK [Update apt cache] 2026-03-31 00:17:49.930515 | controller | ok 2026-03-31 00:17:49.939017 | 2026-03-31 00:17:49.939106 | TASK [Disable firewalld] 2026-03-31 00:17:49.964625 | controller | skipping: Conditional result was False 2026-03-31 00:17:49.973385 | 2026-03-31 00:17:49.973528 | TASK [Install "jq" for log collection] 2026-03-31 00:17:52.486740 | controller | changed 2026-03-31 00:17:52.489588 | 2026-03-31 00:17:52.489661 | PLAY RECAP 2026-03-31 00:17:52.489722 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-31 00:17:52.489753 | 2026-03-31 00:17:52.605548 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-03-31 00:17:52.608184 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-31 00:17:53.190934 | 2026-03-31 00:17:53.191073 | PLAY [all] 2026-03-31 00:17:53.201253 | 2026-03-31 00:17:53.201348 | TASK [Copy inventory file for Zuul] 2026-03-31 00:17:54.192167 | controller | changed 2026-03-31 00:17:54.197605 | 2026-03-31 00:17:54.197683 | TASK [Switch "ansible_host" to private IP] 2026-03-31 00:17:54.513479 | controller | changed: 1 replacements made 2026-03-31 00:17:54.520064 | 2026-03-31 00:17:54.520158 | TASK [Run Molecule scenario] 2026-03-31 00:17:54.959928 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-31 00:17:54.960126 | controller | Creating virtual environment at: .venv 2026-03-31 00:17:55.278146 | controller | Downloading ansible-core (2.3MiB) 2026-03-31 00:17:55.279221 | controller | Downloading cryptography (4.3MiB) 2026-03-31 00:17:55.279686 | controller | Downloading pygments (1.2MiB) 2026-03-31 00:17:55.449167 | controller | Downloading cryptography 2026-03-31 00:17:55.506525 | controller | Downloading pygments 2026-03-31 00:17:55.720918 | controller | Downloading ansible-core 2026-03-31 00:17:55.767367 | controller | Installed 36 packages in 45ms 2026-03-31 00:17:56.505004 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-31 00:17:56.505069 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-31 00:17:57.430818 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-31 00:17:57.430886 | controller | INFO default scenario not found, disabling shared state. 2026-03-31 00:17:57.431026 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-31 00:17:57.431106 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-03-31 00:18:14.134656 | controller | INFO [helm > dependency] Executing 2026-03-31 00:18:14.134809 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-03-31 00:18:14.135109 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-03-31 00:18:14.135299 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-31 00:18:14.143701 | controller | INFO [helm > cleanup] Executing 2026-03-31 00:18:14.144011 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-31 00:18:14.149669 | controller | INFO [helm > destroy] Executing 2026-03-31 00:18:14.149696 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-31 00:18:14.149778 | controller | INFO [helm > destroy] Executed: Successful 2026-03-31 00:18:14.155957 | controller | INFO [helm > syntax] Executing 2026-03-31 00:18:15.160997 | controller | 2026-03-31 00:18:15.161033 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-03-31 00:18:15.227984 | controller | INFO [helm > syntax] Executed: Successful 2026-03-31 00:18:15.238453 | controller | INFO [helm > create] Executing 2026-03-31 00:18:15.240569 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-31 00:18:15.252318 | controller | INFO [helm > prepare] Executing 2026-03-31 00:18:16.209165 | controller | 2026-03-31 00:18:16.209230 | controller | PLAY [Prepare] ***************************************************************** 2026-03-31 00:18:16.209236 | controller | 2026-03-31 00:18:16.209241 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-31 00:18:16.209247 | controller | Tuesday 31 March 2026 00:18:16 +0000 (0:00:00.030) 0:00:00.030 ********* 2026-03-31 00:18:17.539852 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-31 00:18:17.547222 | controller | ok: [controller] 2026-03-31 00:18:17.547469 | controller | 2026-03-31 00:18:17.547723 | controller | TASK [Install Debian packages] ************************************************* 2026-03-31 00:18:17.547997 | controller | Tuesday 31 March 2026 00:18:17 +0000 (0:00:01.340) 0:00:01.370 ********* 2026-03-31 00:18:19.415841 | controller | ok: [controller] 2026-03-31 00:18:19.415988 | controller | 2026-03-31 00:18:19.416213 | controller | PLAY RECAP ********************************************************************* 2026-03-31 00:18:19.416476 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-31 00:18:19.416698 | controller | 2026-03-31 00:18:19.416860 | controller | 2026-03-31 00:18:19.417150 | controller | TASKS RECAP ******************************************************************** 2026-03-31 00:18:19.417359 | controller | Tuesday 31 March 2026 00:18:19 +0000 (0:00:01.868) 0:00:03.239 ********* 2026-03-31 00:18:19.417554 | controller | =============================================================================== 2026-03-31 00:18:19.417758 | controller | Install Debian packages ------------------------------------------------- 1.87s 2026-03-31 00:18:19.417950 | controller | Gathering Facts --------------------------------------------------------- 1.34s 2026-03-31 00:18:19.526856 | controller | INFO [helm > prepare] Executed: Successful 2026-03-31 00:18:19.536178 | controller | INFO [helm > converge] Executing 2026-03-31 00:18:20.560649 | controller | 2026-03-31 00:18:20.561671 | controller | PLAY [Converge] **************************************************************** 2026-03-31 00:18:20.561777 | controller | 2026-03-31 00:18:20.561905 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-31 00:18:20.562024 | controller | Tuesday 31 March 2026 00:18:20 +0000 (0:00:00.014) 0:00:00.014 ********* 2026-03-31 00:18:22.705134 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-31 00:18:22.708253 | controller | ok: [controller] 2026-03-31 00:18:22.708363 | controller | 2026-03-31 00:18:22.708498 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-31 00:18:22.708630 | controller | Tuesday 31 March 2026 00:18:22 +0000 (0:00:02.149) 0:00:02.164 ********* 2026-03-31 00:18:23.203153 | controller | ok: [controller] 2026-03-31 00:18:23.203290 | controller | 2026-03-31 00:18:23.203583 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-31 00:18:23.203837 | controller | Tuesday 31 March 2026 00:18:23 +0000 (0:00:00.494) 0:00:02.659 ********* 2026-03-31 00:18:24.469935 | controller | ok: [controller] 2026-03-31 00:18:24.470055 | controller | 2026-03-31 00:18:24.470270 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-31 00:18:24.470475 | controller | Tuesday 31 March 2026 00:18:24 +0000 (0:00:01.266) 0:00:03.925 ********* 2026-03-31 00:18:24.954682 | controller | changed: [controller] 2026-03-31 00:18:24.954827 | controller | 2026-03-31 00:18:24.955040 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-31 00:18:24.955291 | controller | Tuesday 31 March 2026 00:18:24 +0000 (0:00:00.485) 0:00:04.410 ********* 2026-03-31 00:18:24.998314 | controller | ok: [controller] => { 2026-03-31 00:18:24.998533 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-31 00:18:24.998736 | controller | } 2026-03-31 00:18:24.998909 | controller | 2026-03-31 00:18:24.999188 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-31 00:18:24.999387 | controller | Tuesday 31 March 2026 00:18:24 +0000 (0:00:00.043) 0:00:04.454 ********* 2026-03-31 00:18:26.079051 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-03-31 00:18:26.088297 | controller | changed: [controller] 2026-03-31 00:18:26.088589 | controller | 2026-03-31 00:18:26.088807 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-31 00:18:26.089111 | controller | Tuesday 31 March 2026 00:18:26 +0000 (0:00:01.089) 0:00:05.544 ********* 2026-03-31 00:18:28.319028 | controller | changed: [controller] 2026-03-31 00:18:28.319162 | controller | 2026-03-31 00:18:28.319396 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-31 00:18:28.319592 | controller | Tuesday 31 March 2026 00:18:28 +0000 (0:00:02.230) 0:00:07.774 ********* 2026-03-31 00:18:28.901931 | controller | ok: [controller] 2026-03-31 00:18:28.902079 | controller | 2026-03-31 00:18:28.902316 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-31 00:18:28.902508 | controller | Tuesday 31 March 2026 00:18:28 +0000 (0:00:00.583) 0:00:08.357 ********* 2026-03-31 00:18:29.332424 | controller | changed: [controller] 2026-03-31 00:18:29.332458 | controller | 2026-03-31 00:18:29.332466 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-31 00:18:29.332472 | controller | Tuesday 31 March 2026 00:18:29 +0000 (0:00:00.428) 0:00:08.786 ********* 2026-03-31 00:18:29.837587 | controller | ok: [controller] 2026-03-31 00:18:29.837729 | controller | 2026-03-31 00:18:29.837952 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-31 00:18:29.838155 | controller | Tuesday 31 March 2026 00:18:29 +0000 (0:00:00.507) 0:00:09.293 ********* 2026-03-31 00:18:30.169018 | controller | changed: [controller] 2026-03-31 00:18:30.169239 | controller | 2026-03-31 00:18:30.169544 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-31 00:18:30.169877 | controller | Tuesday 31 March 2026 00:18:30 +0000 (0:00:00.330) 0:00:09.624 ********* 2026-03-31 00:18:30.490001 | controller | ok: [controller] 2026-03-31 00:18:30.490046 | controller | 2026-03-31 00:18:30.490052 | controller | TASK [Install plugin] ********************************************************** 2026-03-31 00:18:30.490056 | controller | Tuesday 31 March 2026 00:18:30 +0000 (0:00:00.318) 0:00:09.942 ********* 2026-03-31 00:18:30.561564 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-31 00:18:30.561618 | controller | 2026-03-31 00:18:30.561730 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-31 00:18:30.561841 | controller | Tuesday 31 March 2026 00:18:30 +0000 (0:00:00.073) 0:00:10.015 ********* 2026-03-31 00:18:30.621269 | controller | ok: [controller] => { 2026-03-31 00:18:30.621364 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-31 00:18:30.621391 | controller | } 2026-03-31 00:18:30.621403 | controller | 2026-03-31 00:18:30.621416 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-31 00:18:30.621543 | controller | Tuesday 31 March 2026 00:18:30 +0000 (0:00:00.060) 0:00:10.076 ********* 2026-03-31 00:18:31.385781 | controller | changed: [controller] 2026-03-31 00:18:31.385895 | controller | 2026-03-31 00:18:31.386110 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-31 00:18:31.386388 | controller | Tuesday 31 March 2026 00:18:31 +0000 (0:00:00.762) 0:00:10.839 ********* 2026-03-31 00:18:34.533058 | controller | changed: [controller] 2026-03-31 00:18:34.533098 | controller | 2026-03-31 00:18:34.533107 | controller | PLAY RECAP ********************************************************************* 2026-03-31 00:18:34.533118 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-31 00:18:34.533125 | controller | 2026-03-31 00:18:34.533132 | controller | 2026-03-31 00:18:34.533140 | controller | TASKS RECAP ******************************************************************** 2026-03-31 00:18:34.533147 | controller | Tuesday 31 March 2026 00:18:34 +0000 (0:00:03.148) 0:00:13.988 ********* 2026-03-31 00:18:34.533154 | controller | =============================================================================== 2026-03-31 00:18:34.533161 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 3.15s 2026-03-31 00:18:34.533168 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.23s 2026-03-31 00:18:34.533175 | controller | Gathering Facts --------------------------------------------------------- 2.15s 2026-03-31 00:18:34.533182 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.27s 2026-03-31 00:18:34.533189 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.09s 2026-03-31 00:18:34.533196 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.76s 2026-03-31 00:18:34.533203 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.58s 2026-03-31 00:18:34.533210 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.51s 2026-03-31 00:18:34.533218 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.49s 2026-03-31 00:18:34.533225 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.49s 2026-03-31 00:18:34.533245 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.43s 2026-03-31 00:18:34.533259 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.33s 2026-03-31 00:18:34.533266 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s 2026-03-31 00:18:34.533276 | controller | Install plugin ---------------------------------------------------------- 0.07s 2026-03-31 00:18:34.533486 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-03-31 00:18:34.533498 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-03-31 00:18:34.689521 | controller | INFO [helm > converge] Executed: Successful 2026-03-31 00:18:34.701058 | controller | INFO [helm > idempotence] Executing 2026-03-31 00:18:35.646917 | controller | 2026-03-31 00:18:35.646950 | controller | PLAY [Converge] **************************************************************** 2026-03-31 00:18:35.646957 | controller | 2026-03-31 00:18:35.646962 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-31 00:18:35.646966 | controller | Tuesday 31 March 2026 00:18:35 +0000 (0:00:00.013) 0:00:00.013 ********* 2026-03-31 00:18:36.788827 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-31 00:18:36.794272 | controller | ok: [controller] 2026-03-31 00:18:36.794550 | controller | 2026-03-31 00:18:36.794839 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-03-31 00:18:36.795116 | controller | Tuesday 31 March 2026 00:18:36 +0000 (0:00:01.147) 0:00:01.161 ********* 2026-03-31 00:18:37.302261 | controller | ok: [controller] 2026-03-31 00:18:37.302435 | controller | 2026-03-31 00:18:37.302588 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-03-31 00:18:37.302737 | controller | Tuesday 31 March 2026 00:18:37 +0000 (0:00:00.507) 0:00:01.669 ********* 2026-03-31 00:18:38.401510 | controller | ok: [controller] 2026-03-31 00:18:38.401795 | controller | 2026-03-31 00:18:38.402066 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-03-31 00:18:38.402325 | controller | Tuesday 31 March 2026 00:18:38 +0000 (0:00:01.098) 0:00:02.767 ********* 2026-03-31 00:18:38.881618 | controller | ok: [controller] 2026-03-31 00:18:38.881702 | controller | 2026-03-31 00:18:38.882015 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-31 00:18:38.882048 | controller | Tuesday 31 March 2026 00:18:38 +0000 (0:00:00.476) 0:00:03.244 ********* 2026-03-31 00:18:38.929165 | controller | ok: [controller] => { 2026-03-31 00:18:38.930150 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-03-31 00:18:38.930179 | controller | } 2026-03-31 00:18:38.930185 | controller | 2026-03-31 00:18:38.930190 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-31 00:18:38.930195 | controller | Tuesday 31 March 2026 00:18:38 +0000 (0:00:00.050) 0:00:03.295 ********* 2026-03-31 00:18:39.473142 | controller | ok: [controller] 2026-03-31 00:18:39.473204 | controller | 2026-03-31 00:18:39.473354 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-31 00:18:39.473487 | controller | Tuesday 31 March 2026 00:18:39 +0000 (0:00:00.544) 0:00:03.839 ********* 2026-03-31 00:18:41.245425 | controller | ok: [controller] 2026-03-31 00:18:41.245471 | controller | 2026-03-31 00:18:41.245556 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-03-31 00:18:41.245684 | controller | Tuesday 31 March 2026 00:18:41 +0000 (0:00:01.768) 0:00:05.608 ********* 2026-03-31 00:18:41.785485 | controller | ok: [controller] 2026-03-31 00:18:41.785536 | controller | 2026-03-31 00:18:41.785643 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-03-31 00:18:41.785739 | controller | Tuesday 31 March 2026 00:18:41 +0000 (0:00:00.541) 0:00:06.150 ********* 2026-03-31 00:18:42.206398 | controller | ok: [controller] 2026-03-31 00:18:42.206454 | controller | 2026-03-31 00:18:42.206593 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-03-31 00:18:42.206726 | controller | Tuesday 31 March 2026 00:18:42 +0000 (0:00:00.422) 0:00:06.572 ********* 2026-03-31 00:18:42.667919 | controller | ok: [controller] 2026-03-31 00:18:42.667985 | controller | 2026-03-31 00:18:42.668046 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-03-31 00:18:42.668132 | controller | Tuesday 31 March 2026 00:18:42 +0000 (0:00:00.461) 0:00:07.034 ********* 2026-03-31 00:18:42.985453 | controller | ok: [controller] 2026-03-31 00:18:42.985509 | controller | 2026-03-31 00:18:42.985637 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-03-31 00:18:42.985755 | controller | Tuesday 31 March 2026 00:18:42 +0000 (0:00:00.317) 0:00:07.351 ********* 2026-03-31 00:18:43.297520 | controller | ok: [controller] 2026-03-31 00:18:43.297895 | controller | 2026-03-31 00:18:43.297927 | controller | TASK [Install plugin] ********************************************************** 2026-03-31 00:18:43.297933 | controller | Tuesday 31 March 2026 00:18:43 +0000 (0:00:00.308) 0:00:07.660 ********* 2026-03-31 00:18:43.353497 | controller | included: vexxhost.containers.download_artifact for controller 2026-03-31 00:18:43.353573 | controller | 2026-03-31 00:18:43.353675 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-03-31 00:18:43.353793 | controller | Tuesday 31 March 2026 00:18:43 +0000 (0:00:00.057) 0:00:07.718 ********* 2026-03-31 00:18:43.396253 | controller | ok: [controller] => { 2026-03-31 00:18:43.397144 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-03-31 00:18:43.397175 | controller | } 2026-03-31 00:18:43.397179 | controller | 2026-03-31 00:18:43.397184 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-03-31 00:18:43.397188 | controller | Tuesday 31 March 2026 00:18:43 +0000 (0:00:00.044) 0:00:07.762 ********* 2026-03-31 00:18:43.836364 | controller | ok: [controller] 2026-03-31 00:18:43.836390 | controller | 2026-03-31 00:18:43.836394 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-03-31 00:18:43.836398 | controller | Tuesday 31 March 2026 00:18:43 +0000 (0:00:00.438) 0:00:08.200 ********* 2026-03-31 00:18:45.576988 | controller | ok: [controller] 2026-03-31 00:18:45.577355 | controller | 2026-03-31 00:18:45.577638 | controller | PLAY RECAP ********************************************************************* 2026-03-31 00:18:45.577916 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-31 00:18:45.578235 | controller | 2026-03-31 00:18:45.578503 | controller | 2026-03-31 00:18:45.578755 | controller | TASKS RECAP ******************************************************************** 2026-03-31 00:18:45.579011 | controller | Tuesday 31 March 2026 00:18:45 +0000 (0:00:01.743) 0:00:09.944 ********* 2026-03-31 00:18:45.579273 | controller | =============================================================================== 2026-03-31 00:18:45.579605 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.77s 2026-03-31 00:18:45.579944 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.74s 2026-03-31 00:18:45.580273 | controller | Gathering Facts --------------------------------------------------------- 1.15s 2026-03-31 00:18:45.580592 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.10s 2026-03-31 00:18:45.580890 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s 2026-03-31 00:18:45.581272 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.54s 2026-03-31 00:18:45.581537 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.51s 2026-03-31 00:18:45.581870 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.48s 2026-03-31 00:18:45.582164 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-03-31 00:18:45.582414 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.44s 2026-03-31 00:18:45.582673 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s 2026-03-31 00:18:45.582974 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.32s 2026-03-31 00:18:45.583246 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-03-31 00:18:45.583514 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-03-31 00:18:45.583762 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-03-31 00:18:45.584048 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-03-31 00:18:45.725139 | controller | INFO [helm > idempotence] Executed: Successful 2026-03-31 00:18:45.736975 | controller | INFO [helm > side_effect] Executing 2026-03-31 00:18:45.736991 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-31 00:18:45.741169 | controller | INFO [helm > verify] Executing 2026-03-31 00:18:46.602428 | controller | 2026-03-31 00:18:46.602841 | controller | PLAY [Verify] ****************************************************************** 2026-03-31 00:18:46.603114 | controller | 2026-03-31 00:18:46.603448 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-31 00:18:46.603788 | controller | Tuesday 31 March 2026 00:18:46 +0000 (0:00:00.009) 0:00:00.009 ********* 2026-03-31 00:18:47.694636 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-31 00:18:47.700821 | controller | ok: [controller] 2026-03-31 00:18:47.701131 | controller | 2026-03-31 00:18:47.701396 | controller | TASK [Get the current Helm version] ******************************************** 2026-03-31 00:18:47.701651 | controller | Tuesday 31 March 2026 00:18:47 +0000 (0:00:01.098) 0:00:01.108 ********* 2026-03-31 00:18:48.153056 | controller | changed: [controller] 2026-03-31 00:18:48.153336 | controller | 2026-03-31 00:18:48.153594 | controller | TASK [Assert Helm version] ***************************************************** 2026-03-31 00:18:48.153852 | controller | Tuesday 31 March 2026 00:18:48 +0000 (0:00:00.451) 0:00:01.560 ********* 2026-03-31 00:18:48.183218 | controller | ok: [controller] => { 2026-03-31 00:18:48.183488 | controller | "changed": false, 2026-03-31 00:18:48.183752 | controller | "msg": "All assertions passed" 2026-03-31 00:18:48.183990 | controller | } 2026-03-31 00:18:48.184212 | controller | 2026-03-31 00:18:48.184451 | controller | PLAY RECAP ********************************************************************* 2026-03-31 00:18:48.184713 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-31 00:18:48.184999 | controller | 2026-03-31 00:18:48.185226 | controller | 2026-03-31 00:18:48.185462 | controller | TASKS RECAP ******************************************************************** 2026-03-31 00:18:48.185707 | controller | Tuesday 31 March 2026 00:18:48 +0000 (0:00:00.030) 0:00:01.591 ********* 2026-03-31 00:18:48.185934 | controller | =============================================================================== 2026-03-31 00:18:48.186169 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-03-31 00:18:48.186405 | controller | Get the current Helm version -------------------------------------------- 0.45s 2026-03-31 00:18:48.186640 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-03-31 00:18:48.299460 | controller | INFO [helm > verify] Executed: Successful 2026-03-31 00:18:48.310810 | controller | INFO [helm > cleanup] Executing 2026-03-31 00:18:48.311299 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-31 00:18:48.317669 | controller | INFO [helm > destroy] Executing 2026-03-31 00:18:48.317793 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-03-31 00:18:48.317883 | controller | INFO [helm > destroy] Executed: Successful 2026-03-31 00:18:48.318219 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-31 00:18:48.602939 | controller | ok: Runtime: 0:00:53.536059 2026-03-31 00:18:48.606923 | 2026-03-31 00:18:48.606976 | PLAY RECAP 2026-03-31 00:18:48.607023 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-31 00:18:48.607044 | 2026-03-31 00:18:48.734575 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-31 00:18:48.737577 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-03-31 00:18:49.326210 | 2026-03-31 00:18:49.326910 | PLAY [all] 2026-03-31 00:18:49.338272 | 2026-03-31 00:18:49.338349 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-03-31 00:18:49.727699 | controller | changed 2026-03-31 00:18:49.732874 | 2026-03-31 00:18:49.732961 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-03-31 00:18:50.149189 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-31 00:18:50.151859 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-31 00:18:50.155860 | controller | environment: line 1: kubectl: command not found 2026-03-31 00:18:50.157115 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-31 00:18:50.158007 | controller | environment: line 1: kubectl: command not found 2026-03-31 00:18:50.159699 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-31 00:18:50.161564 | controller | environment: line 1: kubectl: command not found 2026-03-31 00:18:50.162643 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-31 00:18:50.164011 | controller | environment: line 1: kubectl: command not found 2026-03-31 00:18:50.165514 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-31 00:18:50.167044 | controller | environment: line 1: kubectl: command not found 2026-03-31 00:18:50.167972 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-31 00:18:50.261621 | controller | ok: Runtime: 0:00:00.031334 2026-03-31 00:18:50.267091 | 2026-03-31 00:18:50.267160 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-03-31 00:18:50.514686 | controller | changed 2026-03-31 00:18:50.520119 | 2026-03-31 00:18:50.520219 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-03-31 00:18:50.764771 | controller | environment: line 5: kubectl: command not found 2026-03-31 00:18:50.764871 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-31 00:18:50.764885 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-31 00:18:50.764914 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-31 00:18:51.051395 | controller | ok: Runtime: 0:00:00.013062 2026-03-31 00:18:51.058723 | 2026-03-31 00:18:51.058794 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-03-31 00:18:51.722699 | controller | changed: 2026-03-31 00:18:51.722970 | controller | created directory /var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/work/logs/controller 2026-03-31 00:18:51.723035 | controller | cd+++++++++ objects/ 2026-03-31 00:18:51.723086 | controller | cd+++++++++ objects/cluster/ 2026-03-31 00:18:51.723135 | controller | cd+++++++++ objects/namespaced/ 2026-03-31 00:18:51.732992 | 2026-03-31 00:18:51.733061 | TASK [gather-pod-logs : creating directory for pod logs] 2026-03-31 00:18:51.987888 | controller | changed 2026-03-31 00:18:51.992351 | 2026-03-31 00:18:51.992419 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-03-31 00:18:52.250098 | controller | changed 2026-03-31 00:18:52.255444 | 2026-03-31 00:18:52.255541 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-03-31 00:18:52.500324 | controller | environment: line 3: kubectl: command not found 2026-03-31 00:18:52.786960 | controller | ok: Runtime: 0:00:00.028208 2026-03-31 00:18:52.792799 | 2026-03-31 00:18:52.792864 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-03-31 00:18:53.301566 | controller | changed: 2026-03-31 00:18:53.301762 | controller | cd+++++++++ pod-logs/ 2026-03-31 00:18:53.301803 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-03-31 00:18:53.310643 | 2026-03-31 00:18:53.310702 | LOOP [helm-release-status : creating directory for helm release status] 2026-03-31 00:18:53.534880 | controller | changed: "values" 2026-03-31 00:18:53.751106 | controller | changed: "releases" 2026-03-31 00:18:53.762688 | 2026-03-31 00:18:53.762844 | TASK [helm-release-status : Gather get release status for helm charts] 2026-03-31 00:18:53.997792 | controller | /bin/bash: line 3: kubectl: command not found 2026-03-31 00:18:54.294039 | controller | ok: Runtime: 0:00:00.008044 2026-03-31 00:18:54.298834 | 2026-03-31 00:18:54.298904 | TASK [helm-release-status : Downloads logs to executor] 2026-03-31 00:18:54.810355 | controller | changed: 2026-03-31 00:18:54.810672 | controller | cd+++++++++ helm/ 2026-03-31 00:18:54.810706 | controller | cd+++++++++ helm/releases/ 2026-03-31 00:18:54.810733 | controller | cd+++++++++ helm/values/ 2026-03-31 00:18:54.815579 | 2026-03-31 00:18:54.815634 | PLAY RECAP 2026-03-31 00:18:54.815684 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-31 00:18:54.815706 | 2026-03-31 00:18:54.957173 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-03-31 00:18:54.960010 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-31 00:18:55.531672 | 2026-03-31 00:18:55.531813 | PLAY [all] 2026-03-31 00:18:55.543488 | 2026-03-31 00:18:55.543627 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-31 00:18:55.589219 | controller | skipping: Conditional result was False 2026-03-31 00:18:55.600810 | 2026-03-31 00:18:55.600909 | TASK [fetch-output : Set log path for single node] 2026-03-31 00:18:55.642824 | controller | ok 2026-03-31 00:18:55.648344 | 2026-03-31 00:18:55.648445 | LOOP [fetch-output : Ensure local output dirs] 2026-03-31 00:18:56.052501 | controller -> localhost | ok: "/var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/work/logs" 2026-03-31 00:18:56.261080 | controller -> localhost | changed: "/var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/work/artifacts" 2026-03-31 00:18:56.469358 | controller -> localhost | changed: "/var/lib/zuul/builds/821b528d3d484658b0cc1131eb67e5fa/work/docs" 2026-03-31 00:18:56.486921 | 2026-03-31 00:18:56.487058 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-31 00:18:57.141404 | controller | changed: .d..t...... ./ 2026-03-31 00:18:57.141653 | controller | changed: All items complete 2026-03-31 00:18:57.141692 | 2026-03-31 00:18:57.605519 | controller | changed: .d..t...... ./ 2026-03-31 00:18:58.063044 | controller | changed: .d..t...... ./ 2026-03-31 00:18:58.089416 | 2026-03-31 00:18:58.089599 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-31 00:18:58.498446 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007615 2026-03-31 00:18:58.710195 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006884 2026-03-31 00:18:58.732231 | 2026-03-31 00:18:58.732377 | PLAY [all] 2026-03-31 00:18:58.737540 | 2026-03-31 00:18:58.737612 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-31 00:18:59.216270 | controller | changed 2026-03-31 00:18:59.222676 | 2026-03-31 00:18:59.222744 | PLAY RECAP 2026-03-31 00:18:59.222804 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-31 00:18:59.222835 | 2026-03-31 00:18:59.365630 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-31 00:18:59.368569 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-31 00:18:59.936842 | 2026-03-31 00:18:59.936995 | PLAY [localhost] 2026-03-31 00:18:59.947229 | 2026-03-31 00:18:59.947322 | TASK [Generate Zuul manifest] 2026-03-31 00:18:59.968809 | localhost | ok 2026-03-31 00:18:59.985270 | 2026-03-31 00:18:59.985351 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-31 00:19:00.311134 | localhost | changed 2026-03-31 00:19:00.323651 | 2026-03-31 00:19:00.323755 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-31 00:19:00.355892 | localhost | ok 2026-03-31 00:19:00.364671 | 2026-03-31 00:19:00.364738 | TASK [Upload logs] 2026-03-31 00:19:00.386209 | localhost | ok 2026-03-31 00:19:00.446021 | 2026-03-31 00:19:00.446183 | TASK [Set zuul-log-path fact] 2026-03-31 00:19:00.465549 | localhost | ok 2026-03-31 00:19:00.479083 | 2026-03-31 00:19:00.479162 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-31 00:19:00.509356 | localhost | ok 2026-03-31 00:19:00.516521 | 2026-03-31 00:19:00.516590 | TASK [upload-logs : Create log directories] 2026-03-31 00:19:00.903740 | localhost | changed 2026-03-31 00:19:00.910677 | 2026-03-31 00:19:00.910770 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-31 00:19:01.298018 | localhost -> localhost | ok: Runtime: 0:00:00.005745 2026-03-31 00:19:01.305911 | 2026-03-31 00:19:01.305977 | TASK [upload-logs : Upload logs to log server] 2026-03-31 00:19:01.741951 | localhost | Output suppressed because no_log was given 2026-03-31 00:19:01.747118 | 2026-03-31 00:19:01.747209 | LOOP [upload-logs : Compress console log and json output] 2026-03-31 00:19:01.790695 | localhost | skipping: Conditional result was False 2026-03-31 00:19:01.797744 | localhost | skipping: Conditional result was False 2026-03-31 00:19:01.812797 | 2026-03-31 00:19:01.812923 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-31 00:19:01.859245 | localhost | skipping: Conditional result was False 2026-03-31 00:19:01.859651 | 2026-03-31 00:19:01.863594 | localhost | skipping: Conditional result was False 2026-03-31 00:19:01.879229 | 2026-03-31 00:19:01.879399 | LOOP [upload-logs : Upload console log and json output]